Closed Bug 1600487 Opened 5 years ago Closed 4 years ago

Intermittent PID 3566 | console.error: "exception thrown: TypeError: text.split is not a function,processText@http://*/unity-webgl/Data/mozbench.js:11:19\nModule.print@http://*/unity-webgl/index.html?raptor:60:24\nabort@http://*/WebGLBenchmarks.js:98:6920

Categories

(Testing :: Raptor, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1524545

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=278907757&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Ohi23lrRTPS3FdHIS50pYQ/runs/0/artifacts/public/logs/live_backing.log


[task 2019-11-30T23:26:23.842Z] 23:26:23 INFO - raptor-control-server Info: received webext_status: update tab: 2
[task 2019-11-30T23:26:23.845Z] 23:26:23 INFO - raptor-control-server Info: received webext_status: test tab updated: 2
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] update tab: 2"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] update tab: 2"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] test tab updated: 2"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] test tab updated: 2"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] ready to poll for results; turning on page-timeout timer"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] now is 1575156383843, set raptor alarm raptor-page-timeout to expire at 1575157283843"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:26:23.849Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:26:23.967Z] 23:26:23 INFO - PID 3566 | console.log: "[raptor-benchmarkjs] raptor benchmark content loaded"
[task 2019-11-30T23:26:24.103Z] 23:26:24 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:26:24.345Z] 23:26:24 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
...
[task 2019-11-30T23:27:10.415Z] 23:27:10 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:27:10.478Z] 23:27:10 ERROR - PID 3566 | console.error: "exception thrown: TypeError: text.split is not a function,processText@http://127.0.0.1:59569/unity-webgl/Data/mozbench.js:11:19\nModule.print@http://127.0.0.1:59569/unity-webgl/index.html?raptor:60:24\nabort@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:98:69204\nvMn@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:89:1\nXxa@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nLBa@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nWda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\n$da@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\n$da@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\n$da@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\n$da@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nNxa@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nZAa@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nWda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\n$da@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nhxa@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\niea@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nWda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nbwe@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nvyb@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:49:1\nZda@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nKca@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:25:1\nVue@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nEue@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:13:1\nbdd@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:33:1\nx8f@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:45:1\nfGn@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:89:1\ndynCall@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:1:4741\nBrowser_mainLoop_runner/<@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:1:243649\nrunIter@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:1:132109\nBrowser_mainLoop_runner@http://127.0.0.1:59569/unity-webgl/Data/WebGLBenchmarks.js:1:243554\n"
[task 2019-11-30T23:27:10.656Z] 23:27:10 ERROR - PID 3566 | TypeError: text.split is not a functionconsole.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:27:10.907Z] 23:27:10 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
...
[task 2019-11-30T23:41:23.682Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:41:23.847Z] 23:41:23 INFO - PID 3566 | console.error: "raptor-page-timeout on http://127.0.0.1:59569/unity-webgl/index.html?raptor"
[task 2019-11-30T23:41:23.849Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:41:23.849Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] raptor-unity-webgl-firefox,http://127.0.0.1:59569/unity-webgl/index.html?raptor"
[task 2019-11-30T23:41:23.850Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] capturing screenshot"
[task 2019-11-30T23:41:23.852Z] 23:41:23 INFO - raptor-control-server Info: received webext_raptor-page-timeout: [u'raptor-unity-webgl-firefox', u'http://127.0.0.1:59569/unity-webgl/index.html?raptor']
[task 2019-11-30T23:41:23.859Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:41:23.895Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:41:23.895Z] 23:41:23 INFO - raptor-control-server Info: received webext_screenshot
[task 2019-11-30T23:41:23.896Z] 23:41:23 INFO - perftest-results-handler Info: received screenshot
[task 2019-11-30T23:41:23.896Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] closed tab 2"
[task 2019-11-30T23:41:23.896Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] benchmark test finished"
[task 2019-11-30T23:41:23.896Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:41:23.896Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] "
[task 2019-11-30T23:41:23.912Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] Removed tab: 2"
[task 2019-11-30T23:41:23.912Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-11-30T23:41:23.912Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] Removed tab: 2"
[task 2019-11-30T23:41:23.915Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:41:23.923Z] 23:41:23 INFO - raptor-control-server Info: received request to shutdown the browser
[task 2019-11-30T23:41:23.923Z] 23:41:23 INFO - raptor-control-server Info: shutting down browser (pid: 3566)
[task 2019-11-30T23:41:23.923Z] 23:41:23 INFO - raptor-control-server Info: received webext_status: Removed tab: 2
[task 2019-11-30T23:41:23.931Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:41:23.931Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] post success"
[task 2019-11-30T23:41:23.933Z] 23:41:23 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:41:24.195Z] 23:41:24 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:41:24.438Z] 23:41:24 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:41:24.696Z] 23:41:24 INFO - PID 3566 | console.log: "[raptor-runnerjs] checking results..."
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - raptor-main Info: removing webext /home/cltbld/tasks/task_1575155712/build/tests/raptor/raptor/../webext/raptor
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - perftest-results-handler Info: summarizing raptor test results
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - perftest-output Error: no raptor test results found for raptor-unity-webgl-firefox
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - perftest-output Info: error: no raptor test results found, so no need to combine browser cycles
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - perftest-output Error: no summarized raptor results found for raptor-unity-webgl-firefox
[task 2019-11-30T23:41:25.196Z] 23:41:25 INFO - perftest-output Info: screen captures can be found locally at: /home/cltbld/tasks/task_1575155712/build/screenshots.html
[task 2019-11-30T23:41:25.204Z] 23:41:25 INFO - perftest-results-handler Critical: PERFHERDER_DATA was seen 0 times, expected 1.
[task 2019-11-30T23:41:25.204Z] 23:41:25 INFO - raptor-control-server Info: shutting down control server
[task 2019-11-30T23:41:25.434Z] 23:41:25 INFO - raptor-main Info: finished
[task 2019-11-30T23:41:25.435Z] 23:41:25 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: no raptor test results were found for raptor-unity-webgl-firefox
[task 2019-11-30T23:41:25.520Z] 23:41:25 ERROR - Return code: 1
[task 2019-11-30T23:41:25.520Z] 23:41:25 WARNING - setting return code to 1
[task 2019-11-30T23:41:25.521Z] 23:41:25 INFO - Copying Raptor results to upload dir:
[task 2019-11-30T23:41:25.521Z] 23:41:25 INFO - /home/cltbld/tasks/task_1575155712/build/blobber_upload_dir/perfherder-data.json
[task 2019-11-30T23:41:25.521Z] 23:41:25 INFO - Copying raptor results from /home/cltbld/tasks/task_1575155712/build/raptor.json to /home/cltbld/tasks/task_1575155712/build/blobber_upload_dir/perfherder-data.json
[task 2019-11-30T23:41:25.521Z] 23:41:25 CRITICAL - Error copying results /home/cltbld/tasks/task_1575155712/build/raptor.json to upload dir /home/cltbld/tasks/task_1575155712/build/blobber_upload_dir/perfherder-data.json
[task 2019-11-30T23:41:25.521Z] 23:41:25 INFO - [Errno 2] No such file or directory: u'/home/cltbld/tasks/task_1575155712/build/raptor.json'
[task 2019-11-30T23:41:25.522Z] 23:41:25 INFO - /home/cltbld/tasks/task_1575155712/build/blobber_upload_dir/screenshots.html
[task 2019-11-30T23:41:25.522Z] 23:41:25 INFO - Copying raptor results from /home/cltbld/tasks/task_1575155712/build/screenshots.html to /home/cltbld/tasks/task_1575155712/build/blobber_upload_dir/screenshots.html
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - Running post-action listener: _package_coverage_data
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - Running post-action listener: _resource_record_post_action
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - Running post-action listener: process_java_coverage_data
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - Running post-action listener: stop_device
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - [mozharness: 2019-11-30 23:41:25.523448Z] Finished run-tests step (success)
[task 2019-11-30T23:41:25.523Z] 23:41:25 INFO - Running post-run listener: _resource_record_post_run
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - Total resource usage - Wall time: 1541s; CPU: 12.0%; Read bytes: 66310144; Write bytes: 1272324608; Read time: 584; Write time: 110600
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - TinderboxPrint: CPU usage<br/>12.2%
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - TinderboxPrint: I/O read bytes / time<br/>66,310,144 / 584
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - TinderboxPrint: I/O write bytes / time<br/>1,272,324,608 / 110,600
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - TinderboxPrint: CPU idle<br/>10,736.9 (87.9%)
[task 2019-11-30T23:41:25.622Z] 23:41:25 INFO - TinderboxPrint: CPU user<br/>1,400.1 (11.5%)
[task 2019-11-30T23:41:25.623Z] 23:41:25 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2019-11-30T23:41:25.623Z] 23:41:25 INFO - install - Wall time: 7s; CPU: 13.0%; Read bytes: 1015808; Write bytes: 146788352; Read time: 32; Write time: 13516
[task 2019-11-30T23:41:25.640Z] 23:41:25 INFO - run-tests - Wall time: 1533s; CPU: 12.0%; Read bytes: 57655296; Write bytes: 1123570176; Read time: 464; Write time: 96880
[task 2019-11-30T23:41:26.016Z] 23:41:26 WARNING - returning nonzero exit status 1
[fetches 2019-11-30T23:41:26.036Z] removing /home/cltbld/tasks/task_1575155712/fetches
[fetches 2019-11-30T23:41:26.046Z] finished
[taskcluster 2019-11-30T23:41:26.054Z] Exit Code: 1

I think this is related with bug 1524545

See Also: → 1524545
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE

Bug 1524545 only moves it to tier3 and only runs it on central. But it doesn't fix the underlying problem. Or where are you targeting a fix?

Flags: needinfo?(fstrugariu)

The fix and investigation should be done in Bug 1524545 - Intermittent raptor-main TEST-UNEXPECTED-FAIL: no raptor test results were found for raptor-unity-webgl-firefox

This error is just the output of unity-webgl in the console.

Flags: needinfo?(fstrugariu)
You need to log in before you can comment on or make changes to this bug.