ahal provided this link for a place where this is working correctly (i.e. where `Last test finished` shows up instead of the test name), in a Windows reftest log: https://treeherder.mozilla.org/logviewer.html#?job_id=218864193&repo=autoland That log ends like this: ``` 19:59:33 INFO - REFTEST TEST-PASS(EXPECTED RANDOM) | file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1.svg == file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1-ref.svg | image comparison, max difference: 0, number of differing pixels: 0 19:59:33 INFO - REFTEST TEST-END | file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1.svg == file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1-ref.svg 19:59:33 INFO - REFTEST INFO | Slowest test took 71ms (file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-startSame-1b.svg) 19:59:33 INFO - REFTEST INFO | Total canvas count = 2 [...] 19:59:34 INFO - 1545854374695 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) 20:05:44 ERROR - REFTEST ERROR | Last test finished | application timed out after 370 seconds with no output 20:05:44 ERROR - REFTEST ERROR | Force-terminating active process(es). 20:05:44 INFO - REFTEST TEST-INFO | started process screenshot 20:05:44 INFO - REFTEST TEST-INFO | screenshot: exit 0 ``` In contrast, here's an Android crashtest log where this isn't working properly: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=255173994&repo=autoland&lineNumber=14569 That log ends like this: ``` 17:59:21 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | 3176 / 3775 (84%) 17:59:21 INFO - REFTEST TEST-PASS | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | (LOAD ONLY) 17:59:21 INFO - REFTEST TEST-END | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html 18:05:52 INFO - wait for org.mozilla.geckoview.test complete; top activity=org.mozilla.geckoview.test 18:05:53 INFO - org.mozilla.geckoview.test unexpectedly found running. Killing... [...] 18:06:07 WARNING - TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | application timed out after 370 seconds with no output ```
Bug 1565286 Comment 3 Edit History
Note: The actual edited comment in the bug view page will always show the original commenter’s name and original timestamp.
ahal provided this link for a place where this is working correctly (i.e. where `Last test finished` shows up instead of the test name), in a Windows reftest log: https://treeherder.mozilla.org/logviewer.html#?job_id=218864193&repo=autoland That log ends like this: ``` 19:59:33 INFO - REFTEST TEST-PASS(EXPECTED RANDOM) | file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1.svg == file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1-ref.svg | image comparison, max difference: 0, number of differing pixels: 0 19:59:33 INFO - REFTEST TEST-END | file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1.svg == file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-additive-1-ref.svg 19:59:33 INFO - REFTEST INFO | Slowest test took 71ms (file:///Z:/task_1545853338/build/tests/reftest/tests/layout/reftests/svg/smil/sort/sort-startSame-1b.svg) 19:59:33 INFO - REFTEST INFO | Total canvas count = 2 [...] 19:59:34 INFO - 1545854374695 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) 20:05:44 ERROR - REFTEST ERROR | Last test finished | application timed out after 370 seconds with no output 20:05:44 ERROR - REFTEST ERROR | Force-terminating active process(es). 20:05:44 INFO - REFTEST TEST-INFO | started process screenshot 20:05:44 INFO - REFTEST TEST-INFO | screenshot: exit 0 ``` In contrast, here's an Android crashtest log where this isn't working properly: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=255173994&repo=autoland&lineNumber=14569 That log ends like this: ``` 17:59:21 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | 3176 / 3775 (84%) 17:59:21 INFO - REFTEST TEST-PASS | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | (LOAD ONLY) 17:59:21 INFO - REFTEST TEST-END | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html 18:05:52 INFO - wait for org.mozilla.geckoview.test complete; top activity=org.mozilla.geckoview.test 18:05:53 INFO - org.mozilla.geckoview.test unexpectedly found running. Killing... [...] 18:06:07 WARNING - TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/tests/layout/style/crashtests/1546255.html | application timed out after 370 seconds with no output ``` (Some unrelated-looking logspam replaced with [...] for brevity.)