(INFO) 2018-07-06 02:31:49,613 timeout_retry.WaitFor:105 condition '<lambda>' met
(INFO) 2018-07-06 02:31:49,613 timeout_retry.WaitFor:105 condition '<lambda>' met
(WARNING) 2018-07-06 02:31:49,614 desktop_browser_finder.FindAllAvailableBrowsers:274 Chrome build location for mac_x86_64 not found. Browser will be run without Flash.
(INFO) 2018-07-06 02:31:49,778 browser_finder.FindBrowser:123 Chose browser: PossibleDesktopBrowser(type=reference, executable=/b/s/w/ir/third_party/catapult/common/py_utils/py_utils/bin/reference_builds/chrome_stable_mac_x86_64_c8bef32ba36d46afed8fc8483aeb72ce65656986/chrome-mac/Google Chrome.app/Contents/MacOS/Google Chrome, flash=None)
(INFO) 2018-07-06 02:31:49,780 timeout_retry.WaitFor:105 condition '<lambda>' met
(INFO) 2018-07-06 02:31:49,780 timeout_retry.WaitFor:105 condition '<lambda>' met
(WARNING) 2018-07-06 02:31:49,780 desktop_browser_finder.FindAllAvailableBrowsers:274 Chrome build location for mac_x86_64 not found. Browser will be run without Flash.
(INFO) 2018-07-06 02:31:49,951 browser_finder.FindBrowser:123 Chose browser: PossibleDesktopBrowser(type=reference, executable=/b/s/w/ir/third_party/catapult/common/py_utils/py_utils/bin/reference_builds/chrome_stable_mac_x86_64_c8bef32ba36d46afed8fc8483aeb72ce65656986/chrome-mac/Google Chrome.app/Contents/MacOS/Google Chrome, flash=None)
(INFO) 2018-07-06 02:31:49,951 ts_proxy_server.StartServer:68 Tsproxy commandline: ['/b/s/w/ir/.swarming_module_cache/vpython/fe1f6b/bin/python', '/b/s/w/ir/third_party/catapult/telemetry/third_party/tsproxy/tsproxy.py', '--port=0', '--desthost=127.0.0.1']
(INFO) 2018-07-06 02:31:50,031 ts_proxy_server.StartServer:75 TsProxy port: 65066
(INFO) 2018-07-06 02:31:50,032 __init__._StartedForwarding:47 DoNothingForwarder started between 127.0.0.1:65066 and 65066
[ RUN ] power.idle_platform/IdleStory_10s
(INFO) 2018-07-06 02:32:00,177 trace_data.Serialize:190 Trace sizes in bytes: {'cpuSnapshots': 310091, 'telemetry': 3908}
(INFO) 2018-07-06 02:32:04,855 trace_data.Serialize:199 trace2html finished in 4.68 seconds.
(INFO) 2018-07-06 02:32:04,856 timeline_based_measurement._ComputeTimelineBasedMetrics:310 Starting to compute metrics on trace
(INFO) 2018-07-06 02:32:06,076 timeline_based_measurement._ComputeTimelineBasedMetrics:316 Processing resulting traces took 1.220 seconds
[ OK ] power.idle_platform/IdleStory_10s (16058 ms)
[ RUN ] power.idle_platform/IdleStory_60s
(INFO) 2018-07-06 02:33:06,443 trace_data.Serialize:190 Trace sizes in bytes: {'cpuSnapshots': 1767344, 'telemetry': 3906}
(INFO) 2018-07-06 02:33:11,114 trace_data.Serialize:199 trace2html finished in 4.67 seconds.
(INFO) 2018-07-06 02:33:11,115 timeline_based_measurement._ComputeTimelineBasedMetrics:310 Starting to compute metrics on trace
(INFO) 2018-07-06 02:33:12,380 timeline_based_measurement._ComputeTimelineBasedMetrics:316 Processing resulting traces took 1.265 seconds
[ OK ] power.idle_platform/IdleStory_60s (66301 ms)
[ RUN ] power.idle_platform/IdleStory_120s
(INFO) 2018-07-06 02:35:13,062 trace_data.Serialize:190 Trace sizes in bytes: {'cpuSnapshots': 3503563, 'telemetry': 3898}
(INFO) 2018-07-06 02:35:17,769 trace_data.Serialize:199 trace2html finished in 4.71 seconds.
(INFO) 2018-07-06 02:35:17,770 timeline_based_measurement._ComputeTimelineBasedMetrics:310 Starting to compute metrics on trace
(INFO) 2018-07-06 02:35:19,122 timeline_based_measurement._ComputeTimelineBasedMetrics:316 Processing resulting traces took 1.352 seconds
[ OK ] power.idle_platform/IdleStory_120s (126747 ms)
(INFO) 2018-07-06 02:35:19,140 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmpjoecLC.html to gs://chrome-telemetry-output/IdleStory_10s_2018-07-06_02-31-50_25582.html
View generated trace files online at https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/IdleStory_10s_2018-07-06_02-31-50_25582.html for story IdleStory_10s
(INFO) 2018-07-06 02:35:21,182 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmpozU5nJ.html to gs://chrome-telemetry-output/IdleStory_60s_2018-07-06_02-32-06_60844.html
View generated trace files online at https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/IdleStory_60s_2018-07-06_02-32-06_60844.html for story IdleStory_60s
(INFO) 2018-07-06 02:35:23,357 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmpK1Ev2G.html to gs://chrome-telemetry-output/IdleStory_120s_2018-07-06_02-33-12_11429.html
View generated trace files online at https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/IdleStory_120s_2018-07-06_02-33-12_11429.html for story IdleStory_120s
(INFO) 2018-07-06 02:35:25,527 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmp33e8HWtelemetry/artifacts/telemetry_testyAOSWV to gs://chrome-telemetry-output/e9604542-80ff-11e8-b938-787b8ab93ad2
Uploading logs of page IdleStory_60s to https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/e9604542-80ff-11e8-b938-787b8ab93ad2 (1 out of 1)
(INFO) 2018-07-06 02:35:26,851 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmp33e8HWtelemetry/artifacts/telemetry_testk7jwAW to gs://chrome-telemetry-output/ea2b2be1-80ff-11e8-96c7-787b8ab93ad2
Uploading logs of page IdleStory_120s to https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/ea2b2be1-80ff-11e8-96c7-787b8ab93ad2 (1 out of 1)
(INFO) 2018-07-06 02:35:28,250 cloud_storage.Insert:378 Uploading /b/s/w/itO80c0O/tmp33e8HWtelemetry/artifacts/telemetry_testRPgSKB to gs://chrome-telemetry-output/eb00a5ca-80ff-11e8-b9a3-787b8ab93ad2
Uploading logs of page IdleStory_10s to https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/eb00a5ca-80ff-11e8-b9a3-787b8ab93ad2 (1 out of 1)
(INFO) 2018-07-06 02:35:29,557 memory_debug.LogHostMemoryUsage:60 Used 4.2 GiB out of 16.0 GiB memory available.
(INFO) 2018-07-06 02:35:29,557 memory_debug.LogHostMemoryUsage:61 Memory usage of top 10 processes groups
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - python2.7 - 106.8 MiB - pids: ['18011', '18016', '20547']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - Python - 104.4 MiB - pids: ['291', '18005', '18006']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - NotificationCenter - 46.0 MiB - pids: ['293']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - Finder - 43.4 MiB - pids: ['254']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - python - 36.8 MiB - pids: ['301']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - routined - 33.1 MiB - pids: ['286']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - suggestd - 32.9 MiB - pids: ['344']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - SystemUIServer - 32.9 MiB - pids: ['252']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - CalendarAgent - 32.6 MiB - pids: ['298']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:77 - soagent - 30.8 MiB - pids: ['307']
(INFO) 2018-07-06 02:35:29,571 memory_debug.LogHostMemoryUsage:78 Current process:
(INFO) 2018-07-06 02:35:29,571 memory_debug._LogProcessInfo:41 61.3 MiB (pid=20547)
[ PASSED ] 3 tests.
(WARNING) 2018-07-06 02:35:29,577 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,581 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,584 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,588 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,592 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,595 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,599 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,603 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,607 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,611 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,614 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,618 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,622 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,625 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,629 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,633 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
(WARNING) 2018-07-06 02:35:29,637 histogram_set_json_output_formatter.Format:27 Found existing histograms json but failed to parse it.
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/histograms.json
View result at file:///b/s/w/itO80c0O/tmp33e8HWtelemetry/test-results.json