xpcshell test services/common/tests/unit/test_storage_server.js fails intermittently on Android x86 emulator

RESOLVED DUPLICATE of bug 845623

Status

RESOLVED DUPLICATE of bug 845623
5 years ago
5 years ago

People

(Reporter: gbrown, Assigned: gbrown)

Tracking

Firefox Tracking Flags

(Not tracked)

Details

(Whiteboard: [reit-x86])

Attachments

(2 attachments)

(Assignee)

Description

5 years ago
On Android x86 emulators, I can run most xpcshell tests successfully, but a few fail -- this is one of them.

804 23:13:21  WARNING -  TEST-UNEXPECTED-FAIL | /home/cltbld/tests/scripts/scripts/build/tests/xpcshell/tests/services/common/tests     /unit/test_storage_server.js | test failed (with xpcshell return code: 0), see following log:
 805 23:13:21     INFO -  >>>>>>>
 806 23:13:21     INFO -  xpcw: cd /mnt/sdcard/tests/xpcshell/services/common/tests/unit
 807 23:13:21     INFO -  xpcw: xpcshell -r /mnt/sdcard/tests/xpcshell/c/httpd.manifest --greomni /data/local/xpcb/fennec-25.0a1.multi.android-i386.apk -m -n -s -e const _HTTPD_JS_PATH = "/mnt/sdcard/tests/xpcshell/c/httpd.js"; -e const _HEAD_JS_PATH = "/mnt/sdcard/tests/xpcshell/head.js"; -e const _TESTING_MODULES_DIR = "/mnt/sdcard/tests/xpcshell/m"; -f /mnt/sdcard/tests/xpcshell/head.js -e const _SERVER_ADDR = "localhost" -e const _HEAD_FILES = ["/mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_global.js", "/mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_helpers.js", "/mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_http.js"]; -e const _TAIL_FILES = []; -e const _TEST_FILE = ["test_storage_server.js"]; -e _execute_test(); quit(0);
 808 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
 809 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 0 pending (2)
 810 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test finished (2)
 811 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | running event loop
 812 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_creation
 813 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_creation pending (2)
 814 23:13:21     INFO -  Ensure a simple server can be created.
 815 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_creation : 122] true == true
 816 23:13:21     INFO -  Started on 41883
 817 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 0 finished (2)
 818 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 1 pending (2)
 819 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_creation finished (2)
 820 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_synchronous_start
 821 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_synchronous_start pending (2)
 822 23:13:21     INFO -  Ensure starting using startSynchronous works.
 823 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 1 finished (2)
 824 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 2 pending (2)
 825 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_synchronous_start finished (2)
 826 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_url_parsing
 827 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_url_parsing pending (2)
 828 23:13:21     INFO -  Ensure server parses URLs properly.
 829 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 146] "/2.0/12345/storage/crypto/keys" == "/2.0/12     345/storage/crypto/keys"
 830 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 147] "2.0" == "2.0"
 831 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 148] "12345" == "12345"
 832 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 149] "storage" == "storage"
 833 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 150] "crypto/keys" == "crypto/keys"
 834 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 151] null == null
 835 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 156] "/2.0/123/storage/crypto" == "/2.0/123/stora     ge/crypto"
 836 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 157] "2.0" == "2.0"
 837 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 158] "123" == "123"
 838 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 159] "storage" == "storage"
 839 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 160] "crypto" == "crypto"
 840 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 164] null == undefined
 841 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 169] "/2.0/123456/storage" == "/2.0/123456/storag     e"
 842 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 170] "2.0" == "2.0"
 843 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 171] "123456" == "123456"
 844 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 172] "storage" == "storage"
 845 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 173] undefined == undefined
 846 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 177] "storage" == "storage"
 847 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_url_parsing : 178] undefined == undefined
 848 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 3 pending (3)
 849 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_url_parsing finished (3)
 850 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 2 finished (2)
 851 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_basic_http
 852 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_basic_http pending (2)
 853 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 345
 854 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_basic_http : 186] true == true
 855 23:13:21     INFO -  Started on 41972
 856 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_basic_http : 190] 0 == 0
 857 23:13:21     INFO -  localRequest: /2.0/storage/crypto/keys
 858 23:13:21     INFO -  url: http://localhost:41972/2.0/storage/crypto/keys
 859 23:13:21     INFO -  req is [object Object]
 860 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 3 finished (2)
 861 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/storage/crypto/keys
 862 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Unexpected request: bad URL /2.0/storage/crypto/keys
 863 23:13:21     INFO -  Services.Common.Test.Server    INFO    Response: 404 Not Found
 864 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:41972/2.0/storage/crypto/keys 404
 865 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_basic_http/< : 194] null == null
 866 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_basic_http/< : 195] 1 == 1
 867 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 4 pending (2)
 868 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_basic_http finished (2)
 868 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_basic_http finished (2)
 869 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_info_collections
 870 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_info_collections pending (2)
 871 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
 872 23:13:21     INFO -  info/collections on empty server should be empty object.
 873 23:13:21     INFO -  localRequest: /2.0/123/info/collections
 874 23:13:21     INFO -  url: http://localhost:50419/2.0/123/info/collections
 875 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/info/collections
 876 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
 877 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
 878 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
 879 23:13:21     INFO -  Services.Common.Test.Server    INFO    Response: 200 OK
 880 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:50419/2.0/123/info/collections 200
 881 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 882 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
 883 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 884 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 885 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 886 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 40] true == true
 887 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 888 23:13:21     INFO -                       setting a property that has only a getter
 889 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 890 23:13:21     INFO -                       setting a property that has only a getter
 891 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 892 23:13:21     INFO -                       setting a property that has only a getter
 893 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 894 23:13:21     INFO -                       setting a property that has only a getter
 895 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 896 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 41] "application/json" == "application/json"
 897 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 210] undefined == null
 898 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 211] 200 == 200
 899 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 212] "{}" == "{}"
 900 23:13:21     INFO -  Creating an empty collection should result in collection appearing.
 901 23:13:21     INFO -  localRequest: /2.0/123/info/collections
 902 23:13:21     INFO -  url: http://localhost:50419/2.0/123/info/collections
 903 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 904 23:13:21     INFO -                       setting a property that has only a getter
 905 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 906 23:13:21     INFO -                       setting a property that has only a getter
 907 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 908 23:13:21     INFO -                       setting a property that has only a getter
 909 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 910 23:13:21     INFO -                       setting a property that has only a getter
 911 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 912 23:13:21     INFO -                       setting a property that has only a getter
 913 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/info/collections
 914 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
 915 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
 916 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
 917 23:13:21     INFO -  Services.Common.Test.Server    INFO    Response: 200 OK
 918 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:50419/2.0/123/info/collections 200
 919 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 920 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
 921 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 922 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 923 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 924 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 40] true == true
 925 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 926 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 41] "application/json" == "application/json"
 927 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 218] undefined == null
 928 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 219] 200 == 200
 929 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 930 23:13:21     INFO -                       setting a property that has only a getter
 931 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 932 23:13:21     INFO -                       setting a property that has only a getter
 933 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 934 23:13:21     INFO -                       setting a property that has only a getter
 935 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 936 23:13:21     INFO -                       setting a property that has only a getter
 937 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 938 23:13:21     INFO -                       setting a property that has only a getter
 939 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 940 23:13:21     INFO -                       setting a property that has only a getter
 941 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 942 23:13:21     INFO -                       setting a property that has only a getter
 943 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 944 23:13:21     INFO -                       setting a property that has only a getter
 945 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 946 23:13:21     INFO -                       setting a property that has only a getter
 947 23:13:21     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_helpers.js | [do_check_attribute_co     unt : 18] 1 == 1
 948 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 222] true == true
 949 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_info_collections : 223] 1375769596351 == 1375769596351
 950 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 4 finished (2)
 951 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 5 pending (2)
 952 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_info_collections finished (2)
 953 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_bso_get_existing
 954 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_get_existing pending (2)
 955 23:13:21     INFO -  Ensure that BSO retrieval works.
 956 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
 957 23:13:21     INFO -  localRequest: /2.0/123/storage/test/bso
 958 23:13:21     INFO -  url: http://localhost:36508/2.0/123/storage/test/bso
 959 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/storage/test/bso
 960 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
 961 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
 962 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
 963 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:36508/2.0/123/storage/test/bso 200
 964 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 965 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
 966 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 967 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 968 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 969 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 40] true == true
 970 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 971 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 41] "application/json" == "application/json"
 972 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 243] undefined == null
 973 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 244] 200 == 200
 974 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
 975 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 976 23:13:21     INFO -                       setting a property that has only a getter
 977 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 978 23:13:21     INFO -                       setting a property that has only a getter
 979 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 980 23:13:21     INFO -                       setting a property that has only a getter
 981 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 982 23:13:21     INFO -                       setting a property that has only a getter
 983 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
 984 23:13:21     INFO -                       setting a property that has only a getter
 985 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 986 23:13:21     INFO -                       setting a property that has only a getter
 987 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 988 23:13:21     INFO -                       setting a property that has only a getter
 989 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 990 23:13:21     INFO -                       setting a property that has only a getter
 991 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
 992 23:13:21     INFO -                       setting a property that has only a getter
 993 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 245] "application/json" == "application/json     "
 994 23:13:21     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_helpers.js | [do_check_attribute_co     unt : 18] 3 == 3
 995 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 248] "bso" == "bso"
 996 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 249] 1375769596580 == 1375769596580
 997 23:13:21     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_helpers.js | [do_check_attribute_co     unt : 18] 1 == 1
 998 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_get_existing : 252] "bar" == "bar"
 999 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 5 finished (2)
