2025-10-20 12:21:59 [scrapy.core.scraper] ERROR: Spider error processing <GET https://www.bca.co.uk/> (referer: None)
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/dist-packages/twisted/internet/defer.py", line 1251, in adapt
    extracted: _SelfResultT | Failure = result.result()
                                        ^^^^^^^^^^^^^^^
  File "/usr/src/app/crawlers/auth_check/bca_login_check.py", line 117, in on_login_page_load_success
    await page.fill(selectors["password_input"], self.password)
  File "/usr/local/lib/python3.11/dist-packages/playwright/async_api/_generated.py", line 9681, in fill
    await self._impl_obj.fill(
  File "/usr/local/lib/python3.11/dist-packages/playwright/_impl/_page.py", line 811, in fill
    return await self._main_frame.fill(**locals_to_params(locals()))
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/dist-packages/playwright/_impl/_frame.py", line 527, in fill
    await self._channel.send("fill", locals_to_params(locals()))
  File "/usr/local/lib/python3.11/dist-packages/playwright/_impl/_connection.py", line 59, in send
    return await self._connection.wrap_api_call(
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/dist-packages/playwright/_impl/_connection.py", line 514, in wrap_api_call
    raise rewrite_error(error, f"{parsed_st['apiName']}: {error}") from None
playwright._impl._errors.TimeoutError: Page.fill: Timeout 30000ms exceeded.
Call log:
waiting for locator("input[name=\"password\"]")
  -   locator resolved to <input required="" name="password" type="password" maxl…/>
  -   fill("klhl")
  - attempting fill action
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #1
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #2
  -   waiting 20ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #3
  -   waiting 100ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #4
  -   waiting 100ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #5
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #6
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #7
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #8
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #9
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #10
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #11
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #12
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #13
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #14
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #15
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #16
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #17
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #18
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #19
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #20
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #21
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #22
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #23
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #24
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #25
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #26
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #27
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #28
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #29
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #30
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #31
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #32
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #33
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #34
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #35
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #36
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #37
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #38
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #39
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #40
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #41
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #42
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #43
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #44
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #45
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #46
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #47
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #48
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #49
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #50
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #51
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #52
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #53
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #54
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #55
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #56
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #57
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #58
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #59
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #60
  -   waiting 500ms

2.11.2

127.0.0.1:6023

cb71b34af8d74450

2025-10-20 12:21:16 [scrapy.core.engine] DEBUG: Crawled (200) <GET https://www.bca.co.uk/> (referer: None) ['playwright']

2025-10-20 12:21:52 [scrapy.extensions.logstats] INFO: Crawled 1 pages (at 1 pages/min), scraped 0 items (at 0 items/min)

2025-10-20 12:20:51 [scrapy.utils.log] INFO: Scrapy 2.11.2 started (bot: SourcingV2)
2025-10-20 12:20:51 [scrapy.utils.log] INFO: Versions: lxml 5.2.2.0, libxml2 2.12.6, cssselect 1.2.0, parsel 1.9.1, w3lib 2.1.2, Twisted 24.3.0, Python 3.11.2 (main, Mar 13 2023, 12:18:29) [GCC 12.2.0], pyOpenSSL 24.1.0 (OpenSSL 3.2.1 30 Jan 2024), cryptography 42.0.7, Platform Linux-5.15.0-1092-azure-x86_64-with-glibc2.36
2025-10-20 12:20:51 [urllib3.connectionpool] DEBUG: Starting new HTTPS connection (1): api.alx.test-cluster.alx.tech:443
2025-10-20 12:20:51 [urllib3.connectionpool] DEBUG: https://api.alx.test-cluster.alx.tech:443 "POST /car-purchase/debug/supplier-credential-verification-task HTTP/1.1" 204 0
2025-10-20 12:20:51 [scrapy.addons] INFO: Enabled addons:
[]
2025-10-20 12:20:52 [asyncio] DEBUG: Using selector: EpollSelector
2025-10-20 12:20:52 [scrapy.utils.log] DEBUG: Using reactor: twisted.internet.asyncioreactor.AsyncioSelectorReactor
2025-10-20 12:20:52 [scrapy.utils.log] DEBUG: Using asyncio event loop: asyncio.unix_events._UnixSelectorEventLoop
2025-10-20 12:20:52 [scrapy.extensions.telnet] INFO: Telnet Password: cb71b34af8d74450
2025-10-20 12:20:52 [scrapy.middleware] INFO: Enabled extensions:
['scrapy.extensions.corestats.CoreStats',
 'scrapy.extensions.telnet.TelnetConsole',
 'scrapy.extensions.memusage.MemoryUsage',
 'scrapy.extensions.feedexport.FeedExporter',
 'scrapy.extensions.logstats.LogStats',
 'scrapy.extensions.closespider.CloseSpider']
2025-10-20 12:20:52 [scrapy.crawler] INFO: Overridden settings:
{'BOT_NAME': 'SourcingV2',
 'CLOSESPIDER_TIMEOUT': 7200,
 'FEED_EXPORT_ENCODING': 'utf-8',
 'LOG_FILE': '/var/log/scrapyd/logs/sourcing_v2/bca_login_check.gb/3701b888adaf11f0ad3f16f441cc2453.log',
 'LOG_FORMATTER': 'crawlers.log_formatter.SourcingLogFormatter',
 'NEWSPIDER_MODULE': 'spiders',
 'REQUEST_FINGERPRINTER_CLASS': 'scrapy_zyte_api.ScrapyZyteAPIRequestFingerprinter',
 'REQUEST_FINGERPRINTER_IMPLEMENTATION': '2.7',
 'SPIDER_MODULES': ['spiders', 'auth_check'],
 'TWISTED_REACTOR': 'twisted.internet.asyncioreactor.AsyncioSelectorReactor',
 'USER_AGENT': ''}
2025-10-20 12:20:52 [scrapy_zyte_api.handler] INFO: Using a Zyte API key starting with '5857011'
2025-10-20 12:20:52 [scrapy_zyte_api.handler] INFO: Using a Zyte API key starting with '5857011'
2025-10-20 12:20:52 [scrapy.middleware] INFO: Enabled downloader middlewares:
['scrapy.downloadermiddlewares.offsite.OffsiteMiddleware',
 'scrapy.downloadermiddlewares.httpauth.HttpAuthMiddleware',
 'scrapy.downloadermiddlewares.downloadtimeout.DownloadTimeoutMiddleware',
 'scrapy.downloadermiddlewares.defaultheaders.DefaultHeadersMiddleware',
 'scrapy.downloadermiddlewares.useragent.UserAgentMiddleware',
 'scrapy_zyte_api.ScrapyZyteAPIDownloaderMiddleware',
 'scrapy.downloadermiddlewares.retry.RetryMiddleware',
 'scrapy.downloadermiddlewares.redirect.MetaRefreshMiddleware',
 'scrapy.downloadermiddlewares.httpcompression.HttpCompressionMiddleware',
 'scrapy.downloadermiddlewares.redirect.RedirectMiddleware',
 'scrapy.downloadermiddlewares.cookies.CookiesMiddleware',
 'scrapy.downloadermiddlewares.httpproxy.HttpProxyMiddleware',
 'scrapy.downloadermiddlewares.stats.DownloaderStats']
2025-10-20 12:20:52 [scrapy.middleware] INFO: Enabled spider middlewares:
['scrapy.spidermiddlewares.httperror.HttpErrorMiddleware',
 'scrapy.spidermiddlewares.referer.RefererMiddleware',
 'scrapy.spidermiddlewares.urllength.UrlLengthMiddleware',
 'scrapy.spidermiddlewares.depth.DepthMiddleware']
2025-10-20 12:20:52 [scrapy.middleware] INFO: Enabled item pipelines:
[]
2025-10-20 12:20:52 [scrapy.core.engine] INFO: Spider opened
2025-10-20 12:20:52 [scrapy.extensions.logstats] INFO: Crawled 0 pages (at 0 pages/min), scraped 0 items (at 0 items/min)
2025-10-20 12:20:52 [scrapy.extensions.telnet] INFO: Telnet console listening on 127.0.0.1:6023
2025-10-20 12:20:52 [scrapy-playwright] INFO: Starting download handler

2025-10-20 12:20:52 [scrapy-playwright] INFO: Starting download handler
2025-10-20 12:21:12 [scrapy-playwright] INFO: Launching browser firefox
2025-10-20 12:21:13 [scrapy-playwright] INFO: Browser firefox launched
2025-10-20 12:21:13 [scrapy-playwright] DEBUG: Browser context started: 'default' (persistent=False, remote=False)
2025-10-20 12:21:13 [scrapy-playwright] DEBUG: [Context=default] New page created, page count is 1 (1 for all contexts)
2025-10-20 12:21:14 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/> (resource type: document)
2025-10-20 12:21:14 [scrapy-playwright] DEBUG: [Context=default] Response: <407 https://www.bca.co.uk/>
2025-10-20 12:21:14 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/> (resource type: document)
2025-10-20 12:21:14 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/>
2025-10-20 12:21:14 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/dynatrace/ruxitagentjs_ICA7NVfghqrux_10323250822043923.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/docroot/fonts/roboto/roboto.css> (resource type: stylesheet, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/css/website~2025-10-08-11-19-16-000~cache.css> (resource type: stylesheet, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/scripts/bluebird.min~2025-10-08-11-19-16-000~cache.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/scripts/jquery.min~2025-10-08-11-19-16-000~cache.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/scripts/iframeResizer.min~2025-10-08-11-19-16-000~cache.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/scripts/picturefill.min~2025-10-08-11-19-16-000~cache.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/website.bundle~2025-10-08-11-19-16-000~cache.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/scripts/fonts/mtiFontTrackingCode.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://assets.adobedtm.com/e28f204a9afaa64d3abd53e8425bd74c231b805a/satelliteLib-890bc7386f06c2b4e9f438bfbfdf700fe4efb037.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/dynatrace/ruxitagentjs_ICA7NVfghqrux_10323250822043923.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/css/website~2025-10-08-11-19-16-000~cache.css>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://fonts.googleapis.com/css2?family=Montserrat:ital,wght@0,100..900;1,100..900&display=swap> (resource type: stylesheet, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/scripts/bluebird.min~2025-10-08-11-19-16-000~cache.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/docroot/fonts/roboto/roboto.css>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/scripts/jquery.min~2025-10-08-11-19-16-000~cache.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/scripts/iframeResizer.min~2025-10-08-11-19-16-000~cache.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/scripts/picturefill.min~2025-10-08-11-19-16-000~cache.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/website.bundle~2025-10-08-11-19-16-000~cache.js>
2025-10-20 12:21:15 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/scripts/fonts/mtiFontTrackingCode.js>
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://fonts.googleapis.com/css2?family=Montserrat:ital,wght@0,100..900;1,100..900&display=swap>
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://fast.fonts.net/t/1.css?apiType=css&projectid=3699a6d6-1bf0-4c36-8ac5-9492541c3a69> (resource type: stylesheet, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://assets.adobedtm.com/e28f204a9afaa64d3abd53e8425bd74c231b805a/satelliteLib-890bc7386f06c2b4e9f438bfbfdf700fe4efb037.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://fast.fonts.net/t/1.css?apiType=css&projectid=3699a6d6-1bf0-4c36-8ac5-9492541c3a69>
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/0.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/497.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/507.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/472.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/495.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/500.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [scrapy-playwright] DEBUG: [Context=default] Request: <GET https://www.bca.co.uk/.resources/bca-frontend/chunks/496.d6b92b613a5f4e5bc1da.chunk.js> (resource type: script, referrer: https://www.bca.co.uk/)
2025-10-20 12:21:16 [urllib3.connectionpool] DEBUG: Starting new HTTPS connection (1): publicsuffix.org:443
2025-10-20 12:21:16 [urllib3.connectionpool] DEBUG: https://publicsuffix.org:443 "GET /list/public_suffix_list.dat HTTP/1.1" 200 85478
2025-10-20 12:21:16 [scrapy.core.engine] DEBUG: Crawled (200) <GET https://www.bca.co.uk/> (referer: None) ['playwright']
2025-10-20 12:21:17 [scrapy-playwright] DEBUG: [Context=default] Response: <200 https://www.bca.co.uk/.resources/bca-frontend/chunks/0.d6b92b613a5f4e5bc1da.chunk.js>

  -   element is not visible
  - retrying fill action, attempt #25
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #26
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #27
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #28
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #29
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #30
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #31
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #32
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #33
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #34
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #35
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #36
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #37
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #38
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #39
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #40
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #41
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #42
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #43
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #44
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #45
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #46
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #47
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #48
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #49
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #50
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #51
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #52
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #53
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #54
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #55
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #56
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #57
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #58
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #59
  -   waiting 500ms
  -   waiting for element to be visible, enabled and editable
  -   element is not visible
  - retrying fill action, attempt #60
  -   waiting 500ms

2025-10-20 12:21:59 [scrapy.core.engine] INFO: Closing spider (finished)
2025-10-20 12:21:59 [scrapy.extensions.feedexport] INFO: Stored jsonlines feed (0 items) in: file:///var/lib/scrapyd/items/sourcing_v2/bca_login_check.gb/3701b888adaf11f0ad3f16f441cc2453.jl
2025-10-20 12:21:59 [scrapy.statscollectors] INFO: Dumping Scrapy stats:
{'downloader/request_bytes': 169,
 'downloader/request_count': 1,
 'downloader/request_method_count/GET': 1,
 'downloader/response_bytes': 36006,
 'downloader/response_count': 1,
 'downloader/response_status_count/200': 1,
 'elapsed_time_seconds': 67.311536,
 'feedexport/success_count/FileFeedStorage': 1,
 'finish_reason': 'finished',
 'finish_time': datetime.datetime(2025, 10, 20, 12, 21, 59, 505105, tzinfo=datetime.timezone.utc),
 'log_count/DEBUG': 203,
 'log_count/ERROR': 1,
 'log_count/INFO': 19,
 'memusage/max': 129368064,
 'memusage/startup': 111296512,
 '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': 1,
 'playwright/page_count/max_concurrent': 1,
 'playwright/request_count': 98,
 'playwright/request_count/aborted': 20,
 'playwright/request_count/method/GET': 94,
 'playwright/request_count/method/POST': 4,
 'playwright/request_count/navigation': 6,
 'playwright/request_count/resource_type/document': 6,
 'playwright/request_count/resource_type/fetch': 8,
 'playwright/request_count/resource_type/font': 6,
 'playwright/request_count/resource_type/image': 20,
 'playwright/request_count/resource_type/images': 2,
 'playwright/request_count/resource_type/other': 1,
 'playwright/request_count/resource_type/script': 43,
 'playwright/request_count/resource_type/stylesheet': 8,
 'playwright/request_count/resource_type/xhr': 4,
 'playwright/response_count': 74,
 'playwright/response_count/method/GET': 70,
 'playwright/response_count/method/POST': 4,
 'playwright/response_count/resource_type/document': 6,
 'playwright/response_count/resource_type/fetch': 8,
 'playwright/response_count/resource_type/font': 6,
 'playwright/response_count/resource_type/images': 2,
 'playwright/response_count/resource_type/other': 1,
 'playwright/response_count/resource_type/script': 39,
 'playwright/response_count/resource_type/stylesheet': 8,
 'playwright/response_count/resource_type/xhr': 4,
 'response_received_count': 1,
 'scheduler/dequeued': 1,
 'scheduler/dequeued/memory': 1,
 'scheduler/enqueued': 1,
 'scheduler/enqueued/memory': 1,
 'spider_exceptions/TimeoutError': 1,
 'start_time': datetime.datetime(2025, 10, 20, 12, 20, 52, 193569, tzinfo=datetime.timezone.utc)}
2025-10-20 12:21:59 [scrapy.core.engine] INFO: Spider closed (finished)

/1/log/utf8/sourcing_v2/bca_login_check.gb/3701b888adaf11f0ad3f16f441cc2453/?job_finished=True

http://scrapyd-1:6800/logs/sourcing_v2/bca_login_check.gb/3701b888adaf11f0ad3f16f441cc2453.log