Back to build
Raw log | Switch to full mode | Login

{ "shards": [ { "bot_dimensions": [ { "key": "caches", "value": [ "swarming_module_cache_vpython" ] }, { "key": "cores", "value": [ "8" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-Broadwell_GCE", "x86-64-avx2" ] }, { "key": "gce", "value": [ "1" ] }, { "key": "gpu", "value": [ "none" ] }, { "key": "id", "value": [ "chrome-trusty-us-central1-c-74-o82z" ] }, { "key": "image", "value": [ "chrome-trusty-19041600-95c8804d634" ] }, { "key": "inside_docker", "value": [ "0" ] }, { "key": "kvm", "value": [ "1" ] }, { "key": "machine_type", "value": [ "n1-standard-8" ] }, { "key": "os", "value": [ "Linux", "Ubuntu", "Ubuntu-14.04" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.6" ] }, { "key": "server_version", "value": [ "4267-53b17d5" ] }, { "key": "zone", "value": [ "us", "us-central", "us-central1", "us-central1-c" ] } ], "bot_id": "chrome-trusty-us-central1-c-74-o82z", "bot_version": "bd68e04e43fc4d1b7b5b65890a29c5d5bae55ab1423851733f92abd7e13dda14", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-amd64", "version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC" }, "packages": [ { "package_name": "infra/python/cpython/linux-amd64", "path": ".swarming_module", "version": "46c0c897ca0f053799ee41fd148bb7a47232df47" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": ".swarming_module", "version": "2737ea8ed9b958f4d5aa9ffe106115a649ada241" }, { "package_name": "infra/tools/luci/vpython-native/linux-amd64", "path": ".swarming_module", "version": "tfTmX4aGCfFtfQucj_nEfwiNisK0RmpKrITkZMBBq3cC" }, { "package_name": "infra/tools/luci/vpython/linux-amd64", "path": ".swarming_module", "version": "bmnYyAg9mbM_538s1dIP1hZZmOtc8C7ssF41ztN2cr0C" } ] }, "completed_ts": "2019-04-21T07:36:46.758697", "costs_usd": [ 0.01912922145322822 ], "created_ts": "2019-04-21T07:33:59.264269", "duration": 139.29487991333008, "modified_ts": "2019-04-21T07:36:46.758697", "name": "extensions_browsertests/Ubuntu-14.04/afb88a97e6/Linux ChromiumOS MSan Tests/12485", "output": "/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:354: SNIMissingWarning: An HTTPS request has been made, but the SNI (Server Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\n/b/s/swarming_bot.1.zip/third_party/urllib3/util/ssl_.py:150: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings\nInitializing built-in extension Generic Event Extension\nInitializing built-in extension SHAPE\nInitializing built-in extension MIT-SHM\nInitializing built-in extension XInputExtension\nInitializing built-in extension XTEST\nInitializing built-in extension BIG-REQUESTS\nInitializing built-in extension SYNC\nInitializing built-in extension XKEYBOARD\nInitializing built-in extension XC-MISC\nInitializing built-in extension SECURITY\nInitializing built-in extension XINERAMA\nInitializing built-in extension XFIXES\nInitializing built-in extension RENDER\nInitializing built-in extension RANDR\nInitializing built-in extension COMPOSITE\nInitializing built-in extension DAMAGE\nInitializing built-in extension MIT-SCREEN-SAVER\nInitializing built-in extension DOUBLE-BUFFER\nInitializing built-in extension RECORD\nInitializing built-in extension DPMS\nInitializing built-in extension Present\nInitializing built-in extension DRI3\nInitializing built-in extension X-Resource\nInitializing built-in extension XVideo\nInitializing built-in extension XVideo-MotionCompensation\nInitializing built-in extension SELinux\nInitializing built-in extension GLX\n[dix] Could not init font path element /usr/share/fonts/X11/cyrillic, removing from list!\n[dix] Could not init font path element /usr/share/fonts/X11/100dpi/:unscaled, removing from list!\n[dix] Could not init font path element /usr/share/fonts/X11/75dpi/:unscaled, removing from list!\n[dix] Could not init font path element /usr/share/fonts/X11/100dpi, removing from list!\n[dix] Could not init font path element /usr/share/fonts/X11/75dpi, removing from list!\nAdditional test environment:\n CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox\n CHROME_HEADLESS=1\n G_SLICE=always-malloc\n LANG=en_US.UTF-8\n LLVM_SYMBOLIZER_PATH=/b/s/w/ir/third_party/llvm-build/Release+Asserts/bin/llvm-symbolizer\n MSAN_OPTIONS=symbolize=0 handle_abort=1\n NSS_DISABLE_ARENA_FREE_LIST=1\n NSS_DISABLE_UNLOAD=1\nCommand: ./extensions_browsertests --test-launcher-bot-mode --cfi-diag=0 --test-launcher-print-test-stdio=always --test-launcher-summary-output=/b/s/w/ioMtR44p/output.json\n\nObt-Message: Xinerama extension is not present on the server\nObt-Message: XRandR extension is not present on the server\nOpenbox-Message: Unable to find a valid menu file \"/var/lib/openbox/debian-menu.xml\"\nIMPORTANT DEBUGGING NOTE: each test is run inside its own process.\nFor debugging a test inside a debugger, use the\n--gtest_filter=<your_test_name> flag along with either\n--single_process (to run the test in one launcher/browser process) or\n--single-process (to do the above, and also run Chrome in single-process mode).\nUsing sharding settings from environment. This is shard 0/1\nUsing 4 parallel jobs.\n[ RUN ] BluetoothShellApiTest.ApiSanityCheck\n\nDevTools listening on ws://127.0.0.1:44758/devtools/browser/a093cd0b-ea60-44a1-a38a-e466a94c0181\n[12291:12291:0421/003428.853448:33167296838:INFO:CONSOLE(0)] \"[SUCCESS] bluetoothSanityCheck\", source: chrome-extension://pjdjhejcdkeebjehnokfbfnjmgmgdjlc/_generated_background_page.html (0)\n[12291:12322:0421/003428.929517:33167372891:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] BluetoothShellApiTest.ApiSanityCheck (1457 ms)\n[1/202] BluetoothShellApiTest.ApiSanityCheck (1651 ms)\n[ RUN ] BluetoothSocketApiTest.PermissionDenied\n\nDevTools listening on ws://127.0.0.1:43084/devtools/browser/58bf05db-6ee4-4b84-89ee-8e9611cd1fd8\n[12293:12293:0421/003428.856122:33167299503:INFO:CONSOLE(0)] \"[SUCCESS] (no test)\", source: chrome-extension://pjdjhejcdkeebjehnokfbfnjmgmgdjlc/_generated_background_page.html (0)\n[12293:12347:0421/003428.930152:33167373523:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] BluetoothSocketApiTest.PermissionDenied (1350 ms)\n[2/202] BluetoothSocketApiTest.PermissionDenied (1747 ms)\n[ RUN ] DisplaySourceApiTest.DisplaySourceExtension\n\nDevTools listening on ws://127.0.0.1:45222/devtools/browser/e6d176bf-3552-4886-8813-3f724c15fd3e\n[12297:12297:0421/003428.972106:33167415484:INFO:CONSOLE(0)] \"[SUCCESS] testGetAvailableSinks\", source: chrome-extension://eakonlkfnnjfnpbjnakpgifnjcdpfofn/_generated_background_page.html (0)\n[12297:12297:0421/003428.978322:33167421711:INFO:CONSOLE(0)] \"[SUCCESS] testOnSinksUpdated\", source: chrome-extension://eakonlkfnnjfnpbjnakpgifnjcdpfofn/_generated_background_page.html (0)\n[12297:12297:0421/003428.987944:33167431328:INFO:CONSOLE(0)] \"[SUCCESS] testRequestAuthentication\", source: chrome-extension://eakonlkfnnjfnpbjnakpgifnjcdpfofn/_generated_background_page.html (0)\n[12297:12297:0421/003429.001297:33167444674:INFO:CONSOLE(0)] \"[SUCCESS] testStartSessionErrorReport\", source: chrome-extension://eakonlkfnnjfnpbjnakpgifnjcdpfofn/_generated_background_page.html (0)\n[12297:12297:0421/003429.008015:33167451388:INFO:CONSOLE(0)] \"[SUCCESS] testTerminateSessionErrorReport\", source: chrome-extension://eakonlkfnnjfnpbjnakpgifnjcdpfofn/_generated_background_page.html (0)\n[12297:12366:0421/003429.068048:33167511428:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] DisplaySourceApiTest.DisplaySourceExtension (1434 ms)\n[3/202] DisplaySourceApiTest.DisplaySourceExtension (1795 ms)\n[ RUN ] BluetoothSocketApiTest.Listen\n\nDevTools listening on ws://127.0.0.1:38754/devtools/browser/f19fcdb1-b444-4029-9163-e454ca01ba04\n[12292:12292:0421/003429.011499:33167454892:INFO:CONSOLE(0)] \"[SUCCESS] testListen\", source: chrome-extension://pjdjhejcdkeebjehnokfbfnjmgmgdjlc/_generated_background_page.html (0)\n[12292:12332:0421/003429.097448:33167540832:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] BluetoothSocketApiTest.Listen (1575 ms)\n[4/202] BluetoothSocketApiTest.Listen (1961 ms)\n[ RUN ] DnsApiTest.DnsResolveIPLiteral\n\nDevTools listening on ws://127.0.0.1:36310/devtools/browser/61a6205e-0fff-4785-ba1a-014c036ad401\n[ OK ] DnsApiTest.DnsResolveIPLiteral (1228 ms)\n[5/202] DnsApiTest.DnsResolveIPLiteral (1635 ms)\n[ RUN ] DnsApiTest.DnsResolveHostname\n\nDevTools listening on ws://127.0.0.1:33849/devtools/browser/cf7b5056-4b57-4a88-877a-8ed7b339e81a\n[12561:12621:0421/003430.529123:33168972517:ERROR:browser_process_sub_thread.cc(217)] Waited 15 ms for network service\n[ OK ] DnsApiTest.DnsResolveHostname (1343 ms)\n[6/202] DnsApiTest.DnsResolveHostname (1623 ms)\n[ RUN ] DnsApiTest.DnsExtension\n\nDevTools listening on ws://127.0.0.1:36891/devtools/browser/26b76130-1619-4dae-85a7-7d5f0766d3ca\n[12567:12567:0421/003430.876695:33169320176:INFO:CONSOLE(0)] \"[SUCCESS] testIPLiteralResolution\", source: chrome-extension://fncagodipnkiklcgjhnilcffcmnkamlo/_generated_background_page.html (0)\n[12567:12567:0421/003430.890277:33169333657:INFO:CONSOLE(0)] \"[SUCCESS] testHostnameResolution\", source: chrome-extension://fncagodipnkiklcgjhnilcffcmnkamlo/_generated_background_page.html (0)\n[12567:12567:0421/003430.902595:33169345986:INFO:CONSOLE(0)] \"[SUCCESS] testNonexistentHostnameResolution\", source: chrome-extension://fncagodipnkiklcgjhnilcffcmnkamlo/_generated_background_page.html (0)\n[ OK ] DnsApiTest.DnsExtension (1725 ms)\n[7/202] DnsApiTest.DnsExtension (1888 ms)\n[ RUN ] FeedbackPrivateApiTest.Basic\n\nDevTools listening on ws://127.0.0.1:39836/devtools/browser/a7a313b8-9ac5-4d4e-b262-97d176bcdd0c\n[12573:12573:0421/003430.769800:33169213190:INFO:CONSOLE(0)] \"[SUCCESS] getUserEmailTest\", source: chrome-extension://gfdkimpbcpahaombhbimeihdjnejgicl/_generated_background_page.html (0)\n[12573:12573:0421/003430.810245:33169253626:INFO:CONSOLE(0)] \"[SUCCESS] getSystemInfoTest\", source: chrome-extension://gfdkimpbcpahaombhbimeihdjnejgicl/_generated_background_page.html (0)\n[12573:12573:0421/003430.823693:33169267144:INFO:CONSOLE(0)] \"[SUCCESS] sendFeedbackTest\", source: chrome-extension://gfdkimpbcpahaombhbimeihdjnejgicl/_generated_background_page.html (0)\n[12573:12626:0421/003430.896397:33169339783:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] FeedbackPrivateApiTest.Basic (1497 ms)\n[8/202] FeedbackPrivateApiTest.Basic (1876 ms)\n[ RUN ] HidApiTest.OnDeviceAdded\n\nDevTools listening on ws://127.0.0.1:41107/devtools/browser/731e5646-51e4-455a-8512-a693ad066ea6\n[ OK ] HidApiTest.OnDeviceAdded (1543 ms)\n[9/202] HidApiTest.OnDeviceAdded (1885 ms)\n[ RUN ] HidApiTest.OnDeviceRemoved\n\nDevTools listening on ws://127.0.0.1:39011/devtools/browser/9ec8ac79-66a1-48e3-89db-ead4bff1c08e\n[12790:12929:0421/003432.798284:33171241670:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] HidApiTest.OnDeviceRemoved (1663 ms)\n[10/202] HidApiTest.OnDeviceRemoved (1884 ms)\n[ RUN ] HidApiTest.HidApp\n\nDevTools listening on ws://127.0.0.1:35840/devtools/browser/a0e8351b-4327-47e2-a870-bf557838a5f1\n[12764:12764:0421/003432.212510:33170655910:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithNoOptions\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.235278:33170678662:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithLegacyVidAndPid\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.266420:33170709817:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithNoFilters\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.303163:33170746549:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithVidPidFilter\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.342118:33170785502:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithUsageFilter\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.352378:33170795760:INFO:CONSOLE(0)] \"[SUCCESS] testGetDevicesWithUnauthorizedDevice\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.433261:33170876644:INFO:CONSOLE(0)] \"[SUCCESS] testDeviceInfo\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.445336:33170888721:INFO:CONSOLE(0)] \"[SUCCESS] testConnectWithInvalidDeviceId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.473697:33170917162:INFO:CONSOLE(0)] \"[SUCCESS] testConnectAndDisconnect\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.484571:33170927954:INFO:CONSOLE(0)] \"[SUCCESS] testDisconnectWithInvalidConnectionId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.496236:33170939629:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveWithInvalidConnectionId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.521553:33170964930:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveWithReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.547157:33170990531:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveWithoutReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.557820:33171001202:INFO:CONSOLE(0)] \"[SUCCESS] testSendWithInvalidConnectionId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.585113:33171028496:INFO:CONSOLE(0)] \"[SUCCESS] testSendOversizeReport\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.607432:33171050816:INFO:CONSOLE(0)] \"[SUCCESS] testSendWithReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.631637:33171075021:INFO:CONSOLE(0)] \"[SUCCESS] testSendWithoutReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.661293:33171104675:INFO:CONSOLE(0)] \"[SUCCESS] testSendWithInvalidReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.685573:33171128957:INFO:CONSOLE(0)] \"[SUCCESS] testSendWithUnexpectedReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.694936:33171138318:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveFeatureReportWithInvalidConnectionId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.715901:33171159285:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveFeatureReportWithReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.737252:33171180636:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveFeatureReportWithoutReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.762844:33171206226:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveFeatureReportWithInvalidReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.785153:33171228536:INFO:CONSOLE(0)] \"[SUCCESS] testReceiveFeatureReportWithUnexpectedReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.796271:33171239655:INFO:CONSOLE(0)] \"[SUCCESS] testSendFeatureReportWithInvalidConnectionId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.831112:33171274494:INFO:CONSOLE(0)] \"[SUCCESS] testSendFeatureReportWithReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.857308:33171300691:INFO:CONSOLE(0)] \"[SUCCESS] testSendFeatureReportWithoutReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.890040:33171333421:INFO:CONSOLE(0)] \"[SUCCESS] testSendFeatureReportWithInvalidReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12764:0421/003432.918651:33171362034:INFO:CONSOLE(0)] \"[SUCCESS] testSendFeatureReportWithUnexpectedReportId\", source: chrome-extension://abdjffimllhadmhemlicclecfaihddmm/_generated_background_page.html (0)\n[12764:12808:0421/003432.978144:33171421518:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] HidApiTest.HidApp (2243 ms)\n[11/202] HidApiTest.HidApp (2407 ms)\n[ RUN ] HidApiTest.GetUserSelectedDevices\n\nDevTools listening on ws://127.0.0.1:44750/devtools/browser/dc048f7c-0c69-4d10-a404-0bc62e40693e\n[ OK ] HidApiTest.GetUserSelectedDevices (1554 ms)\n[12/202] HidApiTest.GetUserSelectedDevices (1908 ms)\n[ RUN ] PrinterProviderApiTest.PrintJobAsyncSuccess\n\nDevTools listening on ws://127.0.0.1:37299/devtools/browser/30a773f2-ba3d-4d6d-abf4-e6594a345d16\n[13038:13038:0421/003433.962853:33172406242:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://jmfninjglbekcffongcllfjohdefaohb/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.PrintJobAsyncSuccess (1359 ms)\n[13/202] PrinterProviderApiTest.PrintJobAsyncSuccess (1635 ms)\n[ RUN ] PrinterProviderApiTest.NoPrintEventListener\n\nDevTools listening on ws://127.0.0.1:46839/devtools/browser/e60858e7-adab-46c2-ae71-8d05908887bf\n[13061:13061:0421/003434.693210:33173136596:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://jmfninjglbekcffongcllfjohdefaohb/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.NoPrintEventListener (1610 ms)\n[14/202] PrinterProviderApiTest.NoPrintEventListener (1889 ms)\n[ RUN ] PrinterProviderApiTest.PrintRequestInvalidCallbackParam\n\nDevTools listening on ws://127.0.0.1:46683/devtools/browser/227552d2-89a7-4258-be48-e87ac28de04b\n[13066:13066:0421/003434.770676:33173214110:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://jmfninjglbekcffongcllfjohdefaohb/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.PrintRequestInvalidCallbackParam (1623 ms)\n[15/202] PrinterProviderApiTest.PrintRequestInvalidCallbackParam (1884 ms)\n[ RUN ] PrinterProviderApiTest.PrintJobFailed\n\nDevTools listening on ws://127.0.0.1:43079/devtools/browser/cee1a10b-8f62-4ec0-badf-7662828c5319\n[13051:13051:0421/003434.724062:33173167456:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://jmfninjglbekcffongcllfjohdefaohb/_generated_background_page.html (0)\n[13051:13157:0421/003434.781057:33173224438:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] PrinterProviderApiTest.PrintJobFailed (1756 ms)\n[16/202] PrinterProviderApiTest.PrintJobFailed (2148 ms)\n[ RUN ] PrinterProviderApiTest.PrintRequestDataNotSet\n\nDevTools listening on ws://127.0.0.1:41122/devtools/browser/47006128-804b-4885-9b07-432b8d8acf0c\n[ OK ] PrinterProviderApiTest.PrintRequestDataNotSet (1045 ms)\n[17/202] PrinterProviderApiTest.PrintRequestDataNotSet (1412 ms)\n[ RUN ] PrinterProviderApiTest.PrintRequestAppUnloaded\n\nDevTools listening on ws://127.0.0.1:35632/devtools/browser/3cd73c58-50c2-4729-ac56-99ed4fd8b225\n[13347:13347:0421/003436.467107:33174910495:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://jmfninjglbekcffongcllfjohdefaohb/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.PrintRequestAppUnloaded (1548 ms)\n[18/202] PrinterProviderApiTest.PrintRequestAppUnloaded (1874 ms)\n[ RUN ] PrinterProviderApiTest.GetCapabilitySuccess\n\nDevTools listening on ws://127.0.0.1:35851/devtools/browser/24232e43-4552-461d-92ca-b459a62d6819\n[13349:13349:0421/003436.456854:33174900247:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.GetCapabilitySuccess (1469 ms)\n[19/202] PrinterProviderApiTest.GetCapabilitySuccess (1884 ms)\n[ RUN ] PrinterProviderApiTest.GetCapabilityAsyncSuccess\n\nDevTools listening on ws://127.0.0.1:45554/devtools/browser/f7cb55a5-b65a-437a-9fe9-8fcd6ec20cae\n[13367:13367:0421/003436.513117:33174956502:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[13367:13429:0421/003436.647757:33175091131:ERROR:browser_process_sub_thread.cc(217)] Waited 17 ms for network service\n[ OK ] PrinterProviderApiTest.GetCapabilityAsyncSuccess (1482 ms)\n[20/202] PrinterProviderApiTest.GetCapabilityAsyncSuccess (1882 ms)\n[ RUN ] PrinterProviderApiTest.EmptyCapability\n\nDevTools listening on ws://127.0.0.1:33147/devtools/browser/cf995085-8ceb-4575-b20e-18c393adcd51\n[13457:13457:0421/003436.944943:33175388332:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[13457:13591:0421/003437.004350:33175447723:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] PrinterProviderApiTest.EmptyCapability (1229 ms)\n[21/202] PrinterProviderApiTest.EmptyCapability (1626 ms)\n[ RUN ] PrinterProviderApiTest.GetCapabilityAppUnloaded\n\nDevTools listening on ws://127.0.0.1:42364/devtools/browser/f2b3a5e9-2fef-4f3f-ba6a-43bb0474fc85\n[13658:13658:0421/003438.473795:33176917188:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[13658:13697:0421/003438.530410:33176973795:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] PrinterProviderApiTest.GetCapabilityAppUnloaded (9009 ms)\n[22/202] PrinterProviderApiTest.GetCapabilityAppUnloaded (9226 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersSuccess\n\nDevTools listening on ws://127.0.0.1:45804/devtools/browser/7e3764ea-e27c-4024-bdf0-446414aebb3d\n[13686:13686:0421/003438.621283:33177064674:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[13686:13755:0421/003438.669511:33177112897:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersSuccess (8672 ms)\n[23/202] PrinterProviderApiTest.GetPrintersSuccess (8960 ms)\n[ RUN ] PrinterProviderApiTest.NoCapabilityEventListener\n\nDevTools listening on ws://127.0.0.1:34453/devtools/browser/49983027-e060-4e73-a886-af1e6ffa6144\n[13654:13654:0421/003438.417318:33176860703:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[13654:13698:0421/003438.490212:33176933588:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] PrinterProviderApiTest.NoCapabilityEventListener (9145 ms)\n[24/202] PrinterProviderApiTest.NoCapabilityEventListener (9481 ms)\n[ RUN ] PrinterProviderApiTest.CapabilityInvalidValue\n\nDevTools listening on ws://127.0.0.1:37209/devtools/browser/7b69df8e-0645-4856-aff1-5a464e15a3ed\n[13655:13655:0421/003438.402165:33176845566:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fmcnjaijanledicomfgmdmjdlipkncao/_generated_background_page.html (0)\n[13655:13701:0421/003438.469923:33176913296:ERROR:browser_process_sub_thread.cc(217)] Waited 2 ms for network service\n[ OK ] PrinterProviderApiTest.CapabilityInvalidValue (9081 ms)\n[25/202] PrinterProviderApiTest.CapabilityInvalidValue (9485 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersAsyncSuccess\n\nDevTools listening on ws://127.0.0.1:37561/devtools/browser/2a2cb27e-7ba2-40e6-9c26-a75af5197ef1\n[13934:13934:0421/003447.716897:33186160273:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[13934:13981:0421/003447.818756:33186262136:ERROR:browser_process_sub_thread.cc(217)] Waited 24 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersAsyncSuccess (9529 ms)\n[26/202] PrinterProviderApiTest.GetPrintersAsyncSuccess (9742 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersTwoExtensions\n\nDevTools listening on ws://127.0.0.1:33309/devtools/browser/4c7a0549-372f-48fe-96ec-2a25276c47f2\n[13935:13935:0421/003448.033139:33186476525:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[13935:13935:0421/003448.037222:33186480598:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://ahfhpcanhefelfnkiadilpkejbhhmghj/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.GetPrintersTwoExtensions (9475 ms)\n[27/202] PrinterProviderApiTest.GetPrintersTwoExtensions (9742 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersTwoExtensionsBothUnloaded\n\nDevTools listening on ws://127.0.0.1:41613/devtools/browser/6df6d3a3-b33a-4f68-9a66-8eaf538dd049\n[13938:13938:0421/003448.098018:33186541467:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[13938:13938:0421/003448.098654:33186542036:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://ahfhpcanhefelfnkiadilpkejbhhmghj/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.GetPrintersTwoExtensionsBothUnloaded (9421 ms)\n[28/202] PrinterProviderApiTest.GetPrintersTwoExtensionsBothUnloaded (9739 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersTwoExtensionsOneFails\n\nDevTools listening on ws://127.0.0.1:44398/devtools/browser/ba7f4077-e5bc-45e3-87e9-a511dd67c92a\n[13941:13941:0421/003447.061480:33185504856:ERROR:device_event_log_impl.cc(162)] [00:34:47.061] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:ManagedStateListChanged: 92ms\n[13941:13941:0421/003448.154370:33186597759:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://ahfhpcanhefelfnkiadilpkejbhhmghj/_generated_background_page.html (0)\n[13941:13941:0421/003448.163383:33186606761:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[13941:13977:0421/003448.216922:33186660303:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersTwoExtensionsOneFails (9357 ms)\n[29/202] PrinterProviderApiTest.GetPrintersTwoExtensionsOneFails (9739 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersNoListener\n\nDevTools listening on ws://127.0.0.1:33410/devtools/browser/9f703a2d-cfc8-43e3-9053-7ee73462b114\n[14261:14261:0421/003457.418959:33195862344:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.GetPrintersNoListener (1443 ms)\n[30/202] PrinterProviderApiTest.GetPrintersNoListener (1616 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersNotArray\n\nDevTools listening on ws://127.0.0.1:41537/devtools/browser/22fbdc9c-27e4-42b0-b82b-719a928d39f6\n[14264:14264:0421/003457.464433:33195907821:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[14264:14304:0421/003457.533675:33195977054:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersNotArray (1427 ms)\n[31/202] PrinterProviderApiTest.GetPrintersNotArray (1615 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersInvalidPrinterValueType\n\nDevTools listening on ws://127.0.0.1:34901/devtools/browser/d56b145a-49d3-4d5e-b04c-3e2fd799b16d\n[14267:14267:0421/003457.487531:33195930915:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[14267:14316:0421/003457.563483:33196006867:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersInvalidPrinterValueType (1394 ms)\n[32/202] PrinterProviderApiTest.GetPrintersInvalidPrinterValueType (1607 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersTwoExtensionsOneWithNoListener\n\nDevTools listening on ws://127.0.0.1:37695/devtools/browser/310fbd61-f490-4ecc-97f6-82a5c57428a5\n[14260:14260:0421/003457.430447:33195873835:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[14260:14260:0421/003457.773690:33196217088:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://ahfhpcanhefelfnkiadilpkejbhhmghj/_generated_background_page.html (0)\n[14260:14307:0421/003457.826068:33196269446:ERROR:browser_process_sub_thread.cc(217)] Waited 1 ms for network service\n[ OK ] PrinterProviderApiTest.GetPrintersTwoExtensionsOneWithNoListener (1820 ms)\n[33/202] PrinterProviderApiTest.GetPrintersTwoExtensionsOneWithNoListener (2132 ms)\n[ RUN ] PrinterProviderApiTest.GetPrintersInvalidPrinterValue\n\nDevTools listening on ws://127.0.0.1:36969/devtools/browser/1c4df71f-957b-4507-a7ab-0396fdab57a3\n[14538:14538:0421/003458.836230:33197279619:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://fnnlgageeedihnidgcifkdeeecafomfm/_generated_background_page.html (0)\n[ OK ] PrinterProviderApiTest.GetPrintersInvalidPrinterValue (1280 ms)\n[34/202] PrinterProviderApiTest.GetPrintersInvalidPrinterValue (1615 ms)\n[ RUN ] PrinterProviderApiTest.GetUsbPrinterInfo\n\nDevTools listening on ws://127.0.0.1:33454/devtools/browser/df32eecd-58b9-48cc-b177-d63d49d84aeb\n[14539:14539:0421/003459.221427:33197664883:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://iknlcommnonhaplfklglpgdkhecflmpa/_generated_background_page.html (0)\n[14539:14616:0421/003459.291061:33197734444:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] PrinterProviderApiTest.GetUsbPrinterInfo (1557 ms)\n[35/202] PrinterProviderApiTest.GetUsbPrinterInfo (1875 ms)\n[ RUN ] PrinterProviderApiTest.GetUsbPrinterInfoEmptyResponse\n\nDevTools listening on ws://127.0.0.1:42857/devtools/browser/da689239-2a16-4078-9507-eeab3cced8e9\n[14542:14542:0421/003459.214038:33197657430:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://iknlcommnonhaplfklglpgdkhecflmpa/_generated_background_page.html (0)\n[14542:14625:0421/003459.266579:33197709962:ERROR:browser_process_sub_thread.cc(217)] Waited 2 ms for network service\n[ OK ] PrinterProviderApiTest.GetUsbPrinterInfoEmptyResponse (1476 ms)\n[36/202] PrinterProviderApiTest.GetUsbPrinterInfoEmptyResponse (1878 ms)\n[ RUN ] PrinterProviderApiTest.GetUsbPrinterInfoNoListener\n\nDevTools listening on ws://127.0.0.1:38978/devtools/browser/74a6b364-a22d-4093-87c7-5d0581d7058b\n[14569:14569:0421/003459.346556:33197789931:INFO:CONSOLE(0)] \"[SUCCESS] printTest\", source: chrome-extension://iknlcommnonhaplfklglpgdkhecflmpa/_generated_background_page.html (0)\n[14569:14670:0421/003459.392187:33197835568:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] PrinterProviderApiTest.GetUsbPrinterInfoNoListener (1252 ms)\n[37/202] PrinterProviderApiTest.GetUsbPrinterInfoNoListener (1610 ms)\n[ RUN ] SocketApiTest.SocketUDPCreateGood\n\nDevTools listening on ws://127.0.0.1:34013/devtools/browser/0253cdf2-2c0c-4c81-82d3-76a1c483fd7f\n[ OK ] SocketApiTest.SocketUDPCreateGood (887 ms)\n[38/202] SocketApiTest.SocketUDPCreateGood (1094 ms)\n[ RUN ] SocketApiTest.SocketTCPCreateGood\n\nDevTools listening on ws://127.0.0.1:42570/devtools/browser/52e89775-d1af-45cc-a2ca-90ecd63638bf\n[ OK ] SocketApiTest.SocketTCPCreateGood (706 ms)\n[39/202] SocketApiTest.SocketTCPCreateGood (953 ms)\n[ RUN ] SocketsTcpApiTest.SocketsTcpCreateGood\n\nDevTools listening on ws://127.0.0.1:46583/devtools/browser/61207ce4-9e6d-49f8-a5d5-7fd5b4925e30\n[14822:14874:0421/003500.503471:33198946858:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] SocketsTcpApiTest.SocketsTcpCreateGood (763 ms)\n[40/202] SocketsTcpApiTest.SocketsTcpCreateGood (952 ms)\n[ RUN ] SocketApiTest.GetNetworkList\n\nDevTools listening on ws://127.0.0.1:39237/devtools/browser/3153cbd3-a70b-4168-8847-f83f5dd0e41e\n[14819:14878:0421/003500.521951:33198965334:ERROR:browser_process_sub_thread.cc(217)] Waited 18 ms for network service\n[ OK ] SocketApiTest.GetNetworkList (860 ms)\n[41/202] SocketApiTest.GetNetworkList (1084 ms)\n[ RUN ] SocketsTcpServerApiTest.SocketTCPCreateGood\n\nDevTools listening on ws://127.0.0.1:35032/devtools/browser/980b61ef-034b-4811-93e0-adb190c61541\n[ OK ] SocketsTcpServerApiTest.SocketTCPCreateGood (947 ms)\n[42/202] SocketsTcpServerApiTest.SocketTCPCreateGood (1347 ms)\n[ RUN ] SocketsUdpApiTest.SocketsUdpCreateGood\n\nDevTools listening on ws://127.0.0.1:33372/devtools/browser/d6f931bb-41e1-49c0-b5ba-b2baae2c70b2\n[15158:15180:0421/003502.627461:33201070864:ERROR:browser_process_sub_thread.cc(217)] Waited 11 ms for network service\n[ OK ] SocketsUdpApiTest.SocketsUdpCreateGood (573 ms)\n[43/202] SocketsUdpApiTest.SocketsUdpCreateGood (826 ms)\n[ RUN ] SocketsTcpServerApiTest.SocketTCPServerExtension\n\nDevTools listening on ws://127.0.0.1:44110/devtools/browser/549088eb-7724-4413-88b1-d25da8845c33\n[14960:14960:0421/003502.049500:33200492891:INFO:CONSOLE(125)] \"Running tests, protocol tcp_server, echo server 127.0.0.1:8888\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (125)\n[14960:14960:0421/003502.064742:33200508128:INFO:CONSOLE(44)] \"Server socket created: sd=1\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (44)\n[14960:14960:0421/003502.070851:33200514234:INFO:CONSOLE(50)] \"Server socket 'listen' completed: sd=1, result=0\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (50)\n[14960:14960:0421/003502.081044:33200524430:INFO:CONSOLE(59)] \"Client socket created: sd=1\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (59)\n[14960:14960:0421/003502.097322:33200540708:INFO:CONSOLE(83)] \"Server socket 'accept' event: sd=1, client sd=2\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (83)\n[14960:14960:0421/003502.101459:33200544841:INFO:CONSOLE(63)] \"Client socket connected: sd=1\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (63)\n[14960:14960:0421/003502.115675:33200559071:INFO:CONSOLE(69)] \"Client socket data sent: sd=1, result=0\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (69)\n[14960:14960:0421/003502.118244:33200561626:INFO:CONSOLE(92)] \"Client socket 'receive' event: sd=2, bytes=24\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (92)\n[14960:14960:0421/003502.124704:33200568096:INFO:CONSOLE(114)] \"Client socket 'receive error' event: sd=1, result=-100\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (114)\n[14960:14960:0421/003502.125562:33200568940:INFO:CONSOLE(72)] \"Client socket disconnected: sd=1\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (72)\n[14960:14960:0421/003502.126451:33200569829:INFO:CONSOLE(114)] \"Client socket 'receive error' event: sd=2, result=-100\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/background.js (114)\n[14960:14960:0421/003502.639790:33201083170:INFO:CONSOLE(0)] \"[SUCCESS] testSocketListening\", source: chrome-extension://maapjikibbpceckgjljajhegnpceikmc/_generated_background_page.html (0)\n[14960:15036:0421/003502.702936:33201146319:ERROR:browser_process_sub_thread.cc(217)] Waited 1 ms for network service\n[ OK ] SocketsTcpServerApiTest.SocketTCPServerExtension (1959 ms)\n[44/202] SocketsTcpServerApiTest.SocketTCPServerExtension (2172 ms)\n[ RUN ] SocketsTcpApiTest.SocketTcpExtension\n\nDevTools listening on ws://127.0.0.1:36616/devtools/browser/7de5dc23-4729-4ed9-888d-56dd8b135e22\nEcho TCP server started on port 40650...\nsending server_data: {\"host\": \"127.0.0.1\", \"port\": 40650} (36 bytes)\n[14922:14922:0421/003502.643249:33201086637:INFO:CONSOLE(442)] \"Running tests for TCP, echo server www.foo.com:40650\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (442)\n[14922:14922:0421/003502.685200:33201128575:INFO:CONSOLE(0)] \"[SUCCESS] testSocketCreation\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[14922:14922:0421/003502.687253:33201130625:INFO:CONSOLE(0)] \"Unchecked runtime.lastError: Socket not found\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[14922:14922:0421/003502.695777:33201139158:INFO:CONSOLE(121)] \"onCreateComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (121)\n[14922:14922:0421/003502.697185:33201140555:INFO:CONSOLE(125)] \"add event listeners\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (125)\n[14922:14922:0421/003502.701144:33201144513:INFO:CONSOLE(135)] \"onGetInfoAfterCreateComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (135)\n[14922:14922:0421/003502.709616:33201152997:INFO:CONSOLE(160)] \"onUpdateComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (160)\n[14922:14922:0421/003502.712331:33201155711:INFO:CONSOLE(165)] \"onGetInfoAfterUpdateComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (165)\n[14922:14922:0421/003502.726759:33201170134:INFO:CONSOLE(188)] \"onConnectComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (188)\n[14922:14922:0421/003502.730735:33201174107:INFO:CONSOLE(196)] \"onGetInfoAfterConnectComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (196)\n[14922:14922:0421/003502.736452:33201179834:INFO:CONSOLE(214)] \"onSetPausedComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (214)\n[14922:14922:0421/003502.738874:33201182246:INFO:CONSOLE(219)] \"onGetInfoAfterSetPausedComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (219)\n[14922:14922:0421/003502.741771:33201185141:INFO:CONSOLE(225)] \"onUnpauseComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (225)\n[14922:14922:0421/003502.744150:33201187518:INFO:CONSOLE(230)] \"onGetInfoAfterUnpauseComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (230)\n[14922:14922:0421/003502.747775:33201191144:INFO:CONSOLE(236)] \"onSetNoDelayComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (236)\n[14922:14922:0421/003502.750431:33201193799:INFO:CONSOLE(247)] \"onSetKeepAliveComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (247)\n[14922:14922:0421/003502.763283:33201206654:INFO:CONSOLE(261)] \"onSendComplete: 24 bytes.\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (261)\n[14922:14922:0421/003502.765666:33201209034:INFO:CONSOLE(269)] \"onSocketReceive\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (269)\n[14922:14922:0421/003502.774848:33201218237:INFO:CONSOLE(275)] \"echo data received, closing socket\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (275)\n[14922:14922:0421/003502.777244:33201220624:INFO:CONSOLE(294)] \"onCloseComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (294)\n[14922:14922:0421/003502.777874:33201221248:INFO:CONSOLE(0)] \"[SUCCESS] testSending\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[14922:14977:0421/003502.838018:33201281401:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] SocketsTcpApiTest.SocketTcpExtension (2405 ms)\n[45/202] SocketsTcpApiTest.SocketTcpExtension (2678 ms)\n[ RUN ] SocketsTcpApiTest.SocketTcpExtensionTLS\n\nDevTools listening on ws://127.0.0.1:44814/devtools/browser/a340f71a-d375-4efd-b0fd-9d65f889c024\nHTTPS server started on https://127.0.0.1:44567...\nsending server_data: {\"host\": \"127.0.0.1\", \"port\": 44567} (36 bytes)\n[14952:14952:0421/003502.848955:33201292344:INFO:CONSOLE(448)] \"Running tests for HTTPS, server 127.0.0.1:44567\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (448)\n[14952:14952:0421/003502.872089:33201315472:INFO:CONSOLE(339)] \"HTTPS onPausedComplete. Connecting to 127.0.0.1:44567\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (339)\n[14952:14952:0421/003502.880553:33201323939:INFO:CONSOLE(346)] \"HTTPS onConnectComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (346)\n[14952:14952:0421/003502.954103:33201397498:INFO:CONSOLE(353)] \"HTTPS onSecureComplete(0)\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (353)\n[14952:14952:0421/003502.957333:33201400713:INFO:CONSOLE(367)] \"HTTPS onUnpauseComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (367)\n[14952:14952:0421/003502.970408:33201413795:INFO:CONSOLE(375)] \"HTTPS onSendComplete: 26 bytes.\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (375)\n[14952:14952:0421/003502.978140:33201421524:INFO:CONSOLE(383)] \"HTTPS onSocketReceive\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (383)\n[14952:14952:0421/003502.983649:33201427034:INFO:CONSOLE(383)] \"HTTPS onSocketReceive\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (383)\n[14952:14952:0421/003502.988959:33201432342:INFO:CONSOLE(402)] \"HTTPS onSocketReceiveError\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (402)\n[14952:14952:0421/003502.991500:33201434879:INFO:CONSOLE(391)] \"HTTPS receive: got HTTP/1.0 200 OK\n\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (391)\n[14952:14952:0421/003502.993434:33201436814:INFO:CONSOLE(394)] \"HTTPS response received, closing socket.\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (394)\n[14952:14952:0421/003502.995831:33201439209:INFO:CONSOLE(411)] \"HTTPS Test Succeeded\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (411)\n[14952:14952:0421/003502.998511:33201441888:INFO:CONSOLE(330)] \"HTTPS onCreateComplete: in MISUSE_SECURE_PENDING_READ mode, skipping setPaused(false).\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (330)\n[14952:14952:0421/003502.998780:33201442152:INFO:CONSOLE(339)] \"HTTPS onPausedComplete. Connecting to 127.0.0.1:44567\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (339)\n[14952:14952:0421/003503.005374:33201448754:INFO:CONSOLE(346)] \"HTTPS onConnectComplete\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (346)\n[14952:14952:0421/003503.008878:33201452257:INFO:CONSOLE(402)] \"HTTPS onSocketReceiveError\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (402)\n[14952:14952:0421/003503.013645:33201457025:INFO:CONSOLE(353)] \"HTTPS onSecureComplete(-2)\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (353)\n[14952:14952:0421/003503.015201:33201458569:INFO:CONSOLE(0)] \"Unchecked runtime.lastError: net::ERR_FAILED\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[14952:14952:0421/003503.016512:33201459877:INFO:CONSOLE(411)] \"HTTPS Test Succeeded\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/background.js (411)\n[14952:14952:0421/003503.017510:33201460877:INFO:CONSOLE(0)] \"[SUCCESS] testSecure\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[14952:14952:0421/003503.018813:33201462178:INFO:CONSOLE(0)] \"Unchecked runtime.lastError: Socket not found\", source: chrome-extension://poppegbdkmfildfgekgnkhhbhnkndnka/_generated_background_page.html (0)\n[ OK ] SocketsTcpApiTest.SocketTcpExtensionTLS (2488 ms)\n[46/202] SocketsTcpApiTest.SocketTcpExtensionTLS (2668 ms)\n[ RUN ] SystemDisplayApiTest.SetDisplayNotKioskEnabled\n\nDevTools listening on ws://127.0.0.1:38303/devtools/browser/315b2905-a4d7-41ce-a8cd-3e47317baec1\n[ OK ] SystemDisplayApiTest.SetDisplayNotKioskEnabled (756 ms)\n[47/202] SystemDisplayApiTest.SetDisplayNotKioskEnabled (954 ms)\n[ RUN ] SystemDisplayApiTest.SetDisplayKioskEnabled\n\nDevTools listening on ws://127.0.0.1:40755/devtools/browser/32eb35b4-96aa-4b86-aac7-41340bd94daa\n[ OK ] SystemDisplayApiTest.SetDisplayKioskEnabled (911 ms)\n[48/202] SystemDisplayApiTest.SetDisplayKioskEnabled (1087 ms)\n[ RUN ] SystemDisplayApiTest.EnableUnifiedDesktop\n\nDevTools listening on ws://127.0.0.1:33250/devtools/browser/64b91c01-6657-47e3-b981-fa95698b7012\n[ OK ] SystemDisplayApiTest.EnableUnifiedDesktop (713 ms)\n[49/202] SystemDisplayApiTest.EnableUnifiedDesktop (987 ms)\n[ RUN ] SystemDisplayApiTest.GetDisplayInfo\n\nDevTools listening on ws://127.0.0.1:34934/devtools/browser/7b6aad41-f52b-430d-8362-1d95d5a584b5\n[15267:15267:0421/003504.745830:33203189220:INFO:CONSOLE(0)] \"[SUCCESS] testGet\", source: chrome-extension://bkinmoclckljagifknhkidpikmgdllbj/_generated_background_page.html (0)\n[ OK ] SystemDisplayApiTest.GetDisplayInfo (1893 ms)\n[50/202] SystemDisplayApiTest.GetDisplayInfo (2148 ms)\n[ RUN ] SystemDisplayApiTest.OverscanCalibrationStart\n\nDevTools listening on ws://127.0.0.1:46339/devtools/browser/ffa1ae18-f3b1-4863-8222-cdc6c8f9f1f7\n[ OK ] SystemDisplayApiTest.OverscanCalibrationStart (618 ms)\n[51/202] SystemDisplayApiTest.OverscanCalibrationStart (836 ms)\n[ RUN ] SystemCpuApiTest.Cpu\n\nDevTools listening on ws://127.0.0.1:43655/devtools/browser/6374fbf0-c199-4470-b903-ca75ec895bd1\n[15262:15262:0421/003504.825131:33203268526:INFO:CONSOLE(0)] \"[SUCCESS] testGet\", source: chrome-extension://ajldehohkfhkledlcpchnlcbabmklola/_generated_background_page.html (0)\n[ OK ] SystemCpuApiTest.Cpu (2025 ms)\n[52/202] SystemCpuApiTest.Cpu (2405 ms)\n[ RUN ] SystemDisplayApiTest.ShowNativeTouchCalibration\n\nDevTools listening on ws://127.0.0.1:34424/devtools/browser/d66dd931-0927-4a24-9944-41926c1c0553\n[ OK ] SystemDisplayApiTest.ShowNativeTouchCalibration (694 ms)\n[53/202] SystemDisplayApiTest.ShowNativeTouchCalibration (957 ms)\n[ RUN ] SystemDisplayApiTest.ShowNativeTouchCalibrationFail\n\nDevTools listening on ws://127.0.0.1:43287/devtools/browser/5782f176-67c5-466d-aa0a-a6c9159705c4\n[ OK ] SystemDisplayApiTest.ShowNativeTouchCalibrationFail (991 ms)\n[54/202] SystemDisplayApiTest.ShowNativeTouchCalibrationFail (1347 ms)\n[ RUN ] SystemDisplayApiTest.OverscanCalibrationApp\n\nDevTools listening on ws://127.0.0.1:41220/devtools/browser/f8a2dd16-3ae6-4741-b170-bd3ca8df9a5c\n[15528:15528:0421/003505.767795:33204211454:ERROR:device_event_log_impl.cc(162)] [00:35:05.767] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:NotifyDeviceListChanged: 55ms\n[15528:15572:0421/003506.408967:33204852349:ERROR:browser_process_sub_thread.cc(217)] Waited 0 ms for network service\n[ OK ] SystemDisplayApiTest.OverscanCalibrationApp (1376 ms)\n[55/202] SystemDisplayApiTest.OverscanCalibrationApp (1622 ms)\n[ RUN ] SystemDisplayApiTest.OverscanCalibrationAppNoComplete\n\nDevTools listening on ws://127.0.0.1:33767/devtools/browser/b3458087-9735-4756-9fdd-2ad0209d4fd9\n[ OK ] SystemDisplayApiTest.OverscanCalibrationAppNoComplete (1366 ms)\n[56/202] SystemDisplayApiTest.OverscanCalibrationAppNoComplete (1614 ms)\n[ RUN ] SystemDisplayApiTest.SetMirrorMode\n\nDevTools listening on ws://127.0.0.1:45416/devtools/browser/27fcb59a-0a96-4d09-9d23-0eb5f8ffbceb\n[ OK ] SystemDisplayApiTest.SetMirrorMode (909 ms)\n[57/202] SystemDisplayApiTest.SetMirrorMode (1361 ms)\n[ RUN ] SystemMemoryApiTest.Memory\n\nDevTools listening on ws://127.0.0.1:38751/devtools/browser/89799330-4aa6-46ff-9acd-904b496460b7\n[15742:15742:0421/003507.833739:33206277126:INFO:CONSOLE(0)] \"[SUCCESS] testGet\", source: chrome-extension://cdmnihblblmdllfcfaghdgelichpoigg/_generated_background_page.html (0)\n[15742:15788:0421/003507.903332:33206346707:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] SystemMemoryApiTest.Memory (1400 ms)\n[58/202] SystemMemoryApiTest.Memory (1613 ms)\n[ RUN ] SystemNetworkApiTest.SystemNetworkExtension\n\nDevTools listening on ws://127.0.0.1:41246/devtools/browser/52b8d6e5-a36f-4319-bf5b-b908047712f2\n[15747:15747:0421/003507.806970:33206250354:INFO:CONSOLE(0)] \"[SUCCESS] testGetNetworkInterfaces\", source: chrome-extension://peploceeopidapgkpigbcghejkiefdof/_generated_background_page.html (0)\n[15747:15796:0421/003507.886043:33206329425:ERROR:browser_process_sub_thread.cc(217)] Waited 18 ms for network service\n[ OK ] SystemNetworkApiTest.SystemNetworkExtension (1246 ms)\n[59/202] SystemNetworkApiTest.SystemNetworkExtension (1609 ms)\n[ RUN ] SystemStorageApiTest.Storage\n\nDevTools listening on ws://127.0.0.1:43906/devtools/browser/9e8b1e9c-0260-404e-be42-d7bc7bb9e104\n[15748:15748:0421/003507.830783:33206274175:INFO:CONSOLE(0)] \"[SUCCESS] testGetInfo\", source: chrome-extension://lcjpjopkpfobeicjahdpdimkgpbniedm/_generated_background_page.html (0)\n[15748:15748:0421/003507.845601:33206288984:INFO:CONSOLE(0)] \"[SUCCESS] testGetAvailableCapacity\", source: chrome-extension://lcjpjopkpfobeicjahdpdimkgpbniedm/_generated_background_page.html (0)\n[15748:15795:0421/003507.914101:33206357482:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] SystemStorageApiTest.Storage (1243 ms)\n[60/202] SystemStorageApiTest.Storage (1620 ms)\n[ RUN ] SystemStorageApiTest.StorageAttachment\n\nDevTools listening on ws://127.0.0.1:37504/devtools/browser/d6afb44c-81e2-4a93-9b17-ca48213ce04f\n[15953:15953:0421/003508.458142:33206901521:INFO:CONSOLE(0)] \"[SUCCESS] testAttachedEvent\", source: chrome-extension://jjlpgnokdbmbdepnoeedenkmejahomlj/_generated_background_page.html (0)\n[15953:15953:0421/003508.467006:33206910389:INFO:CONSOLE(0)] \"[SUCCESS] testDetachedEvent\", source: chrome-extension://jjlpgnokdbmbdepnoeedenkmejahomlj/_generated_background_page.html (0)\n[15953:15982:0421/003508.529977:33206973361:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] SystemStorageApiTest.StorageAttachment (883 ms)\n[61/202] SystemStorageApiTest.StorageAttachment (1125 ms)\n[ RUN ] SystemStorageEjectApiTest.EjectBadDeviceTest\n\nDevTools listening on ws://127.0.0.1:46275/devtools/browser/cb8c9a2d-9050-4026-acf8-7dd88df69d31\n[ OK ] SystemStorageEjectApiTest.EjectBadDeviceTest (1375 ms)\n[62/202] SystemStorageEjectApiTest.EjectBadDeviceTest (1625 ms)\n[ RUN ] UsbApiTest.DeviceHandling\n\nDevTools listening on ws://127.0.0.1:42796/devtools/browser/839effa8-86fd-4831-8968-61cf62a1e564\n[16042:16042:0421/003509.487225:33207930612:INFO:CONSOLE(0)] \"[SUCCESS] getDevices\", source: chrome-extension://oimogkhbfjpjlbhdedioaaiopoiblomi/_generated_background_page.html (0)\n[16042:16042:0421/003509.511421:33207954805:INFO:CONSOLE(0)] \"[SUCCESS] getConfigurations\", source: chrome-extension://oimogkhbfjpjlbhdedioaaiopoiblomi/_generated_background_page.html (0)\n[16042:16042:0421/003509.525847:33207969235:INFO:CONSOLE(0)] \"[SUCCESS] explicitCloseDevice\", source: chrome-extension://oimogkhbfjpjlbhdedioaaiopoiblomi/_generated_background_page.html (0)\n[ OK ] UsbApiTest.DeviceHandling (1306 ms)\n[63/202] UsbApiTest.DeviceHandling (1616 ms)\n[ RUN ] SystemStorageEjectApiTest.EjectTest\n\nDevTools listening on ws://127.0.0.1:39937/devtools/browser/08fad718-905b-4d78-bdd4-8a1d157d4158\n[ OK ] SystemStorageEjectApiTest.EjectTest (1534 ms)\n[64/202] SystemStorageEjectApiTest.EjectTest (1898 ms)\n[ RUN ] UsbApiTest.ResetDevice\n\nDevTools listening on ws://127.0.0.1:36413/devtools/browser/693d377e-dec6-400a-90ab-4c4887310b85\n[16086:16086:0421/003509.937438:33208380820:INFO:CONSOLE(0)] \"[SUCCESS] resetDevice\", source: chrome-extension://cagkpdoidbnhhpebmmjlecneljcemoma/_generated_background_page.html (0)\n[16086:16238:0421/003509.987542:33208430916:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] UsbApiTest.ResetDevice (1257 ms)\n[65/202] UsbApiTest.ResetDevice (1610 ms)\n[ RUN ] UsbApiTest.ZeroLengthTransfer\n\nDevTools listening on ws://127.0.0.1:43529/devtools/browser/46a28979-faf3-4b73-8819-b3038cd63437\n[16320:16320:0421/003511.486137:33209929529:INFO:CONSOLE(0)] \"[SUCCESS] zeroLengthTransfer\", source: chrome-extension://ilbmbaodnpbmlnfajeogckkbhbmfhgkj/_generated_background_page.html (0)\n[16320:16394:0421/003511.535982:33209979364:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] UsbApiTest.ZeroLengthTransfer (1193 ms)\n[66/202] UsbApiTest.ZeroLengthTransfer (1347 ms)\n[ RUN ] UsbApiTest.SetConfiguration\n\nDevTools listening on ws://127.0.0.1:45210/devtools/browser/04935777-4c8b-4b86-9272-eddc12aa235f\n[16304:16304:0421/003511.338365:33209781753:INFO:CONSOLE(0)] \"[SUCCESS] setConfiguration\", source: chrome-extension://ckcmgoddccbfdhcclolpemoikdlfalef/_generated_background_page.html (0)\n[16304:16343:0421/003511.396680:33209840076:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] UsbApiTest.SetConfiguration (1528 ms)\n[67/202] UsbApiTest.SetConfiguration (1877 ms)\n[ RUN ] UsbApiTest.ListInterfaces\n\nDevTools listening on ws://127.0.0.1:46516/devtools/browser/a8641ba3-dd2b-4a2c-85cf-b3f833ea8065\n[16305:16305:0421/003511.361145:33209804527:INFO:CONSOLE(0)] \"[SUCCESS] listInterfaces\", source: chrome-extension://ineijkcbfhdikcboocllcejimmhhnebn/_generated_background_page.html (0)\n[16305:16352:0421/003511.445021:33209888409:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] UsbApiTest.ListInterfaces (1524 ms)\n[68/202] UsbApiTest.ListInterfaces (1872 ms)\n[ RUN ] UsbApiTest.TransferEvent\n\nDevTools listening on ws://127.0.0.1:46136/devtools/browser/589dc21d-e34f-4a85-8237-28873836ebd4\n[16308:16308:0421/003511.401768:33209845154:INFO:CONSOLE(0)] \"[SUCCESS] controlTransfer\", source: chrome-extension://gdicjfjadakkdflaaacoojocdddlfcde/_generated_background_page.html (0)\n[16308:16308:0421/003511.414639:33209858023:INFO:CONSOLE(0)] \"[SUCCESS] bulkTransfer\", source: chrome-extension://gdicjfjadakkdflaaacoojocdddlfcde/_generated_background_page.html (0)\n[16308:16308:0421/003511.428592:33209871974:INFO:CONSOLE(0)] \"[SUCCESS] interruptTransfer\", source: chrome-extension://gdicjfjadakkdflaaacoojocdddlfcde/_generated_background_page.html (0)\n[16308:16308:0421/003511.439355:33209882740:INFO:CONSOLE(0)] \"[SUCCESS] isochronousTransfer\", source: chrome-extension://gdicjfjadakkdflaaacoojocdddlfcde/_generated_background_page.html (0)\n[16308:16351:0421/003511.515836:33209959212:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] UsbApiTest.TransferEvent (1502 ms)\n[69/202] UsbApiTest.TransferEvent (1872 ms)\n[ RUN ] UsbApiTest.InvalidTimeout\n\nDevTools listening on ws://127.0.0.1:34911/devtools/browser/c8d4cb0c-8873-42b4-9061-98b324da4dc5\n[16573:16573:0421/003513.134209:33211577620:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://amdhgpkfemkjpgnomphgbcipfmgmdehp/_generated_background_page.html (0)\n[16573:16573:0421/003513.145325:33211588699:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://amdhgpkfemkjpgnomphgbcipfmgmdehp/_generated_background_page.html (0)\n[16573:16573:0421/003513.154730:33211598115:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://amdhgpkfemkjpgnomphgbcipfmgmdehp/_generated_background_page.html (0)\n[16573:16573:0421/003513.164045:33211607428:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://amdhgpkfemkjpgnomphgbcipfmgmdehp/_generated_background_page.html (0)\n[16573:16612:0421/003513.228229:33211671612:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] UsbApiTest.InvalidTimeout (1436 ms)\n[70/202] UsbApiTest.InvalidTimeout (1615 ms)\n[ RUN ] UsbApiTest.OnDeviceAdded\n\nDevTools listening on ws://127.0.0.1:38470/devtools/browser/baf8762f-558d-4dca-9b78-c17a8025b2cc\n[16576:16616:0421/003513.335134:33211778511:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] UsbApiTest.OnDeviceAdded (1448 ms)\n[71/202] UsbApiTest.OnDeviceAdded (1616 ms)\n[ RUN ] UsbApiTest.TransferFailure\n\nDevTools listening on ws://127.0.0.1:37359/devtools/browser/6eb5af5a-985f-4ac4-b502-b6a7cb1f8f58\n[16569:16569:0421/003513.168728:33211612111:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://jfbocmapdffdpambggllmfoaphmaboac/_generated_background_page.html (0)\n[16569:16569:0421/003513.185904:33211629286:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://jfbocmapdffdpambggllmfoaphmaboac/_generated_background_page.html (0)\n[16569:16569:0421/003513.202677:33211646078:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://jfbocmapdffdpambggllmfoaphmaboac/_generated_background_page.html (0)\n[16569:16569:0421/003513.213511:33211656888:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://jfbocmapdffdpambggllmfoaphmaboac/_generated_background_page.html (0)\n[16569:16569:0421/003513.224304:33211667687:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://jfbocmapdffdpambggllmfoaphmaboac/_generated_background_page.html (0)\n[16569:16624:0421/003513.286300:33211729684:ERROR:browser_process_sub_thread.cc(217)] Waited 11 ms for network service\n[ OK ] UsbApiTest.TransferFailure (1605 ms)\n[72/202] UsbApiTest.TransferFailure (1872 ms)\n[ RUN ] UsbApiTest.InvalidLengthTransfer\n\nDevTools listening on ws://127.0.0.1:45518/devtools/browser/5a093753-f320-4d7a-9bbb-1edfa3131052\n[16570:16570:0421/003513.135671:33211579089:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.148459:33211591831:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.158445:33211601828:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.173253:33211616637:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.184726:33211628111:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.194461:33211637847:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.205017:33211648410:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.221216:33211664597:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.235672:33211679055:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.254604:33211697983:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.267845:33211711227:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.278308:33211721691:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16570:0421/003513.289683:33211733078:INFO:CONSOLE(0)] \"[SUCCESS] undefined\", source: chrome-extension://clfafekapmkhhibalmodfbifgldpifob/_generated_background_page.html (0)\n[16570:16620:0421/003513.360701:33211804086:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] UsbApiTest.InvalidLengthTransfer (1606 ms)\n[73/202] UsbApiTest.InvalidLengthTransfer (1876 ms)\n[ RUN ] UsbApiTest.OnDeviceRemoved\n\nDevTools listening on ws://127.0.0.1:41383/devtools/browser/15b5364f-8a72-4705-9c91-4a118c22f686\n[16837:16865:0421/003514.886693:33213330081:ERROR:browser_process_sub_thread.cc(217)] Waited 9 ms for network service\n[ OK ] UsbApiTest.OnDeviceRemoved (1468 ms)\n[74/202] UsbApiTest.OnDeviceRemoved (1619 ms)\n[ RUN ] UsbApiTest.GetUserSelectedDevices\n\nDevTools listening on ws://127.0.0.1:37945/devtools/browser/814d9e63-b37a-4e66-a8dd-167549f09487\n[16839:16894:0421/003514.974943:33213418328:ERROR:browser_process_sub_thread.cc(217)] Waited 16 ms for network service\n[ OK ] UsbApiTest.GetUserSelectedDevices (1467 ms)\n[75/202] UsbApiTest.GetUserSelectedDevices (1613 ms)\n[ RUN ] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPage\n\nDevTools listening on ws://127.0.0.1:43142/devtools/browser/e396655d-f79b-41c8-aa7b-a2bf2b55ebd2\n[ OK ] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPage (1373 ms)\n[76/202] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPage (1613 ms)\n[ RUN ] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPageNoWait\n\nDevTools listening on ws://127.0.0.1:45111/devtools/browser/073c98ba-2187-42c7-8dcb-294097c7bf3f\n[16846:16899:0421/003514.937077:33213380460:ERROR:browser_process_sub_thread.cc(217)] Waited 7 ms for network service\n[ OK ] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPageNoWait (1302 ms)\n[77/202] ExtensionBrowsertestUtilTest.ExecuteScriptInBackgroundPageNoWait (1619 ms)\n[ RUN ] AppViewTest.TestAppViewGoodDataShouldSucceed\n\nDevTools listening on ws://127.0.0.1:37499/devtools/browser/9b66d9a8-0e83-4721-a7fb-9a6c866442c3\n[17100:17100:0421/003515.785894:33214229284:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/ir/extensions/test/data/app_view/apitest/skeleton:\n[17100:17100:0421/003515.786051:33214229429:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'minimum_chrome_version'.\n[17100:17100:0421/003516.943201:33215386590:INFO:CONSOLE(20)] \"appToEmbed afbbknndjeagpaekchjbbdmgnmhpeomh\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (20)\n[17100:17100:0421/003517.100441:33215543828:INFO:CONSOLE(20)] \"Attempting to connect to app with good params.\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (20)\n[17100:17100:0421/003517.667422:33216110809:INFO:CONSOLE(20)] \"Connected.\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (20)\n[17100:17130:0421/003517.840881:33216284264:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] AppViewTest.TestAppViewGoodDataShouldSucceed (2806 ms)\n[78/202] AppViewTest.TestAppViewGoodDataShouldSucceed (3206 ms)\n[ RUN ] WebViewAPITest.EmbedderVisibilityChanged\n\nDevTools listening on ws://127.0.0.1:35578/devtools/browser/5e20f7bd-f636-48c3-a2c5-6e97dc14b840\n[17107:17107:0421/003517.993110:33216436496:INFO:CONSOLE(6)] \"onAppCommand: hide-embedder\", source: chrome-extension://njoflokikcjbllkjjimeagdoledhbija/main.js (6)\n[17107:17158:0421/003518.148649:33216592048:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] WebViewAPITest.EmbedderVisibilityChanged (2981 ms)\n[79/202] WebViewAPITest.EmbedderVisibilityChanged (3188 ms)\n[ RUN ] WebViewAPITest.DisplayNoneSetSrc\n\nDevTools listening on ws://127.0.0.1:36026/devtools/browser/4218944f-4a59-45b2-8db2-6b54e9daac6f\n[17110:17110:0421/003517.480140:33215923525:INFO:CONSOLE(6)] \"onAppCommand: navigate-guest\", source: chrome-extension://fmgpgbkijfkhoelhgbpcmcmcblflldhk/main.js (6)\n[17110:17110:0421/003517.480802:33215924179:INFO:CONSOLE(34)] \"navigate-guest command\", source: chrome-extension://fmgpgbkijfkhoelhgbpcmcmcblflldhk/main.js (34)\n[17110:17110:0421/003517.558808:33216002193:INFO:CONSOLE(6)] \"onAppCommand: navigate-guest\", source: chrome-extension://fmgpgbkijfkhoelhgbpcmcmcblflldhk/main.js (6)\n[17110:17110:0421/003517.558995:33216002378:INFO:CONSOLE(34)] \"navigate-guest command\", source: chrome-extension://fmgpgbkijfkhoelhgbpcmcmcblflldhk/main.js (34)\n[17110:17110:0421/003517.603568:33216046955:INFO:CONSOLE(6)] \"onAppCommand: show-guest\", source: chrome-extension://fmgpgbkijfkhoelhgbpcmcmcblflldhk/main.js (6)\n[17110:17162:0421/003518.144949:33216588339:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.DisplayNoneSetSrc (2895 ms)\n[80/202] WebViewAPITest.DisplayNoneSetSrc (3181 ms)\n[ RUN ] AppViewTest.TestAppViewMediaRequest\n\nDevTools listening on ws://127.0.0.1:36355/devtools/browser/836df216-7f1e-4695-a142-a489e656c9f8\n[17103:17103:0421/003517.105421:33215548809:INFO:CONSOLE(74)] \"appToEmbed pgiodgaahpiagdbhliednffmhidckege\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (74)\n[17103:17103:0421/003517.199109:33215642498:INFO:CONSOLE(76)] \"Attempting to connect to app.\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (76)\n[17103:17103:0421/003517.984351:33216427738:INFO:CONSOLE(84)] \"Connected.\", source: chrome-extension://edgmjfmjpplaibnccnlbgaaakabpghcm/main.js (84)\n[17103:17166:0421/003519.403586:33217846962:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] AppViewTest.TestAppViewMediaRequest (4284 ms)\n[81/202] AppViewTest.TestAppViewMediaRequest (4504 ms)\n[ RUN ] WebViewAPITest.GuestVisibilityChanged\n\nDevTools listening on ws://127.0.0.1:39733/devtools/browser/ab791172-69ad-4c78-a1be-5ff650364ecf\n[17417:17417:0421/003520.905578:33219348963:INFO:CONSOLE(6)] \"onAppCommand: hide-guest\", source: chrome-extension://njoflokikcjbllkjjimeagdoledhbija/main.js (6)\n[17417:17462:0421/003521.075138:33219518525:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] WebViewAPITest.GuestVisibilityChanged (2840 ms)\n[82/202] WebViewAPITest.GuestVisibilityChanged (3187 ms)\n[ RUN ] WebViewAPITest.CloseOnLoadcommit\n\nDevTools listening on ws://127.0.0.1:45924/devtools/browser/19c2b7e9-71eb-4c0e-b655-28dc2c5f1d24\n[17420:17467:0421/003521.570866:33220014243:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.CloseOnLoadcommit (3211 ms)\n[83/202] WebViewAPITest.CloseOnLoadcommit (3447 ms)\n[ RUN ] WebViewAPITest.ReloadEmbedder\n\nDevTools listening on ws://127.0.0.1:42897/devtools/browser/ff0c6d00-fc6b-4d0f-851b-89967713ded6\n[ OK ] WebViewAPITest.ReloadEmbedder (3418 ms)\n[84/202] WebViewAPITest.ReloadEmbedder (3707 ms)\n[ RUN ] WebViewAPITest.TestAllowTransparencyAttribute\n\nDevTools listening on ws://127.0.0.1:38598/devtools/browser/2adac7e6-a126-4cb9-ab4d-e5ddf5f2493c\n[17629:17644:0421/003522.232623:33220676018:ERROR:browser_process_sub_thread.cc(217)] Waited 12 ms for network service\n[ OK ] WebViewAPITest.TestAllowTransparencyAttribute (2584 ms)\n[85/202] WebViewAPITest.TestAllowTransparencyAttribute (2926 ms)\n[ RUN ] WebViewAPITest.TestCustomElementCallbacksInaccessible\n\nDevTools listening on ws://127.0.0.1:44836/devtools/browser/404dc775-7707-4c62-a2c2-40a4915e44ac\n[17797:17882:0421/003523.998930:33222442308:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestCustomElementCallbacksInaccessible (2169 ms)\n[86/202] WebViewAPITest.TestCustomElementCallbacksInaccessible (2407 ms)\n[ RUN ] WebViewAPITest.TestAPIMethodExistence\n\nDevTools listening on ws://127.0.0.1:32973/devtools/browser/2f00fef2-3c0b-4a38-950a-c9de9b726ebd\n[17738:17790:0421/003523.987954:33222431339:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestAPIMethodExistence (2540 ms)\n[87/202] WebViewAPITest.TestAPIMethodExistence (2936 ms)\n[ RUN ] WebViewAPITest.TestAutosizeAfterNavigation\n\nDevTools listening on ws://127.0.0.1:39672/devtools/browser/3ba5b87b-9d33-487a-a126-96042f3cc309\n[17895:17966:0421/003525.463787:33223907179:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestAutosizeAfterNavigation (2896 ms)\n[88/202] WebViewAPITest.TestAutosizeAfterNavigation (3189 ms)\n[ RUN ] WebViewAPITest.TestAssignSrcAfterCrash\n\nDevTools listening on ws://127.0.0.1:44263/devtools/browser/38fab229-3a79-4a24-8e5c-e0da879088b8\n[17852:17852:0421/003525.056857:33223500243:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -3: ERR_ABORTED.\", source: extensions::webViewEvents (268)\n[17852:17947:0421/003525.574366:33224017741:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestAssignSrcAfterCrash (3404 ms)\n[89/202] WebViewAPITest.TestAssignSrcAfterCrash (3734 ms)\n[ RUN ] WebViewAPITest.TestAutosizeHeight\n\nDevTools listening on ws://127.0.0.1:36345/devtools/browser/eefb7491-b3ff-4deb-add9-5d18b6403be3\n[18079:18120:0421/003527.078954:33225522329:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestAutosizeHeight (2708 ms)\n[90/202] WebViewAPITest.TestAutosizeHeight (2928 ms)\n[ RUN ] WebViewAPITest.TestAutosizeBeforeNavigation\n\nDevTools listening on ws://127.0.0.1:43808/devtools/browser/fe19d8be-82a9-406c-b59d-e20fc41f6982\n[18075:18106:0421/003527.026960:33225470345:ERROR:browser_process_sub_thread.cc(217)] Waited 16 ms for network service\n[ OK ] WebViewAPITest.TestAutosizeBeforeNavigation (2808 ms)\n[91/202] WebViewAPITest.TestAutosizeBeforeNavigation (3190 ms)\n[ RUN ] WebViewAPITest.TestCannotMutateEventName\n\nDevTools listening on ws://127.0.0.1:33893/devtools/browser/c92a6f7e-15ef-4437-9b60-726ce76fad53\n[ OK ] WebViewAPITest.TestCannotMutateEventName (2452 ms)\n[92/202] WebViewAPITest.TestCannotMutateEventName (2660 ms)\n[ RUN ] WebViewAPITest.TestAutosizeRemoveAttributes\n\nDevTools listening on ws://127.0.0.1:35600/devtools/browser/da97e029-eed8-4edd-b927-d12a179bdda8\n[18248:18272:0421/003528.652985:33227096378:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestAutosizeRemoveAttributes (2903 ms)\n[93/202] WebViewAPITest.TestAutosizeRemoveAttributes (3192 ms)\n[ RUN ] WebViewAPITest.TestChromeExtensionURL\n\nDevTools listening on ws://127.0.0.1:33315/devtools/browser/3f703d47-f21a-4a0d-8103-4a16aabb239a\n[18414:18414:0421/003529.819895:33228263280:INFO:CONSOLE(14)] \"guest_with_inline_script.html: Inline script ran\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/guest_with_inline_script.html (14)\n[18414:18434:0421/003530.006230:33228449618:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestChromeExtensionURL (2581 ms)\n[94/202] WebViewAPITest.TestChromeExtensionURL (2928 ms)\n[ RUN ] WebViewAPITest.TestChromeExtensionRelativePath\n\nDevTools listening on ws://127.0.0.1:44961/devtools/browser/9c6d138b-62a2-4d37-a35b-1d8e475e1ebb\n[18411:18411:0421/003529.969990:33228413387:INFO:CONSOLE(14)] \"guest_with_inline_script.html: Inline script ran\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/guest_with_inline_script.html (14)\n[18411:18438:0421/003530.160985:33228604361:ERROR:browser_process_sub_thread.cc(217)] Waited 7 ms for network service\n[ OK ] WebViewAPITest.TestChromeExtensionRelativePath (2856 ms)\n[95/202] WebViewAPITest.TestChromeExtensionRelativePath (3192 ms)\n[ RUN ] WebViewAPITest.TestContentLoadEvent\n\nDevTools listening on ws://127.0.0.1:46867/devtools/browser/b3c84263-d879-4dc2-bf73-469ea4e95799\n[18584:18609:0421/003531.356102:33229799488:ERROR:browser_process_sub_thread.cc(217)] Waited 16 ms for network service\n[ OK ] WebViewAPITest.TestContentLoadEvent (2809 ms)\n[96/202] WebViewAPITest.TestContentLoadEvent (3186 ms)\n[ RUN ] WebViewAPITest.TestContextMenu\n[18596:18596:0421/003529.485652:33227929039:ERROR:device_event_log_impl.cc(162)] [00:35:29.485] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:NotifyDeviceListChanged: 53ms\n[18596:18596:0421/003529.486402:33227929787:ERROR:device_event_log_impl.cc(162)] [00:35:29.486] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:ManagedStateListChanged: 58ms\n\nDevTools listening on ws://127.0.0.1:40295/devtools/browser/26f9013a-3844-48db-9e13-357f357d0c03\n[18596:18629:0421/003531.557662:33230001037:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestContextMenu (2602 ms)\n[97/202] WebViewAPITest.TestContextMenu (2927 ms)\n[ RUN ] WebViewAPITest.TestDeclarativeWebRequestAPISendMessage\n\nDevTools listening on ws://127.0.0.1:39831/devtools/browser/a6ba906c-7916-4aca-bf5c-e1a7dd73c913\n[18758:18758:0421/003532.198439:33230641832:INFO:CONSOLE(576)] \"http://localhost:44721/empty_guest.html\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (576)\n[18758:18778:0421/003532.771172:33231214555:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[18758:18778:0421/003532.783794:33231227167:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[18758:18778:0421/003532.813677:33231257052:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestDeclarativeWebRequestAPISendMessage (2329 ms)\n[98/202] WebViewAPITest.TestDeclarativeWebRequestAPISendMessage (2663 ms)\n[ RUN ] WebViewAPITest.TestDeclarativeWebRequestAPI\n\nDevTools listening on ws://127.0.0.1:34831/devtools/browser/ce153f86-27a4-4b19-8ecb-2c53553d51ea\n[18755:18755:0421/003532.868688:33231312084:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -20: ERR_BLOCKED_BY_CLIENT.\", source: extensions::webViewEvents (268)\n[18755:18782:0421/003532.986883:33231430260:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestDeclarativeWebRequestAPI (2642 ms)\n[99/202] WebViewAPITest.TestDeclarativeWebRequestAPI (2927 ms)\n[ RUN ] WebViewAPITest.TestDestroyOnEventListener\n\nDevTools listening on ws://127.0.0.1:43885/devtools/browser/32fcdba4-46c4-47f2-bc8e-b8289f2840d3\n[18919:18919:0421/003534.150008:33232593400:INFO:CONSOLE(625)] \"loadcommit1\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (625)\n[18919:18919:0421/003534.197703:33232641106:INFO:CONSOLE(630)] \"loadcommit2\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (630)\n[18919:18937:0421/003534.369873:33232813270:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestDestroyOnEventListener (2643 ms)\n[100/202] WebViewAPITest.TestDestroyOnEventListener (2927 ms)\n[ RUN ] WebViewAPITest.TestDialogAlert\n\nDevTools listening on ws://127.0.0.1:40168/devtools/browser/ae6c1268-d022-42e7-a4ae-60296ff2ef58\n[18923:18923:0421/003534.502253:33232945661:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[18923:18923:0421/003534.733245:33233176632:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[18923:18923:0421/003534.757537:33233200922:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[18923:18923:0421/003534.759536:33233202915:INFO:CONSOLE(113)] \"The alert dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (113)\n[18923:18923:0421/003534.783583:33233226968:INFO:CONSOLE(128)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (128)\n[18923:18923:0421/003534.788264:33233231648:INFO:CONSOLE(132)] \"The app has responded to the dialog request.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (132)\n[18923:18923:0421/003534.793593:33233236975:INFO:CONSOLE(120)] \"webview has been unblocked after requesting an alert dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (120)\n[18923:18955:0421/003534.939397:33233382784:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestDialogAlert (3075 ms)\n[101/202] WebViewAPITest.TestDialogAlert (3457 ms)\n[ RUN ] WebViewAPITest.TestDialogConfirm\n\nDevTools listening on ws://127.0.0.1:43480/devtools/browser/27eaf54e-bc1e-41c6-9f9d-dbc773ae0293\n[19094:19094:0421/003535.606071:33234049461:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[19094:19094:0421/003535.835295:33234278681:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[19094:19094:0421/003535.864126:33234307512:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[19094:19094:0421/003535.864358:33234307735:INFO:CONSOLE(143)] \"The confirm dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (143)\n[19094:19094:0421/003535.889053:33234332441:INFO:CONSOLE(158)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (158)\n[19094:19094:0421/003535.896243:33234339627:INFO:CONSOLE(162)] \"The app has responded to the dialog request.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (162)\n[19094:19094:0421/003535.899626:33234343009:INFO:CONSOLE(150)] \"webview has reported a result for its confirm dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (150)\n[19094:19117:0421/003536.089355:33234532738:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] WebViewAPITest.TestDialogConfirm (2943 ms)\n[102/202] WebViewAPITest.TestDialogConfirm (3187 ms)\n[ RUN ] WebViewAPITest.TestDialogConfirmCancel\n\nDevTools listening on ws://127.0.0.1:33462/devtools/browser/5240a9c0-aa29-4f81-a9ce-fdeb4f294693\n[19097:19097:0421/003535.783158:33234226542:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[19097:19097:0421/003536.008188:33234451574:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[19097:19097:0421/003536.033336:33234476721:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[19097:19097:0421/003536.034334:33234477712:INFO:CONSOLE(173)] \"The confirm dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (173)\n[19097:19097:0421/003536.057904:33234501289:INFO:CONSOLE(188)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (188)\n[19097:19097:0421/003536.063466:33234506850:INFO:CONSOLE(192)] \"The app has responded to the dialog request.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (192)\n[19097:19097:0421/003536.068589:33234511975:INFO:CONSOLE(180)] \"webview has reported a result for its confirm dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (180)\n[19097:19121:0421/003536.211354:33234654729:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestDialogConfirmCancel (2947 ms)\n[103/202] WebViewAPITest.TestDialogConfirmCancel (3204 ms)\n[ RUN ] WebViewAPITest.TestDialogConfirmDefaultCancel\n\nDevTools listening on ws://127.0.0.1:35795/devtools/browser/1fe47e56-d8d3-4e79-88a8-67b1bacada1f\n[19250:19250:0421/003536.846257:33235289648:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[19250:19250:0421/003537.083829:33235527216:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[19250:19250:0421/003537.117651:33235561034:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[19250:19250:0421/003537.117847:33235561217:INFO:CONSOLE(203)] \"The confirm dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (203)\n[19250:19250:0421/003537.146378:33235589763:INFO:CONSOLE(218)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (218)\n[19250:19250:0421/003537.173943:33235617329:INFO:CONSOLE(162)] \"<webview>: A confirm dialog was blocked.\", source: extensions::webViewActionRequests (162)\n[19250:19250:0421/003537.176736:33235620118:INFO:CONSOLE(210)] \"webview has reported a result for its confirm dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (210)\n[19250:19274:0421/003537.374664:33235818056:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestDialogConfirmDefaultCancel (2712 ms)\n[104/202] WebViewAPITest.TestDialogConfirmDefaultCancel (2926 ms)\n[ RUN ] WebViewAPITest.TestDialogConfirmDefaultGCCancel\n\nDevTools listening on ws://127.0.0.1:33698/devtools/browser/6c053c6d-3988-44bf-bbde-03117a5a52fa\n[19304:19304:0421/003537.703934:33236147321:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[19304:19304:0421/003537.934732:33236378119:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[19304:19304:0421/003537.956963:33236400350:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[19304:19304:0421/003537.957923:33236401306:INFO:CONSOLE(231)] \"The confirm dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (231)\n[19304:19304:0421/003537.978828:33236422216:INFO:CONSOLE(246)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (246)\n[19304:19304:0421/003538.042071:33236485454:INFO:CONSOLE(162)] \"<webview>: A confirm dialog was blocked.\", source: extensions::webViewActionRequests (162)\n[19304:19304:0421/003538.044340:33236487718:INFO:CONSOLE(238)] \"webview has reported a result for its confirm dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (238)\n[19304:19361:0421/003538.224180:33236667570:ERROR:browser_process_sub_thread.cc(217)] Waited 22 ms for network service\n[ OK ] WebViewAPITest.TestDialogConfirmDefaultGCCancel (2880 ms)\n[105/202] WebViewAPITest.TestDialogConfirmDefaultGCCancel (3203 ms)\n[ RUN ] WebViewAPITest.TestDisplayNoneWebviewLoad\n\nDevTools listening on ws://127.0.0.1:46055/devtools/browser/2ec0fbaf-5ca1-4d50-a42d-99ab17c05ce8\n[19424:19456:0421/003539.008513:33237451905:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] WebViewAPITest.TestDisplayNoneWebviewLoad (2533 ms)\n[106/202] WebViewAPITest.TestDisplayNoneWebviewLoad (2924 ms)\n[ RUN ] WebViewAPITest.TestDialogPrompt\n\nDevTools listening on ws://127.0.0.1:43065/devtools/browser/f718c543-783f-4346-a344-10e683624358\n[19421:19421:0421/003538.924797:33237368184:INFO:CONSOLE(77)] \"webview has loaded.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (77)\n[19421:19421:0421/003539.141183:33237584567:INFO:CONSOLE(81)] \"Script has been injected into webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (81)\n[19421:19421:0421/003539.160879:33237604270:INFO:CONSOLE(92)] \"A communication channel has been established with webview.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (92)\n[19421:19421:0421/003539.162477:33237605856:INFO:CONSOLE(264)] \"The prompt dialog test has started.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (264)\n[19421:19421:0421/003539.186315:33237629696:INFO:CONSOLE(279)] \"webview has requested a dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (279)\n[19421:19421:0421/003539.196773:33237640157:INFO:CONSOLE(284)] \"The app has responded to the dialog request.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (284)\n[19421:19421:0421/003539.200854:33237644241:INFO:CONSOLE(271)] \"webview has reported a result for its prompt dialog.\", source: chrome-extension://cfociaogdekheojfbgljemgknfbolfic/embedder.js (271)\n[19421:19460:0421/003539.347545:33237790932:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestDialogPrompt (3008 ms)\n[107/202] WebViewAPITest.TestDialogPrompt (3192 ms)\n[ RUN ] WebViewAPITest.TestEventName\n\nDevTools listening on ws://127.0.0.1:43365/devtools/browser/fcc0187e-d068-4b1e-9849-78abe88e6aa4\n[ OK ] WebViewAPITest.TestEventName (2594 ms)\n[108/202] WebViewAPITest.TestEventName (2929 ms)\n[ RUN ] WebViewAPITest.TestExecuteScript\n\nDevTools listening on ws://127.0.0.1:38697/devtools/browser/fc9d41c0-a879-4229-bfa8-e2e2e8cf03a5\n[19653:19690:0421/003541.272035:33239715429:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestExecuteScript (2745 ms)\n[109/202] WebViewAPITest.TestExecuteScript (2924 ms)\n[ RUN ] WebViewAPITest.TestExecuteScriptFail\n\nDevTools listening on ws://127.0.0.1:40053/devtools/browser/af3de8de-9a37-41e0-bf8c-71c5fa58397c\n[19751:19751:0421/003541.480080:33239923454:INFO:CONSOLE(194)] \"<webview>: Script cannot be injected into content until the page has loaded.\", source: extensions::webView (194)\n[19751:19782:0421/003541.587846:33240031222:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestExecuteScriptFail (2186 ms)\n[110/202] WebViewAPITest.TestExecuteScriptFail (2405 ms)\n[ RUN ] WebViewAPITest.TestFindAPI\n\nDevTools listening on ws://127.0.0.1:38659/devtools/browser/8340349f-0723-48aa-b6c1-3025dd10dfd9\n[19756:19800:0421/003542.801323:33241244699:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestFindAPI (3262 ms)\n[111/202] WebViewAPITest.TestFindAPI (3448 ms)\n[ RUN ] WebViewAPITest.TestFindAPI_findupdate\n\nDevTools listening on ws://127.0.0.1:43026/devtools/browser/4237f7e1-b749-49d7-b9b0-7fba63f888c4\n[19893:19918:0421/003543.526179:33241969570:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] WebViewAPITest.TestFindAPI_findupdate (3029 ms)\n[112/202] WebViewAPITest.TestFindAPI_findupdate (3188 ms)\n[ RUN ] WebViewAPITest.TestGetProcessId\n\nDevTools listening on ws://127.0.0.1:41020/devtools/browser/5e996a8f-cda7-4f80-9ab1-14c9af23433f\n[19973:20006:0421/003544.496865:33242940251:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestGetProcessId (3042 ms)\n[113/202] WebViewAPITest.TestGetProcessId (3451 ms)\n[ RUN ] WebViewAPITest.TestHiddenBeforeNavigation\n\nDevTools listening on ws://127.0.0.1:42526/devtools/browser/28f7f6bc-5444-453b-938f-f370aa832529\n[19988:19988:0421/003544.352823:33242796208:INFO:CONSOLE(941)] \"webview.loadstop\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (941)\n[19988:19988:0421/003544.557086:33243000471:INFO:CONSOLE(956)] \"script injection success\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (956)\n[19988:20026:0421/003544.696980:33243140357:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestHiddenBeforeNavigation (2877 ms)\n[114/202] WebViewAPITest.TestHiddenBeforeNavigation (3190 ms)\n[ RUN ] WebViewAPITest.TestInlineScriptFromAccessibleResources\n\nDevTools listening on ws://127.0.0.1:46779/devtools/browser/97e3b2bb-d831-4dd3-b085-d45262954e16\n[20133:20133:0421/003545.330222:33243773612:INFO:CONSOLE(14)] \"guest_with_inline_script.html: Inline script ran\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/guest_with_inline_script.html (14)\n[20133:20133:0421/003545.344098:33243787487:INFO:CONSOLE(976)] \"consolemessage: guest_with_inline_script.html: Inline script ran\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (976)\n[20133:20146:0421/003545.514918:33243958300:ERROR:browser_process_sub_thread.cc(217)] Waited 2 ms for network service\n[ OK ] WebViewAPITest.TestInlineScriptFromAccessibleResources (2542 ms)\n[115/202] WebViewAPITest.TestInlineScriptFromAccessibleResources (2923 ms)\n[ RUN ] WebViewAPITest.TestInvalidChromeExtensionURL\n\nDevTools listening on ws://127.0.0.1:34658/devtools/browser/8d62f3bc-ae52-4000-b947-a073fdfe2fe5\n[20165:20230:0421/003546.091124:33244534505:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] WebViewAPITest.TestInvalidChromeExtensionURL (2371 ms)\n[116/202] WebViewAPITest.TestInvalidChromeExtensionURL (2685 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortChromeExtensionURLWrongPartition\n\nDevTools listening on ws://127.0.0.1:45507/devtools/browser/da8ddb66-985f-465c-ab1b-7be8a8388596\n[20305:20346:0421/003547.592029:33246035428:ERROR:browser_process_sub_thread.cc(217)] Waited 12 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortChromeExtensionURLWrongPartition (2694 ms)\n[117/202] WebViewAPITest.TestLoadAbortChromeExtensionURLWrongPartition (2924 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortEmptyResponse\n\nDevTools listening on ws://127.0.0.1:45666/devtools/browser/5db72c2e-754e-4b6e-bfbb-84acf2d40e41\n[20306:20342:0421/003547.615966:33246059352:ERROR:browser_process_sub_thread.cc(217)] Waited 0 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortEmptyResponse (2621 ms)\n[118/202] WebViewAPITest.TestLoadAbortEmptyResponse (2928 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortIllegalChromeURL\n\nDevTools listening on ws://127.0.0.1:44669/devtools/browser/19e9bed1-7b6b-479a-a6da-3c8d06196f02\n[20440:20440:0421/003547.933592:33246376985:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -301: ERR_DISALLOWED_URL_SCHEME.\", source: extensions::webViewEvents (268)\n[20440:20484:0421/003548.204046:33246647433:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortIllegalChromeURL (2270 ms)\n[119/202] WebViewAPITest.TestLoadAbortIllegalChromeURL (2707 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortIllegalFileURL\n\nDevTools listening on ws://127.0.0.1:34044/devtools/browser/bd09899d-82e9-4e89-ad48-5c5f56e070cc\n[20495:20495:0421/003548.402934:33246846328:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -301: ERR_DISALLOWED_URL_SCHEME.\", source: extensions::webViewEvents (268)\n[20495:20546:0421/003548.884336:33247327710:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortIllegalFileURL (2454 ms)\n[120/202] WebViewAPITest.TestLoadAbortIllegalFileURL (2674 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortInvalidNavigation\n\nDevTools listening on ws://127.0.0.1:36775/devtools/browser/526a4f5f-a786-472f-844c-103090cb8af6\n[20645:20645:0421/003550.260280:33248703688:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -300: ERR_INVALID_URL.\", source: extensions::webViewEvents (268)\n[20645:20688:0421/003550.604575:33249047957:ERROR:browser_process_sub_thread.cc(217)] Waited 19 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortInvalidNavigation (2659 ms)\n[121/202] WebViewAPITest.TestLoadAbortInvalidNavigation (2929 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortIllegalJavaScriptURL\n\nDevTools listening on ws://127.0.0.1:33771/devtools/browser/1189b44f-3ae3-40f2-943a-0d004b1c8380\n[20644:20644:0421/003550.271544:33248714934:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -301: ERR_DISALLOWED_URL_SCHEME.\", source: extensions::webViewEvents (268)\n[20644:20684:0421/003550.616995:33249060379:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortIllegalJavaScriptURL (2794 ms)\n[122/202] WebViewAPITest.TestLoadAbortIllegalJavaScriptURL (3188 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortNonWebSafeScheme\n\nDevTools listening on ws://127.0.0.1:46359/devtools/browser/5e3f2755-ece4-4af1-9bcb-5755fb9230a3\n[20724:20724:0421/003550.665383:33249108770:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -301: ERR_DISALLOWED_URL_SCHEME.\", source: extensions::webViewEvents (268)\n[20724:20816:0421/003550.950023:33249393402:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestLoadAbortNonWebSafeScheme (2297 ms)\n[123/202] WebViewAPITest.TestLoadAbortNonWebSafeScheme (2670 ms)\n[ RUN ] WebViewAPITest.TestLoadAbortUnknownScheme\n\nDevTools listening on ws://127.0.0.1:44073/devtools/browser/0ae3cb22-7962-4b10-971b-1e125920d347\n[20826:20826:0421/003551.266150:33249709536:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -302: ERR_UNKNOWN_URL_SCHEME.\", source: extensions::webViewEvents (268)\n[ OK ] WebViewAPITest.TestLoadAbortUnknownScheme (2427 ms)\n[124/202] WebViewAPITest.TestLoadAbortUnknownScheme (2661 ms)\n[ RUN ] WebViewAPITest.TestCanGoBack\n\nDevTools listening on ws://127.0.0.1:36174/devtools/browser/f597362f-a17f-4932-843e-f1702d82cd48\n[20982:20982:0421/003553.418786:33251862179:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -11: ERR_NOT_IMPLEMENTED.\", source: extensions::webViewEvents (268)\n[20982:21017:0421/003553.618152:33252061539:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestCanGoBack (2605 ms)\n[125/202] WebViewAPITest.TestCanGoBack (2924 ms)\n[ RUN ] WebViewAPITest.TestLoadStartLoadRedirect\n\nDevTools listening on ws://127.0.0.1:41136/devtools/browser/d9a4ff1e-0a84-4cf6-b33d-9a2130f299a6\n[21002:21060:0421/003553.723648:33252167035:ERROR:browser_process_sub_thread.cc(217)] Waited 15 ms for network service\n[ OK ] WebViewAPITest.TestLoadStartLoadRedirect (2430 ms)\n[126/202] WebViewAPITest.TestLoadStartLoadRedirect (2694 ms)\n[ RUN ] WebViewAPITest.TestLoadProgressEvent\n\nDevTools listening on ws://127.0.0.1:42903/devtools/browser/3dea1594-f963-4f06-a3ab-f689f507d1ef\n[20974:21020:0421/003553.860238:33252303613:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestLoadProgressEvent (2994 ms)\n[127/202] WebViewAPITest.TestLoadProgressEvent (3190 ms)\n[ RUN ] WebViewAPITest.TestNavigateAfterResize\n\nDevTools listening on ws://127.0.0.1:40719/devtools/browser/82011199-98de-44bc-a521-99a4234b9dd5\n[21073:21202:0421/003554.570004:33253013379:ERROR:browser_process_sub_thread.cc(217)] Waited 7 ms for network service\n[ OK ] WebViewAPITest.TestNavigateAfterResize (2799 ms)\n[128/202] WebViewAPITest.TestNavigateAfterResize (3206 ms)\n[ RUN ] WebViewAPITest.TestContentInitiatedNavigationToDataUrlBlocked\n\nDevTools listening on ws://127.0.0.1:38728/devtools/browser/f012887a-dbe9-447a-8420-9a96913460e3\n[21314:21314:0421/003556.635770:33255079164:INFO:CONSOLE(0)] \"Not allowed to navigate top frame to data URL: data:text/html,foo\", source: data:text/html,<script>window.location.href = \"data:text/html,foo\";</script> (0)\n[21314:21364:0421/003556.825004:33255268379:ERROR:browser_process_sub_thread.cc(217)] Waited 18 ms for network service\n[ OK ] WebViewAPITest.TestContentInitiatedNavigationToDataUrlBlocked (2887 ms)\n[129/202] WebViewAPITest.TestContentInitiatedNavigationToDataUrlBlocked (3189 ms)\n[ RUN ] WebViewAPITest.TestNavOnConsecutiveSrcAttributeChanges\n\nDevTools listening on ws://127.0.0.1:38806/devtools/browser/f82de1db-a1cf-4127-801f-ffc545b556c3\n[21316:21361:0421/003556.902166:33255345555:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestNavOnConsecutiveSrcAttributeChanges (2918 ms)\n[130/202] WebViewAPITest.TestNavOnConsecutiveSrcAttributeChanges (3193 ms)\n[ RUN ] WebViewAPITest.TestNavOnSrcAttributeChange\n\nDevTools listening on ws://127.0.0.1:38264/devtools/browser/e9aa188f-76b7-4a73-a4ec-9b38c5dac093\n[21319:21368:0421/003556.950427:33255393810:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestNavOnSrcAttributeChange (2909 ms)\n[131/202] WebViewAPITest.TestNavOnSrcAttributeChange (3191 ms)\n[ RUN ] WebViewAPITest.TestNewWindow\n\nDevTools listening on ws://127.0.0.1:37922/devtools/browser/d4fd7ebf-6048-4443-9deb-88e9ae1101f7\n[ OK ] WebViewAPITest.TestNewWindow (3091 ms)\n[132/202] WebViewAPITest.TestNewWindow (3487 ms)\n[ RUN ] WebViewAPITest.TestNewWindowNoPreventDefault\n\nDevTools listening on ws://127.0.0.1:41580/devtools/browser/b1871017-187b-44bb-9542-ba607b844aba\n[21657:21706:0421/003600.366849:33258810236:ERROR:browser_process_sub_thread.cc(217)] Waited 20 ms for network service\n[ OK ] WebViewAPITest.TestNewWindowNoPreventDefault (3238 ms)\n[133/202] WebViewAPITest.TestNewWindowNoPreventDefault (3450 ms)\n[ RUN ] WebViewAPITest.TestNewWindowTwoListeners\n\nDevTools listening on ws://127.0.0.1:43919/devtools/browser/9b00bc29-5ddc-4bde-8cc4-afeda78724b1\n[ OK ] WebViewAPITest.TestNewWindowTwoListeners (3273 ms)\n[134/202] WebViewAPITest.TestNewWindowTwoListeners (3449 ms)\n[ RUN ] WebViewAPITest.TestNewWindowNoReferrerLink\n\nDevTools listening on ws://127.0.0.1:44276/devtools/browser/37b82935-f8b1-4d13-9a1e-cdb38e680ccb\n[21666:21702:0421/003600.570586:33259013961:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestNewWindowNoReferrerLink (3409 ms)\n[135/202] WebViewAPITest.TestNewWindowNoReferrerLink (3722 ms)\n[ RUN ] WebViewAPITest.TestOnEventProperty\n\nDevTools listening on ws://127.0.0.1:40707/devtools/browser/07e09186-65f3-4841-9f8c-eefa6031d5d4\n[21858:21871:0421/003600.901479:33259344854:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestOnEventProperty (2470 ms)\n[136/202] WebViewAPITest.TestOnEventProperty (2663 ms)\n[ RUN ] WebViewAPITest.TestPartitionChangeAfterNavigation\n\nDevTools listening on ws://127.0.0.1:41960/devtools/browser/ce13c8c6-a6be-4546-ab78-3098b002b3af\n[21994:21994:0421/003603.201950:33261645327:INFO:CONSOLE(155)] \"<webview>: The object has already navigated, so its partition cannot be changed.\", source: extensions::webViewAttributes (155)\n[21994:22033:0421/003603.440707:33261884104:ERROR:browser_process_sub_thread.cc(217)] Waited 35 ms for network service\n[ OK ] WebViewAPITest.TestPartitionChangeAfterNavigation (2845 ms)\n[137/202] WebViewAPITest.TestPartitionChangeAfterNavigation (3201 ms)\n[ RUN ] WebViewAPITest.TestPartitionRemovalAfterNavigationFails\n\nDevTools listening on ws://127.0.0.1:39517/devtools/browser/44a7009b-7d96-4026-a02d-8490da3875fd\n[22002:22002:0421/003603.746054:33262189446:INFO:CONSOLE(155)] \"<webview>: The object has already navigated, so its partition cannot be changed.\", source: extensions::webViewAttributes (155)\n[ OK ] WebViewAPITest.TestPartitionRemovalAfterNavigationFails (3276 ms)\n[138/202] WebViewAPITest.TestPartitionRemovalAfterNavigationFails (3460 ms)\n[ RUN ] WebViewAPITest.TestRemoveWebviewOnExit\n\nDevTools listening on ws://127.0.0.1:45426/devtools/browser/667c1094-d898-4247-aa0c-b6e9a1b61146\n[22024:22126:0421/003603.926167:33262369551:ERROR:browser_process_sub_thread.cc(217)] Waited 11 ms for network service\n[ OK ] WebViewAPITest.TestRemoveWebviewOnExit (2838 ms)\n[139/202] WebViewAPITest.TestRemoveWebviewOnExit (3187 ms)\n[ RUN ] WebViewAPITest.TestReassignSrcAttribute\n\nDevTools listening on ws://127.0.0.1:41838/devtools/browser/464ad076-98b5-419d-9a07-b747027041d2\n[22011:22011:0421/003603.621604:33262064992:INFO:CONSOLE(1476)] \"[1] loadstop called\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1476)\n[22011:22011:0421/003603.658951:33262102337:INFO:CONSOLE(268)] \"<webview>: The load has aborted with error -3: ERR_ABORTED.\", source: extensions::webViewEvents (268)\n[22011:22011:0421/003603.742336:33262185722:INFO:CONSOLE(1476)] \"[2] loadstop called\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1476)\n[22011:22011:0421/003603.897381:33262340768:INFO:CONSOLE(1476)] \"[3] loadstop called\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1476)\n[22011:22084:0421/003604.026544:33262469930:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestReassignSrcAttribute (3126 ms)\n[140/202] WebViewAPITest.TestReassignSrcAttribute (3448 ms)\n[ RUN ] WebViewAPITest.TestReload\n\nDevTools listening on ws://127.0.0.1:42207/devtools/browser/6e6a6fe2-4888-4911-acda-ea56e2b93c73\n[22328:22357:0421/003606.374879:33264818265:ERROR:browser_process_sub_thread.cc(217)] Waited 11 ms for network service\n[ OK ] WebViewAPITest.TestReload (2570 ms)\n[141/202] WebViewAPITest.TestReload (2942 ms)\n[ RUN ] WebViewAPITest.TestRemoveWebviewAfterNavigation\n\nDevTools listening on ws://127.0.0.1:41364/devtools/browser/a293eef4-8576-4ea8-bab0-4b957bfa03cf\n[22374:22441:0421/003606.675290:33265118679:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[22374:22441:0421/003606.680060:33265123443:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[22374:22441:0421/003606.722248:33265165642:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestRemoveWebviewAfterNavigation (2394 ms)\n[142/202] WebViewAPITest.TestRemoveWebviewAfterNavigation (2665 ms)\n[ RUN ] WebViewAPITest.TestRemoveSrcAttribute\n\nDevTools listening on ws://127.0.0.1:46734/devtools/browser/469b9236-b0a6-4b26-9558-da17de144885\n[22371:22447:0421/003607.143900:33265587291:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestRemoveSrcAttribute (2875 ms)\n[143/202] WebViewAPITest.TestRemoveSrcAttribute (3186 ms)\n[ RUN ] WebViewAPITest.TestReloadAfterTerminate\n\nDevTools listening on ws://127.0.0.1:37696/devtools/browser/d51f9ed4-2be8-47e1-81d0-48f14ea68a6e\n[ OK ] WebViewAPITest.TestReloadAfterTerminate (3314 ms)\n[144/202] WebViewAPITest.TestReloadAfterTerminate (3715 ms)\n[ RUN ] WebViewAPITest.TestResizeWebviewResizesContent\n\nDevTools listening on ws://127.0.0.1:38273/devtools/browser/67aafa99-692f-4087-99ee-878bc78c5f31\n[22639:22639:0421/003609.320981:33267764372:INFO:CONSOLE(1611)] \"The resize test has been injected into webview.\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1611)\n[22639:22639:0421/003609.323491:33267766877:INFO:CONSOLE(1617)] \"The guest script for a two-way comm channel has been injected into webview.\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1617)\n[22639:22639:0421/003609.345292:33267788677:INFO:CONSOLE(1628)] \"A communication channel has been established with webview.\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1628)\n[22639:22639:0421/003609.345695:33267789082:INFO:CONSOLE(1629)] \"Resizing <webview> width from 300px to 400px.\", source: chrome-extension://lnfpfpobnbjbeofoboclakehoopaekod/main.js (1629)\n[22639:22686:0421/003609.584577:33268027960:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestResizeWebviewResizesContent (2850 ms)\n[145/202] WebViewAPITest.TestResizeWebviewResizesContent (3196 ms)\n[ RUN ] WebViewAPITest.TestWebRequestAPI\n\nDevTools listening on ws://127.0.0.1:40091/devtools/browser/106fda47-72d3-43fd-9a5a-082ae291c418\n[22727:22823:0421/003610.016055:33268459443:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[22727:22823:0421/003610.024054:33268467433:ERROR:service_manager_context.cc(242)] Attempting to run unsupported native service: /b/s/w/ir/out/Release/content_browser.service\n[22727:22823:0421/003610.085426:33268528808:ERROR:browser_process_sub_thread.cc(217)] Waited 29 ms for network service\n[ OK ] WebViewAPITest.TestWebRequestAPI (2582 ms)\n[146/202] WebViewAPITest.TestWebRequestAPI (2949 ms)\n[ RUN ] WebViewAPITest.TestTerminateAfterExit\n\nDevTools listening on ws://127.0.0.1:44164/devtools/browser/a544608d-587b-40f1-b27e-d2b396fddda3\n[22663:22754:0421/003610.141170:33268584550:ERROR:browser_process_sub_thread.cc(217)] Waited 18 ms for network service\n[ OK ] WebViewAPITest.TestTerminateAfterExit (3097 ms)\n[147/202] WebViewAPITest.TestTerminateAfterExit (3465 ms)\n[ RUN ] WebViewAPITest.TestWebRequestAPIWithHeaders\n\nDevTools listening on ws://127.0.0.1:37493/devtools/browser/041e9b03-3d7d-4611-a1d6-bbfb0dfc63b9\n[22796:22834:0421/003610.565273:33269008658:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestWebRequestAPIWithHeaders (2705 ms)\n[148/202] WebViewAPITest.TestWebRequestAPIWithHeaders (2935 ms)\n[ RUN ] WebViewAPITest.TestWebRequestAPIWithExtraHeaders\n\nDevTools listening on ws://127.0.0.1:45529/devtools/browser/aa18dd1f-3ad3-49fa-ab6c-54e69bd45233\n[22986:23064:0421/003612.933413:33271376791:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestWebRequestAPIWithExtraHeaders (3009 ms)\n[149/202] WebViewAPITest.TestWebRequestAPIWithExtraHeaders (3188 ms)\n[ RUN ] WebViewAPITest.TestWebRequestAPIExistence\n\nDevTools listening on ws://127.0.0.1:42006/devtools/browser/3648e6cd-fb25-4a7d-8b81-e57729bfec9b\n[23078:23151:0421/003613.264855:33271708232:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] WebViewAPITest.TestWebRequestAPIExistence (2742 ms)\n[150/202] WebViewAPITest.TestWebRequestAPIExistence (2944 ms)\n[ RUN ] WebViewAPITest.TestWebRequestAPIGoogleProperty\n\nDevTools listening on ws://127.0.0.1:46223/devtools/browser/c9165ff9-f58a-4c26-909f-e9fa60ad55c8\n[23132:23256:0421/003613.221118:33271664505:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestWebRequestAPIGoogleProperty (2429 ms)\n[151/202] WebViewAPITest.TestWebRequestAPIGoogleProperty (2666 ms)\n[ RUN ] WebViewAPITest.TestLoadEventsSameDocumentNavigation\n\nDevTools listening on ws://127.0.0.1:43641/devtools/browser/c240253f-11e2-417e-bd3c-3cde232a8a33\n[23075:23154:0421/003613.237274:33271680657:ERROR:browser_process_sub_thread.cc(217)] Waited 18 ms for network service\n[ OK ] WebViewAPITest.TestLoadEventsSameDocumentNavigation (2800 ms)\n[152/202] WebViewAPITest.TestLoadEventsSameDocumentNavigation (3190 ms)\n[ RUN ] WebViewAPITest.TestNoUserCodeSetOnEventProperty\n\nDevTools listening on ws://127.0.0.1:45787/devtools/browser/238095d2-8615-4bd6-ae5d-815a181a6f02\n[23418:23418:0421/003615.920644:33274364068:INFO:CONSOLE(0)] \"[SUCCESS] testSetOnEventProperty\", source: chrome-extension://hojfjgdpdcooegekhbdnfapnpcddaagl/main.html (0)\n[23418:23484:0421/003616.083877:33274527254:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestNoUserCodeSetOnEventProperty (2479 ms)\n[153/202] WebViewAPITest.TestNoUserCodeSetOnEventProperty (2658 ms)\n[ RUN ] WebViewAPITest.TestWebViewInsideFrame\n\nDevTools listening on ws://127.0.0.1:42537/devtools/browser/09a14d4b-f0e2-45d9-9772-a2a7958ad1c0\n[23357:23404:0421/003616.017322:33274460707:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestWebViewInsideFrame (2907 ms)\n[154/202] WebViewAPITest.TestWebViewInsideFrame (3218 ms)\n[ RUN ] WebViewAPITest.TestCaptureVisibleRegion\n\nDevTools listening on ws://127.0.0.1:43627/devtools/browser/ebb4bd27-4fb8-4f57-b6ad-be3df65a74ce\n[23393:23393:0421/003616.138324:33274581710:INFO:web_contents_capture_client.cc(60)] Screenshot taken\n[23393:23488:0421/003616.429210:33274872586:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] WebViewAPITest.TestCaptureVisibleRegion (3006 ms)\n[155/202] WebViewAPITest.TestCaptureVisibleRegion (3184 ms)\n[ RUN ] WebViewAPITest.TestNoUserCodeCreate\n\nDevTools listening on ws://127.0.0.1:39506/devtools/browser/e8a75794-fd6c-48ff-a99e-225109bef843\n[23399:23399:0421/003616.287010:33274730406:INFO:CONSOLE(0)] \"[SUCCESS] testCreate\", source: chrome-extension://hojfjgdpdcooegekhbdnfapnpcddaagl/main.html (0)\n[23399:23492:0421/003616.458968:33274902343:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewAPITest.TestNoUserCodeCreate (2998 ms)\n[156/202] WebViewAPITest.TestNoUserCodeCreate (3187 ms)\n[ RUN ] WebViewAPITest.TestNoUserCodeGetSetAttributes\n\nDevTools listening on ws://127.0.0.1:33181/devtools/browser/cc65dcf7-b646-472c-97f7-e6cae5dd46ba\n[23686:23686:0421/003618.765554:33277208947:INFO:CONSOLE(0)] \"[SUCCESS] testGetSetAttributes\", source: chrome-extension://hojfjgdpdcooegekhbdnfapnpcddaagl/main.html (0)\n[23686:23737:0421/003618.884026:33277327411:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewAPITest.TestNoUserCodeGetSetAttributes (2619 ms)\n[157/202] WebViewAPITest.TestNoUserCodeGetSetAttributes (2924 ms)\n[ RUN ] WebViewAPITest.TestNoUserCodeBackForward\n\nDevTools listening on ws://127.0.0.1:46718/devtools/browser/115f06e4-9139-48b8-a95f-cd8cdb6fb0cd\n[23687:23687:0421/003618.836678:33277280074:INFO:CONSOLE(0)] \"[SUCCESS] testBackForward\", source: chrome-extension://hojfjgdpdcooegekhbdnfapnpcddaagl/main.html (0)\n[23687:23736:0421/003618.982965:33277426340:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] WebViewAPITest.TestNoUserCodeBackForward (2644 ms)\n[158/202] WebViewAPITest.TestNoUserCodeBackForward (2934 ms)\n[ RUN ] WebViewAPITest.TestClosedShadowRoot\n\nDevTools listening on ws://127.0.0.1:40911/devtools/browser/a6136610-6ca7-4ffc-b3e7-77bb1267af78\n[23709:23768:0421/003619.367361:33277810745:ERROR:browser_process_sub_thread.cc(217)] Waited 12 ms for network service\n[ OK ] WebViewAPITest.TestClosedShadowRoot (2710 ms)\n[159/202] WebViewAPITest.TestClosedShadowRoot (2920 ms)\n[ RUN ] WebViewAPITest.TestNoUserCodeFocus\n\nDevTools listening on ws://127.0.0.1:45496/devtools/browser/0fd782e4-b3c2-4a62-af7c-e5e636250351\n[23706:23706:0421/003619.343772:33277787160:INFO:CONSOLE(0)] \"[SUCCESS] testFocus\", source: chrome-extension://hojfjgdpdcooegekhbdnfapnpcddaagl/main.html (0)\n[23706:23771:0421/003619.493806:33277937188:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewAPITest.TestNoUserCodeFocus (2891 ms)\n[160/202] WebViewAPITest.TestNoUserCodeFocus (3185 ms)\n[ RUN ] WebViewDPIAPITest.TestAutosizeBeforeNavigation\n\nDevTools listening on ws://127.0.0.1:40941/devtools/browser/22bea800-e7bd-44df-a956-ae6dab1d704d\n[23983:24053:0421/003622.071724:33280515105:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] WebViewDPIAPITest.TestAutosizeBeforeNavigation (2884 ms)\n[161/202] WebViewDPIAPITest.TestAutosizeBeforeNavigation (3194 ms)\n[ RUN ] WebViewDPIAPITest.TestAutosizeHeight\n\nDevTools listening on ws://127.0.0.1:41005/devtools/browser/b3a8bb60-f153-4676-8d54-010adfa9d4ec\n[23984:24054:0421/003622.170301:33280613679:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewDPIAPITest.TestAutosizeHeight (2897 ms)\n[162/202] WebViewDPIAPITest.TestAutosizeHeight (3194 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestAllow\n\nDevTools listening on ws://127.0.0.1:42958/devtools/browser/0b77904f-1480-47f0-95fa-86117aa049e3\n[24074:24074:0421/003620.377619:33278821004:ERROR:device_event_log_impl.cc(162)] [00:36:20.377] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:NotifyNetworkPropertiesUpdated: 58ms\n[24074:24177:0421/003622.422220:33280865605:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestAllow (2612 ms)\n[163/202] WebViewMediaAccessAPITest.TestAllow (2931 ms)\n[ RUN ] WebViewDPIAPITest.TestAutosizeRemoveAttributes\n\nDevTools listening on ws://127.0.0.1:35255/devtools/browser/16d9f5a0-9a9e-4173-b117-f219a43a7916\n[24031:24031:0421/003620.424859:33278868262:ERROR:device_event_log_impl.cc(162)] [00:36:20.410] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:NotifyNetworkConnectionStateChanged: 76ms\n[24031:24166:0421/003622.605766:33281049144:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewDPIAPITest.TestAutosizeRemoveAttributes (3036 ms)\n[164/202] WebViewDPIAPITest.TestAutosizeRemoveAttributes (3195 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestAllowAndThenDeny\n\nDevTools listening on ws://127.0.0.1:38845/devtools/browser/c581f5f3-d7cd-4a92-8d49-19470b25bd66\n[24338:24338:0421/003623.103726:33281547117:ERROR:device_event_log_impl.cc(162)] [00:36:23.102] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:NotifyNetworkPropertiesUpdated: 69ms\n[24338:24382:0421/003625.349122:33283792498:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestAllowAndThenDeny (2951 ms)\n[165/202] WebViewMediaAccessAPITest.TestAllowAndThenDeny (3189 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestAllowAsync\n\nDevTools listening on ws://127.0.0.1:42330/devtools/browser/66b271c5-f4a7-4af0-9710-26d6fddf2f88\n[24344:24385:0421/003625.442894:33283886281:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestAllowAsync (2976 ms)\n[166/202] WebViewMediaAccessAPITest.TestAllowAsync (3193 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestAllowTwice\n\nDevTools listening on ws://127.0.0.1:34101/devtools/browser/650fe39d-5e3a-49bd-865f-cd37b0d5f1a5\n[24358:24358:0421/003623.541836:33281985221:ERROR:device_event_log_impl.cc(162)] [00:36:23.541] Network: device_event_log.cc:122 @@@ Slow method: ../../chromeos/network/network_state_handler.cc:ManagedStateListChanged: 67ms\n[24358:24460:0421/003625.650533:33284093916:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestAllowTwice (2932 ms)\n[167/202] WebViewMediaAccessAPITest.TestAllowTwice (3188 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestCheck\n\nDevTools listening on ws://127.0.0.1:44241/devtools/browser/8cda5e2b-b186-43ec-b7c9-832c62b41f3c\n[24359:24359:0421/003625.465273:33283908658:INFO:CONSOLE(41)] \"onWebViewLoadStop\", source: chrome-extension://pkplgfpeldolfbambadcapnhgcldcpig/embedder.js (41)\n[24359:24433:0421/003625.698127:33284141503:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestCheck (2935 ms)\n[168/202] WebViewMediaAccessAPITest.TestCheck (3184 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestDeny\n\nDevTools listening on ws://127.0.0.1:39685/devtools/browser/b6bdbff7-3cfd-4907-b998-396bce95bf7e\n[ OK ] WebViewMediaAccessAPITest.TestDeny (3130 ms)\n[169/202] WebViewMediaAccessAPITest.TestDeny (3466 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestDenyWithPreventDefault\n\nDevTools listening on ws://127.0.0.1:36434/devtools/browser/d4f23a2d-e22f-4728-9b43-d6ff1c9b501e\n[24690:24756:0421/003628.710236:33287153612:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestDenyWithPreventDefault (2797 ms)\n[170/202] WebViewMediaAccessAPITest.TestDenyWithPreventDefault (3186 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestDenyThenAllowThrows\n\nDevTools listening on ws://127.0.0.1:38346/devtools/browser/8bb8ebe5-d266-4d80-9420-1b1074c9e26f\n[24676:24719:0421/003628.878861:33287322243:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestDenyThenAllowThrows (3210 ms)\n[171/202] WebViewMediaAccessAPITest.TestDenyThenAllowThrows (3485 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestNoListenersImplyDeny\n\nDevTools listening on ws://127.0.0.1:44223/devtools/browser/fb883ae7-4bef-46ea-987a-37039fc55023\n[24691:24691:0421/003628.637481:33287080874:INFO:CONSOLE(279)] \"<webview>: The permission request for \"media\" has been denied.\", source: extensions::webViewActionRequests (279)\n[24691:24751:0421/003628.817992:33287261367:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WebViewMediaAccessAPITest.TestNoListenersImplyDeny (2868 ms)\n[172/202] WebViewMediaAccessAPITest.TestNoListenersImplyDeny (3225 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForFoo\n\nDevTools listening on ws://127.0.0.1:39289/devtools/browser/01259c19-7113-475e-9871-2172eca52ee8\n[25011:25011:0421/003630.958690:33289402089:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.T2TmQJ:\n[25011:25011:0421/003630.958846:33289402225:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[25011:25062:0421/003631.119296:33289562680:ERROR:browser_process_sub_thread.cc(217)] Waited 21 ms for network service\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForFoo (1995 ms)\n[173/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForFoo (2135 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForBar\n\nDevTools listening on ws://127.0.0.1:44795/devtools/browser/9867f90a-d004-4d8d-9e06-331b8f0b9cf9\n[25008:25008:0421/003630.962341:33289405726:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.YIVjQG:\n[25008:25008:0421/003630.962477:33289405857:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[25008:25061:0421/003631.087587:33289530975:ERROR:browser_process_sub_thread.cc(217)] Waited 0 ms for network service\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForBar (2004 ms)\n[174/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_ChainTraversalForBar (2397 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NoMatchingOfAboutBlank\n\nDevTools listening on ws://127.0.0.1:34514/devtools/browser/90dc172f-0120-4c21-97b0-a0c72289f382\n[25012:25012:0421/003631.015923:33289459310:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.ErktEK:\n[25012:25012:0421/003631.016077:33289459459:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[25012:25053:0421/003631.167694:33289611093:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NoMatchingOfAboutBlank (2002 ms)\n[175/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NoMatchingOfAboutBlank (2394 ms)\n[ RUN ] WebViewMediaAccessAPITest.TestNoPreventDefaultImpliesDeny\n\nDevTools listening on ws://127.0.0.1:40776/devtools/browser/06898da6-962e-4b51-96b3-cf3001480a33\n[25007:25007:0421/003631.799213:33290242600:INFO:CONSOLE(279)] \"<webview>: The permission request for \"media\" has been denied.\", source: extensions::webViewActionRequests (279)\n[ OK ] WebViewMediaAccessAPITest.TestNoPreventDefaultImpliesDeny (3042 ms)\n[176/202] WebViewMediaAccessAPITest.TestNoPreventDefaultImpliesDeny (3182 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotYetCommittedURL\n\nDevTools listening on ws://127.0.0.1:40849/devtools/browser/01fe3bdf-9452-450e-9fd0-011d60d94a0d\n[25330:25330:0421/003633.362749:33291806140:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.Wfpwyd:\n[25330:25330:0421/003633.362899:33291806275:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotYetCommittedURL (1947 ms)\n[177/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotYetCommittedURL (2138 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotAllFrames\n\nDevTools listening on ws://127.0.0.1:44693/devtools/browser/7cb90cc3-eba9-48a4-9839-082e5d9e5791\n[25326:25326:0421/003633.200337:33291643727:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.dOpmhW:\n[25326:25326:0421/003633.200687:33291644076:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotAllFrames (2024 ms)\n[178/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_NotAllFrames (2402 ms)\n[ RUN ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_CssIsIgnored\n\nDevTools listening on ws://127.0.0.1:41543/devtools/browser/0b7e9322-e48d-441e-91e6-be4f3d617ce7\n[25342:25342:0421/003633.388889:33291832274:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/itR4aW2x/.org.chromium.Chromium.ahXQ9h:\n[25342:25342:0421/003633.389059:33291832435:WARNING:shell_extension_loader.cc(52)] Unrecognized manifest key 'content_scripts'.\n[ OK ] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_CssIsIgnored (1920 ms)\n[179/202] URLLoaderFactoryManagerBrowserTest.ContentScriptMatching_CssIsIgnored (2148 ms)\n[ RUN ] AudioApiTest.Audio\n\nDevTools listening on ws://127.0.0.1:44710/devtools/browser/5a3ab733-875f-4b8e-9d25-a3cad106c9b2\n[25426:25426:0421/003633.315354:33291758743:INFO:CONSOLE(0)] \"[SUCCESS] startDeviceChangedListener\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.421332:33291864719:INFO:CONSOLE(0)] \"[SUCCESS] getDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.476561:33291919945:INFO:CONSOLE(0)] \"[SUCCESS] getDevicesWithEmptyFilterTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.501931:33291945313:INFO:CONSOLE(0)] \"[SUCCESS] getInputDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.522499:33291965932:INFO:CONSOLE(0)] \"[SUCCESS] getOutputDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.580617:33292024001:INFO:CONSOLE(0)] \"[SUCCESS] getActiveDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.626305:33292069690:INFO:CONSOLE(0)] \"[SUCCESS] getInactiveDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.698521:33292141988:INFO:CONSOLE(0)] \"[SUCCESS] setPropertiesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.712107:33292155482:INFO:CONSOLE(0)] \"[SUCCESS] inputMuteTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.725453:33292168826:INFO:CONSOLE(0)] \"[SUCCESS] outputMuteTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.743079:33292186460:INFO:CONSOLE(0)] \"[SUCCESS] setActiveDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.766726:33292210105:INFO:CONSOLE(0)] \"[SUCCESS] setActiveDevicesOutputOnlyTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.790486:33292233869:INFO:CONSOLE(0)] \"[SUCCESS] setActiveDevicesFailInputTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.813492:33292256878:INFO:CONSOLE(0)] \"[SUCCESS] setActiveDevicesFailOutputTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.829611:33292272986:INFO:CONSOLE(0)] \"[SUCCESS] clearActiveDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.834580:33292277950:INFO:CONSOLE(0)] \"[SUCCESS] verifyNoDeviceChangedEvents\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.841429:33292284800:INFO:CONSOLE(0)] \"[SUCCESS] deprecated_GetInfoTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.900791:33292344166:INFO:CONSOLE(0)] \"[SUCCESS] deprecated_setProperties_isMuted\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003633.970854:33292414237:INFO:CONSOLE(0)] \"[SUCCESS] deprecated_setProperties_volume\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003634.054352:33292497737:INFO:CONSOLE(0)] \"[SUCCESS] deprecated_setProperties_gain\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25426:0421/003634.066486:33292509884:INFO:CONSOLE(0)] \"[SUCCESS] deprecated_SetActiveDevicesTest\", source: chrome-extension://dghjakdgbamppfnglidiciilfimhefjk/_generated_background_page.html (0)\n[25426:25546:0421/003634.156804:33292600184:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] AudioApiTest.Audio (1857 ms)\n[180/202] AudioApiTest.Audio (2149 ms)\n[ RUN ] AudioApiTest.OnLevelChangedOutputDevice\n\nDevTools listening on ws://127.0.0.1:42932/devtools/browser/ba61f174-365f-4a97-85d6-06eeb014049d\n[25665:25665:0421/003634.842082:33293285474:INFO:CONSOLE(0)] \"[SUCCESS] waitForLevelChangedEventTests\", source: chrome-extension://lfbhhclfciphcmdcidfjmfhbocjeccba/_generated_background_page.html (0)\n[25665:25698:0421/003634.919920:33293363301:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] AudioApiTest.OnLevelChangedOutputDevice (1294 ms)\n[181/202] AudioApiTest.OnLevelChangedOutputDevice (1612 ms)\n[ RUN ] AudioApiTest.OnOutputMuteChanged\n\nDevTools listening on ws://127.0.0.1:45379/devtools/browser/c284faec-1154-44cc-b353-88dbcf213292\n[25666:25666:0421/003634.893441:33293336828:INFO:CONSOLE(0)] \"[SUCCESS] waitForMuteChangedEventTests\", source: chrome-extension://cfgojhiaibbkibjdaoakjhgdhodgjlkl/_generated_background_page.html (0)\n[ OK ] AudioApiTest.OnOutputMuteChanged (1297 ms)\n[182/202] AudioApiTest.OnOutputMuteChanged (1616 ms)\n[ RUN ] AudioApiTest.OnInputMuteChanged\n\nDevTools listening on ws://127.0.0.1:33545/devtools/browser/335817d6-6f0b-409e-b1a1-3b8673807542\n[25667:25667:0421/003634.867270:33293310662:INFO:CONSOLE(0)] \"[SUCCESS] waitForMuteChangedEventTests\", source: chrome-extension://dgipifpljmbbgpcffjdepehfbpoonbfa/_generated_background_page.html (0)\n[25667:25703:0421/003634.964328:33293407720:ERROR:browser_process_sub_thread.cc(217)] Waited 23 ms for network service\n[ OK ] AudioApiTest.OnInputMuteChanged (1246 ms)\n[183/202] AudioApiTest.OnInputMuteChanged (1614 ms)\n[ RUN ] AudioApiTest.OnNodesChangedAddNodes\n\nDevTools listening on ws://127.0.0.1:41712/devtools/browser/bcd16d5d-aea6-40bd-a4c2-dcd78baf9bf0\n[25744:25744:0421/003635.448205:33293891602:INFO:CONSOLE(0)] \"[SUCCESS] waitForDeviceChangedEventTests\", source: chrome-extension://dbflnniincamlgejphenlbepnpefoohf/_generated_background_page.html (0)\n[25744:25867:0421/003635.500810:33293944184:ERROR:browser_process_sub_thread.cc(217)] Waited 6 ms for network service\n[ OK ] AudioApiTest.OnNodesChangedAddNodes (1008 ms)\n[184/202] AudioApiTest.OnNodesChangedAddNodes (1379 ms)\n[ RUN ] CecPrivateKioskApiTest.TestAllApiFunctions\n\nDevTools listening on ws://127.0.0.1:33104/devtools/browser/fb7bbfd3-8322-4148-a2bf-fcc6ebb61699\n[25928:25928:0421/003636.624184:33295067572:INFO:CONSOLE(0)] \"[SUCCESS] getInitialState\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25928:25928:0421/003636.648961:33295092354:INFO:CONSOLE(0)] \"[SUCCESS] sendStandBy\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25928:25928:0421/003636.666036:33295109436:INFO:CONSOLE(0)] \"[SUCCESS] getStandByStateState\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25928:25928:0421/003636.682509:33295125888:INFO:CONSOLE(0)] \"[SUCCESS] sendWakeup\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25928:25928:0421/003636.693399:33295136785:INFO:CONSOLE(0)] \"[SUCCESS] getWokeUpState\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25928:25967:0421/003636.748943:33295192318:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] CecPrivateKioskApiTest.TestAllApiFunctions (1422 ms)\n[185/202] CecPrivateKioskApiTest.TestAllApiFunctions (1616 ms)\n[ RUN ] AudioApiTest.OnNodesChangedRemoveNodes\n\nDevTools listening on ws://127.0.0.1:46342/devtools/browser/a079cd65-0bda-431a-9520-980528253f61\n[25926:25926:0421/003636.678296:33295121672:INFO:CONSOLE(0)] \"[SUCCESS] waitForDeviceChangedEventTests\", source: chrome-extension://fechlgojfnpcmbbigekkpakejfmhnajc/_generated_background_page.html (0)\n[25926:25975:0421/003636.743866:33295187252:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] AudioApiTest.OnNodesChangedRemoveNodes (1497 ms)\n[186/202] AudioApiTest.OnNodesChangedRemoveNodes (1876 ms)\n[ RUN ] WhitelistedAudioApiTest.DeprecatedApi\n\nDevTools listening on ws://127.0.0.1:41082/devtools/browser/1f77ca87-5e39-45f4-9195-ebdd2298efe5\n[25927:25927:0421/003636.550154:33294993547:INFO:CONSOLE(0)] \"[SUCCESS] startDeviceChangedListener\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25927:0421/003636.654861:33295098244:INFO:CONSOLE(0)] \"[SUCCESS] getInfoTest\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25927:0421/003636.718004:33295161470:INFO:CONSOLE(0)] \"[SUCCESS] setActiveDevicesTest\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25927:0421/003636.785024:33295228411:INFO:CONSOLE(0)] \"[SUCCESS] setPropertiesTest\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25927:0421/003636.845059:33295288434:INFO:CONSOLE(0)] \"[SUCCESS] setPropertiesInvalidValuesTest\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25927:0421/003636.849967:33295293349:INFO:CONSOLE(0)] \"[SUCCESS] verifyDeviceChangedEvents\", source: chrome-extension://jlgnoeceollaejlkenecblnjmdcfhfgc/_generated_background_page.html (0)\n[25927:25971:0421/003636.907270:33295350658:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] WhitelistedAudioApiTest.DeprecatedApi (1620 ms)\n[187/202] WhitelistedAudioApiTest.DeprecatedApi (1872 ms)\n[ RUN ] CecPrivateNonKioskApiTest.TestCecPrivateNotAvailable\n\nDevTools listening on ws://127.0.0.1:41491/devtools/browser/24839ef4-af56-4537-8178-23f128804bb2\n[25960:25960:0421/003636.489493:33294932893:WARNING:shell_extension_loader.cc(49)] Warnings loading extension at /b/s/w/ir/extensions/test/data/api_test/cec_private/non_kiosk_api_not_available:\n[25960:25960:0421/003636.489666:33294933047:WARNING:shell_extension_loader.cc(52)] 'cecPrivate' is only allowed to run in kiosk apps sessions, but this is user-less session.\n[25960:25960:0421/003636.897044:33295340430:INFO:CONSOLE(0)] \"[SUCCESS] ensureCecPrivateNotDefined\", source: chrome-extension://jabiebdnficieldhmegebckfhpfidfla/_generated_background_page.html (0)\n[25960:26097:0421/003636.968372:33295411746:ERROR:browser_process_sub_thread.cc(217)] Waited 5 ms for network service\n[ OK ] CecPrivateNonKioskApiTest.TestCecPrivateNotAvailable (1218 ms)\n[188/202] CecPrivateNonKioskApiTest.TestCecPrivateNotAvailable (1615 ms)\n[ RUN ] MediaPerceptionPrivateApiTest.SetComponentProcessState\n\nDevTools listening on ws://127.0.0.1:36507/devtools/browser/42f1b4ea-6094-4319-a9c4-866a45bcea67\n[26200:26200:0421/003638.476194:33296919581:INFO:CONSOLE(0)] \"[SUCCESS] setComponentProcessStateStartedWithNoInstalledComponent\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26200:26200:0421/003638.486474:33296929849:INFO:CONSOLE(0)] \"[SUCCESS] setAnalyticsComponentLight\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26200:26200:0421/003638.492155:33296935543:ERROR:media_perception_private_apitest.cc(58)] Not implemented reached in virtual void extensions::(anonymous namespace)::TestMediaPerceptionAPIDelegate::SetMediaPerceptionRequestHandler(extensions::MediaPerceptionAPIDelegate::MediaPerceptionRequestHandler)\n[26200:26200:0421/003638.498259:33296941655:INFO:CONSOLE(0)] \"[SUCCESS] setComponentProcessStateStarted\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26200:26200:0421/003638.507830:33296951210:INFO:CONSOLE(0)] \"[SUCCESS] setComponentProcessStateStopped\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26200:26200:0421/003638.520552:33296963941:INFO:CONSOLE(0)] \"[SUCCESS] setComponentProcessStateUnsettable\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26200:26243:0421/003638.579973:33297023353:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] MediaPerceptionPrivateApiTest.SetComponentProcessState (1469 ms)\n[189/202] MediaPerceptionPrivateApiTest.SetComponentProcessState (1612 ms)\n[ RUN ] MediaPerceptionPrivateApiTest.SetAnalyticsComponent\n\nDevTools listening on ws://127.0.0.1:35530/devtools/browser/037aa1ab-64f5-4c65-b392-1129903ae36f\n[26192:26192:0421/003638.531278:33296974664:INFO:CONSOLE(0)] \"[SUCCESS] setAnalyticsComponentLight\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26192:26192:0421/003638.541214:33296984597:INFO:CONSOLE(0)] \"[SUCCESS] setAnalyticsComponentFullExpectFailure\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26192:26192:0421/003638.560137:33297003521:INFO:CONSOLE(0)] \"[SUCCESS] setAnalyticsComponentWithProcessRunningSuccess\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26192:26239:0421/003638.631100:33297074488:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] MediaPerceptionPrivateApiTest.SetAnalyticsComponent (1672 ms)\n[190/202] MediaPerceptionPrivateApiTest.SetAnalyticsComponent (1880 ms)\n[ RUN ] MediaPerceptionPrivateApiTest.GetDiagnostics\n\nDevTools listening on ws://127.0.0.1:36306/devtools/browser/a131cf21-761b-41f6-8a46-7b1e4004c6f4\n[26209:26209:0421/003638.487695:33296931095:INFO:CONSOLE(0)] \"[SUCCESS] setStateRunning\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26209:26209:0421/003638.506883:33296950270:INFO:CONSOLE(0)] \"[SUCCESS] getDiagnostics\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26209:26278:0421/003638.588478:33297031861:ERROR:browser_process_sub_thread.cc(217)] Waited 14 ms for network service\n[ OK ] MediaPerceptionPrivateApiTest.GetDiagnostics (1236 ms)\n[191/202] MediaPerceptionPrivateApiTest.GetDiagnostics (1609 ms)\n[ RUN ] MediaPerceptionPrivateApiTest.State\n\nDevTools listening on ws://127.0.0.1:46731/devtools/browser/20e44abf-b7be-4e97-8b00-6a2acf38a591\n[26201:26201:0421/003638.469373:33296912769:INFO:CONSOLE(0)] \"[SUCCESS] getStateUninitialized\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.482405:33296925791:INFO:CONSOLE(0)] \"[SUCCESS] setStateRunning\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.491592:33296934976:INFO:CONSOLE(0)] \"[SUCCESS] getStateRunning\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.504116:33296947504:INFO:CONSOLE(0)] \"[SUCCESS] setStateUnsettable\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.512235:33296955617:INFO:CONSOLE(0)] \"[SUCCESS] setStateSuspendedButWithDeviceContextFail\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.521695:33296965094:INFO:CONSOLE(0)] \"[SUCCESS] setStateSuspendedButWithConfigurationFail\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.531541:33296974923:INFO:CONSOLE(0)] \"[SUCCESS] setStateSuspendedButWithWhiteboardFail\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.541227:33296984614:INFO:CONSOLE(0)] \"[SUCCESS] setStateSuspendedButWithVideoStreamParamFail\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.550952:33296994337:INFO:CONSOLE(0)] \"[SUCCESS] setStateRestarted\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.561694:33297005087:INFO:CONSOLE(0)] \"[SUCCESS] setStateRunningWithoutOptionalParameters\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26201:26201:0421/003638.571732:33297015114:INFO:CONSOLE(0)] \"[SUCCESS] setStateStopped\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[ OK ] MediaPerceptionPrivateApiTest.State (1479 ms)\n[192/202] MediaPerceptionPrivateApiTest.State (1872 ms)\n[ RUN ] MediaPerceptionPrivateApiTest.MediaPerception\n\nDevTools listening on ws://127.0.0.1:42724/devtools/browser/296ff48e-0d3b-49a0-8bb9-5228e0c21e6c\n[26466:26466:0421/003640.101868:33298545257:INFO:CONSOLE(0)] \"[SUCCESS] setStateRunning\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26466:26466:0421/003640.119247:33298562635:INFO:CONSOLE(0)] \"[SUCCESS] registerListener\", source: chrome-extension://epcifkihnkjgphfkloaaleeakhpmgdmn/_generated_background_page.html (0)\n[26466:26494:0421/003640.185240:33298628631:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] MediaPerceptionPrivateApiTest.MediaPerception (1442 ms)\n[193/202] MediaPerceptionPrivateApiTest.MediaPerception (1620 ms)\n[ RUN ] VirtualKeyboardApiTest.Test\n\nDevTools listening on ws://127.0.0.1:46785/devtools/browser/15a242c4-8e58-4d2c-9a92-f2d75022c000\n[26473:26473:0421/003640.264274:33298707660:INFO:CONSOLE(0)] \"[SUCCESS] setRestrictedKeyboard\", source: chrome-extension://gilafhccpgkdkadmiohlelonjjflphdc/_generated_background_page.html (0)\n[26473:26473:0421/003640.285209:33298728592:INFO:CONSOLE(0)] \"[SUCCESS] setNotRestrictedKeyboard\", source: chrome-extension://gilafhccpgkdkadmiohlelonjjflphdc/_generated_background_page.html (0)\n[26473:26473:0421/003640.293894:33298737280:INFO:CONSOLE(0)] \"[SUCCESS] differentAndPartialEnabledStates\", source: chrome-extension://gilafhccpgkdkadmiohlelonjjflphdc/_generated_background_page.html (0)\n[26473:26522:0421/003640.372508:33298815893:ERROR:browser_process_sub_thread.cc(217)] Waited 4 ms for network service\n[ OK ] VirtualKeyboardApiTest.Test (1405 ms)\n[194/202] VirtualKeyboardApiTest.Test (1608 ms)\n[ RUN ] SystemPowerSourceApiTest.TestAllApiFunctions\n\nDevTools listening on ws://127.0.0.1:33459/devtools/browser/89875fd5-dfcc-4ab7-8682-1fe49ac02829\n[26467:26467:0421/003640.207903:33298651293:INFO:CONSOLE(0)] \"[SUCCESS] getPowerSourceInfo\", source: chrome-extension://oaadedlfjikeejoppbckfeciddilknjg/_generated_background_page.html (0)\n[26467:26467:0421/003640.231392:33298674779:INFO:CONSOLE(0)] \"[SUCCESS] onPowerChanged\", source: chrome-extension://oaadedlfjikeejoppbckfeciddilknjg/_generated_background_page.html (0)\n[26467:26526:0421/003640.302155:33298745538:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] SystemPowerSourceApiTest.TestAllApiFunctions (1485 ms)\n[195/202] SystemPowerSourceApiTest.TestAllApiFunctions (1872 ms)\n[ RUN ] ShellRuntimeApiTest.RuntimeReload\n\nDevTools listening on ws://127.0.0.1:45472/devtools/browser/23d2dfdf-f154-4c2b-bbf3-90ca03dfd175\n[26474:26530:0421/003640.531369:33298974745:ERROR:browser_process_sub_thread.cc(217)] Waited 1 ms for network service\n[ OK ] ShellRuntimeApiTest.RuntimeReload (1500 ms)\n[196/202] ShellRuntimeApiTest.RuntimeReload (1870 ms)\n[ RUN ] ShellApiTest.LoadExtension\n\nDevTools listening on ws://127.0.0.1:36657/devtools/browser/7c66575b-1b88-4691-9382-f75abbd96241\n[26748:26801:0421/003642.025917:33300469306:ERROR:browser_process_sub_thread.cc(217)] Waited 8 ms for network service\n[ OK ] ShellApiTest.LoadExtension (1436 ms)\n[197/202] ShellApiTest.LoadExtension (1614 ms)\n[ RUN ] ShellDesktopControllerAuraBrowserTest.CloseAppWindow\n\nDevTools listening on ws://127.0.0.1:39432/devtools/browser/447e4ab9-2b9f-4ad2-b396-2a11da1d6ff7\n[26762:26762:0421/003642.425664:33300869055:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[26762:26840:0421/003642.615414:33301058797:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] ShellDesktopControllerAuraBrowserTest.CloseAppWindow (1709 ms)\n[198/202] ShellDesktopControllerAuraBrowserTest.CloseAppWindow (1875 ms)\n[ RUN ] ShellApiTest.LoadApp\n\nDevTools listening on ws://127.0.0.1:46091/devtools/browser/b461a00c-fe40-4577-a9c2-1df6615e4842\n[26752:26752:0421/003642.363928:33300807315:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[26752:26807:0421/003642.527195:33300970578:ERROR:browser_process_sub_thread.cc(217)] Waited 15 ms for network service\n[ OK ] ShellApiTest.LoadApp (1826 ms)\n[199/202] ShellApiTest.LoadApp (2135 ms)\n[ RUN ] ShellRuntimeApiTest.RuntimeReloadApp\n\nDevTools listening on ws://127.0.0.1:40102/devtools/browser/524ff8a2-74c6-45cb-bee6-c3a7bdd108e6\n[26728:26728:0421/003642.114016:33300557406:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[26728:26728:0421/003642.907247:33301350634:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[26728:26767:0421/003643.050488:33301493864:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] ShellRuntimeApiTest.RuntimeReloadApp (2660 ms)\n[200/202] ShellRuntimeApiTest.RuntimeReloadApp (2941 ms)\n[ RUN ] ShellDesktopControllerAuraBrowserTest.TwoAppWindows\n\nDevTools listening on ws://127.0.0.1:40144/devtools/browser/bf294222-ddbe-4e6e-971f-fb0580d44813\n[27001:27001:0421/003643.442198:33301885587:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[27001:27001:0421/003643.717936:33302161312:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[27001:27036:0421/003644.339640:33302783016:ERROR:browser_process_sub_thread.cc(217)] Waited 10 ms for network service\n[ OK ] ShellDesktopControllerAuraBrowserTest.TwoAppWindows (2119 ms)\n[201/202] ShellDesktopControllerAuraBrowserTest.TwoAppWindows (2412 ms)\n[ RUN ] ShellDesktopControllerAuraBrowserTest.ReloadApp\n\nDevTools listening on ws://127.0.0.1:40617/devtools/browser/03819686-68bb-4fd6-8cfd-db649b4fd0b4\n[27048:27048:0421/003644.083759:33302527150:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[27048:27048:0421/003644.769892:33303213268:INFO:CONSOLE(6)] \"app window opened\", source: chrome-extension://hcilemblcaaimepojbkmnkcocgbmfoea/world.js (6)\n[27048:27106:0421/003645.365838:33303809212:ERROR:browser_process_sub_thread.cc(217)] Waited 3 ms for network service\n[ OK ] ShellDesktopControllerAuraBrowserTest.ReloadApp (2548 ms)\n[202/202] ShellDesktopControllerAuraBrowserTest.ReloadApp (2934 ms)\nSUCCESS: all tests passed.\n", "outputs_ref": { "isolated": "f0c4d0ad8f24ddaa91b073af606fd315552f49c2", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "4457a26fc3151611", "server_versions": [ "4267-53b17d5" ], "started_ts": "2019-04-21T07:34:00.491729", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:Linux ChromiumOS MSan Tests", "buildnumber:12485", "cpu:x86-64", "data:afb88a97e69255546afa86ddbf76206b9745ea92", "gpu:none", "master:chromium.memory", "name:extensions_browsertests", "os:Ubuntu-14.04", "pool:Chrome", "priority:25", "project:chromium", "purpose:CI", "purpose:luci", "purpose:post-commit", "service_account:none", "slavename:swarm2374-c4", "spec_name:chromium.ci:Linux ChromiumOS MSan Tests", "stepname:extensions_browsertests", "swarming.pool.template:none", "swarming.pool.version:b0a6cb78cd34832b49b843b36f65ec5a7a568a79", "user:None" ], "task_id": "4457a26fc3151610", "try_number": "1" } ] }