Closed Bug 1440558 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | security/manager/ssl/tests/unit/test_nonascii_path.js | Test timed out

Categories

(Core :: Security: PSM, defect, P5)

defect

Tracking

()

RESOLVED INVALID

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=163866707&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/VvS0O7PjQ6e1tw-0NyI6Nw/runs/0/artifacts/public/logs/live_backing.log

[task 2018-02-23T03:35:38.658Z] 03:35:38     INFO -  TEST-START | security/manager/ssl/tests/unit/test_nonascii_path.js
[task 2018-02-23T03:40:38.659Z] 03:40:38  WARNING -  TEST-UNEXPECTED-TIMEOUT | security/manager/ssl/tests/unit/test_nonascii_path.js | Test timed out
[task 2018-02-23T03:40:38.659Z] 03:40:38     INFO -  TEST-INFO took 300000ms
[task 2018-02-23T03:40:38.880Z] 03:40:38     INFO -  xpcshell return code: -1
[task 2018-02-23T03:40:48.696Z] 03:40:48     INFO -  mozdevice Timeout exceeded for shell call 'adb shell /data/local/xpcb/xpcw /mnt/sdcard/tests/xpc/security/manager/ssl/tests/unit -r /mnt/sdcard/tests/xpc/c/httpd.manifest --greomni /data/local/xpcb/target.apk -m -s -e 'const _HEAD_JS_PATH = "/mnt/sdcard/tests/xpc/head.js";' -e 'const _MOZINFO_JS_PATH = "/mnt/sdcard/tests/xpc/p/mozinfo.json";' -e 'const _TESTING_MODULES_DIR = "/mnt/sdcard/tests/xpc/m";' -f /mnt/sdcard/tests/xpc/head.js -e 'const _SERVER_ADDR = "localhost"' -e 'const _HEAD_FILES = ["/mnt/sdcard/tests/xpc/security/manager/ssl/tests/unit/head_psm.js"];' -e 'const _JSDEBUGGER_PORT = 0;' -e 'const _TEST_FILE = ["test_nonascii_path.js"];' -e 'const _TEST_NAME = "security/manager/ssl/tests/unit/test_nonascii_path.js"' -e '_execute_test(); quit(0);'; echo $?'
[task 2018-02-23T03:40:49.558Z] 03:40:49     INFO -  security/manager/ssl/tests/unit/test_nonascii_path.js | Process still running after test!
[task 2018-02-23T03:40:49.558Z] 03:40:49  WARNING -  TEST-UNEXPECTED-FAIL | Received SIGINT (control-C), so stopped run. (Use --keep-going to keep running tests after killing one with SIGINT)
[task 2018-02-23T03:40:49.558Z] 03:40:49     INFO -  INFO | Result summary:
[task 2018-02-23T03:40:49.559Z] 03:40:49     INFO -  INFO | Passed: 29
[task 2018-02-23T03:40:49.559Z] 03:40:49  WARNING -  INFO | Failed: 1
[task 2018-02-23T03:40:49.559Z] 03:40:49  WARNING -  One or more unittests failed.
[task 2018-02-23T03:40:49.560Z] 03:40:49     INFO -  INFO | Todo: 0
[task 2018-02-23T03:40:49.560Z] 03:40:49     INFO -  INFO | Retried: 0
[task 2018-02-23T03:40:49.560Z] 03:40:49     INFO -  SUITE-END | took 627s
[task 2018-02-23T03:40:49.582Z] 03:40:49    ERROR - Return code: 1
[task 2018-02-23T03:40:49.582Z] 03:40:49     INFO - TinderboxPrint: xpcshell<br/>29/<em class="testfail">1</em>/0
[task 2018-02-23T03:40:49.582Z] 03:40:49     INFO - ##### xpcshell log ends
[task 2018-02-23T03:40:49.583Z] 03:40:49     INFO - ##### test-1 emulator log begins
[task 2018-02-23T03:40:49.583Z] 03:40:49     INFO - Reading from file /tmp/tmp_dtYs_
[task 2018-02-23T03:40:49.584Z] 03:40:49     INFO - emulator: found SDK root at /builds/worker/workspace/build/android-sdk18
[task 2018-02-23T03:40:49.584Z] 03:40:49     INFO - emulator: Android virtual device file at: /builds/worker/workspace/build/.android/avd/test-1.ini
[task 2018-02-23T03:40:49.584Z] 03:40:49     INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.ini: parsing as .ini file
[task 2018-02-23T03:40:49.584Z] 03:40:49     INFO - emulator:    1: KEY='avd.ini.encoding' VALUE='ISO-8859-1'
[task 2018-02-23T03:40:49.584Z] 03:40:49     INFO - emulator:    2: KEY='target' VALUE='android-17'
[task 2018-02-23T03:40:49.585Z] 03:40:49     INFO - emulator:    3: KEY='path' VALUE='/builds/worker/workspace/build/.android/avd/test-1.avd'
[task 2018-02-23T03:40:49.585Z] 03:40:49     INFO - emulator:    4: KEY='path.rel' VALUE='avd/test-1.avd'
[task 2018-02-23T03:40:49.585Z] 03:40:49     INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.ini: parsing finished
[task 2018-02-23T03:40:49.586Z] 03:40:49     INFO - emulator: virtual device content at /builds/worker/workspace/build/.android/avd/test-1.avd
[task 2018-02-23T03:40:49.586Z] 03:40:49     INFO - emulator: virtual device config file: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini
[task 2018-02-23T03:40:49.586Z] 03:40:49     INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini: parsing as .ini file
[task 2018-02-23T03:40:49.586Z] 03:40:49     INFO - emulator:    1: KEY='hw.mainKeys' VALUE='no'
[task 2018-02-23T03:40:49.586Z] 03:40:49     INFO - emulator:    2: KEY='hw.sensors.proximity' VALUE='yes'
[task 2018-02-23T03:40:49.587Z] 03:40:49     INFO - emulator:    3: KEY='skin.dynamic' VALUE='yes'
[task 2018-02-23T03:40:49.587Z] 03:40:49     INFO - emulator:    4: KEY='sdcard.size' VALUE='500M'
[task 2018-02-23T03:40:49.587Z] 03:40:49     INFO - emulator:    5: KEY='hw.audioInput' VALUE='yes'
[task 2018-02-23T03:40:49.587Z] 03:40:49     INFO - emulator:    6: KEY='skin.name' VALUE='1024x816'
[task 2018-02-23T03:40:49.587Z] 03:40:49     INFO - emulator:    7: KEY='hw.dPad' VALUE='no'
[task 2018-02-23T03:40:49.588Z] 03:40:49     INFO - emulator:    8: KEY='hw.ramSize' VALUE='1024'
[task 2018-02-23T03:40:49.588Z] 03:40:49     INFO - emulator:    9: KEY='hw.accelerometer' VALUE='yes'
[task 2018-02-23T03:40:49.588Z] 03:40:49     INFO - emulator:   10: KEY='hw.keyboard.lid' VALUE='yes'
[task 2018-02-23T03:40:49.588Z] 03:40:49     INFO - emulator:   11: KEY='hw.camera.back' VALUE='none'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   12: KEY='hw.gpu.enabled' VALUE='yes'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   13: KEY='hw.sdCard' VALUE='yes'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   14: KEY='hw.device.name' VALUE='mozilla-device'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   15: KEY='abi.type' VALUE='x86'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   16: KEY='hw.gps' VALUE='yes'
[task 2018-02-23T03:40:49.589Z] 03:40:49     INFO - emulator:   17: KEY='hw.cpu.arch' VALUE='x86'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   18: KEY='disk.dataPartition.size' VALUE='600M'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   19: KEY='avd.ini.encoding' VALUE='ISO-8859-1'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   20: KEY='hw.device.hash' VALUE='-671137758'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   21: KEY='skin.path' VALUE='1024x816'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   22: KEY='hw.sensors.orientation' VALUE='yes'
[task 2018-02-23T03:40:49.590Z] 03:40:49     INFO - emulator:   23: KEY='hw.device.manufacturer' VALUE='User'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   24: KEY='hw.keyboard' VALUE='yes'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   25: KEY='hw.lcd.density' VALUE='160'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   26: KEY='vm.heapSize' VALUE='64'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   27: KEY='hw.trackBall' VALUE='no'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   28: KEY='hw.battery' VALUE='yes'
[task 2018-02-23T03:40:49.591Z] 03:40:49     INFO - emulator:   29: KEY='image.sysdir.1' VALUE=''
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: /builds/worker/workspace/build/.android/avd/test-1.avd/config.ini: parsing finished
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: using core hw config path: /builds/worker/workspace/build/.android/avd/test-1.avd/hardware-qemu.ini
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: Found target field in root AVD .ini file: 'android-17'
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: Found AVD target API level: 17
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator:     found image search path: 
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: found a total of 1 search paths for this AVD
[task 2018-02-23T03:40:49.592Z] 03:40:49     INFO - emulator: 'magic' skin format detected: 1024x816
[task 2018-02-23T03:40:49.593Z] 03:40:49     INFO - emulator: autoconfig: -skin 1024x816
[task 2018-02-23T03:40:49.593Z] 03:40:49     INFO - emulator: autoconfig: -skindir (null)
[task 2018-02-23T03:40:49.593Z] 03:40:49     INFO - emulator: no skin-specific hardware.ini in (null)
[task 2018-02-23T03:40:49.593Z] 03:40:49     INFO - emulator: writing default keyset file to /builds/worker/workspace/build/.android/default.keyset
[task 2018-02-23T03:40:49.593Z] 03:40:49     INFO - emulator: found SDK root at /builds/worker/workspace/build/android-sdk18
[task 2018-02-23T03:40:49.594Z] 03:40:49     INFO - emulator: trying to load skin file '/builds/worker/workspace/build/android-sdk18/tools/lib/emulator/skins/dynamic//layout'
[task 2018-02-23T03:40:49.594Z] 03:40:49     INFO - emulator: loaded dynamic skin width=1024 height=816 bpp=16
https://treeherder.mozilla.org/logviewer.html#?job_id=171254793&repo=autoland&lineNumber=1529

