root@autopkgtest-lxc-woqzfz:/tmp/autopkgtest-lxc.zhhnykjt/downtmp/build.3R1/src# su -c "DEBUG='puppeteer:*' sh -xe debian/tests/pkg-js/test -R tap -g 'default arguments'" debci + set -e + export HOME=/tmp/home + mkdir -p /tmp/home + export NODE_PRESERVE_SYMLINKS=1 + [ ! -e node_modules/.package-lock.json ] + rm -rf package-lock.json node_modules + mkdir node_modules + ln -s /usr/share/nodejs/puppeteer/node_modules/devtools-protocol node_modules/ + PUPPETEER_SKIP_DOWNLOAD=1 NPM_CONFIG_CACHE=/tmp/npm_cache npm install > puppeteer@13.1.0 install > node install.js Puppeteer: Compiling TypeScript... **INFO** Skipping browser download. "PUPPETEER_SKIP_DOWNLOAD" environment variable was found. > puppeteer@13.1.0 prepare > node typescript-if-required.js && husky install added 854 packages, and audited 857 packages in 2m 111 packages are looking for funding run `npm fund` for details 11 vulnerabilities (10 moderate, 1 high) To address issues that do not require attention, run: npm audit fix To address all issues (including breaking changes), run: npm audit fix --force Run `npm audit` for details. + ts-node-script scripts/ensure-correct-devtools-protocol-package Checking npm for devtools-protocol revisions: 'npm view "devtools-protocol@<=0.0.950341" version | tail -1' Correct devtools-protocol version found (0.0.948846). + export BINARY=/usr/bin/chromium + export PUPPETEER_ALT_INSTALL=1 + export DUMPIO=1 + xvfb-run mocha --config mocha-config/puppeteer-unit-tests.js -R tap -g default arguments WARN: running Chromium tests with /usr/bin/chromium Running unit tests with: -> product: Chromium -> binary: /usr/bin/chromium ok 1 Launcher specs Puppeteer Puppeteer.launch should return the default arguments puppeteer:launcher Calling /usr/bin/chromium --remote-debugging-port=0 --user-data-dir=/tmp/puppeteer_dev_chrome_profile-FRqsMU +0ms DevTools listening on ws://127.0.0.1:35953/devtools/browser/a2abf6f6-e2ab-48ee-be69-c70ed9c7b9cc [25106:25175:0303/174754.913434:ERROR:object_proxy.cc(623)] Failed to call method: org.freedesktop.DBus.Properties.Get: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files [25106:25175:0303/174754.913657:ERROR:object_proxy.cc(623)] Failed to call method: org.freedesktop.UPower.GetDisplayDevice: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files [25106:25175:0303/174754.913858:ERROR:object_proxy.cc(623)] Failed to call method: org.freedesktop.UPower.EnumerateDevices: object_path= /org/freedesktop/UPower: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files puppeteer:protocol:SEND ► {"method":"Target.setDiscoverTargets","params":{"discover":true},"id":1} +0ms puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"A6340AA131D64D2D5082F45D2E579F17","type":"background_page","title":"CryptoTokenExtension","url":"chrome-extension://kmendfapggjehodndflmmgagdbamhnfd/_generated_background_page.html","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +0ms puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"e6356e1c-9f66-46e0-b760-5fc15b70432f","type":"browser","title":"","url":"","attached":true,"canAccessOpener":false}}} +1ms puppeteer:protocol:RECV ◀ {"id":1,"result":{}} +1ms puppeteer:protocol:RECV ◀ {"method":"Target.targetInfoChanged","params":{"targetInfo":{"targetId":"A6340AA131D64D2D5082F45D2E579F17","type":"background_page","title":"CryptoTokenExtension","url":"chrome-extension://kmendfapggjehodndflmmgagdbamhnfd/_generated_background_page.html","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +9ms [25150:25150:0303/174755.064116:ERROR:sandbox_linux.cc(377)] InitializeSandbox() called with multiple threads in process gpu-process. not ok 2 Launcher specs Puppeteer Puppeteer.launch should work with no default arguments Timeout of 25000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/tmp/autopkgtest-lxc.zhhnykjt/downtmp/build.3R1/src/test/launcher.spec.ts) Error: Timeout of 25000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/tmp/autopkgtest-lxc.zhhnykjt/downtmp/build.3R1/src/test/launcher.spec.ts) at createTimeoutError (/usr/share/nodejs/mocha/lib/errors.js:498:15) at Test.Runnable._timeoutError (/usr/share/nodejs/mocha/lib/runnable.js:431:10) at Timeout. (/usr/share/nodejs/mocha/lib/runnable.js:246:24) at listOnTimeout (internal/timers.js:554:17) at processTimers (internal/timers.js:497:7) puppeteer:launcher Calling /usr/bin/chromium --enable-features=NetworkService,NetworkServiceInProcess --disable-backgrounding-occluded-windows --disable-breakpad --disable-client-side-phishing-detection --disable-component-extensions-with-background-pages --disable-default-apps --disable-dev-shm-usage --disable-extensions --disable-features=Translate --disable-hang-monitor --disable-ipc-flooding-protection --disable-popup-blocking --disable-prompt-on-repost --disable-renderer-backgrounding --disable-sync --force-color-profile=srgb --metrics-recording-only --no-first-run --enable-automation --password-store=basic --use-mock-keychain --enable-blink-features=IdleDetection --export-tagged-pdf --headless --hide-scrollbars --mute-audio about:blank --remote-debugging-port=0 --user-data-dir=/tmp/puppeteer_dev_chrome_profile-pmxSFt +25s DevTools listening on ws://127.0.0.1:42083/devtools/browser/f2219092-1bbe-41f4-988a-253b0d635653 [0303/174819.747386:WARNING:bluez_dbus_manager.cc(248)] Floss manager not present, cannot set Floss enable/disable. puppeteer:protocol:SEND ► {"method":"Target.setDiscoverTargets","params":{"discover":true},"id":1} +25s puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"B6BF32AD71E822B6D2A9AFE6B5B11D5D","type":"page","title":"","url":"about:blank","attached":false,"canAccessOpener":false,"browserContextId":"8EF8DA380E3AB42D8A403B2234C9B394"}}} +25s puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"addfbf34-1a3d-4d52-b0a9-a155ea1b8d2d","type":"browser","title":"","url":"","attached":false,"canAccessOpener":false}}} +0ms puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"e098ffa5-3cf7-4065-8e99-7007cea01415","type":"browser","title":"","url":"","attached":true,"canAccessOpener":false}}} +0ms puppeteer:protocol:RECV ◀ {"id":1,"result":{}} +0ms [0100/000000.780634:ERROR:zygote_linux.cc(607)] Zygote could not fork: process_type gpu-process numfds 3 child_pid -1 [0100/000000.780844:ERROR:zygote_linux.cc(639)] write: Broken pipe (32) ok 3 Launcher specs Puppeteer Puppeteer.launch should filter out ignored default arguments # tests 3 # pass 2 # fail 1 1..3 [25106:25140:0303/174819.859766:ERROR:object_proxy.cc(623)] Failed to call method: org.freedesktop.DBus.Properties.Get: object_path= /org/freedesktop/portal/desktop: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken. [25106:25140:0303/174819.859786:ERROR:select_file_dialog_impl_portal.cc(243)] Failed to read portal version property puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"C910B6399CC4131301B7C1765FF07675","type":"page","title":"New Tab","url":"chrome://newtab/","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +222ms puppeteer:protocol:SEND ► {"method":"Target.createTarget","params":{"url":"about:blank"},"id":2} +225ms puppeteer:protocol:RECV ◀ {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","type":"page","title":"","url":"about:blank","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +28ms puppeteer:protocol:RECV ◀ {"id":2,"result":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918"}} +5ms puppeteer:protocol:SEND ► {"method":"Target.attachToTarget","params":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","flatten":true},"id":3} +32ms puppeteer:protocol:RECV ◀ {"method":"Target.targetInfoChanged","params":{"targetInfo":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","type":"page","title":"","url":"about:blank","attached":true,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +6ms puppeteer:protocol:RECV ◀ {"method":"Target.attachedToTarget","params":{"sessionId":"F28F307DC1626D7064529D85C3992C2A","targetInfo":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","type":"page","title":"","url":"about:blank","attached":true,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"},"waitingForDebugger":false}} +0ms puppeteer:protocol:RECV ◀ {"id":3,"result":{"sessionId":"F28F307DC1626D7064529D85C3992C2A"}} +0ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Page.enable","id":4} +9ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Page.getFrameTree","id":5} +1ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Target.setAutoAttach","params":{"autoAttach":true,"waitForDebuggerOnStart":false,"flatten":true},"id":6} +0ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Performance.enable","id":7} +1ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Log.enable","id":8} +0ms puppeteer:protocol:RECV ◀ {"method":"Target.targetInfoChanged","params":{"targetInfo":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","type":"page","title":"about:blank","url":"about:blank","attached":true,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +6ms [25285:8:0303/174820.043750:ERROR:command_buffer_proxy_impl.cc(125)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer. puppeteer:protocol:RECV ◀ {"method":"Target.targetInfoChanged","params":{"targetInfo":{"targetId":"C910B6399CC4131301B7C1765FF07675","type":"page","title":"New Tab","url":"chrome://newtab/","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +26ms puppeteer:protocol:RECV ◀ {"id":4,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +2ms puppeteer:protocol:RECV ◀ {"id":5,"result":{"frameTree":{"frame":{"id":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","url":"about:blank","domainAndRegistry":"","securityOrigin":"://","mimeType":"text/html","adFrameStatus":{"adFrameType":"none"},"secureContextType":"InsecureScheme","crossOriginIsolatedContextType":"NotIsolated","gatedAPIFeatures":[]}}},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:RECV ◀ {"id":6,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:RECV ◀ {"id":7,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:RECV ◀ {"id":8,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Page.setLifecycleEventsEnabled","params":{"enabled":true},"id":9} +34ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Runtime.enable","id":10} +0ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Network.enable","id":11} +0ms puppeteer:protocol:RECV ◀ {"method":"Page.lifecycleEvent","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","name":"commit","timestamp":184260.101794},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +13ms puppeteer:protocol:RECV ◀ {"method":"Page.lifecycleEvent","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","name":"DOMContentLoaded","timestamp":184260.101947},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:RECV ◀ {"method":"Page.lifecycleEvent","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","name":"load","timestamp":184260.105978},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +2ms puppeteer:protocol:RECV ◀ {"method":"Page.lifecycleEvent","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","name":"networkAlmostIdle","timestamp":184260.106426},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:RECV ◀ {"method":"Page.lifecycleEvent","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","loaderId":"3B1E4E0E5F7C12FF511725EB670F8B75","name":"networkIdle","timestamp":184260.106426},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:RECV ◀ {"id":9,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:RECV ◀ {"method":"Runtime.executionContextCreated","params":{"context":{"id":1,"origin":"://","name":"","uniqueId":"395991921197761444.8021892716662502400","auxData":{"isDefault":true,"type":"default","frameId":"B2BDC9A70443A8295DF0A31306CBA918"}}},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:RECV ◀ {"id":10,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +1ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Page.addScriptToEvaluateOnNewDocument","params":{"source":"//# sourceURL=__puppeteer_evaluation_script__","worldName":"__puppeteer_utility_world__"},"id":12} +16ms puppeteer:protocol:RECV ◀ {"id":11,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:RECV ◀ {"id":12,"result":{"identifier":"1"},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +2ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Page.createIsolatedWorld","params":{"frameId":"B2BDC9A70443A8295DF0A31306CBA918","worldName":"__puppeteer_utility_world__","grantUniveralAccess":true},"id":13} +3ms puppeteer:protocol:RECV ◀ {"method":"Runtime.executionContextCreated","params":{"context":{"id":2,"origin":"","name":"__puppeteer_utility_world__","uniqueId":"-4221555154240540282.5463594881642292315","auxData":{"isDefault":false,"type":"isolated","frameId":"B2BDC9A70443A8295DF0A31306CBA918"}}},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +15ms puppeteer:protocol:RECV ◀ {"id":13,"result":{"executionContextId":2},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Emulation.setDeviceMetricsOverride","params":{"mobile":false,"width":800,"height":600,"deviceScaleFactor":1,"screenOrientation":{"angle":0,"type":"portraitPrimary"}},"id":14} +15ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Emulation.setTouchEmulationEnabled","params":{"enabled":false},"id":15} +0ms puppeteer:protocol:RECV ◀ {"method":"Page.frameResized","params":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +4ms puppeteer:protocol:RECV ◀ {"id":14,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +6ms puppeteer:protocol:RECV ◀ {"id":15,"result":{},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +0ms puppeteer:protocol:SEND ► {"sessionId":"F28F307DC1626D7064529D85C3992C2A","method":"Runtime.evaluate","params":{"expression":"11 * 11\n//# sourceURL=__puppeteer_evaluation_script__","contextId":1,"returnByValue":true,"awaitPromise":true,"userGesture":true},"id":16} +10ms puppeteer:protocol:RECV ◀ {"id":16,"result":{"result":{"type":"number","value":121,"description":"121"}},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +5ms puppeteer:protocol:SEND ► {"method":"Target.closeTarget","params":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918"},"id":17} +6ms puppeteer:protocol:RECV ◀ {"id":17,"result":{"success":true}} +3ms [25151:25195:0303/174820.111749:ERROR:node_controller.cc(585)] Trying to re-add dropped peer 6F132559754FF56F.22AAB8054A9AD352 [25150:25229:0303/174820.112177:ERROR:node_controller.cc(585)] Trying to re-add dropped peer 6F132559754FF56F.22AAB8054A9AD352 puppeteer:protocol:RECV ◀ {"method":"Inspector.detached","params":{"reason":"Render process gone."},"sessionId":"F28F307DC1626D7064529D85C3992C2A"} +7ms puppeteer:protocol:RECV ◀ {"method":"Target.targetInfoChanged","params":{"targetInfo":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918","type":"other","title":"about:blank","url":"about:blank","attached":false,"canAccessOpener":false,"browserContextId":"831B9AAD2EEC005C8EEB0CDE5D32F209"}}} +4ms puppeteer:protocol:RECV ◀ {"method":"Target.detachedFromTarget","params":{"sessionId":"F28F307DC1626D7064529D85C3992C2A","targetId":"B2BDC9A70443A8295DF0A31306CBA918"}} +0ms puppeteer:protocol:RECV ◀ {"method":"Target.targetDestroyed","params":{"targetId":"B2BDC9A70443A8295DF0A31306CBA918"}} +1ms root@autopkgtest-lxc-woqzfz:/tmp/autopkgtest-lxc.zhhnykjt/downtmp/build.3R1/src# echo $? 1 root@autopkgtest-lxc-woqzfz:/tmp/autopkgtest-lxc.zhhnykjt/downtmp/build.3R1/src#