2026-05-29 22:07:39 [scrapy.utils.log] INFO: Scrapy 2.10.0 started (bot: retriever)
2026-05-29 22:07:39 [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 22:07:39 [scrapy.addons] INFO: Enabled addons:
[]
2026-05-29 22:07:39 [scrapy.crawler] INFO: Overridden settings:
{'AUTOTHROTTLE_ENABLED': True,
'BOT_NAME': 'retriever',
'CONCURRENT_REQUESTS_PER_DOMAIN': 3,
'DOWNLOAD_DELAY': 2.5,
'HTTPCACHE_EXPIRATION_SECS': 86400,
'IMAGES_STORE_S3_ACL': 'public-read',
'LOG_FILE': '/home/scrapyd/logs/loknow_spiders_prod/WsnorthSpider/c21c36805baa11f1bdc0c3bbae71ecef.log',
'LOG_FORMATTER': 'loknow_spiders.logging.PoliteLogFormatter',
'LOG_LEVEL': 'INFO',
'NEWSPIDER_MODULE': 'loknow_spiders.spiders',
'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 22:07:39 [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 22:07:40 [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 22:07:40 [WsnorthSpider] INFO: Current Spider Environment set to: prod
2026-05-29 22:07:40 [WsnorthSpider] INFO: Retrieving AWS secret: spider_prod_secrets
2026-05-29 22:07:40 [botocore.credentials] INFO: Found credentials in shared credentials file: ~/.aws/credentials
2026-05-29 22:07:41 [WsnorthSpider] INFO: In base spider ml_kwargs is None and scrape_type is inventory
2026-05-29 22:07:41 [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 22:07:41 [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 22:07:45 [scrapy.middleware] INFO: Enabled item pipelines:
['loknow_spiders.pipelines.HTMLDetailsDecruftPipeline',
'loknow_spiders.pipelines.GPTVehiclePipeline',
'loknow_spiders.pipelines.PostProcessGPTVehiclePipeline',
'loknow_spiders.pipelines.WsnorthPipeline',
'loknow_spiders.pipelines.RetrieverPipeline']
2026-05-29 22:07:45 [scrapy.core.engine] INFO: Spider opened
2026-05-29 22:07:45 [WsnorthSpider] INFO: RetrieverAPI is making requests to https://api.adretriever.com with headers {'Authorization': 'Token 96dfb640234f1d676d6fa726f8eae6e7aab44cda'}
2026-05-29 22:07:45 [loknow_spiders] INFO: Scrape 5eff230b has been opened.
2026-05-29 22:07:45 [scrapy.extensions.logstats] INFO: Crawled 0 pages (at 0 pages/min), scraped 0 items (at 0 items/min)
2026-05-29 22:07:45 [scrapy-playwright] INFO: Starting download handler
2026-05-29 22:07:45 [scrapy-playwright] INFO: Starting download handler
2026-05-29 22:07:55 [scrapy-playwright] INFO: Launching browser chromium
2026-05-29 22:07:55 [scrapy-playwright] INFO: Browser chromium launched
2026-05-29 22:08:39 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:08:41 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:08:45 [scrapy.extensions.logstats] INFO: Crawled 4 pages (at 4 pages/min), scraped 0 items (at 0 items/min)
2026-05-29 22:08:46 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:08:59 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:08:59 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:f2b3ecf7487d1edbbca743c651f0cc8c
2026-05-29 22:08:59 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2013-KENWORTH-T800-21862
2026-05-29 22:08:59 [root] INFO: Making post request to https://api.adretriever.com/api/import/5eff230b
2026-05-29 22:08:59 [loknow_spiders] INFO: Created: 1 items out of 1
2026-05-29 22:09:14 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-2714' 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:09:14 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-2772' 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:09:14 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-2995' 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:09:14 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-4043' 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:09:19 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:09:19 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:603d728f05498dd375873920cc8f14df
2026-05-29 22:09:19 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2022-Western%20Star-49X-21852
2026-05-29 22:09:45 [scrapy.extensions.logstats] INFO: Crawled 6 pages (at 2 pages/min), scraped 2 items (at 2 items/min)
2026-05-29 22:09:47 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-4805' 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:09:52 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:09:52 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:2683a574caee8ab70c17b2b6a6da5163
2026-05-29 22:09:52 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2022-Western%20Star-49X-21884
2026-05-29 22:10:08 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-5608' 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:10:11 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:10:11 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:7e1f208901179564a95011951f671224
2026-05-29 22:10:11 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2026-Western%20Star-49X-21682
2026-05-29 22:10:11 [root] INFO: Making post request to https://api.adretriever.com/api/import/5eff230b
2026-05-29 22:10:12 [loknow_spiders] INFO: Created: 3 items out of 3
2026-05-29 22:10:25 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-6383' 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:10:30 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:10:30 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:d5dc3996c44ec7f836f0c29184ca5256
2026-05-29 22:10:30 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2026-Western%20Star-49X-21686
2026-05-29 22:10:34 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:10:34 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:f957b2a077144aa31b750246a1c54718
2026-05-29 22:10:34 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2026-Western%20Star-47X-21704
2026-05-29 22:10:43 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-7727' 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:10:43 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-7890' 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:10:45 [scrapy.extensions.logstats] INFO: Crawled 10 pages (at 4 pages/min), scraped 6 items (at 4 items/min)
2026-05-29 22:10:56 [WsnorthSpider] INFO: Closed Playwright page
2026-05-29 22:10:56 [loknow_spiders] INFO: Cache: hit loknow_spiders:gpt-response-cache:e34b0514c4828b48482895cb9c7c095c
2026-05-29 22:10:56 [WsnorthSpider] INFO: Processed https://www.wsnorth.com/inventory/?truck=2026-Western%20Star-47X-21711
2026-05-29 22:10:56 [scrapy.core.engine] INFO: Closing spider (finished)
2026-05-29 22:10:57 [root] INFO: Making post request to https://api.adretriever.com/api/import/5eff230b
2026-05-29 22:10:57 [loknow_spiders] INFO: Created: 3 items out of 3
2026-05-29 22:10:57 [loknow_spiders] INFO: GPT cache hit rate: 100.0%
2026-05-29 22:10:57 [scrapy.extensions.feedexport] INFO: Stored jsonlines feed (7 items) in: file:///home/scrapyd/items/loknow_spiders_prod/WsnorthSpider/c21c36805baa11f1bdc0c3bbae71ecef.jl
2026-05-29 22:10:57 [scrapy.statscollectors] INFO: Dumping Scrapy stats:
{'downloader/request_bytes': 3215,
'downloader/request_count': 11,
'downloader/request_method_count/GET': 11,
'downloader/response_bytes': 1526958,
'downloader/response_count': 11,
'downloader/response_status_count/200': 11,
'elapsed_time_seconds': 192.039415,
'feedexport/success_count/FileFeedStorage': 1,
'finish_reason': 'finished',
'finish_time': datetime.datetime(2026, 5, 29, 22, 10, 57, 854524),
'item_scraped_count': 7,
'log_count/ERROR': 9,
'log_count/INFO': 53,
'log_count/WARNING': 1,
'memusage/max': 174678016,
'memusage/startup': 125263872,
'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': 10,
'playwright/page_count/max_concurrent': 3,
'playwright/request_count': 1468,
'playwright/request_count/method/GET': 1352,
'playwright/request_count/method/POST': 116,
'playwright/request_count/navigation': 53,
'playwright/request_count/resource_type/document': 53,
'playwright/request_count/resource_type/fetch': 109,
'playwright/request_count/resource_type/font': 136,
'playwright/request_count/resource_type/image': 232,
'playwright/request_count/resource_type/other': 10,
'playwright/request_count/resource_type/ping': 1,
'playwright/request_count/resource_type/script': 537,
'playwright/request_count/resource_type/stylesheet': 360,
'playwright/request_count/resource_type/xhr': 30,
'playwright/response_count': 1435,
'playwright/response_count/method/GET': 1350,
'playwright/response_count/method/POST': 85,
'playwright/response_count/resource_type/document': 53,
'playwright/response_count/resource_type/fetch': 79,
'playwright/response_count/resource_type/font': 136,
'playwright/response_count/resource_type/image': 231,
'playwright/response_count/resource_type/other': 10,
'playwright/response_count/resource_type/ping': 1,
'playwright/response_count/resource_type/script': 536,
'playwright/response_count/resource_type/stylesheet': 360,
'playwright/response_count/resource_type/xhr': 29,
'request_depth_max': 1,
'response_received_count': 11,
'robotstxt/request_count': 1,
'robotstxt/response_count': 1,
'robotstxt/response_status_count/200': 1,
'scheduler/dequeued': 10,
'scheduler/dequeued/memory': 10,
'scheduler/enqueued': 10,
'scheduler/enqueued/memory': 10,
'start_time': datetime.datetime(2026, 5, 29, 22, 7, 45, 815109)}
2026-05-29 22:10:57 [scrapy.core.engine] INFO: Spider closed (finished)
2026-05-29 22:10:57 [scrapy-playwright] INFO: Closing download handler
2026-05-29 22:10:57 [scrapy-playwright] INFO: Closing download handler
2026-05-29 22:10:57 [scrapy-playwright] INFO: Closing browser
2026-05-29 22:10:58 [asyncio] ERROR: Task was destroyed but it is pending!
task: <Task pending name='Task-8700' 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]>