https://taskcluster-artifacts.net/RhS9AuyvR0KZA-x245VVqQ/0/public/test_info//logcat-emulator-5554.log

3-30 21:46:43.446  2398  2398 I Gecko   : {"action":"test_status","time":1522446403448,"thread":null,"pid":null,"source":"xpcshell/head.js","test":"security/manager/ssl/tests/unit/test_nonascii_path.js","subtest":"undefined assertion name","status":"PASS","message":"the profile path should contain a non-ASCII character - true == true"}
03-30 21:46:43.905  2398  2398 I Gecko   : 
03-30 21:46:43.905  2398  2398 I Gecko   : {"action":"log","time":1522446403904,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test MAIN run_test pending (1)"}
03-30 21:46:44.396  2398  2398 I Gecko   : 
03-30 21:46:44.396  2398  2398 I Gecko   : {"action":"test_status","time":1522446404400,"thread":null,"pid":null,"source":"xpcshell/head.js","test":"security/manager/ssl/tests/unit/test_nonascii_path.js","subtest":"run_test","status":"PASS","message":"[run_test : 53] CA cert should have successfully loaded - {\"emailAddress\":\"(no email address)\",\"isBuiltInRoot\":false,\"getEmailAddresses\":\"function getEmailAddresses() {\\n    [native code]\\n}\",\"containsEmailAddress\":\"function containsEmailAddress() {\\n    [native code]\\n}\",\"subjectName\":\"CN=ca\",\"subjectAltNames\":\"\",\"commonName\":\"ca\",\"organization\":\"\",\"organizationalUnit\":\"\",\"sha256Fingerprint\":\"0F:CA:92:78:E8:E2:76:62:AC:67:51:A0:45:F4:B8:B8:92:73:3F:53:0B:A0:29:CF:51:0D:86:C8:D8:32:7C:04\",\"sha1Fingerprint\":\"35:FB:7E:39:6C:D2:7D:0D:A3:A7:A9:02:5F:3D:19:6A:3C:5E:C3:9D\",\"tokenName\":\"Software Security Device\",\"issuerName\":\"CN=ca\",\"serialNumber\":\"51:63:B0:CA:3D:7A:13:2A:69:C0:D8:E1:9B:F2:6F:74:CD:E3:82:11\",\"issuerCommonName\":\"ca\",\"issuerOrganiza
03-30 21:46:44.825  2398  2398 I Gecko   : 
03-30 21:46:44.825  2398  2398 I Gecko   : {"action":"test_status","time":1522446404830,"thread":null,"pid":null,"source":"xpcshell/head.js","test":"security/manager/ssl/tests/unit/test_nonascii_path.js","subtest":"run_test","status":"PASS","message":"[run_test : 55] Intermediate cert should have successfully loaded - {\"emailAddress\":\"(no email address)\",\"isBuiltInRoot\":false,\"getEmailAddresses\":\"function getEmailAddresses() {\\n    [native code]\\n}\",\"containsEmailAddress\":\"function containsEmailAddress() {\\n    [native code]\\n}\",\"subjectName\":\"CN=int\",\"subjectAltNames\":\"\",\"commonName\":\"int\",\"organization\":\"\",\"organizationalUnit\":\"\",\"sha256Fingerprint\":\"C7:B5:3A:56:7F:F2:9D:29:D9:34:A1:32:A2:D6:E3:19:A2:EF:B9:A3:ED:E3:37:42:D5:D2:75:1D:28:0C:CD:89\",\"sha1Fingerprint\":\"47:10:B5:F1:B0:49:C6:23:65:BC:0A:BD:4A:6F:A7:3C:39:26:1E:2A\",\"tokenName\":\"Software Security Device\",\"issuerName\":\"CN=ca\",\"serialNumber\":\"4D:2F:41:1C:7C:2F:EE:66:C6:62:BD:60:FC:60:92:AC:AF:69:2E:63\",\"issuerCommonName\":\"ca\",\"is
Blocks: 1433903
This doesn't appear to have happened in >2 months. Unclear why the intermittent bug cleanup task didn't already close this...
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.