1000 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 6 pending (2)
1001 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_get_existing finished (2)
1002 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_percent_decoding
1003 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_percent_decoding pending (2)
1004 23:13:21     INFO -  Ensure query string arguments with percent encoded are handled.
1005 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
1006 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1007 23:13:21     INFO -                       setting a property that has only a getter
1008 23:13:21     INFO -  localRequest: /2.0/123/storage/test?ids=001%2C002
1009 23:13:21     INFO -  url: http://localhost:38868/2.0/123/storage/test?ids=001%2C002
1010 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/storage/test?ids=001%     2C002
1011 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1012 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1013 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1014 23:13:21     INFO -  Services.Common.Test.Server    INFO    Records: 2
1015 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:38868/2.0/123/storage/test?ids=001%2C002 200
1016 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1017 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1018 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1019 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1020 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1021 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1022 23:13:21     INFO -                       setting a property that has only a getter
1023 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1024 23:13:21     INFO -                       setting a property that has only a getter
1025 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1026 23:13:21     INFO -                       setting a property that has only a getter
1027 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 40] true == true
1028 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1029 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 41] "application/json" == "application/json"
1030 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_percent_decoding : 271] undefined == null
1031 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_percent_decoding : 272] 200 == 200
1032 23:13:21     INFO -  TEST-PASS | /mnt/sdcard/tests/xpcshell/services/common/tests/unit/head_helpers.js | [do_check_attribute_co     unt : 18] 2 == 2
1033 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 6 finished (2)
1034 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 7 pending (2)
1035 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_percent_decoding finished (2)
1036 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_bso_404
1037 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_404 pending (2)
1038 23:13:21     INFO -  Ensure the server responds with a 404 if a BSO does not exist.
1039 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1040 23:13:21     INFO -                       setting a property that has only a getter
1041 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1042 23:13:21     INFO -                       setting a property that has only a getter
1043 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1044 23:13:21     INFO -                       setting a property that has only a getter
1045 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1046 23:13:21     INFO -                       setting a property that has only a getter
1047 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1048 23:13:21     INFO -                       setting a property that has only a getter
1049 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1050 23:13:21     INFO -                       setting a property that has only a getter
1051 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
1052 23:13:21     INFO -  localRequest: /2.0/123/storage/test/foo
1053 23:13:21     INFO -  url: http://localhost:45455/2.0/123/storage/test/foo
1054 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/storage/test/foo
1055 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1056 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1057 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1058 23:13:21     INFO -  Services.Common.Test.Server    INFO    Response: 404 Not Found
1059 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:45455/2.0/123/storage/test/foo 404
1060 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1061 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1062 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1063 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1064 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_404 : 291] undefined == null
1065 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_404 : 293] 404 == 404
1066 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1067 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_404 : 294] false == false
1068 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 7 finished (2)
1069 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1070 23:13:21     INFO -                       setting a property that has only a getter
1071 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1072 23:13:21     INFO -                       setting a property that has only a getter
1073 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1074 23:13:21     INFO -                       setting a property that has only a getter
1075 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1076 23:13:21     INFO -                       setting a property that has only a getter
1077 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1078 23:13:21     INFO -                       setting a property that has only a getter
1079 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1080 23:13:21     INFO -                       setting a property that has only a getter
1081 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1082 23:13:21     INFO -                       setting a property that has only a getter
1083 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1084 23:13:21     INFO -                       setting a property that has only a getter
1085 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 8 pending (2)
1086 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_404 finished (2)
1087 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_bso_if_modified_since_304
1088 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_if_modified_since_304 pending (2)
1089 23:13:21     INFO -  Ensure the server responds properly to X-If-Modified-Since for BSOs.
1090 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
1091 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 310] [object Object] != null
1092 23:13:21     INFO -  localRequest: /2.0/123/storage/test/bso
1093 23:13:21     INFO -  url: http://localhost:46227/2.0/123/storage/test/bso
1094 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/storage/test/bso
1095 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1096 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1097 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1098 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:46227/2.0/123/storage/test/bso 304
1099 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1100 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1101 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1102 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1103 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1104 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 46] false == false
1105 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1106 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1107 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 49] "0" == "0"
1108 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 320] null == undefined
1109 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 322] 304 == 304
1110 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1111 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 323] false == false
1112 23:13:21     INFO -  localRequest: /2.0/123/storage/test/bso
1113 23:13:21     INFO -  url: http://localhost:46227/2.0/123/storage/test/bso
1114 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1115 23:13:21     INFO -                       setting a property that has only a getter
1116 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1117 23:13:21     INFO -                       setting a property that has only a getter
1118 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1119 23:13:21     INFO -                       setting a property that has only a getter
1120 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1121 23:13:21     INFO -                       setting a property that has only a getter
1122 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1123 23:13:21     INFO -                       setting a property that has only a getter
1124 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1125 23:13:21     INFO -                       setting a property that has only a getter
1126 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1127 23:13:21     INFO -                       setting a property that has only a getter
1128 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1129 23:13:21     INFO -                       setting a property that has only a getter
1130 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1131 23:13:21     INFO -                       setting a property that has only a getter
1132 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1133 23:13:21     INFO -                       setting a property that has only a getter
1134 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1135 23:13:21     INFO -                       setting a property that has only a getter
1136 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: GET /2.0/123/storage/test/bso
1137 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1138 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1139 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1140 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   GET http://localhost:46227/2.0/123/storage/test/bso 200
1141 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1142 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1143 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1144 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1145 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1146 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 40] true == true
1147 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1148 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1149 23:13:21     INFO -                       setting a property that has only a getter
1150 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1151 23:13:21     INFO -                       setting a property that has only a getter
1152 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1153 23:13:21     INFO -                       setting a property that has only a getter
1154 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1155 23:13:21     INFO -                       setting a property that has only a getter
1156 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1157 23:13:21     INFO -                       setting a property that has only a getter
1158 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 41] "application/json" == "application/json"
1159 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 329] null == undefined
1160 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 330] 200 == 200
1161 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1162 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_modified_since_304 : 331] "application/json" == "applica     tion/json"
1163 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 8 finished (2)
1164 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 9 pending (2)
1165 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_if_modified_since_304 finished (2)
1166 23:13:21     INFO -  TEST-INFO | test_storage_server.js | Starting test_bso_if_unmodified_since
1167 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test test_bso_if_unmodified_since pending (2)
1168 23:13:21     INFO -  Ensure X-If-Unmodified-Since works properly on BSOs.
1169 23:13:21     INFO -  Services.Common.Test.Server    INFO    Registering new user with server: 123
1170 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1171 23:13:21     INFO -                       setting a property that has only a getter
1172 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1173 23:13:21     INFO -                       setting a property that has only a getter
1174 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1175 23:13:21     INFO -                       setting a property that has only a getter
1176 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1177 23:13:21     INFO -                       setting a property that has only a getter
1178 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1179 23:13:21     INFO -                       setting a property that has only a getter
1180 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_unmodified_since : 347] [object Object] != null
1181 23:13:21     INFO -  Ensure we get a 412 for specified times older than server time.
1182 23:13:21     INFO -  localRequest: /2.0/123/storage/test/bso
1183 23:13:21     INFO -  url: http://localhost:34206/2.0/123/storage/test/bso
1184 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   PUT Length: 20
1185 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: PUT /2.0/123/storage/test/bso
1186 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1187 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1188 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1189 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   PUT http://localhost:34206/2.0/123/storage/test/bso 412
1190 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1191 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1192 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1193 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1194 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1195 23:13:21     INFO -                       setting a property that has only a getter
1196 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1197 23:13:21     INFO -                       setting a property that has only a getter
1198 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_unmodified_since : 358] null == undefined
1199 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_unmodified_since : 359] 412 == 412
1200 23:13:21     INFO -  Ensure we get a 204 if update goes through.
1201 23:13:21     INFO -  localRequest: /2.0/123/storage/test/bso
1202 23:13:21     INFO -  url: http://localhost:34206/2.0/123/storage/test/bso
1203 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   PUT Length: 20
1204 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1205 23:13:21     INFO -                       setting a property that has only a getter
1206 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1207 23:13:21     INFO -                       setting a property that has only a getter
1207 23:13:21     INFO -                       setting a property that has only a getter
1208 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1209 23:13:21     INFO -                       setting a property that has only a getter
1210 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1211 23:13:21     INFO -                       setting a property that has only a getter
1212 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1213 23:13:21     INFO -                       setting a property that has only a getter
1214 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   StorageServer: Handling request: PUT /2.0/123/storage/test/bso
1215 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Authorization: Basic MTIzOnBhc3N3b3Jk
1216 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Decoded Basic Auth: 123:password
1217 23:13:21     INFO -  Services.Common.Test.Server    DEBUG   Got HTTP Basic auth for user: 123
1218 23:13:21     INFO -  Services.Common.RESTRequest    DEBUG   PUT http://localhost:34206/2.0/123/storage/test/bso 412
1219 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1220 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [validateResponse : 34] true == true
1221 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1222 23:13:21     INFO -  Sync.RESTResponse  TRACE   Processing response headers.
1223 23:13:21     INFO -  TEST-PASS | test_storage_server.js | [test_bso_if_unmodified_since : 367] null == undefined
1224 23:13:21  WARNING -  TEST-UNEXPECTED-FAIL | test_storage_server.js | 412 == 204 - See following stack:
1225 23:13:21     INFO -  JS frame :: test_storage_server.js :: test_bso_if_unmodified_since :: line 368
1226 23:13:21     INFO -  JS frame :: /mnt/sdcard/tests/xpcshell/head.js :: _run_next_test :: line 1160
1227 23:13:21     INFO -  JS frame :: /mnt/sdcard/tests/xpcshell/head.js :: do_execute_soon/<.run :: line 444
1228 23:13:21     INFO -  native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
1229 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | exiting test
1230 23:13:21  WARNING -  TEST-UNEXPECTED-FAIL | (xpcshell/head.js) | TypeError: invalid 'in' operand error - See following stack:
1231 23:13:21     INFO -  JS frame :: do_throw@/mnt/sdcard/tests/xpcshell/head.js:482
1232 23:13:21     INFO -  JS frame :: _run_next_test@/mnt/sdcard/tests/xpcshell/head.js:1162
1233 23:13:21     INFO -  JS frame :: do_execute_soon/<.run@/mnt/sdcard/tests/xpcshell/head.js:444
1234 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | exiting test
1235 23:13:21     INFO -  TEST-INFO | (xpcshell/head.js) | test run_next_test 9 finished (2)
1236 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1237 23:13:21     INFO -                       setting a property that has only a getter
1238 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1239 23:13:21     INFO -                       setting a property that has only a getter
1240 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:661
1241 23:13:21     INFO -                       setting a property that has only a getter
1242 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1243 23:13:21     INFO -                       setting a property that has only a getter
1244 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1245 23:13:21     INFO -                       setting a property that has only a getter
1246 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1247 23:13:21     INFO -                       setting a property that has only a getter
1248 23:13:21     INFO -  System JS : WARNING resource://services-common/rest.js:623
1249 23:13:21     INFO -                       setting a property that has only a getter
1250 23:13:21     INFO -  !!! error running onStopped callback: TypeError: callback is not a function
1251 23:13:21     INFO -  ^@
1252 23:13:21     INFO -  <<<<<<<
(Assignee)

