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

cmd.exe /c type .debug.log in dir C:\b\s\w\ir\cache\builder\sdk: allow_subannotations: False base_name: 'debug log' cmd: ['cmd.exe', '/c', 'type', '.debug.log'] cwd: 'C:\\b\\s\\w\\ir\\cache\\builder\\sdk' env: {} env_prefixes: {} env_suffixes: {} infra_step: False name: 'debug log' nest_level: 0 ok_ret: frozenset([-256, -255, -254, -253, -252, -251, -250, -249, -248, -247, -246, -245, -244, -243, -242, -241, -240, -239, -238, -237, -236, -235, -234, -233, -232, -231, -230, -229, -228, -227, -226, -225, -224, -223, -222, -221, -220, -219, -218, -217, -216, -215, -214, -213, -212, -211, -210, -209, -208, -207, -206, -205, -204, -203, -202, -201, -200, -199, -198, -197, -196, -195, -194, -193, -192, -191, -190, -189, -188, -187, -186, -185, -184, -183, -182, -181, -180, -179, -178, -177, -176, -175, -174, -173, -172, -171, -170, -169, -168, -167, -166, -165, -164, -163, -162, -161, -160, -159, -158, -157, -156, -155, -154, -153, -152, -151, -150, -149, -148, -147, -146, -145, -144, -143, -142, -141, -140, -139, -138, -137, -136, -135, -134, -133, -132, -131, -130, -129, -128, -127, -126, -125, -124, -123, -122, -121, -120, -119, -118, -117, -116, -115, -114, -113, -112, -111, -110, -109, -108, -107, -106, -105, -104, -103, -102, -101, -100, -99, -98, -97, -96, -95, -94, -93, -92, -91, -90, -89, -88, -87, -86, -85, -84, -83, -82, -81, -80, -79, -78, -77, -76, -75, -74, -73, -72, -71, -70, -69, -68, -67, -66, -65, -64, -63, -62, -61, -60, -59, -58, -57, -56, -55, -54, -53, -52, -51, -50, -49, -48, -47, -46, -45, -44, -43, -42, -41, -40, -39, -38, -37, -36, -35, -34, -33, -32, -31, -30, -29, -28, -27, -26, -25, -24, -23, -22, -21, -20, -19, -18, -17, -16, -15, -14, -13, -12, -11, -10, -9, -8, -7, -6, -5, -4, -3, -2, -1, 0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30, 31, 32, 33, 34, 35, 36, 37, 38, 39, 40, 41, 42, 43, 44, 45, 46, 47, 48, 49, 50, 51, 52, 53, 54, 55, 56, 57, 58, 59, 60, 61, 62, 63, 64, 65, 66, 67, 68, 69, 70, 71, 72, 73, 74, 75, 76, 77, 78, 79, 80, 81, 82, 83, 84, 85, 86, 87, 88, 89, 90, 91, 92, 93, 94, 95, 96, 97, 98, 99, 100, 101, 102, 103, 104, 105, 106, 107, 108, 109, 110, 111, 112, 113, 114, 115, 116, 117, 118, 119, 120, 121, 122, 123, 124, 125, 126, 127, 128, 129, 130, 131, 132, 133, 134, 135, 136, 137, 138, 139, 140, 141, 142, 143, 144, 145, 146, 147, 148, 149, 150, 151, 152, 153, 154, 155, 156, 157, 158, 159, 160, 161, 162, 163, 164, 165, 166, 167, 168, 169, 170, 171, 172, 173, 174, 175, 176, 177, 178, 179, 180, 181, 182, 183, 184, 185, 186, 187, 188, 189, 190, 191, 192, 193, 194, 195, 196, 197, 198, 199, 200, 201, 202, 203, 204, 205, 206, 207, 208, 209, 210, 211, 212, 213, 214, 215, 216, 217, 218, 219, 220, 221, 222, 223, 224, 225, 226, 227, 228, 229, 230, 231, 232, 233, 234, 235, 236, 237, 238, 239, 240, 241, 242, 243, 244, 245, 246, 247, 248, 249, 250, 251, 252, 253, 254, 255]) trigger_specs: () full environment: ALLUSERSPROFILE: C:\ProgramData APPDATA: C:\Users\chrome-bot\AppData\Roaming BOTO_CONFIG: C:\b\s\w\ir\tmp\gsutil_task\.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: C:\b\s\cipd_cache\cache CIPD_EXE_SHIM: "C:\b\s\w\ir\cipd_bin_packages\vpython.exe" CIPD_PROTOCOL: v2 COMMONPROGRAMFILES: C:\Program Files\Common Files COMMONPROGRAMFILES(X86): C:\Program Files (x86)\Common Files COMMONPROGRAMW6432: C:\Program Files\Common Files COMPUTERNAME: WIN10-DART-0552 COMSPEC: C:\Windows\system32\cmd.exe FPS_BROWSER_APP_PROFILE_STRING: Internet Explorer FPS_BROWSER_USER_PROFILE_STRING: Default GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOMEDRIVE: C: HOMEPATH: \Users\chrome-bot INFRA_GIT_WRAPPER_HOME: C:\b\s\w\ir\tmp\git_home_task LC_ALL: English_United States.UTF-8 LC_COLLATE: English_United States.UTF-8 LC_CTYPE: English_United States.UTF-8 LC_MONETARY: English_United States.UTF-8 LC_NUMERIC: English_United States.UTF-8 LC_TIME: English_United States.UTF-8 LOCALAPPDATA: C:\Users\chrome-bot\AppData\Local LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8936688432441121520 LOGDOG_STREAM_PROJECT: dart LOGDOG_STREAM_SERVER_PATH: net.pipe:LUCILogDogKitchen_11144 LOGONSERVER: \\WIN10-DART-0552 LUCI_CONTEXT: C:\b\s\w\itns4gum\luci_context.750816754 MAC_CHROMIUM_TMPDIR: C:\b\s\w\ir\tmp\t NO_GCE_CHECK: False NUMBER_OF_PROCESSORS: 8 ONEDRIVE: C:\Users\chrome-bot\OneDrive OS: Windows_NT PATH: C:\b\s\w\ir\cipd_bin_packages;C:\b\s\w\ir\cipd_bin_packages\bin;C:\b\s\cipd_cache\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Puppet Labs\Puppet\bin;C:\b\s\w\ir\cache\vpython\1bda7b\lib\site-packages\pywin32_system32 PATHEXT: .COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE: AMD64 PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 63 Stepping 0, GenuineIntel PROCESSOR_LEVEL: 6 PROCESSOR_REVISION: 3f00 PROGRAMDATA: C:\ProgramData PROGRAMFILES: C:\Program Files PROGRAMFILES(X86): C:\Program Files (x86) PROGRAMW6432: C:\Program Files PROMPT: $P$G PSMODULEPATH: C:\Program Files\WindowsPowerShell\Modules;C:\Windows\system32\WindowsPowerShell\v1.0\Modules PUBLIC: C:\Users\Public PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SESSIONNAME: Console SWARMING_BOT_ID: win10-dart-05529b1d-us-central1-c-qrc8 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 3fa813885baf2f11 SYSTEMDRIVE: C: SYSTEMROOT: C:\Windows TEMP: C:\b\s\w\ir\tmp\t TEMPDIR: C:\b\s\w\ir\tmp\t TMP: C:\b\s\w\ir\tmp\t TMPDIR: C:\b\s\w\ir\tmp\t USERDOMAIN: WIN10-DART-0552 USERDOMAIN_ROAMINGPROFILE: WIN10-DART-0552 USERNAME: chrome-bot USERPROFILE: C:\Users\chrome-bot VPYTHON_VIRTUALENV_ROOT: C:\b\s\w\ir\cache\vpython WINDIR: C:\Windows 2018-08-31 02:25:43.810153 Info: Chrome (BROWSER1) - Starting chrome browser on: http://127.0.0.1:51473/driver/BROWSER1 2018-08-31 02:25:43.810153 Info: Chrome (BROWSER1) - Got version: Can't get version on windows 2018-08-31 02:25:43.825790 Info: Chrome (BROWSER1) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\d314bb80-acff-11e8-87d5-ddeae0176c09 http://127.0.0.1:51473/driver/BROWSER1 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:25:45.509939 Info: Chrome (BROWSER2) - Starting chrome browser on: http://127.0.0.1:51473/driver/BROWSER2 2018-08-31 02:25:45.514816 Info: Chrome (BROWSER2) - Got version: Can't get version on windows 2018-08-31 02:25:45.517752 Info: Chrome (BROWSER2) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\d314bb82-acff-11e8-87d5-ddeae0176c09 http://127.0.0.1:51473/driver/BROWSER2 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:25:53.935153 Info: Chrome (BROWSER3) - Starting chrome browser on: http://127.0.0.1:51473/driver/BROWSER3 2018-08-31 02:25:53.935153 Info: Chrome (BROWSER3) - Got version: Can't get version on windows 2018-08-31 02:25:53.952303 Info: Chrome (BROWSER3) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\db38816a-acff-11e8-87d5-ddeae0176c09 http://127.0.0.1:51473/driver/BROWSER3 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:25:55.880941 Info: Chrome (BROWSER4) - Starting chrome browser on: http://127.0.0.1:51473/driver/BROWSER4 2018-08-31 02:25:55.881910 Info: Chrome (BROWSER4) - Got version: Can't get version on windows 2018-08-31 02:25:55.883865 Info: Chrome (BROWSER4) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\db38816c-acff-11e8-87d5-ddeae0176c09 http://127.0.0.1:51473/driver/BROWSER4 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:25:56.998124 Info: Chrome (BROWSER1) - Close called on browser 2018-08-31 02:25:56.998124 Info: Chrome (BROWSER1) - Successfully sent kill signal to process. 2018-08-31 02:25:57.126062 Info: Chrome (BROWSER1) - Browser closed with exitcode -1 2018-08-31 02:26:27.138367 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:26:27.138367 Info: Chrome (BROWSER1) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\d314bb80-acff-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\d314bb80-acff-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:26:27.138367 Info: Chrome (BROWSER2) - Close called on browser 2018-08-31 02:26:27.138367 Info: Chrome (BROWSER2) - Successfully sent kill signal to process. 2018-08-31 02:26:27.169626 Info: Chrome (BROWSER2) - Browser closed with exitcode -1 2018-08-31 02:26:57.175771 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:26:57.178697 Info: Chrome (BROWSER2) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\d314bb82-acff-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\d314bb82-acff-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:26:57.178697 Info: Chrome (BROWSER3) - Close called on browser 2018-08-31 02:26:57.178697 Info: Chrome (BROWSER3) - Successfully sent kill signal to process. 2018-08-31 02:26:57.209954 Info: Chrome (BROWSER3) - Browser closed with exitcode -1 2018-08-31 02:27:27.216385 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:27:27.216385 Info: Chrome (BROWSER3) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\db38816a-acff-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\db38816a-acff-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:27:27.216385 Info: Chrome (BROWSER4) - Close called on browser 2018-08-31 02:27:27.216385 Info: Chrome (BROWSER4) - Successfully sent kill signal to process. 2018-08-31 02:27:27.263177 Info: Chrome (BROWSER4) - Browser closed with exitcode -1 2018-08-31 02:27:57.278797 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:27:57.278797 Info: Chrome (BROWSER4) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\db38816c-acff-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\db38816c-acff-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:29:13.392364 Info: Chrome (BROWSER1) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER1 2018-08-31 02:29:13.402131 Info: Chrome (BROWSER1) - Got version: Can't get version on windows 2018-08-31 02:29:13.409955 Info: Chrome (BROWSER1) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\521ce6bb-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER1 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:15.916776 Info: Chrome (BROWSER2) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER2 2018-08-31 02:29:15.918731 Info: Chrome (BROWSER2) - Got version: Can't get version on windows 2018-08-31 02:29:15.922643 Info: Chrome (BROWSER2) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\521ce6bd-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER2 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:17.407017 Info: Chrome (BROWSER3) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER3 2018-08-31 02:29:17.407988 Info: Chrome (BROWSER3) - Got version: Can't get version on windows 2018-08-31 02:29:17.410915 Info: Chrome (BROWSER3) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\521ce6bf-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER3 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:33.885500 Info: Chrome (BROWSER4) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER4 2018-08-31 02:29:33.921631 Info: Chrome (BROWSER4) - Got version: Can't get version on windows 2018-08-31 02:29:33.925554 Info: Chrome (BROWSER4) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\5e57eda8-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER4 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:36.537848 Info: Chrome (BROWSER5) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER5 2018-08-31 02:29:36.538814 Info: Chrome (BROWSER5) - Got version: Can't get version on windows 2018-08-31 02:29:36.541754 Info: Chrome (BROWSER5) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\5e57edaa-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER5 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:48.337632 Info: Chrome (BROWSER6) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER6 2018-08-31 02:29:48.370841 Info: Chrome (BROWSER6) - Got version: Can't get version on windows 2018-08-31 02:29:48.376692 Info: Chrome (BROWSER6) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\66f48d81-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER6 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:51.173556 Info: Chrome (BROWSER7) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER7 2018-08-31 02:29:51.174531 Info: Chrome (BROWSER7) - Got version: Can't get version on windows 2018-08-31 02:29:51.177466 Info: Chrome (BROWSER7) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\66f48d83-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER7 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:29:54.295632 Info: Chrome (BROWSER8) - Starting chrome browser on: http://127.0.0.1:51532/driver/BROWSER8 2018-08-31 02:29:54.296598 Info: Chrome (BROWSER8) - Got version: Can't get version on windows 2018-08-31 02:29:54.299530 Info: Chrome (BROWSER8) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\66f48d85-ad00-11e8-87d5-ddeae0176c09 http://127.0.0.1:51532/driver/BROWSER8 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:30:51.993777 Info: Chrome (BROWSER1) - Close called on browser 2018-08-31 02:30:51.994754 Info: Chrome (BROWSER1) - Successfully sent kill signal to process. 2018-08-31 02:30:52.303342 Info: Chrome (BROWSER1) - Browser closed with exitcode -1 2018-08-31 02:31:22.309889 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:31:22.309889 Info: Chrome (BROWSER1) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\521ce6bb-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\521ce6bb-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:31:22.309889 Info: Chrome (BROWSER2) - Close called on browser 2018-08-31 02:31:22.309889 Info: Chrome (BROWSER2) - Successfully sent kill signal to process. 2018-08-31 02:31:22.341094 Info: Chrome (BROWSER2) - Browser closed with exitcode -1 2018-08-31 02:31:52.356700 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:31:52.356700 Info: Chrome (BROWSER2) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\521ce6bd-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\521ce6bd-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:31:52.356700 Info: Chrome (BROWSER3) - Close called on browser 2018-08-31 02:31:52.356700 Info: Chrome (BROWSER3) - Successfully sent kill signal to process. 2018-08-31 02:31:52.387920 Info: Chrome (BROWSER3) - Browser closed with exitcode -1 2018-08-31 02:32:22.403567 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:32:22.403567 Info: Chrome (BROWSER3) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\521ce6bf-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\521ce6bf-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:32:22.403567 Info: Chrome (BROWSER4) - Close called on browser 2018-08-31 02:32:22.403567 Info: Chrome (BROWSER4) - Successfully sent kill signal to process. 2018-08-31 02:32:22.434800 Info: Chrome (BROWSER4) - Browser closed with exitcode -1 2018-08-31 02:32:52.466005 Info: Chrome (BROWSER5) - Close called on browser 2018-08-31 02:32:52.466005 Info: Chrome (BROWSER5) - Successfully sent kill signal to process. 2018-08-31 02:32:52.497248 Info: Chrome (BROWSER5) - Browser closed with exitcode -1 2018-08-31 02:33:22.512871 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:33:22.512871 Info: Chrome (BROWSER5) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\5e57edaa-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\5e57edaa-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:33:22.512871 Info: Chrome (BROWSER6) - Close called on browser 2018-08-31 02:33:22.512871 Info: Chrome (BROWSER6) - Successfully sent kill signal to process. 2018-08-31 02:33:22.544087 Info: Chrome (BROWSER6) - Browser closed with exitcode -1 2018-08-31 02:33:52.559710 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:33:52.559710 Info: Chrome (BROWSER6) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\66f48d81-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\66f48d81-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:33:52.559710 Info: Chrome (BROWSER7) - Close called on browser 2018-08-31 02:33:52.559710 Info: Chrome (BROWSER7) - Successfully sent kill signal to process. 2018-08-31 02:33:52.590953 Info: Chrome (BROWSER7) - Browser closed with exitcode -1 2018-08-31 02:34:22.606544 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:34:22.606544 Info: Chrome (BROWSER7) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\66f48d83-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\66f48d83-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:34:22.606544 Info: Chrome (BROWSER8) - Close called on browser 2018-08-31 02:34:22.606544 Info: Chrome (BROWSER8) - Successfully sent kill signal to process. 2018-08-31 02:34:22.637767 Info: Chrome (BROWSER8) - Browser closed with exitcode -1 2018-08-31 02:34:52.653396 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:34:52.653396 Info: Chrome (BROWSER8) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\66f48d85-ad00-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\66f48d85-ad00-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:34:52.653396 Warning: Double reporting tests: 2018-08-31 02:34:52.653396 Warning: http://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short5_ory_ui_class_tree_element_test/test.html?crossOriginPort=51533, output: 2018-08-31 02:34:52.653396 Warning: [{"type":"debug","value":"test_controller.js started","timestamp":0},{"type":"debug","value":"Sending events to driver page (isFirstMessage = true, isStatusUpdate = true, isDone = false)","timestamp":0},{"type":"print","value":"dart-calling-main","timestamp":0.282},{"type":"print","value":"dart-main-done","timestamp":0.342},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation default\u001b[0m","timestamp":0.443},{"type":"message_received","value":"dart-calling-main","timestamp":0.459},{"type":"message_received","value":"dart-main-done","timestamp":0.46},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.46},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created after attachment\u001b[0m","timestamp":0.493},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: elements created after attachment \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":0.521},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.522},{"type":"print","value":" element_test.js 2524:17 Object.wrapException\n element_test.js 3000:15 Object.propertyTypeError\n element_test.js 3011:9 Object.interceptedTypeCheck\n element_test.js 24426:40 ClassRepositoryMock.getObject$1\n element_test.js 18427:75 <fn>\n element_test.js 5137:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5793:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 21460:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 21381:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 21451:27 StackZoneSpecification__registerBinaryCallback_closure.dart.StackZoneSpecification__registerBinaryCallback_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 6214:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 5775:38 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 21381:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 21422:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5457:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 7878:176 _CustomZone.registerCallback$1$1\n element_test.js 7814:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5373:33 Object.scheduleMicrotask\n element_test.js 5762:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5109:57 Object._asyncRethrow\n element_test.js 18415:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 18413:35 HtmlElement._refresh$0\n element_test.js 18343:14 HtmlElement.attached$0\n element_test.js 24679:41 Object.J.attached$0$x\n element_test.js 13091:16 StaticClosure.dart._callAttached\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 24281:31 main__closure.call$0\n element_test.js 21875:55 <fn>\n element_test.js 5137:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5793:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 21863:31 Declarer_test___closure.call$0\n element_test.js 22222:53 <fn>\n element_test.js 5137:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5793:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 22211:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5461:16 StaticClosure.dart._rootRun\n element_test.js 7851:160 _CustomZone.run$1$1\n element_test.js 5644:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 21811:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 22365:118 <fn>\n element_test.js 5137:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5793:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 22356:31 Invoker__onRun____closure.call$0\n element_test.js 6084:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 21381:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 21422:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 7878:176 _CustomZone.registerCallback$1$1\n element_test.js 7814:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5437:44 Object.Timer_Timer\n element_test.js 5151:9 Object.Future_Future\n element_test.js 22318:19 <fn>\n element_test.js 5137:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 7894:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5145:30 Object._wrapJsFunctionForAsync\n element_test.js 22307:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5461:16 StaticClosure.dart._rootRun\n element_test.js 7851:160 _CustomZone.run$1$1\n element_test.js 5644:89 Object._runZoned\n ","timestamp":0.851},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":0.891},{"type":"print","value":"","timestamp":0.892},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short5_ory_ui_class_tree_element_test/element_test.js:7979:\nUncaught \n\n","timestamp":0.893},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.893},{"type":"dom","value":"<head>\n <meta http-equiv=\"X-UA-Compatible\" content=\"IE=edge\">\n <meta name=\"dart.unittest\" content=\"full-stack-traces\">\n <style>\n .unittest-table { font-family:monospace; border:1px; }\n .unittest-pass { background: #6b3;}\n .unittest-fail { background: #d55;}\n .unittest-error { background: #a11;}\n class-tree virtual-tree .class-tree-item {\n line-height: 25px;\n height: 25px;\n padding-left: 10%;\n padding-right: 10%;\n }\n </style>\n</head>\n<body>\n <script type=\"text/javascript\" src=\"/root_dart/tools/testing/dart/test_controller.js\"></script>\n <script src=\"element_test.js\"></script>\n\n\n</body>","timestamp":0.893}] 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:34:52.653396 Warning: http://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/test.html?crossOriginPort=51533, output: 2018-08-31 02:34:52.653396 Warning: [{"type":"debug","value":"test_controller.js started","timestamp":0},{"type":"debug","value":"Sending events to driver page (isFirstMessage = true, isStatusUpdate = true, isDone = false)","timestamp":0},{"type":"print","value":"dart-calling-main","timestamp":0.244},{"type":"print","value":"dart-main-done","timestamp":0.298},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.369},{"type":"message_received","value":"dart-calling-main","timestamp":0.403},{"type":"message_received","value":"dart-main-done","timestamp":0.404},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.404},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.425},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: elements created \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":0.492},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.492},{"type":"print","value":" element_test.js 2930:17 Object.wrapException\n element_test.js 3416:15 Object.propertyTypeError\n element_test.js 3427:9 Object.interceptedTypeCheck\n element_test.js 28431:37 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 28437:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22039:86 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 25182:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6661:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6222:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5904:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5820:33 Object.scheduleMicrotask\n element_test.js 6209:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5557:57 Object._asyncRethrow\n element_test.js 22029:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 22027:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22051:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 21649:14 HtmlElement.attached$0\n element_test.js 28730:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 27987:31 main_closure0.call$0\n element_test.js 25597:55 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25585:31 Declarer_test___closure.call$0\n element_test.js 25944:53 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25933:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25533:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 26087:118 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26078:31 Invoker__onRun____closure.call$0\n element_test.js 6531:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5884:44 Object.Timer_Timer\n element_test.js 5599:9 Object.Future_Future\n element_test.js 26040:19 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26029:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ","timestamp":0.759},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: reacts to refresh\u001b[0m","timestamp":0.833},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -2\u001b[0m: reacts to refresh \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":0.87},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":0.87},{"type":"print","value":" element_test.js 2930:17 Object.wrapException\n element_test.js 3416:15 Object.propertyTypeError\n element_test.js 3427:9 Object.interceptedTypeCheck\n element_test.js 28431:37 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 28437:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22039:86 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 25182:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6661:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6222:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5904:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5820:33 Object.scheduleMicrotask\n element_test.js 6209:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5557:57 Object._asyncRethrow\n element_test.js 22029:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 22027:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22051:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 21649:14 HtmlElement.attached$0\n element_test.js 28730:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 28070:31 main__closure.call$0\n element_test.js 25597:55 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25585:31 Declarer_test___closure.call$0\n element_test.js 25944:53 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25933:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25533:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 26087:118 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26078:31 Invoker__onRun____closure.call$0\n element_test.js 6531:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5884:44 Object.Timer_Timer\n element_test.js 5599:9 Object.Future_Future\n element_test.js 26040:19 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26029:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ","timestamp":1.045},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -2\u001b[0m: reacts to gc\u001b[0m","timestamp":1.058},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to gc \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.089},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.089},{"type":"print","value":" element_test.js 2930:17 Object.wrapException\n element_test.js 3416:15 Object.propertyTypeError\n element_test.js 3427:9 Object.interceptedTypeCheck\n element_test.js 28431:37 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 28437:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22039:86 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 25182:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6661:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6222:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5904:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5820:33 Object.scheduleMicrotask\n element_test.js 6209:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5557:57 Object._asyncRethrow\n element_test.js 22029:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 22027:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22051:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 21649:14 HtmlElement.attached$0\n element_test.js 28730:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 28163:31 main__closure0.call$0\n element_test.js 25597:55 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25585:31 Declarer_test___closure.call$0\n element_test.js 25944:53 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25933:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25533:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 26087:118 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26078:31 Invoker__onRun____closure.call$0\n element_test.js 6531:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5884:44 Object.Timer_Timer\n element_test.js 5599:9 Object.Future_Future\n element_test.js 26040:19 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26029:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ","timestamp":1.259},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -3\u001b[0m: reacts to sort change\u001b[0m","timestamp":1.268},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -4\u001b[0m: reacts to sort change \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":1.292},{"type":"print","value":" TypeError: Instance of 'IsolateRefMock': type 'IsolateRefMock' is not a subtype of type 'Isolate'","timestamp":1.293},{"type":"print","value":" element_test.js 2930:17 Object.wrapException\n element_test.js 3416:15 Object.propertyTypeError\n element_test.js 3427:9 Object.interceptedTypeCheck\n element_test.js 28431:37 AllocationProfileRepositoryMock.$get$4$combine$gc$reset\n element_test.js 28437:21 AllocationProfileRepositoryMock.$get$3$gc$reset\n element_test.js 22039:86 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n element_test.js 25182:52 StackZoneSpecification__registerBinaryCallback__closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n ===== asynchronous gap ===========================\n element_test.js 6661:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 6222:31 _AsyncAwaitCompleter_completeError_closure.dart._AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5904:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5820:33 Object.scheduleMicrotask\n element_test.js 6209:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5557:57 Object._asyncRethrow\n element_test.js 22029:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 22027:64 HtmlElement._allocation_profile$_refresh$2$gc$reset\n element_test.js 22051:21 HtmlElement._allocation_profile$_refresh$0\n element_test.js 21649:14 HtmlElement.attached$0\n element_test.js 28730:41 Object.J.attached$0$x\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 28287:31 main__closure1.call$0\n element_test.js 25597:55 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25585:31 Declarer_test___closure.call$0\n element_test.js 25944:53 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25933:31 Invoker_waitForOutstandingCallbacks_closure.dart.Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 25533:31 Declarer_test_closure.dart.Declarer_test_closure.call$0\n element_test.js 26087:118 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 6240:14 _wrapJsFunctionForAsync_closure.dart._wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26078:31 Invoker__onRun____closure.call$0\n element_test.js 6531:52 Future_Future_closure.dart.Future_Future_closure.call$0\n element_test.js 25103:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 25144:27 StackZoneSpecification__registerCallback_closure.dart.StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8327:176 _CustomZone.registerCallback$1$1\n element_test.js 8263:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5884:44 Object.Timer_Timer\n element_test.js 5599:9 Object.Future_Future\n element_test.js 26040:19 <fn>\n element_test.js 5585:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8343:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5593:30 Object._wrapJsFunctionForAsync\n element_test.js 26029:31 Invoker__onRun___closure.dart.Invoker__onRun___closure.call$0\n element_test.js 5908:16 StaticClosure.dart._rootRun\n element_test.js 8300:160 _CustomZone.run$1$1\n element_test.js 6091:89 Object._runZoned\n ","timestamp":1.423},{"type":"print","value":"00:01 \u001b[32m+1\u001b[0m\u001b[31m -4\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":1.439},{"type":"print","value":"","timestamp":1.439},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short1_llocation_profile_element_test/element_test.js:8428:\nUncaught \n\n","timestamp":1.44},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":1.44},{"type":"dom","value":"<head>\n <meta http-equiv=\"X-UA-Compatible\" content=\"IE=edge\">\n <meta name=\"dart.unittest\" content=\"full-stack-traces\">\n <style>\n .unittest-table { font-family:monospace; border:1px; }\n .unittest-pass { background: #6b3;}\n .unittest-fail { background: #d55;}\n .unittest-error { background: #a11;}\n </style>\n</head>\n<body>\n <script type=\"text/javascript\" src=\"/root_dart/tools/testing/dart/test_controller.js\"></script>\n <script src=\"element_test.js\"></script>\n\n\n<allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile><allocation-profile><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>allocation profile</a><ul></ul></span></li><nav-refresh><li><button disabled=\"\">Download</button></li></nav-refresh><nav-refresh><li><button>Reset Accumulator</button></li></nav-refresh><nav-refresh><li><button>GC</button></li></nav-refresh><nav-refresh><li><button>Refresh</button></li></nav-refresh><div class=\"nav-option\"><input type=\"checkbox\" id=\"allocation-profile-auto-refresh\"><label for=\"allocation-profile-auto-refresh\">Auto-refresh on GC</label></div><nav-notify><div><div></div></div></nav-notify></ul></nav><div class=\"content-centered-big\"><h2>Allocation Profile</h2><hr></div><div class=\"content-centered-big\"><h2>Loading...</h2></div></allocation-profile></body>","timestamp":1.44}] 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:34:52.653396 Warning: http://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/test.html?crossOriginPort=51533, output: 2018-08-31 02:34:52.653396 Warning: [{"type":"debug","value":"test_controller.js started","timestamp":0},{"type":"debug","value":"Sending events to driver page (isFirstMessage = true, isStatusUpdate = true, isDone = false)","timestamp":0},{"type":"print","value":"dart-calling-main","timestamp":0.237},{"type":"print","value":"dart-main-done","timestamp":0.292},{"type":"print","value":"00:00 \u001b[32m+0\u001b[0m: instantiation\u001b[0m","timestamp":0.365},{"type":"message_received","value":"dart-calling-main","timestamp":0.384},{"type":"message_received","value":"dart-main-done","timestamp":0.384},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.384},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m: elements created\u001b[0m","timestamp":0.404},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: elements created \u001b[1m\u001b[31m[E]\u001b[0m\u001b[0m","timestamp":0.479},{"type":"print","value":" Expected: false\n Actual: <true>\n ","timestamp":0.48},{"type":"print","value":" element_test.js 2765:17 Object.wrapException\n element_test.js 2778:15 Object.throwExpression\n element_test.js 25003:16 Object.fail\n element_test.js 25000:9 Object._expect\n element_test.js 24978:9 Object.expect\n element_test.js 26316:11 main__closure.call$2\n element_test.js 26355:28 HeapSnapshotRepositoryMock.$get$3$gc$roots\n element_test.js 20018:101 <fn>\n element_test.js 5295:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5948:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 6369:39 _SyncCompleter.dart._Completer.completeError$2\n element_test.js 5930:31 _AsyncAwaitCompleter_completeError_closure.call$0\n element_test.js 23539:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 23580:27 StackZoneSpecification__registerCallback_closure.call$0\n element_test.js 5614:18 StaticClosure.dart._rootRun\n ===== asynchronous gap ===========================\n element_test.js 8048:176 _CustomZone.registerCallback$1$1\n element_test.js 7984:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5530:33 Object.scheduleMicrotask\n element_test.js 5917:13 _AsyncAwaitCompleter.completeError$2\n element_test.js 5267:57 Object._asyncRethrow\n element_test.js 20012:22 <fn>\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 20010:50 HtmlElement._heap_snapshot$_refresh$0\n element_test.js 19932:14 HtmlElement.attached$0\n element_test.js 26654:41 Object.J.attached$0$x\n element_test.js 13246:16 StaticClosure.dart._callAttached\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 26251:31 main_closure0.call$0\n element_test.js 24006:55 <fn>\n element_test.js 5295:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5948:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 23994:31 Declarer_test___closure.call$0\n element_test.js 24342:53 <fn>\n element_test.js 5295:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5948:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 24331:31 Invoker_waitForOutstandingCallbacks_closure.call$0\n element_test.js 5618:16 StaticClosure.dart._rootRun\n element_test.js 8021:160 _CustomZone.run$1$1\n element_test.js 5799:89 Object._runZoned\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 23942:31 Declarer_test_closure.call$0\n element_test.js 24485:118 <fn>\n element_test.js 5295:15 _wrapJsFunctionForAsync_closure.$protected\n element_test.js 5948:14 _wrapJsFunctionForAsync_closure.call$2\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 24476:31 Invoker__onRun____closure.call$0\n element_test.js 6239:52 Future_Future_closure.call$0\n element_test.js 23539:18 StackZoneSpecification._stack_zone_specification$_run$1$2\n element_test.js 23580:27 StackZoneSpecification__registerCallback_closure.call$0\n ===== asynchronous gap ===========================\n element_test.js 8048:176 _CustomZone.registerCallback$1$1\n element_test.js 7984:73 _CustomZone.bindCallbackGuarded$1\n element_test.js 5594:44 Object.Timer_Timer\n element_test.js 5309:9 Object.Future_Future\n element_test.js 24438:19 <fn>\n element_test.js 5295:15 _wrapJsFunctionForAsync_closure.$protected\n ===== asynchronous gap ===========================\n element_test.js 8064:224 _CustomZone.registerBinaryCallback$3$1\n element_test.js 5303:30 Object._wrapJsFunctionForAsync\n element_test.js 24427:31 Invoker__onRun___closure.call$0\n element_test.js 5618:16 StaticClosure.dart._rootRun\n element_test.js 8021:160 _CustomZone.run$1$1\n element_test.js 5799:89 Object._runZoned\n ","timestamp":0.779},{"type":"print","value":"00:00 \u001b[32m+1\u001b[0m\u001b[31m -1\u001b[0m: \u001b[31mSome tests failed.\u001b[0m","timestamp":0.8},{"type":"print","value":"","timestamp":0.801},{"type":"window_onerror","value":"window.onerror called: \n\nhttp://127.0.0.1:51532/root_build/generated_tests/dart2js-chrome-sdk/short33__ui_heap_snapshot_element_test/element_test.js:8149:\nUncaught \n\n","timestamp":0.802},{"type":"debug","value":"Sending events to driver page (isFirstMessage = false, isStatusUpdate = false, isDone = true)","timestamp":0.802},{"type":"dom","value":"<head>\n <meta http-equiv=\"X-UA-Compatible\" content=\"IE=edge\">\n <meta name=\"dart.unittest\" content=\"full-stack-traces\">\n <style>\n .unittest-table { font-family:monospace; border:1px; }\n .unittest-pass { background: #6b3;}\n .unittest-fail { background: #d55;}\n .unittest-error { background: #a11;}\n </style>\n</head>\n<body>\n <script type=\"text/javascript\" src=\"/root_dart/tools/testing/dart/test_controller.js\"></script>\n <script src=\"element_test.js\"></script>\n\n\n<heap-snapshot><nav class=\"nav-bar\"><ul><nav-top-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">Observatory</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/vm-connect\">Connect to a VM</a><ul></ul></li></nav-menu-item></ul></span></li></nav-top-menu><nav-vm-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/vm\">vm-display-name</a><ul></ul></span></li></nav-vm-menu><nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a href=\"#/inspect?isolateId=i-id\">i-name</a><ul><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/debugger?isolateId=i-id\">debugger</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/class-tree?isolateId=i-id\">class hierarchy</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler?isolateId=i-id\">cpu profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/profiler-table?isolateId=i-id\">cpu profile (table)</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/allocation-profiler?isolateId=i-id\">allocation profile</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-snapshot?isolateId=i-id\">heap snapshot</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/heap-map?isolateId=i-id\">heap map</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/metrics?isolateId=i-id\">metrics</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/persistent-handles?isolateId=i-id\">persistent handles</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/ports?isolateId=i-id\">ports</a><ul></ul></li></nav-menu-item><nav-menu-item><li class=\"nav-menu-item\"><a href=\"#/logging?isolateId=i-id\">logging</a><ul></ul></li></nav-menu-item></ul></span></li></nav-isolate-menu><li class=\"nav-menu\"><span class=\"nav-menu_label\"><a>heap snapshot</a><ul></ul></span></li><nav-refresh><li><button>Refresh</button></li></nav-refresh><nav-notify><div><div></div></div></nav-notify></ul></nav></heap-snapshot></body>","timestamp":0.802}] 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:34:52.653396 Warning: 2018-08-31 02:35:43.590832 Info: Chrome (BROWSER1) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER1 2018-08-31 02:35:43.590832 Info: Chrome (BROWSER1) - Got version: Can't get version on windows 2018-08-31 02:35:43.606450 Info: Chrome (BROWSER1) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\38f10037-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER1 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:35:45.392829 Info: Chrome (BROWSER2) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER2 2018-08-31 02:35:45.395777 Info: Chrome (BROWSER2) - Got version: Can't get version on windows 2018-08-31 02:35:45.398701 Info: Chrome (BROWSER2) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\38f10039-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER2 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:35:47.825154 Info: Chrome (BROWSER3) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER3 2018-08-31 02:35:47.825154 Info: Chrome (BROWSER3) - Got version: Can't get version on windows 2018-08-31 02:35:47.825154 Info: Chrome (BROWSER3) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\38f1003b-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER3 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:36:09.528298 Info: Chrome (BROWSER4) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER4 2018-08-31 02:36:09.543921 Info: Chrome (BROWSER4) - Got version: Can't get version on windows 2018-08-31 02:36:09.543921 Info: Chrome (BROWSER4) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\4a26f2f0-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER4 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:36:14.731428 Info: Chrome (BROWSER5) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER5 2018-08-31 02:36:14.731428 Info: Chrome (BROWSER5) - Got version: Can't get version on windows 2018-08-31 02:36:14.731428 Info: Chrome (BROWSER5) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\4a26f2f2-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER5 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:36:17.706166 Info: Chrome (BROWSER6) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER6 2018-08-31 02:36:17.706166 Info: Chrome (BROWSER6) - Got version: Can't get version on windows 2018-08-31 02:36:17.715780 Info: Chrome (BROWSER6) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\4a26f2f4-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER6 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:36:23.393671 Warning: HttpServer: could not find file for request path: "/root_build/generated_tests/dart2js-chrome-sdk/tests_compiler_dart2js_extra_deferred_fail_and_retry_test/non_existing.js" 2018-08-31 02:36:34.965760 Info: Chrome (BROWSER7) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER7 2018-08-31 02:36:34.965760 Info: Chrome (BROWSER7) - Got version: Can't get version on windows 2018-08-31 02:36:34.970816 Info: Chrome (BROWSER7) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\594e0383-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER7 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:36:46.001086 Info: Chrome (BROWSER8) - Starting chrome browser on: http://127.0.0.1:51645/driver/BROWSER8 2018-08-31 02:36:46.001086 Info: Chrome (BROWSER8) - Got version: Can't get version on windows 2018-08-31 02:36:46.004014 Info: Chrome (BROWSER8) - Started browser using browsers\Chrome\Application\chrome.exe --user-data-dir=C:\b\s\w\ir\tmp\t\5fe1def2-ad01-11e8-87d5-ddeae0176c09 http://127.0.0.1:51645/driver/BROWSER8 --disable-extensions --disable-popup-blocking --bwsi --no-first-run 2018-08-31 02:37:06.987359 Info: Chrome (BROWSER1) - Close called on browser 2018-08-31 02:37:06.988328 Info: Chrome (BROWSER1) - Successfully sent kill signal to process. 2018-08-31 02:37:07.144598 Info: Chrome (BROWSER1) - Browser closed with exitcode -1 2018-08-31 02:37:37.153249 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:37:37.153249 Info: Chrome (BROWSER1) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\38f10037-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\38f10037-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:37:37.153249 Info: Chrome (BROWSER2) - Close called on browser 2018-08-31 02:37:37.153249 Info: Chrome (BROWSER2) - Successfully sent kill signal to process. 2018-08-31 02:37:37.200087 Info: Chrome (BROWSER2) - Browser closed with exitcode -1 2018-08-31 02:38:07.215694 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:38:07.215694 Info: Chrome (BROWSER2) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\38f10039-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\38f10039-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:38:07.215694 Info: Chrome (BROWSER3) - Close called on browser 2018-08-31 02:38:07.215694 Info: Chrome (BROWSER3) - Successfully sent kill signal to process. 2018-08-31 02:38:07.246918 Info: Chrome (BROWSER3) - Browser closed with exitcode -1 2018-08-31 02:38:37.262597 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:38:37.262597 Info: Chrome (BROWSER3) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\38f1003b-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\38f1003b-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:38:37.262597 Info: Chrome (BROWSER4) - Close called on browser 2018-08-31 02:38:37.262597 Info: Chrome (BROWSER4) - Successfully sent kill signal to process. 2018-08-31 02:38:37.293763 Info: Chrome (BROWSER4) - Browser closed with exitcode -1 2018-08-31 02:39:07.325021 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:39:07.325021 Info: Chrome (BROWSER4) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\4a26f2f0-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\4a26f2f0-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:39:07.325021 Info: Chrome (BROWSER5) - Close called on browser 2018-08-31 02:39:07.325021 Info: Chrome (BROWSER5) - Successfully sent kill signal to process. 2018-08-31 02:39:07.356244 Info: Chrome (BROWSER5) - Browser closed with exitcode -1 2018-08-31 02:39:37.371855 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:39:37.371855 Info: Chrome (BROWSER5) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\4a26f2f2-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\4a26f2f2-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:39:37.371855 Info: Chrome (BROWSER6) - Close called on browser 2018-08-31 02:39:37.371855 Info: Chrome (BROWSER6) - Successfully sent kill signal to process. 2018-08-31 02:39:37.403082 Info: Chrome (BROWSER6) - Browser closed with exitcode -1 2018-08-31 02:40:07.418698 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:40:07.418698 Info: Chrome (BROWSER6) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\4a26f2f4-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\4a26f2f4-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:40:07.418698 Info: Chrome (BROWSER7) - Close called on browser 2018-08-31 02:40:07.418698 Info: Chrome (BROWSER7) - Successfully sent kill signal to process. 2018-08-31 02:40:07.449934 Info: Chrome (BROWSER7) - Browser closed with exitcode -1 2018-08-31 02:40:37.465539 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:40:37.465539 Info: Chrome (BROWSER7) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\594e0383-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\594e0383-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5) 2018-08-31 02:40:37.465539 Info: Chrome (BROWSER8) - Close called on browser 2018-08-31 02:40:37.465539 Info: Chrome (BROWSER8) - Successfully sent kill signal to process. 2018-08-31 02:40:37.481158 Info: Chrome (BROWSER8) - Browser closed with exitcode -1 2018-08-31 02:41:07.496782 Warning: Not waiting for stdout/stderr from subprocess anymore (0:00:30.000000 passed). Please note that this could be an indicator that there is a hanging process which we were unable to kill. (browser: Chrome) 2018-08-31 02:41:07.496782 Info: Chrome (BROWSER8) - Error: failed to delete Chrome user-data-dir C:\b\s\w\ir\tmp\t\5fe1def2-ad01-11e8-87d5-ddeae0176c09, will try again in 40 seconds: FileSystemException: Deletion failed, path = 'C:\b\s\w\ir\tmp\t\5fe1def2-ad01-11e8-87d5-ddeae0176c09' (OS Error: Access is denied. , errno = 5)