Head
2026-05-29 21:50:24 [scrapy.utils.log] INFO: Scrapy 2.10.0 started (bot: retriever)
2026-05-29 21:50:24 [scrapy.utils.log] INFO: Versions: lxml 5.1.0.0, libxml2 2.12.3, cssselect 1.2.0, parsel 1.9.0, w3lib 2.0.0, Twisted 22.4.0, Python 3.10.12 (main, Mar 3 2026, 11:56:32) [GCC 11.4.0], pyOpenSSL 24.1.0 (OpenSSL 3.2.1 30 Jan 2024), cryptography 42.0.5, Platform Linux-6.8.0-1030-aws-x86_64-with-glibc2.35
2026-05-29 21:50:24 [scrapy.addons] INFO: Enabled addons:
[]
2026-05-29 21:50:24 [scrapy.crawler] INFO: Overridden settings:
{'AUTOTHROTTLE_ENABLED': True,
'AUTOTHROTTLE_MAX_DELAY': 120,
'BOT_NAME': 'retriever',
'CONCURRENT_REQUESTS_PER_DOMAIN': 3,
'DOWNLOAD_DELAY': 20,
'HTTPCACHE_EXPIRATION_SECS': 86400,
'IMAGES_STORE_S3_ACL': 'public-read',
'LOG_FILE': '/home/scrapyd/logs/loknow_spiders_prod/StrathmoremotorsSpider/631701885ba811f1bdc0c3bbae71ecef.log',
'LOG_FORMATTER': 'loknow_spiders.logging.PoliteLogFormatter',
'LOG_LEVEL': 'INFO',
'NEWSPIDER_MODULE': 'loknow_spiders.spiders',
'RETRY_TIMES': 4,
'ROBOTSTXT_OBEY': True,
'SPIDER_MODULES': ['loknow_spiders.spiders'],
'TELNETCONSOLE_ENABLED': False,
'TWISTED_REACTOR': 'twisted.internet.asyncioreactor.AsyncioSelectorReactor',
'USER_AGENT': 'AdRetriever (https://adretriever.com)'}
2026-05-29 21:50:24 [py.warnings] WARNING: /home/scrapyd/venv/lib/python3.10/site-packages/scrapy/utils/request.py:248: ScrapyDeprecationWarning: '2.6' is a deprecated value for the 'REQUEST_FINGERPRINTER_IMPLEMENTATION' setting.
It is also the default value. In other words, it is normal to get this warning if you have not defined a value for the 'REQUEST_FINGERPRINTER_IMPLEMENTATION' setting. This is so for backward compatibility reasons, but it will change in a future version of Scrapy.
See the documentation of the 'REQUEST_FINGERPRINTER_IMPLEMENTATION' setting for information on how to handle this deprecation.
return cls(crawler)
2026-05-29 21:50:24 [scrapy.middleware] INFO: Enabled extensions:
['scrapy.extensions.corestats.CoreStats',
'scrapy.extensions.memusage.MemoryUsage',
'scrapy.extensions.feedexport.FeedExporter',
'scrapy.extensions.logstats.LogStats',
'scrapy.extensions.throttle.AutoThrottle']
2026-05-29 21:50:24 [StrathmoremotorsSpider] INFO: Current Spider Environment set to: prod
2026-05-29 21:50:24 [StrathmoremotorsSpider] INFO: Retrieving AWS secret: spider_prod_secrets
2026-05-29 21:50:24 [botocore.credentials] INFO: Found credentials in shared credentials file: ~/.aws/credentials
2026-05-29 21:50:25 [StrathmoremotorsSpider] INFO: In base spider ml_kwargs is None and scrape_type is inventory
2026-05-29 21:50:25 [scrapy.middleware] INFO: Enabled downloader middlewares:
['scrapy.downloadermiddlewares.robotstxt.RobotsTxtMiddleware',
'scrapy.downloadermiddlewares.httpauth.HttpAuthMiddleware',
'scrapy.downloadermiddlewares.downloadtimeout.DownloadTimeoutMiddleware',
'scrapy.downloadermiddlewares.defaultheaders.DefaultHeadersMiddleware',
'scrapy.downloadermiddlewares.useragent.UserAgentMiddleware',
'loknow_spiders.middlewares.ThrottlingRetryMiddleware',
'scrapy.downloadermiddlewares.redirect.MetaRefreshMiddleware',
'scrapy.downloadermiddlewares.httpcompression.HttpCompressionMiddleware',
'scrapy.downloadermiddlewares.redirect.RedirectMiddleware',
'scrapy.downloadermiddlewares.cookies.CookiesMiddleware',
'scrapy.downloadermiddlewares.httpproxy.HttpProxyMiddleware',
'scrapy.downloadermiddlewares.stats.DownloaderStats']
2026-05-29 21:50:25 [scrapy.middleware] INFO: Enabled spider middlewares:
['scrapy.spidermiddlewares.httperror.HttpErrorMiddleware',
'scrapy.spidermiddlewares.offsite.OffsiteMiddleware',
'loknow_spiders.middlewares.RetrieverSpiderMiddleware',
'scrapy.spidermiddlewares.referer.RefererMiddleware',
'scrapy.spidermiddlewares.urllength.UrlLengthMiddleware',
'scrapy.spidermiddlewares.depth.DepthMiddleware']
2026-05-29 21:50:26 [scrapy.middleware] INFO: Enabled item pipelines:
['loknow_spiders.pipelines.HTMLDetailsDecruftPipeline',
'loknow_spiders.pipelines.GPTVehiclePipeline',
'loknow_spiders.pipelines.PostProcessGPTVehiclePipeline',
'loknow_spiders.pipelines.StrathmoremotorsPipeline',
'loknow_spiders.pipelines.RetrieverPipeline']
2026-05-29 21:50:26 [scrapy.core.engine] INFO: Spider opened
2026-05-29 21:50:26 [StrathmoremotorsSpider] INFO: RetrieverAPI is making requests to https://api.adretriever.com with headers {'Authorization': 'Token 96dfb640234f1d676d6fa726f8eae6e7aab44cda'}
2026-05-29 21:50:26 [loknow_spiders] INFO: Scrape e3d66172 has been opened.
2026-05-29 21:50:26 [scrapy.extensions.logstats] INFO: Crawled 0 pages (at 0 pages/min), scraped 0 items (at 0 items/min)
2026-05-29 21:50:26 [scrapy-playwright] INFO: Starting download handler
2026-05-29 21:50:26 [scrapy-playwright] INFO: Starting download handler
2026-05-29 21:51:01 [scrapy-playwright] INFO: Launching browser chromium
2026-05-29 21:51:01 [scrapy-playwright] INFO: Browser chromium launched
2026-05-29 21:51:26 [scrapy.extensions.logstats] INFO: Crawled 3 pages (at 3 pages/min), scraped 0 items (at 0 items/min)
2026-05-29 21:51:27 [loknow_spiders] INFO: Clicking next page element...
2026-05-29 21:51:54 [loknow_spiders] INFO: Clicking next page element...
2026-05-29 21:52:19 [loknow_spiders] INFO: Clicking next page element...
2026-05-29 21:52:26 [scrapy.extensions.logstats] INFO: Crawled 5 pages (at 2 pages/min), scraped 0 items (at 0 items/min)
2026-05-29 21:52:35 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 21:52:40 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:7ea95c6e3bad4ef8e47facecb4b4f77e
2026-05-29 21:52:41 [loknow_spiders] INFO: Timeout querying next page element (could be the last page)
2026-05-29 21:52:41 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 21:52:45 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 21:52:45 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-chevrolet-silverado-3500hd-lt-KdSTjSGMTASU8Hze10decgvdp
2026-05-29 21:52:45 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 21:52:46 [loknow_spiders] INFO: Created: 1 items out of 1
2026-05-29 21:52:49 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-2760' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 21:52:49 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-4686' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 21:52:49 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-4300' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 21:52:52 [StrathmoremotorsSpider] INFO: Closed Playwright page (handling failure for <GET https://strathmoremotors.com/inventory/2026-chevrolet-colorado-4wd-z71-kzikchFZSsqzEckbJNTujAvdp> [Failure instance: Traceback: <class 'playwright._impl._errors.TimeoutError'>: Page.goto: Timeout 30000ms exceeded.
Call log:
- navigating to "https://strathmoremotors.com/inventory/2026-chevrolet-colorado-4wd-z71-kzikchFZSsqzEckbJNTujAvdp", waiting until "load"
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/internet/defer.py:700:errback
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/internet/defer.py:763:_startRunCallbacks
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/internet/defer.py:857:_runCallbacks
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/internet/defer.py:1750:gotResult
--- <exception caught here> ---
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/internet/defer.py:1656:_inlineCallbacks
/home/scrapyd/venv/lib/python3.10/site-packages/twisted/python/failure.py:514:throwExceptionIntoGenerator
/home/scrapyd/venv/lib/python3.10/site-packages/scrapy/core/downloader/middleware.py:54:process_request
Tail
2026-05-29 22:30:46 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:30:46 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-1500-elevation-2a1S2e9zQVOoolsGG8iBaAvdp
2026-05-29 22:31:07 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-127183' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:31:08 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:31:09 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:78d2b9a064549fdc234f27ff3a5d8572
2026-05-29 22:31:09 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-buick-envision-sport-touring-a93qkkQGTOyLyM2Ap0vkSwvdp
2026-05-29 22:31:12 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-128780' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:31:12 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-129023' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:31:26 [scrapy.extensions.logstats] INFO: Crawled 82 pages (at 3 pages/min), scraped 77 items (at 3 items/min)
2026-05-29 22:31:30 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:31:30 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:cc11ad26ccd28db41ae41bce85f3a742
2026-05-29 22:31:37 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:31:37 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-1500-pro-QZerztBfQJGN2wqmJnr4xwvdp
2026-05-29 22:31:37 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:31:38 [loknow_spiders] INFO: Created: 4 items out of 4
2026-05-29 22:31:49 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-130285' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:31:49 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-130530' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:31:58 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:31:59 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:32e31d21c10d48a261051c75b3bdc9b3
2026-05-29 22:32:04 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:32:04 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-buick-envision-sport-touring-MnaIGsu5QFS2B7wkcknFBwvdp
2026-05-29 22:32:17 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:32:17 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-131931' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:32:18 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:d94dd255c44c7681025cb9f7d5c52a93
2026-05-29 22:32:18 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-chevrolet-traverse-z71-9Ueo5al9TSOskAgMdtELxgvdp
2026-05-29 22:32:26 [scrapy.extensions.logstats] INFO: Crawled 85 pages (at 3 pages/min), scraped 80 items (at 3 items/min)
2026-05-29 22:32:38 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:32:39 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-133394' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:32:39 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:ee0b54d7af262490b876578eb2ff62f3
2026-05-29 22:32:44 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:32:44 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-1500-denali-tN1AK5ObT7CDNQBF5Q6FDAvdp
2026-05-29 22:32:44 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:32:45 [loknow_spiders] INFO: Created: 3 items out of 3
2026-05-29 22:32:47 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:32:47 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-134926' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:32:47 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-135268' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:32:48 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:e35c82c7b2ad7ade381311b29e42bab6
2026-05-29 22:32:52 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:32:52 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-chevrolet-traverse-lt-ye7QiadtQq2lj1ZLF2t2XQvdp
2026-05-29 22:33:00 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-136575' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:33:11 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:33:12 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:1cad779de8715118600e90e9a5db3dcf
2026-05-29 22:33:12 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-1500-elevation-LVm12m3cRl2kp3C0iB3p0gvdp
2026-05-29 22:33:22 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-138087' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:33:26 [scrapy.extensions.logstats] INFO: Crawled 87 pages (at 2 pages/min), scraped 83 items (at 3 items/min)
2026-05-29 22:33:45 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:33:46 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:8acacb020915877d3ebe6e8c6a5a7e75
2026-05-29 22:33:52 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:33:52 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-1500-at4x-KOZehmKuRhyW3WSCm3bIkwvdp
2026-05-29 22:33:52 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:33:53 [loknow_spiders] INFO: Created: 3 items out of 3
2026-05-29 22:34:02 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:34:02 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-139593' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:34:02 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-139833' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:34:03 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:936ecd7497f10fa014649b23621df80f
2026-05-29 22:34:06 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:34:06 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-3500hd-at4-o9rERlOfQMSM7bIUpGHuiwvdp
2026-05-29 22:34:22 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:34:23 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-141222' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:34:23 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:8d80dadbbd2fecd4234752bb9be2d563
2026-05-29 22:34:23 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-chevrolet-tahoe-rst-HRkhrVNWRRimFz4ALodOuAvdp
2026-05-29 22:34:26 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-142636' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:34:26 [scrapy.extensions.logstats] INFO: Crawled 90 pages (at 3 pages/min), scraped 86 items (at 3 items/min)
2026-05-29 22:34:46 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:34:47 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:5f6ea8daa0e710058d4fff61742afa18
2026-05-29 22:34:47 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-canyon-elevation-mwJLsgwlR62MPEt7pI2DGAvdp
2026-05-29 22:35:15 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:35:15 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-144004' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:35:15 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-144270' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:35:16 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:3a2663631161b760fbf9e714301df19b
2026-05-29 22:35:19 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:35:19 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-terrain-fwd-elevation-NbMk9LS4SomIGhUOxpYEdQvdp
2026-05-29 22:35:19 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:35:19 [loknow_spiders] INFO: Created: 4 items out of 4
2026-05-29 22:35:24 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-145627' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:35:26 [scrapy.extensions.logstats] INFO: Crawled 92 pages (at 2 pages/min), scraped 88 items (at 2 items/min)
2026-05-29 22:35:43 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:35:44 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:bc0234c2c4602d61e6e168b4a3e7fdf4
2026-05-29 22:35:44 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-chevrolet-equinox-awd-lt-zVoHY3j1RSqXAeZakjTswQvdp
2026-05-29 22:36:07 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:36:07 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-147177' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:36:08 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:f9025a2b21a2815593696491518acbcc
2026-05-29 22:36:08 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-3500hd-denali-mSKjt212SSCVqcZyT4xMXgvdp
2026-05-29 22:36:26 [scrapy.extensions.logstats] INFO: Crawled 95 pages (at 3 pages/min), scraped 90 items (at 2 items/min)
2026-05-29 22:36:32 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:36:33 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-148774' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:36:33 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:3eb97ccde4a32608960c60a1846757c1
2026-05-29 22:36:33 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-3500hd-denali-8qP2swIQSmy2uKIlc2iTugvdp
2026-05-29 22:36:33 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:36:34 [loknow_spiders] INFO: Created: 3 items out of 3
2026-05-29 22:37:05 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:37:06 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-150224' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:37:06 [loknow_spiders] INFO: Cache: missed loknow_spiders:gpt-response-cache:e908d9545873716389e9eb775f339628
2026-05-29 22:37:16 [httpx] INFO: HTTP Request: POST https://api.openai.com/v1/chat/completions "HTTP/1.1 200 OK"
2026-05-29 22:37:16 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-sierra-3500hd-denali-3qlzt0krR9GQQksSURy0LAvdp
2026-05-29 22:37:26 [scrapy.extensions.logstats] INFO: Crawled 97 pages (at 2 pages/min), scraped 92 items (at 2 items/min)
2026-05-29 22:37:31 [StrathmoremotorsSpider] INFO: Closed Playwright page
2026-05-29 22:37:31 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-151708' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:37:32 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:a7c714c3973c4046e0af369608351a41
2026-05-29 22:37:32 [StrathmoremotorsSpider] INFO: Processed https://strathmoremotors.com/inventory/2026-gmc-terrain-awd-denali-sAlu74X9SxaRtWa66fmRcQvdp
2026-05-29 22:37:32 [scrapy.core.engine] INFO: Closing spider (finished)
2026-05-29 22:37:32 [root] INFO: Making post request to https://api.adretriever.com/api/import/e3d66172
2026-05-29 22:37:32 [loknow_spiders] INFO: Created: 2 items out of 2
2026-05-29 22:37:32 [loknow_spiders] INFO: GPT cache hit rate: 47.31182795698925%
2026-05-29 22:37:32 [scrapy.extensions.feedexport] INFO: Stored jsonlines feed (93 items) in: file:///home/scrapyd/items/loknow_spiders_prod/StrathmoremotorsSpider/631701885ba811f1bdc0c3bbae71ecef.jl
2026-05-29 22:37:32 [scrapy.statscollectors] INFO: Dumping Scrapy stats:
{'downloader/exception_count': 8,
'downloader/exception_type_count/playwright._impl._errors.TimeoutError': 8,
'downloader/request_bytes': 54096,
'downloader/request_count': 105,
'downloader/request_method_count/GET': 105,
'downloader/response_bytes': 132504025,
'downloader/response_count': 97,
'downloader/response_status_count/200': 97,
'dupefilter/filtered': 202,
'elapsed_time_seconds': 2826.115953,
'feedexport/success_count/FileFeedStorage': 1,
'finish_reason': 'finished',
'finish_time': datetime.datetime(2026, 5, 29, 22, 37, 32, 938431),
'httpcompression/response_bytes': 1440,
'httpcompression/response_count': 2,
'item_scraped_count': 93,
'log_count/ERROR': 134,
'log_count/INFO': 480,
'log_count/WARNING': 1,
'memusage/max': 267194368,
'memusage/startup': 125448192,
'playwright/context_count': 1,
'playwright/context_count/max_concurrent': 1,
'playwright/context_count/persistent/False': 1,
'playwright/context_count/remote/False': 1,
'playwright/page_count': 103,
'playwright/page_count/max_concurrent': 4,
'playwright/request_count': 26593,
'playwright/request_count/method/GET': 23059,
'playwright/request_count/method/POST': 3534,
'playwright/request_count/navigation': 1323,
'playwright/request_count/resource_type/document': 1323,
'playwright/request_count/resource_type/eventsource': 101,
'playwright/request_count/resource_type/fetch': 1994,
'playwright/request_count/resource_type/font': 1485,
'playwright/request_count/resource_type/image': 4076,
'playwright/request_count/resource_type/other': 196,
'playwright/request_count/resource_type/ping': 1051,
'playwright/request_count/resource_type/script': 8939,
'playwright/request_count/resource_type/stylesheet': 5058,
'playwright/request_count/resource_type/xhr': 2370,
'playwright/response_count': 26248,
'playwright/response_count/method/GET': 22830,
'playwright/response_count/method/POST': 3418,
'playwright/response_count/resource_type/document': 1241,
'playwright/response_count/resource_type/eventsource': 101,
'playwright/response_count/resource_type/fetch': 1879,
'playwright/response_count/resource_type/font': 1484,
'playwright/response_count/resource_type/image': 4069,
'playwright/response_count/resource_type/other': 194,
'playwright/response_count/resource_type/ping': 1050,
'playwright/response_count/resource_type/script': 8817,
'playwright/response_count/resource_type/stylesheet': 5049,
'playwright/response_count/resource_type/xhr': 2364,
'request_depth_max': 1,
'response_received_count': 97,
'robotstxt/request_count': 2,
'robotstxt/response_count': 2,
'robotstxt/response_status_count/200': 2,
'scheduler/dequeued': 103,
'scheduler/dequeued/memory': 103,
'scheduler/enqueued': 103,
'scheduler/enqueued/memory': 103,
'start_time': datetime.datetime(2026, 5, 29, 21, 50, 26, 822478)}
2026-05-29 22:37:32 [scrapy.core.engine] INFO: Spider closed (finished)
2026-05-29 22:37:32 [scrapy-playwright] INFO: Closing download handler
2026-05-29 22:37:32 [scrapy-playwright] INFO: Closing download handler
2026-05-29 22:37:32 [scrapy-playwright] INFO: Closing browser
2026-05-29 22:37:33 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-153474' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>
2026-05-29 22:37:33 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-153208' coro=<ScrapyPlaywrightDownloadHandler._get_response_and_download.<locals>._handle_response() running at /home/scrapyd/venv/lib/python3.10/site-packages/scrapy_playwright/handler.py:524> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[AsyncIOEventEmitter._emit_run.<locals>.callback() at /home/scrapyd/venv/lib/python3.10/site-packages/pyee/asyncio.py:97]>