Comment 1

5 years ago
This failure is intermittent -- it seems to fail about 1 time in 3.
Summary: xpcshell test services/common/tests/unit/test_storage_server.js fails on Android x86 emulator → xpcshell test services/common/tests/unit/test_storage_server.js fails intermittently on Android x86 emulator
(Assignee)

Comment 2

5 years ago
Possible dup of bug 845623?
See Also: → bug 845623
(Assignee)

Comment 3

5 years ago
Created attachment 789968 [details]
failure case with additional logging
(Assignee)

Comment 4

5 years ago
Created attachment 789969 [details]
success case with additional logging
(Assignee)

Comment 5

5 years ago
:gps -- Can you tell what's going wrong here? Suggestions for debugging further?
Flags: needinfo?(gps)
Whiteboard: [reit-x86]
(Assignee)

Comment 6

5 years ago
This change makes the test pass on x86:

-  request.setHeader("X-If-Unmodified-Since", time + 1);
+  request.setHeader("X-If-Unmodified-Since", time + 5);

but I don't know this code well enough to propose this as a fix. I will disable this test on Android unless :gps has a better idea.
This looks like a dupe of bug 845623. I love when intermittents turn into permanent failures. Let's look into the patch in the original bug.
Status: NEW → RESOLVED
Last Resolved: 5 years ago
Flags: needinfo?(gps)
Resolution: --- → DUPLICATE
Duplicate of bug: 845623
(Assignee)

Updated

5 years ago
Blocks: 936226
You need to log in before you can comment on or make changes to this bug.