Closed Bug 1751446 Opened 4 years ago Closed 4 years ago

Intermittent TV netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 106] 0 == 2147500036 | after xpcshell return code: 0

Categories

(Core :: Networking, defect, P5)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=364981396&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtHsgDHAQh2dXE8dGFhV4g/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtHsgDHAQh2dXE8dGFhV4g/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2022-01-21T14:15:07.352Z] 14:15:07     INFO -  TEST-START | netwerk/test/unit/test_trr_cancel.js
[task 2022-01-21T14:15:07.409Z] 14:15:07     INFO -  adb launch_application: am startservice -W -n 'org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.XpcshellTestRunnerService$i0' -a android.intent.action.MAIN --es env0 XPCOM_DEBUG_BREAK=stack-and-abort --es env1 MOZ_CRASHREPORTER=1 --es env2 MOZ_CRASHREPORTER_NO_REPORT=1 --es env3 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env4 MOZ_DEVELOPER_REPO_DIR=/builds/worker/checkouts/gecko --es env5 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env6 MOZ_FETCHES_DIR=/builds/worker/fetches --es env7 MOZ_DISABLE_SOCKET_PROCESS=1 --es env8 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env9 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env10 GRE_HOME=/data/local/tmp/test_root/xpcb --es env11 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/a7522d40-1177-4102-836a-196e32f10662 --es env12 HOME=/data/local/tmp/test_root/xpc/p --es env13 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/6b604132-02af-4d84-9894-2c2d562f456b --es env14 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env15 MOZ_IN_AUTOMATION=1 --es env16 MOZ_ANDROID_CPU_ABI=x86_64 --es env17 MOZHTTP2_PORT=36135 --es env18 MOZNODE_EXEC_PORT=38473 --es env19 TMPDIR=/data/local/tmp/test_root/xpc/p/a7522d40-1177-4102-836a-196e32f10662 --es env20 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/a7522d40-1177-4102-836a-196e32f10662 --es arg0 -g --es arg1 /data/local/tmp/test_root/xpcb --es arg2 --greomni --es arg3 /data/local/tmp/test_root/xpcb/geckoview-test_runner.apk --es arg4 -m --es arg5 -e --es arg6 'const _HEAD_JS_PATH = "/data/local/tmp/test_root/xpc/head.js";' --es arg7 -e --es arg8 'const _MOZINFO_JS_PATH = "/data/local/tmp/test_root/xpc/p/a7522d40-1177-4102-836a-196e32f10662/mozinfo.json";' --es arg9 -e --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/user.js";' --es arg11 -e --es arg12 'const _TESTING_MODULES_DIR = "/data/local/tmp/test_root/xpc/m";' --es arg13 -f --es arg14 /data/local/tmp/test_root/xpc/head.js --es arg15 -e --es arg16 'const _HEAD_FILES = ["/data/local/tmp/test_root/xpc/netwerk/test/unit/head_channels.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_cache.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_cache2.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_cookies.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_trr.js", "/data/local/tmp/test_root/xpc/netwerk/test/unit/head_http3.js"];' --es arg17 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/netwerk/test/unit";' --es arg21 -e --es arg22 'const _TEST_FILE = ["test_trr_cancel.js"];' --es arg23 -e --es arg24 'const _TEST_NAME = "netwerk/test/unit/test_trr_cancel.js";' --es arg25 -e --es arg26 '_execute_test(); quit(0);' --ez use_multiprocess True --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-9e953edb-f8a2-4b59-a83f-6c8159a155bc.log
[task 2022-01-21T14:15:07.724Z] 14:15:07     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr_cancel.js | 6388 | Launched Test App
[task 2022-01-21T14:15:12.932Z] 14:15:12     INFO -  remotexpcshelltests.py | netwerk/test/unit/test_trr_cancel.js | 6388 | Application ran for: 0:00:05.579903
[task 2022-01-21T14:15:13.015Z] 14:15:13  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_trr_cancel.js | xpcshell return code: 0
[task 2022-01-21T14:15:13.015Z] 14:15:13     INFO -  TEST-INFO took 5663ms
[task 2022-01-21T14:15:13.015Z] 14:15:13     INFO -  >>>>>>>
[task 2022-01-21T14:15:13.016Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | start!
[task 2022-01-21T14:15:13.016Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | undefined assertion name - "36135" != null
[task 2022-01-21T14:15:13.016Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | undefined assertion name - "36135" != ""
[task 2022-01-21T14:15:13.016Z] 14:15:13     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-01-21T14:15:13.016Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  running event loop
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | Starting start_trr_server
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  (xpcshell/head.js) | test start_trr_server pending (2)
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | port = 36271
[task 2022-01-21T14:15:13.017Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-01-21T14:15:13.018Z] 14:15:13     INFO -  (xpcshell/head.js) | test start_trr_server finished (2)
[task 2022-01-21T14:15:13.018Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | Starting sanity_check
[task 2022-01-21T14:15:13.018Z] 14:15:13     INFO -  (xpcshell/head.js) | test sanity_check pending (2)
[task 2022-01-21T14:15:13.018Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-01-21T14:15:13.018Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | sanity_check - [sanity_check : 45] true == true
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | sanity_check - [sanity_check : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | sanity_check - [sanity_check : 1] Checking status - 0 == 0
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | sanity_check - [sanity_check : 1] Checking result for example.com - "1.2.3.4" == "1.2.3.4"
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  (xpcshell/head.js) | test sanity_check finished (2)
[task 2022-01-21T14:15:13.019Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | Starting cancel_immediately
[task 2022-01-21T14:15:13.020Z] 14:15:13     INFO -  (xpcshell/head.js) | test cancel_immediately pending (2)
[task 2022-01-21T14:15:13.020Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2022-01-21T14:15:13.020Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 64] true == true
[task 2022-01-21T14:15:13.020Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 65] true == true
[task 2022-01-21T14:15:13.021Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.021Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 68] 2147500036 == 2147500036
[task 2022-01-21T14:15:13.021Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.021Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking status - 0 == 0
[task 2022-01-21T14:15:13.022Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking result for example.org - "2.3.4.5" == "2.3.4.5"
[task 2022-01-21T14:15:13.022Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 70] 1 == 1
[task 2022-01-21T14:15:13.022Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 74] true == true
[task 2022-01-21T14:15:13.022Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 75] true == true
[task 2022-01-21T14:15:13.022Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.023Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 79] 2147500036 == 2147500036
[task 2022-01-21T14:15:13.023Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.023Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 81] 2147500036 == 2147500036
[task 2022-01-21T14:15:13.023Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_immediately - [cancel_immediately : 83] 2 == 2
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  (xpcshell/head.js) | test cancel_immediately finished (2)
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  netwerk/test/unit/test_trr_cancel.js | Starting cancel_delayed
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  (xpcshell/head.js) | test cancel_delayed pending (2)
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  (xpcshell/head.js) | test run_next_test 3 finished (2)
[task 2022-01-21T14:15:13.024Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 101] true == true
[task 2022-01-21T14:15:13.025Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 102] true == true
[task 2022-01-21T14:15:13.025Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.025Z] 14:15:13  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 106] 0 == 2147500036
[task 2022-01-21T14:15:13.025Z] 14:15:13     INFO -  test_trr_cancel.js:cancel_delayed:106
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  /data/local/tmp/test_root/xpc/head.js:_do_main:240
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  /data/local/tmp/test_root/xpc/head.js:_execute_test:604
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  -e:null:1
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  exiting test
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  _abort_failed_test@/data/local/tmp/test_root/xpc/head.js:875:20
[task 2022-01-21T14:15:13.026Z] 14:15:13     INFO -  do_report_result@/data/local/tmp/test_root/xpc/head.js:976:5
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  Assert<@/data/local/tmp/test_root/xpc/head.js:75:21
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  equal@resource://testing-common/Assert.jsm:270:8
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  cancel_delayed@test_trr_cancel.js:106:8
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  _do_main@/data/local/tmp/test_root/xpc/head.js:240:6
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  _execute_test@/data/local/tmp/test_root/xpc/head.js:604:5
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  @-e:1:1
[task 2022-01-21T14:15:13.027Z] 14:15:13     INFO -  exiting test
[task 2022-01-21T14:15:13.028Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 1] Checking that this is the correct callback - true == true
[task 2022-01-21T14:15:13.028Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 1] Checking status - 0 == 0
[task 2022-01-21T14:15:13.028Z] 14:15:13     INFO -  TEST-PASS | netwerk/test/unit/test_trr_cancel.js | cancel_delayed - [cancel_delayed : 1] Checking result for example.com - "1.1.1.1" == "1.1.1.1"
[task 2022-01-21T14:15:13.028Z] 14:15:13     INFO -  <<<<<<<
[task 2022-01-21T14:15:13.118Z] 14:15:13     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/netwerk/test/unit/test_trr_cancel.js folder: /data/local/tmp/test_root/xpc/p/a7522d40-1177-4102-836a-196e32f10662
[task 2022-01-21T14:15:13.906Z] 14:15:13     INFO -  TEST-START | netwerk/test/unit/test_trr_cancel.js

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.