Closed Bug 902089 Opened 12 years ago Closed 12 years ago

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

Categories

(Testing :: General, defect)

Product:
Testing
Component:
General
Platform:
x86
Android
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 845623

People

(Reporter: gbrown, Assigned: gbrown)

References

Details

(Whiteboard: [reit-x86])

Attachments

(2 files)

failure case with additional logging
32.05 KB, text/plain
Details
success case with additional logging
83.31 KB, text/plain
Details
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 - <<<<<<<
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
Possible dup of bug 845623?
See Also: → 845623
:gps -- Can you tell what's going wrong here? Suggestions for debugging further?
Flags: needinfo?(gps)
Whiteboard: [reit-x86]
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
Closed: 12 years ago
Flags: needinfo?(gps)
Resolution: --- → DUPLICATE
Blocks: 936226
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: