1589842 - Perma Tier 2 AttributeError: 'builtin_function_or_method' object has no attribute 'StringIO'

Status: RESOLVED FIXED

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=272024526&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/ZcKsCMnsRUinHlL6cJRseg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-10-19T11:13:57.584Z] 11:13:57 INFO - raptor-control-server Info: received webext_status: saving gecko profile
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] raptor-wasm-godot-firefox_pagecycle_2.profile"
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:13:57.607Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:57.608Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:13:57.619Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] starting Gecko profiling for threads: Compositor,GeckoMain"
[task 2019-10-19T11:13:57.619Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:57.619Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] starting Gecko profiling for threads: Compositor,GeckoMain"
[task 2019-10-19T11:13:57.626Z] 11:13:57 INFO - raptor-control-server Info: received gecko profile filename: raptor-wasm-godot-firefox_pagecycle_2.profile
[task 2019-10-19T11:13:57.626Z] 11:13:57 INFO - raptor-control-server Info: moved gecko profile to /var/folders/16/9pgglvps55q085st5jrjd54c000017/T/tmpLMdbbk/raptor-wasm-godot-firefox_pagecycle_2.profile
[task 2019-10-19T11:13:57.628Z] 11:13:57 INFO - raptor-control-server Info: received webext_status: stopping gecko profiling
[task 2019-10-19T11:13:57.630Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:57.633Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:57.633Z] 11:13:57 INFO - raptor-control-server Info: received webext_status: starting Gecko profiling for threads: Compositor,GeckoMain
[task 2019-10-19T11:13:57.642Z] 11:13:57 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:58.703Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] begin pagecycle 3"
[task 2019-10-19T11:13:58.703Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:58.704Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] begin pagecycle 3"
[task 2019-10-19T11:13:58.704Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] closing Tab: 4"
[task 2019-10-19T11:13:58.707Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:58.707Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] closing Tab: 4"
[task 2019-10-19T11:13:58.707Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] Open new tab"
[task 2019-10-19T11:13:58.707Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:58.707Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] Open new tab"
[task 2019-10-19T11:13:58.724Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] Removed tab: 4"
[task 2019-10-19T11:13:58.725Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:58.725Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] Removed tab: 4"
[task 2019-10-19T11:13:58.789Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] opened new empty tab: 5"
[task 2019-10-19T11:13:58.789Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:58.789Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] opened new empty tab: 5"
[task 2019-10-19T11:13:58.798Z] 11:13:58 INFO - raptor-control-server Info: received webext_status: begin pagecycle 3
[task 2019-10-19T11:13:58.819Z] 11:13:58 INFO - raptor-control-server Info: received webext_status: closing Tab: 4
[task 2019-10-19T11:13:58.827Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:58.827Z] 11:13:58 INFO - raptor-control-server Info: received webext_status: Open new tab
[task 2019-10-19T11:13:58.832Z] 11:13:58 INFO - raptor-control-server Info: received webext_status: Removed tab: 4
[task 2019-10-19T11:13:58.842Z] 11:13:58 INFO - raptor-control-server Info: received webext_status: opened new empty tab: 5
[task 2019-10-19T11:13:58.865Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:58.865Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:58.865Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:58.866Z] 11:13:58 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:59.696Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] update tab: 5"
[task 2019-10-19T11:13:59.697Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:59.697Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] update tab: 5"
[task 2019-10-19T11:13:59.697Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] test tab updated: 5"
[task 2019-10-19T11:13:59.699Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:13:59.699Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] test tab updated: 5"
[task 2019-10-19T11:13:59.700Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] ready to poll for results; turning on page-timeout timer"
[task 2019-10-19T11:13:59.700Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] now is 1571483639695, set raptor alarm raptor-page-timeout to expire at 1571483759695"
[task 2019-10-19T11:13:59.700Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:13:59.702Z] 11:13:59 INFO - raptor-control-server Info: received webext_status: update tab: 5
[task 2019-10-19T11:13:59.710Z] 11:13:59 INFO - raptor-control-server Info: received webext_status: test tab updated: 5
[task 2019-10-19T11:13:59.711Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:59.711Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:13:59.816Z] 11:13:59 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] raptor benchmark content loaded"
[task 2019-10-19T11:14:00.007Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:14:00.300Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:14:00.566Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:14:00.651Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] raptor benchmark received message"
[task 2019-10-19T11:14:00.651Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] raptor-benchmark,wasm-godot,[object Object],[object Object],[object Object],[object Object],[object Object]"
[task 2019-10-19T11:14:00.651Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] sending result back to runner: wasm-godot [object Object],[object Object],[object Object],[object Object],[object Object]"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] received message from http://localhost:49991/wasm-godot/index.html"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] result: wasm-godot [object Object],[object Object],[object Object],[object Object],[object Object]"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] received results from benchmark"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] confirmed wasm-godot"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] results received"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] received results from benchmark"
[task 2019-10-19T11:14:00.685Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-benchmarkjs] confirmed wasm-godot"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] results received"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] results received"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] saving gecko profile"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] saving gecko profile"
[task 2019-10-19T11:14:00.859Z] 11:14:00 INFO - PID 1457 | console.log: "[raptor-runnerjs] cancelled raptor alarm raptor-page-timeout"
[task 2019-10-19T11:14:00.982Z] 11:14:00 INFO - raptor-control-server Info: received webext_status: results received
[task 2019-10-19T11:14:00.982Z] 11:14:00 INFO - raptor-control-server Info: received webext_status: saving gecko profile
[task 2019-10-19T11:14:01.017Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.017Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.017Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.018Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] raptor-wasm-godot-firefox_pagecycle_3.profile"
[task 2019-10-19T11:14:01.018Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:14:01.018Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] Verifying results:"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] [object Object]"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] have 3 results for wasm-godot, as expected"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] [object Object]"
[task 2019-10-19T11:14:01.019Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] closed tab 5"
[task 2019-10-19T11:14:01.021Z] 11:14:01 INFO - raptor-control-server Info: received gecko profile filename: raptor-wasm-godot-firefox_pagecycle_3.profile
[task 2019-10-19T11:14:01.021Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] benchmark test finished"
[task 2019-10-19T11:14:01.021Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:14:01.022Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.022Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] stopping gecko profiling"
[task 2019-10-19T11:14:01.022Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.022Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] "
[task 2019-10-19T11:14:01.022Z] 11:14:01 INFO - raptor-control-server Info: moved gecko profile to /var/folders/16/9pgglvps55q085st5jrjd54c000017/T/tmpLMdbbk/raptor-wasm-godot-firefox_pagecycle_3.profile
[task 2019-10-19T11:14:01.024Z] 11:14:01 INFO - raptor-control-server Info: received webext_status: stopping gecko profiling
[task 2019-10-19T11:14:01.070Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] Removed tab: 5"
[task 2019-10-19T11:14:01.071Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-10-19T11:14:01.071Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] Removed tab: 5"
[task 2019-10-19T11:14:01.103Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.103Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.104Z] 11:14:01 INFO - raptor-control-server Info: received webext_results: {u'expected_browser_cycles': 1, u'subtest_unit': u'ms', u'name': u'raptor-wasm-godot-firefox', u'extra_options': [u'gecko_profile'], u'lower_is_better': True, u'measurements': {u'wasm-godot': [[[{u'name': u'wasm-instantiate', u'time': 215.2}, {u'name': u'engine-instantiate', u'time': 581.4}, {u'name': u'first-frame', u'time': 813.6}, {u'name': u'first-interactive', u'time': 829.16}, {u'name': u'cpuTime', u'time': 826.4799999999999}]], [[{u'name': u'wasm-instantiate', u'time': 219.74}, {u'name': u'engine-instantiate', u'time': 525.52}, {u'name': u'first-frame', u'time': 761.9200000000001}, {u'name': u'first-interactive', u'time': 772.44}, {u'name': u'cpuTime', u'time': 769.78}]], [[{u'name': u'wasm-instantiate', u'time': 219.57999999999998}, {u'name': u'engine-instantiate', u'time': 516.1}, {u'name': u'first-frame', u'time': 751.56}, {u'name': u'first-interactive', u'time': 766.4599999999999}, {u'name': u'cpuTime', u'time': 761.12}]]]}, u'browser_cycle': u'1', u'subtest_lower_is_better': True, u'cold': False, u'browser': u'Firefox 71.0a1 20191019095321', u'type': u'benchmark', u'page': u'http://localhost:49991/wasm-godot/index.html', u'unit': u'ms', u'alert_threshold': 2}
[task 2019-10-19T11:14:01.104Z] 11:14:01 INFO - perftest-results-handler Info: received results in RaptorResultsHandler.add
[task 2019-10-19T11:14:01.109Z] 11:14:01 INFO - raptor-control-server Info: received webext_status: stopping gecko profiling
[task 2019-10-19T11:14:01.109Z] 11:14:01 INFO - raptor-control-server Info: received request to shutdown the browser
[task 2019-10-19T11:14:01.109Z] 11:14:01 INFO - raptor-control-server Info: shutting down browser (pid: 1457)
[task 2019-10-19T11:14:01.128Z] 11:14:01 INFO - raptor-control-server Info: received webext_status: Removed tab: 5
[task 2019-10-19T11:14:01.128Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.131Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.132Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:01.150Z] 11:14:01 INFO - PID 1457 | console.log: "[raptor-runnerjs] post success"
[task 2019-10-19T11:14:03.581Z] 11:14:03 INFO - Sat Oct 19 11:14:03 2019 MainThread Retrieving symbol zip from https://queue.taskcluster.net/v1/task/KeJ8QfNaRp6SMBEG8sH0-A/artifacts/public/build/target.crashreporter-symbols.zip...
[task 2019-10-19T11:14:03.581Z] 11:14:03 INFO - raptor-control-server Info: shutting down control server
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - raptor-main Info: finished
[task 2019-10-19T11:14:03.981Z] 11:14:03 ERROR - Traceback (most recent call last):
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 1530, in <module>
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - main()
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 1498, in main
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 213, in run_tests
[task 2019-10-19T11:14:03.981Z] 11:14:03 INFO - self.run_test_teardown(test)
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 920, in run_test_teardown
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - super(RaptorDesktop, self).run_test_teardown(test)
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 642, in run_test_teardown
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - super(Raptor, self).run_test_teardown(test)
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/raptor.py", line 228, in run_test_teardown
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - self.gecko_profiler.symbolicate()
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/gecko_profile.py", line 149, in symbolicate
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - self.raptor_config['symbols_path']
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - File "/Users/cltbld/tasks/task_1571482936/build/tests/raptor/raptor/profiler/symbolication.py", line 149, in integrate_symbol_zip_from_url
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - with zipfile.ZipFile(cStringIO.StringIO(io.read())) as zf:
[task 2019-10-19T11:14:03.982Z] 11:14:03 INFO - AttributeError: 'builtin_function_or_method' object has no attribute 'StringIO'
[task 2019-10-19T11:14:04.057Z] 11:14:04 ERROR - Return code: 1
[task 2019-10-19T11:14:04.057Z] 11:14:04 WARNING - setting return code to 1
[task 2019-10-19T11:14:04.057Z] 11:14:04 INFO - Copying Raptor results to upload dir:
[task 2019-10-19T11:14:04.057Z] 11:14:04 INFO - /Users/cltbld/tasks/task_1571482936/build/blobber_upload_dir/perfherder-data.json
[task 2019-10-19T11:14:04.058Z] 11:14:04 INFO - Copying raptor results from /Users/cltbld/tasks/task_1571482936/build/raptor.json to /Users/cltbld/tasks/task_1571482936/build/blobber_upload_dir/perfherder-data.json
[task 2019-10-19T11:14:04.058Z] 11:14:04 CRITICAL - Error copying results /Users/cltbld/tasks/task_1571482936/build/raptor.json to upload dir /Users/cltbld/tasks/task_1571482936/build/blobber_upload_dir/perfherder-data.json
[task 2019-10-19T11:14:04.058Z] 11:14:04 INFO - [Errno 2] No such file or directory: u'/Users/cltbld/tasks/task_1571482936/build/raptor.json'
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - Running post-action listener: _package_coverage_data
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - Running post-action listener: _resource_record_post_action
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - Running post-action listener: process_java_coverage_data
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - Running post-action listener: stop_device
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - [mozharness: 2019-10-19 11:14:04.059449Z] Finished run-tests step (success)
[task 2019-10-19T11:14:04.059Z] 11:14:04 INFO - Running post-run listener: _resource_record_post_run
[task 2019-10-19T11:14:04.165Z] 11:14:04 INFO - Total resource usage - Wall time: 65s; CPU: 25.0%; Read bytes: 83513344; Write bytes: 373084160; Read time: 1666; Write time: 899
[task 2019-10-19T11:14:04.165Z] 11:14:04 INFO - TinderboxPrint: CPU usage<br/>25.3%
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: I/O read bytes / time<br/>83,513,344 / 1,666
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: I/O write bytes / time<br/>373,084,160 / 899
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: CPU idle<br/>192.8 (74.6%)
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: CPU system<br/>9.9 (3.8%)
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: CPU user<br/>55.6 (21.5%)
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - TinderboxPrint: Swap in / out<br/>332,361,728 / 0
[task 2019-10-19T11:14:04.166Z] 11:14:04 INFO - install - Wall time: 19s; CPU: 39.0%; Read bytes: 276030976; Write bytes: 268324864; Read time: 15711; Write time: 439
[task 2019-10-19T11:14:04.167Z] 11:14:04 INFO - run-tests - Wall time: 47s; CPU: 19.0%; Read bytes: 74014720; Write bytes: 95629312; Read time: 1555; Write time: 403
[task 2019-10-19T11:14:04.179Z] 11:14:04 WARNING - returning nonzero exit status 1
[taskcluster 2019-10-19T11:14:04.231Z] Exit Code: 1
[taskcluster 2019-10-19T11:14:04.231Z] User Time: 59.914652s
[taskcluster 2019-10-19T11:14:04.231Z] Kernel Time: 21.242453s
[taskcluster 2019-10-19T11:14:04.231Z] Wall Time: 2m4.165082575s
[taskcluster 2019-10-19T11:14:04.231Z] Result: FAILED
[taskcluster 2019-10-19T11:14:04.231Z] === Task Finished ===
[taskcluster 2019-10-19T11:14:04.231Z] Task Duration: 2m4.17026376s
[taskcluster 2019-10-19T11:14:04.626Z] Uploading artifact public/logs/localconfig.json from file logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2020-10-18T09:57:32.445Z
[taskcluster 2019-10-19T11:14:05.333Z] Uploading artifact public/test_info/resource-usage.json from file build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2020-10-18T09:57:32.445Z
[taskcluster:error] exit status 1

Comment 1

[Bogdan Tara[:bogdan_tara | bogdant]]

rwood could you, please, take a look?

Comment 2

[Bogdan Tara[:bogdan_tara | bogdant]]

Fixed by backout
https://hg.mozilla.org/integration/autoland/rev/530ff0c5938780c0e3a0e62a9d1d5716f2e61f7a

Comment 3

[Intermittent Failures Robot]

79 failures in 132 pushes (0.598 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 79

Platform breakdown:

• macosx1014-64-shippable: 46
• linux64-shippable: 26
• windows10-64-shippable: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1589842&startday=2019-10-19&endday=2019-10-19&tree=all

Comment 4

[Intermittent Failures Robot]

79 failures in 3964 pushes (0.02 failures/push) were associated with this bug in the last 7 days.

This is the #11 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 79

Platform breakdown:

• macosx1014-64-shippable: 46
• linux64-shippable: 26
• windows10-64-shippable: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1589842&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 5

[Robert Wood [:rwood]]

Thanks for the backout! Will resolve the issue in the original bug.