builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-047 starttime: 1473289209.25 results: success (0) buildid: 20160907143916 builduid: 52c4edc1153849fe966151c7791feb5c revision: c8096f896f7cf570dd77b503861650847337febc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:09.248006) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:09.248445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:09.248779) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:09.276291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-07 16:00:09.276694) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-07 16:00:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-09-07 16:00:15 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=6.669158 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-07 16:00:15.959691) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:15.960008) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:00:16.009697) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 mins, 15 secs) (at 2016-09-07 16:00:16.010088) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev c8096f896f7cf570dd77b503861650847337febc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev c8096f896f7cf570dd77b503861650847337febc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-07 16:00:16,043 truncating revision to first 12 chars 2016-09-07 16:00:16,043 Setting DEBUG logging. 2016-09-07 16:00:16,043 attempt 1/10 2016-09-07 16:00:16,043 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:01:16,107 sleeping for 9.00s (attempt 1/10) 2016-09-07 16:01:25,116 attempt 2/10 2016-09-07 16:01:25,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:02:25,179 sleeping for 12.50s (attempt 2/10) 2016-09-07 16:02:37,692 attempt 3/10 2016-09-07 16:02:37,692 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:03:37,755 sleeping for 19.75s (attempt 3/10) 2016-09-07 16:03:57,525 attempt 4/10 2016-09-07 16:03:57,525 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:04:57,589 sleeping for 30.62s (attempt 4/10) 2016-09-07 16:05:28,245 attempt 5/10 2016-09-07 16:05:28,245 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:06:28,308 sleeping for 44.94s (attempt 5/10) 2016-09-07 16:07:13,290 attempt 6/10 2016-09-07 16:07:13,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:08:13,354 sleeping for 67.41s (attempt 6/10) 2016-09-07 16:09:20,827 attempt 7/10 2016-09-07 16:09:20,828 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:10:20,891 sleeping for 99.00s (attempt 7/10) 2016-09-07 16:11:59,990 attempt 8/10 2016-09-07 16:11:59,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c8096f896f7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:12:31,337 unpacking tar archive at: autoland-c8096f896f7c/testing/mozharness/ program finished with exit code 0 elapsedTime=735.708204 ========= master_lag: 0.00 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 mins, 15 secs) (at 2016-09-07 16:12:31.719108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 16:12:31.719452) ========= script_repo_revision: c8096f896f7cf570dd77b503861650847337febc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 16:12:31.719881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 16:12:31.720160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 16:12:31.729916) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-07 16:12:31.730196) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:31 INFO - MultiFileLogger online at 20160907 16:12:31 in /builds/slave/test 16:12:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 16:12:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:31 INFO - {'append_to_log': False, 16:12:31 INFO - 'base_work_dir': '/builds/slave/test', 16:12:31 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 16:12:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:31 INFO - 'branch': 'Autoland-Non-PGO', 16:12:31 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:31 INFO - 'config_files': ('talos/linux_config.py',), 16:12:31 INFO - 'default_actions': ('clobber', 16:12:31 INFO - 'read-buildbot-config', 16:12:31 INFO - 'download-and-extract', 16:12:31 INFO - 'populate-webroot', 16:12:31 INFO - 'create-virtualenv', 16:12:31 INFO - 'install', 16:12:31 INFO - 'run-tests'), 16:12:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:31 INFO - 'download_minidump_stackwalk': True, 16:12:31 INFO - 'download_symbols': 'ondemand', 16:12:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:31 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:31 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:31 INFO - 'installer_path': 'installer.exe', 16:12:31 INFO - 'log_level': 'info', 16:12:31 INFO - 'log_name': 'talos', 16:12:31 INFO - 'log_to_console': True, 16:12:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:31 INFO - 'opt_config_files': (), 16:12:31 INFO - 'pip_index': False, 16:12:31 INFO - 'sps_profile': False, 16:12:31 INFO - 'sps_profile_interval': 0, 16:12:31 INFO - 'suite': 'dromaeojs', 16:12:31 INFO - 'system_bits': '32', 16:12:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:31 INFO - 'title': 'talos-linux64-ix-047', 16:12:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:31 INFO - 'use_talos_json': True, 16:12:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:31 INFO - 'work_dir': 'build'} 16:12:31 INFO - [mozharness: 2016-09-07 23:12:31.874448Z] Running clobber step. 16:12:31 INFO - Running pre-action listener: _resource_record_pre_action 16:12:31 INFO - Running main action method: clobber 16:12:31 INFO - rmtree: /builds/slave/test/build 16:12:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:32 INFO - Running post-action listener: _resource_record_post_action 16:12:32 INFO - [mozharness: 2016-09-07 23:12:32.337045Z] Finished clobber step (success) 16:12:32 INFO - [mozharness: 2016-09-07 23:12:32.337148Z] Running read-buildbot-config step. 16:12:32 INFO - Running pre-action listener: _resource_record_pre_action 16:12:32 INFO - Running main action method: read_buildbot_config 16:12:32 INFO - Using buildbot properties: 16:12:32 INFO - { 16:12:32 INFO - "project": "", 16:12:32 INFO - "product": "firefox", 16:12:32 INFO - "script_repo_revision": "production", 16:12:32 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:12:32 INFO - "repository": "", 16:12:32 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:12:32 INFO - "buildid": "20160907143916", 16:12:32 INFO - "pgo_build": "False", 16:12:32 INFO - "basedir": "/builds/slave/test", 16:12:32 INFO - "buildnumber": 453, 16:12:32 INFO - "slavename": "talos-linux64-ix-047", 16:12:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:12:32 INFO - "platform": "ubuntu64_hw", 16:12:32 INFO - "scheduler": "tests-autoland-linux64-talos", 16:12:32 INFO - "branch": "autoland", 16:12:32 INFO - "repo_path": "integration/autoland", 16:12:32 INFO - "revision": "c8096f896f7cf570dd77b503861650847337febc", 16:12:32 INFO - "stage_platform": "linux64", 16:12:32 INFO - "builduid": "52c4edc1153849fe966151c7791feb5c", 16:12:32 INFO - "slavebuilddir": "test" 16:12:32 INFO - } 16:12:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:12:32 INFO - Running post-action listener: _resource_record_post_action 16:12:32 INFO - [mozharness: 2016-09-07 23:12:32.339954Z] Finished read-buildbot-config step (success) 16:12:32 INFO - [mozharness: 2016-09-07 23:12:32.340045Z] Running download-and-extract step. 16:12:32 INFO - Running pre-action listener: _resource_record_pre_action 16:12:32 INFO - Running main action method: download_and_extract 16:12:32 INFO - mkdir: /builds/slave/test/build/tests 16:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:32 INFO - trying https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:12:32 INFO - Downloading https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:12:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:12:32 INFO - Downloaded 1361 bytes. 16:12:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:12:32 INFO - Using the following test package requirements: 16:12:32 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:32 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:32 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:12:32 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'jsshell-linux-x86_64.zip'], 16:12:32 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:32 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:32 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:12:32 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:12:32 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:32 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:32 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:12:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:12:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:12:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:12:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:12:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:43 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:44 INFO - trying https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:44 INFO - Downloading https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:12:45 INFO - Downloaded 55819572 bytes. 16:12:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:45 INFO - mkdir: /builds/slave/test/properties 16:12:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:45 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:45 INFO - Contents: 16:12:45 INFO - build_url:https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:45 INFO - Running post-action listener: _resource_record_post_action 16:12:45 INFO - Running post-action listener: set_extra_try_arguments 16:12:45 INFO - [mozharness: 2016-09-07 23:12:45.947178Z] Finished download-and-extract step (success) 16:12:45 INFO - [mozharness: 2016-09-07 23:12:45.947406Z] Running populate-webroot step. 16:12:45 INFO - Running pre-action listener: _resource_record_pre_action 16:12:45 INFO - Running main action method: populate_webroot 16:12:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:45 INFO - '--tppagecycles', 16:12:45 INFO - '7'], 16:12:45 INFO - 'tests': ['tsvgm']}}, 16:12:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['damp', 'tps']}, 16:12:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'tests': ['damp', 'tps']}, 16:12:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['dromaeo_dom']}, 16:12:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['basic_compositor_video']}, 16:12:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:12:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['a11yr', 16:12:45 INFO - 'ts_paint', 16:12:45 INFO - 'tpaint', 16:12:45 INFO - 'sessionrestore', 16:12:45 INFO - 'sessionrestore_no_auto_restore', 16:12:45 INFO - 'tabpaint']}, 16:12:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['tsvgx', 16:12:45 INFO - 'tsvgr_opacity', 16:12:45 INFO - 'tart', 16:12:45 INFO - 'tscrollx', 16:12:45 INFO - 'cart']}, 16:12:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:45 INFO - 'tsvgr_opacity', 16:12:45 INFO - 'tart', 16:12:45 INFO - 'tscrollx', 16:12:45 INFO - 'cart']}, 16:12:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'talos_options': ['--disable-e10s'], 16:12:45 INFO - 'tests': ['tp5o']}, 16:12:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'tests': ['tp5o']}, 16:12:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'talos_options': ['--disable-e10s', 16:12:45 INFO - '--xperf_path', 16:12:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:45 INFO - 'tests': ['tp5n']}, 16:12:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:45 INFO - 'talos_options': ['--xperf_path', 16:12:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:45 INFO - 'tests': ['tp5n']}}, 16:12:45 INFO - 'talos.zip': {'path': '', 16:12:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:45 INFO - Running post-action listener: _resource_record_post_action 16:12:45 INFO - [mozharness: 2016-09-07 23:12:45.969555Z] Finished populate-webroot step (success) 16:12:45 INFO - [mozharness: 2016-09-07 23:12:45.969646Z] Running create-virtualenv step. 16:12:45 INFO - Running pre-action listener: _resource_record_pre_action 16:12:45 INFO - Running main action method: create_virtualenv 16:12:45 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:12:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:46 INFO - Using real prefix '/usr' 16:12:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:46 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:48 INFO - Installing pip.................done. 16:12:48 INFO - Return code: 0 16:12:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:12:48 INFO - Using env: {'DISPLAY': ':0', 16:12:48 INFO - 'HOME': '/home/cltbld', 16:12:48 INFO - 'LANG': 'en_US.UTF-8', 16:12:48 INFO - 'LANGUAGE': 'en_US:en', 16:12:48 INFO - 'LOGNAME': 'cltbld', 16:12:48 INFO - 'MAIL': '/var/mail/cltbld', 16:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:48 INFO - 'MOZ_NO_REMOTE': '1', 16:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:48 INFO - 'NO_EM_RESTART': '1', 16:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:48 INFO - 'PWD': '/builds/slave/test', 16:12:48 INFO - 'SHELL': '/bin/bash', 16:12:48 INFO - 'SHLVL': '1', 16:12:48 INFO - 'TERM': 'linux', 16:12:48 INFO - 'TMOUT': '86400', 16:12:48 INFO - 'USER': 'cltbld', 16:12:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:48 INFO - '_': '/tools/buildbot/bin/python'} 16:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:12:48 INFO - Cleaning up... 16:12:48 INFO - Return code: 0 16:12:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:12:48 INFO - Using env: {'DISPLAY': ':0', 16:12:48 INFO - 'HOME': '/home/cltbld', 16:12:48 INFO - 'LANG': 'en_US.UTF-8', 16:12:48 INFO - 'LANGUAGE': 'en_US:en', 16:12:48 INFO - 'LOGNAME': 'cltbld', 16:12:48 INFO - 'MAIL': '/var/mail/cltbld', 16:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:48 INFO - 'MOZ_NO_REMOTE': '1', 16:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:48 INFO - 'NO_EM_RESTART': '1', 16:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:48 INFO - 'PWD': '/builds/slave/test', 16:12:48 INFO - 'SHELL': '/bin/bash', 16:12:48 INFO - 'SHLVL': '1', 16:12:48 INFO - 'TERM': 'linux', 16:12:48 INFO - 'TMOUT': '86400', 16:12:48 INFO - 'USER': 'cltbld', 16:12:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:48 INFO - '_': '/tools/buildbot/bin/python'} 16:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:48 INFO - Downloading/unpacking psutil>=3.1.1 16:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:50 INFO - Installing collected packages: psutil 16:12:50 INFO - Running setup.py install for psutil 16:12:51 INFO - building 'psutil._psutil_linux' extension 16:12:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:12:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:12:51 INFO - building 'psutil._psutil_posix' extension 16:12:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:12:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:51 INFO - Successfully installed psutil 16:12:51 INFO - Cleaning up... 16:12:51 INFO - Return code: 0 16:12:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:12:51 INFO - Using env: {'DISPLAY': ':0', 16:12:51 INFO - 'HOME': '/home/cltbld', 16:12:51 INFO - 'LANG': 'en_US.UTF-8', 16:12:51 INFO - 'LANGUAGE': 'en_US:en', 16:12:51 INFO - 'LOGNAME': 'cltbld', 16:12:51 INFO - 'MAIL': '/var/mail/cltbld', 16:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:51 INFO - 'MOZ_NO_REMOTE': '1', 16:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:51 INFO - 'NO_EM_RESTART': '1', 16:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:51 INFO - 'PWD': '/builds/slave/test', 16:12:51 INFO - 'SHELL': '/bin/bash', 16:12:51 INFO - 'SHLVL': '1', 16:12:51 INFO - 'TERM': 'linux', 16:12:51 INFO - 'TMOUT': '86400', 16:12:51 INFO - 'USER': 'cltbld', 16:12:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:51 INFO - '_': '/tools/buildbot/bin/python'} 16:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:12:53 INFO - Installing collected packages: mozsystemmonitor 16:12:53 INFO - Running setup.py install for mozsystemmonitor 16:12:53 INFO - Successfully installed mozsystemmonitor 16:12:53 INFO - Cleaning up... 16:12:53 INFO - Return code: 0 16:12:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:12:53 INFO - Using env: {'DISPLAY': ':0', 16:12:53 INFO - 'HOME': '/home/cltbld', 16:12:53 INFO - 'LANG': 'en_US.UTF-8', 16:12:53 INFO - 'LANGUAGE': 'en_US:en', 16:12:53 INFO - 'LOGNAME': 'cltbld', 16:12:53 INFO - 'MAIL': '/var/mail/cltbld', 16:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:53 INFO - 'MOZ_NO_REMOTE': '1', 16:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:53 INFO - 'NO_EM_RESTART': '1', 16:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:53 INFO - 'PWD': '/builds/slave/test', 16:12:53 INFO - 'SHELL': '/bin/bash', 16:12:53 INFO - 'SHLVL': '1', 16:12:53 INFO - 'TERM': 'linux', 16:12:53 INFO - 'TMOUT': '86400', 16:12:53 INFO - 'USER': 'cltbld', 16:12:53 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:53 INFO - '_': '/tools/buildbot/bin/python'} 16:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:54 INFO - Downloading/unpacking jsonschema==2.5.1 16:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:56 INFO - warning: no files found matching '*.txt' 16:12:56 INFO - no previously-included directories found matching 'build' 16:12:56 INFO - no previously-included directories found matching 'dist' 16:12:56 INFO - no previously-included directories found matching '.git*' 16:12:56 INFO - Installing collected packages: jsonschema, functools32 16:12:56 INFO - Running setup.py install for functools32 16:12:56 INFO - warning: no files found matching '*.txt' 16:12:56 INFO - no previously-included directories found matching 'build' 16:12:56 INFO - no previously-included directories found matching 'dist' 16:12:56 INFO - no previously-included directories found matching '.git*' 16:12:56 INFO - Successfully installed jsonschema functools32 16:12:56 INFO - Cleaning up... 16:12:56 INFO - Return code: 0 16:12:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:12:56 INFO - Using env: {'DISPLAY': ':0', 16:12:56 INFO - 'HOME': '/home/cltbld', 16:12:56 INFO - 'LANG': 'en_US.UTF-8', 16:12:56 INFO - 'LANGUAGE': 'en_US:en', 16:12:56 INFO - 'LOGNAME': 'cltbld', 16:12:56 INFO - 'MAIL': '/var/mail/cltbld', 16:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:56 INFO - 'MOZ_NO_REMOTE': '1', 16:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:56 INFO - 'NO_EM_RESTART': '1', 16:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:56 INFO - 'PWD': '/builds/slave/test', 16:12:56 INFO - 'SHELL': '/bin/bash', 16:12:56 INFO - 'SHLVL': '1', 16:12:56 INFO - 'TERM': 'linux', 16:12:56 INFO - 'TMOUT': '86400', 16:12:56 INFO - 'USER': 'cltbld', 16:12:56 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:56 INFO - '_': '/tools/buildbot/bin/python'} 16:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:12:56 INFO - Cleaning up... 16:12:56 INFO - Return code: 0 16:12:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:12:56 INFO - Using env: {'DISPLAY': ':0', 16:12:56 INFO - 'HOME': '/home/cltbld', 16:12:56 INFO - 'LANG': 'en_US.UTF-8', 16:12:56 INFO - 'LANGUAGE': 'en_US:en', 16:12:56 INFO - 'LOGNAME': 'cltbld', 16:12:56 INFO - 'MAIL': '/var/mail/cltbld', 16:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:56 INFO - 'MOZ_NO_REMOTE': '1', 16:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:56 INFO - 'NO_EM_RESTART': '1', 16:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:56 INFO - 'PWD': '/builds/slave/test', 16:12:56 INFO - 'SHELL': '/bin/bash', 16:12:56 INFO - 'SHLVL': '1', 16:12:56 INFO - 'TERM': 'linux', 16:12:56 INFO - 'TMOUT': '86400', 16:12:56 INFO - 'USER': 'cltbld', 16:12:56 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:56 INFO - '_': '/tools/buildbot/bin/python'} 16:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:56 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - Downloading docopt-0.6.1.tar.gz 16:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:59 INFO - Running setup.py install for blobuploader 16:12:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:59 INFO - Running setup.py install for requests 16:13:00 INFO - Running setup.py install for docopt 16:13:00 INFO - Successfully installed blobuploader requests docopt 16:13:00 INFO - Cleaning up... 16:13:00 INFO - Return code: 0 16:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:00 INFO - Using env: {'DISPLAY': ':0', 16:13:00 INFO - 'HOME': '/home/cltbld', 16:13:00 INFO - 'LANG': 'en_US.UTF-8', 16:13:00 INFO - 'LANGUAGE': 'en_US:en', 16:13:00 INFO - 'LOGNAME': 'cltbld', 16:13:00 INFO - 'MAIL': '/var/mail/cltbld', 16:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:00 INFO - 'MOZ_NO_REMOTE': '1', 16:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:00 INFO - 'NO_EM_RESTART': '1', 16:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:00 INFO - 'PWD': '/builds/slave/test', 16:13:00 INFO - 'SHELL': '/bin/bash', 16:13:00 INFO - 'SHLVL': '1', 16:13:00 INFO - 'TERM': 'linux', 16:13:00 INFO - 'TMOUT': '86400', 16:13:00 INFO - 'USER': 'cltbld', 16:13:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:00 INFO - '_': '/tools/buildbot/bin/python'} 16:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:00 INFO - Running setup.py (path:/tmp/pip-0HRrz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:00 INFO - Running setup.py (path:/tmp/pip-h1lHYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:00 INFO - Running setup.py (path:/tmp/pip-7GFGnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:00 INFO - Running setup.py (path:/tmp/pip-QCC4yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:00 INFO - Running setup.py (path:/tmp/pip-RRLQub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:00 INFO - Running setup.py (path:/tmp/pip-MINTFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:00 INFO - Running setup.py (path:/tmp/pip-PB75fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:00 INFO - Running setup.py (path:/tmp/pip-t4Aqxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:00 INFO - Running setup.py (path:/tmp/pip-jHO18H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:01 INFO - Running setup.py (path:/tmp/pip-EVRirX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:01 INFO - Running setup.py (path:/tmp/pip-U_iIX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:01 INFO - Running setup.py (path:/tmp/pip-31fheT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:01 INFO - Running setup.py (path:/tmp/pip-wftloF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:01 INFO - Running setup.py (path:/tmp/pip-BNA2if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:01 INFO - Running setup.py (path:/tmp/pip-cS9n9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:01 INFO - Running setup.py (path:/tmp/pip-L8YbwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:01 INFO - Running setup.py (path:/tmp/pip-FGzLOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:13:01 INFO - Running setup.py install for manifestparser 16:13:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Running setup.py install for mozcrash 16:13:01 INFO - Running setup.py install for mozdebug 16:13:01 INFO - Running setup.py install for mozdevice 16:13:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozfile 16:13:02 INFO - Running setup.py install for mozhttpd 16:13:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozinfo 16:13:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozInstall 16:13:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozleak 16:13:02 INFO - Running setup.py install for mozlog 16:13:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for moznetwork 16:13:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozprocess 16:13:02 INFO - Running setup.py install for mozprofile 16:13:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:13:02 INFO - Running setup.py install for mozrunner 16:13:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:13:03 INFO - Running setup.py install for mozscreenshot 16:13:03 INFO - Running setup.py install for moztest 16:13:03 INFO - Running setup.py install for mozversion 16:13:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:13:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:13:03 INFO - Cleaning up... 16:13:03 INFO - Return code: 0 16:13:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:03 INFO - Using env: {'DISPLAY': ':0', 16:13:03 INFO - 'HOME': '/home/cltbld', 16:13:03 INFO - 'LANG': 'en_US.UTF-8', 16:13:03 INFO - 'LANGUAGE': 'en_US:en', 16:13:03 INFO - 'LOGNAME': 'cltbld', 16:13:03 INFO - 'MAIL': '/var/mail/cltbld', 16:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:03 INFO - 'MOZ_NO_REMOTE': '1', 16:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:03 INFO - 'NO_EM_RESTART': '1', 16:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:03 INFO - 'PWD': '/builds/slave/test', 16:13:03 INFO - 'SHELL': '/bin/bash', 16:13:03 INFO - 'SHLVL': '1', 16:13:03 INFO - 'TERM': 'linux', 16:13:03 INFO - 'TMOUT': '86400', 16:13:03 INFO - 'USER': 'cltbld', 16:13:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:03 INFO - '_': '/tools/buildbot/bin/python'} 16:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:03 INFO - Running setup.py (path:/tmp/pip-OnOWU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:03 INFO - Running setup.py (path:/tmp/pip-Jkj8pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:03 INFO - Running setup.py (path:/tmp/pip-BfOmN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:03 INFO - Running setup.py (path:/tmp/pip-A9A7BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:03 INFO - Running setup.py (path:/tmp/pip-ZoHf3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:03 INFO - Running setup.py (path:/tmp/pip-piYZMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:04 INFO - Running setup.py (path:/tmp/pip-1wXFBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:04 INFO - Running setup.py (path:/tmp/pip-mgp17Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:04 INFO - Running setup.py (path:/tmp/pip-xDko5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:04 INFO - Running setup.py (path:/tmp/pip-lSqUne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:04 INFO - Running setup.py (path:/tmp/pip-EvnUng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:04 INFO - Running setup.py (path:/tmp/pip-RYTJf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:04 INFO - Running setup.py (path:/tmp/pip-DEDLBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:04 INFO - Running setup.py (path:/tmp/pip-I92Bet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:04 INFO - Running setup.py (path:/tmp/pip-IXkjXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:04 INFO - Running setup.py (path:/tmp/pip-y4aTkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:04 INFO - Running setup.py (path:/tmp/pip-Jeysdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:06 INFO - Downloading blessings-1.6.tar.gz 16:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:13:06 INFO - Installing collected packages: blessings 16:13:06 INFO - Running setup.py install for blessings 16:13:06 INFO - Successfully installed blessings 16:13:06 INFO - Cleaning up... 16:13:07 INFO - Return code: 0 16:13:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:07 INFO - Reading from file tmpfile_stdout 16:13:07 INFO - Current package versions: 16:13:07 INFO - argparse == 1.2.1 16:13:07 INFO - blessings == 1.6 16:13:07 INFO - blobuploader == 1.2.4 16:13:07 INFO - docopt == 0.6.1 16:13:07 INFO - functools32 == 3.2.3-2 16:13:07 INFO - jsonschema == 2.5.1 16:13:07 INFO - manifestparser == 1.1 16:13:07 INFO - mozInstall == 1.12 16:13:07 INFO - mozcrash == 0.17 16:13:07 INFO - mozdebug == 0.1 16:13:07 INFO - mozdevice == 0.48 16:13:07 INFO - mozfile == 1.2 16:13:07 INFO - mozhttpd == 0.7 16:13:07 INFO - mozinfo == 0.9 16:13:07 INFO - mozleak == 0.1 16:13:07 INFO - mozlog == 3.3 16:13:07 INFO - moznetwork == 0.27 16:13:07 INFO - mozprocess == 0.23 16:13:07 INFO - mozprofile == 0.28 16:13:07 INFO - mozrunner == 6.12 16:13:07 INFO - mozscreenshot == 0.1 16:13:07 INFO - mozsystemmonitor == 0.3 16:13:07 INFO - moztest == 0.7 16:13:07 INFO - mozversion == 1.4 16:13:07 INFO - psutil == 3.1.1 16:13:07 INFO - requests == 1.2.3 16:13:07 INFO - wsgiref == 0.1.2 16:13:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:07 INFO - Using env: {'DISPLAY': ':0', 16:13:07 INFO - 'HOME': '/home/cltbld', 16:13:07 INFO - 'LANG': 'en_US.UTF-8', 16:13:07 INFO - 'LANGUAGE': 'en_US:en', 16:13:07 INFO - 'LOGNAME': 'cltbld', 16:13:07 INFO - 'MAIL': '/var/mail/cltbld', 16:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:07 INFO - 'MOZ_NO_REMOTE': '1', 16:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:07 INFO - 'NO_EM_RESTART': '1', 16:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:07 INFO - 'PWD': '/builds/slave/test', 16:13:07 INFO - 'SHELL': '/bin/bash', 16:13:07 INFO - 'SHLVL': '1', 16:13:07 INFO - 'TERM': 'linux', 16:13:07 INFO - 'TMOUT': '86400', 16:13:07 INFO - 'USER': 'cltbld', 16:13:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:07 INFO - '_': '/tools/buildbot/bin/python'} 16:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:07 INFO - Cleaning up... 16:13:07 INFO - Return code: 0 16:13:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3d564b0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2798230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:13:07 INFO - Using env: {'DISPLAY': ':0', 16:13:07 INFO - 'HOME': '/home/cltbld', 16:13:07 INFO - 'LANG': 'en_US.UTF-8', 16:13:07 INFO - 'LANGUAGE': 'en_US:en', 16:13:07 INFO - 'LOGNAME': 'cltbld', 16:13:07 INFO - 'MAIL': '/var/mail/cltbld', 16:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:07 INFO - 'MOZ_NO_REMOTE': '1', 16:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:07 INFO - 'NO_EM_RESTART': '1', 16:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:07 INFO - 'PWD': '/builds/slave/test', 16:13:07 INFO - 'SHELL': '/bin/bash', 16:13:07 INFO - 'SHLVL': '1', 16:13:07 INFO - 'TERM': 'linux', 16:13:07 INFO - 'TMOUT': '86400', 16:13:07 INFO - 'USER': 'cltbld', 16:13:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:07 INFO - '_': '/tools/buildbot/bin/python'} 16:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:13:07 INFO - Cleaning up... 16:13:07 INFO - Return code: 0 16:13:07 INFO - Running post-action listener: _resource_record_post_action 16:13:07 INFO - Running post-action listener: _start_resource_monitoring 16:13:07 INFO - Starting resource monitoring. 16:13:07 INFO - [mozharness: 2016-09-07 23:13:07.686701Z] Finished create-virtualenv step (success) 16:13:07 INFO - [mozharness: 2016-09-07 23:13:07.687167Z] Running install step. 16:13:07 INFO - Running pre-action listener: _resource_record_pre_action 16:13:07 INFO - Running main action method: install 16:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:07 INFO - Reading from file tmpfile_stdout 16:13:07 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:07 INFO - Reading from file tmpfile_stdout 16:13:07 INFO - Output received: 16:13:07 INFO - Usage: mozinstall [options] installer 16:13:07 INFO - Options: 16:13:07 INFO - -h, --help show this help message and exit 16:13:07 INFO - -d DEST, --destination=DEST 16:13:07 INFO - Directory to install application into. [default: 16:13:07 INFO - "/builds/slave/test"] 16:13:07 INFO - --app=APP Application being installed. [default: firefox] 16:13:07 INFO - mkdir: /builds/slave/test/build/application 16:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:13:24 INFO - Reading from file tmpfile_stdout 16:13:24 INFO - Output received: 16:13:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:13:24 INFO - Running post-action listener: _resource_record_post_action 16:13:24 INFO - [mozharness: 2016-09-07 23:13:24.095659Z] Finished install step (success) 16:13:24 INFO - [mozharness: 2016-09-07 23:13:24.095994Z] Running run-tests step. 16:13:24 INFO - Running pre-action listener: _resource_record_pre_action 16:13:24 INFO - Running main action method: run_tests 16:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:24 INFO - Python 2.7.3 16:13:24 INFO - Return code: 0 16:13:24 INFO - grabbing minidump binary from tooltool 16:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2792090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27959b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:13:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:13:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:13:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:13:24 INFO - Return code: 0 16:13:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:13:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:13:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:13:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:13:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:13:24 INFO - Using env: {'DISPLAY': ':0', 16:13:24 INFO - 'HOME': '/home/cltbld', 16:13:24 INFO - 'LANG': 'en_US.UTF-8', 16:13:24 INFO - 'LANGUAGE': 'en_US:en', 16:13:24 INFO - 'LOGNAME': 'cltbld', 16:13:24 INFO - 'MAIL': '/var/mail/cltbld', 16:13:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:24 INFO - 'MOZ_NO_REMOTE': '1', 16:13:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:24 INFO - 'NO_EM_RESTART': '1', 16:13:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:24 INFO - 'PWD': '/builds/slave/test', 16:13:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:24 INFO - 'SHELL': '/bin/bash', 16:13:24 INFO - 'SHLVL': '1', 16:13:24 INFO - 'TERM': 'linux', 16:13:24 INFO - 'TMOUT': '86400', 16:13:24 INFO - 'USER': 'cltbld', 16:13:24 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912', 16:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:24 INFO - '_': '/tools/buildbot/bin/python'} 16:13:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:13:24 INFO - mozversion application_buildid: 20160907143916 16:13:24 INFO - mozversion application_changeset: c8096f896f7cf570dd77b503861650847337febc 16:13:24 INFO - mozversion application_display_name: Nightly 16:13:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:13:24 INFO - mozversion application_name: Firefox 16:13:24 INFO - mozversion application_remotingname: firefox 16:13:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:13:24 INFO - mozversion application_vendor: Mozilla 16:13:24 INFO - mozversion application_version: 51.0a1 16:13:24 INFO - mozversion platform_buildid: 20160907143916 16:13:24 INFO - mozversion platform_changeset: c8096f896f7cf570dd77b503861650847337febc 16:13:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:13:24 INFO - mozversion platform_version: 51.0a1 16:13:24 INFO - using testdate: 1473290004 16:13:24 INFO - actual date: 1473290004 16:13:24 INFO - starting webserver on 'localhost:56056' 16:13:24 INFO - SUITE-START | Running 2 tests 16:13:24 INFO - TEST-START | dromaeo_css 16:13:24 INFO - operating with platform_type : linux_ 16:13:24 INFO - Initialising browser for dromaeo_css test... 16:13:24 INFO - TEST-INFO | started process 26693 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQZ9Ut3/profile http://localhost:56056/getInfo.html) 16:13:28 INFO - PROCESS | 26693 | __metrics Screen width/height:1600/1200 16:13:28 INFO - PROCESS | 26693 | colorDepth:24 16:13:28 INFO - PROCESS | 26693 | Browser inner width/height: 1024/697 16:13:28 INFO - PROCESS | 26693 | __metrics 16:13:29 INFO - PROCESS | 26693 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:13:29 INFO - PROCESS | 26693 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:13:29 INFO - PROCESS | 26693 | console.error: PushService: 16:13:29 INFO - PROCESS | 26693 | stateChangeProcessEnqueue: Error transitioning state 16:13:29 INFO - PROCESS | 26693 | UnknownError 16:13:30 INFO - TEST-INFO | 26693: exit 0 16:13:30 INFO - Browser initialized. 16:13:30 INFO - Running cycle 1/1 for dromaeo_css test... 16:13:30 INFO - TEST-INFO | started process 26775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQZ9Ut3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:42 INFO - PROCESS | 26775 | RSS: Main: 179486720 16:13:42 INFO - PROCESS | 26775 | 16:14:34 INFO - PROCESS | 26775 | 1473290074814 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:16:47 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56056/tests/dromaeo/cssquery-ext.html) 16:16:48 INFO - PROCESS | 26775 | RSS: Main: 272674816 16:16:48 INFO - PROCESS | 26775 | 16:19:51 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-ext.html (next: http://localhost:56056/tests/dromaeo/cssquery-jquery.html) 16:19:52 INFO - PROCESS | 26775 | RSS: Main: 274141184 16:19:52 INFO - PROCESS | 26775 | 16:22:54 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56056/tests/dromaeo/cssquery-mootools.html) 16:22:55 INFO - PROCESS | 26775 | RSS: Main: 256307200 16:22:55 INFO - PROCESS | 26775 | 16:25:57 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56056/tests/dromaeo/cssquery-prototype.html) 16:25:57 INFO - PROCESS | 26775 | RSS: Main: 219336704 16:25:57 INFO - PROCESS | 26775 | 16:29:01 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56056/tests/dromaeo/cssquery-yui.html) 16:29:01 INFO - PROCESS | 26775 | RSS: Main: 218976256 16:29:01 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | Cycle 1(1): loaded http://localhost:56056/tests/dromaeo/cssquery-yui.html (next: http://localhost:56056/tests/dromaeo/cssquery-dojo.html) 16:32:04 INFO - PROCESS | 26775 | RSS: Main: 313974784 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | __start_tp_report 16:32:04 INFO - PROCESS | 26775 | _x_x_mozilla_page_load 16:32:04 INFO - PROCESS | 26775 | _x_x_mozilla_page_load_details 16:32:04 INFO - PROCESS | 26775 | |i|pagename|runs| 16:32:04 INFO - PROCESS | 26775 | |0;dojo.html;2598.1555994301857;2618.5417551928413;2630.552806022976;2674.598810178473;2693.1247344613007;4537.137943790685;4613.054323863608;4760.452547956986;4827.855164345071;4887.633427492934;4659.138059459003;4699.107169637767;4732.881677958044;4765;4842.8062877484945;4292.935605965913;4326.178026175016;4433.667474939376;4460.063386688799;4526.592606665399;4447.4218351614245;4479.7984090716;4493.7977790999485;4522.525134860842;4527.773611319437;4191.287481128185;4229.936550951738;4359.149965756678;4377.737335759864;4409;4278.914421711548;4285.142971405731;4286.442762440894;4390.912181756347;4473.4855491618555;4288.0351920817875;4297.634701050383;4312.913741725148;4312.978435107836;4357.891052723676;4601.286800545888;4694.615088548852;4764.213904705719;4769.260764581495;4824.903501929977;5164.535191832753;5175.404828444702;5179.559737422323;5186.818461353872;5232.633715639907;5187.8184263550975;5195.062119173859;5221.321228240305;5227.790888364499;5282.867928301785;4632.2356811126465;4824.276358546246;4861.951380486221;4875.07373599015;4890.095332363524;5346.1713434417725;5367.4364191759305;5371.677699337974;5374;5423.132298832168;5137.383513978347;5151.5878729701535;5154.664946778447;5203.609729270244;5209.244659524347;4911.263310503382;4972;4987.775550100254;4992.350994370709;5211.921821172609;4972.328735620645;4975.402951645826;4975.601951843843;4976.104301225741;5023.773930173151;4958.826441074545;4965.627577931669;4966.180580204298;4967.254911763264;5022.42242142151;4223.831046758157;4348.499922508889;4354.259775838115;4367.45406824147;4375.803088861009;3808.5239345081864;3812.694984401241;3819.102510909934;3876.6898648108086;3880.0881792778673;4371.672124590668;4377.988281444948;4379.321205213197;4417.271150260171;4433.202023635712;4930.359053323117;5122.026814905156;5129.897402051938;5158.716270605153;5191.870203244874;4377.277749171415;4417.2269852775635;4427.468703755506;4427.5572442755465;4430.579094986036;4654.4414670239785;4664.813407463731;4665.510121437268;4666.556677115668;4726.220173671306;4674.602658773906;4753.786079626356;4841.467438581753;4858;4920.311156438173;5119.0273847968765;5163.767630456714;5178.223266510054;5250.553702935294;5272.2882410874045;4847.684900481458;4853.95146048535;4853.975730121327;4896.951030489792;4916.631252655993;5109.310243117132;5113.207452844815;5115.25828754821;5118.744062796771;5185.637005409585;5047.570956468741;5054.16606259963;5066.898662026665;5188.429272779916;5193.7403129844115;4953.2570114483115;4980.5019498049905;4986.077575648516;5032.748362581783;5044.470330615232;4973.900521989613;4975.278584605139;4978.2034874419915;4979.278004689228;5036.571891389126;4863.173260545645;4919.335889654851;4933.654644175017;4952.232403977389;4973.254011898244;5103.18349064148;5112.258722485293;5116.9232461512365;5154.278401023935;5160.251763494347;4765.309030190672;4999.850004499871;5000.074986127433;5009.474005229398;5076.822311219237 16:32:04 INFO - PROCESS | 26775 | |1;ext.html;534.7941042698561;547.3705238975181;552.284791195402;556.7188569772264;562.4122791682692;27464.176074717765;36374.272514549724;38183.80908095459;38205.808970955106;41869;38703.41944870822;38933.610663893356;39015.609843901555;39075;39102;7711.151773304932;7855.293023627873;7859.253370929752;7861.842763144734;7864.488808227476;7447.627618619074;7524.05949256343;7534.773956781305;7535.284148005931;7536.547807131589;7529.32236098751;7569.848603027964;7577.507462014952;7578.734744283951;7579.886301705479;7522.699092036367;7525.435592330569;7556.47104702671;7559.244075592424;7559.3196612305155;7676.654550545239;7738.303552680286;7738.690452381898;7739;7743.574103424309;37964;38331.23337533262;38353.616463835286;38358.808205959074;38426.80786596077;2858.7998840080995;2879.0643041011544;3039.452898478273;3398.4222682144095;3440.0539851540775;27707.86146069277;27769.305767355978;27823.16530504068;28323.433531329258;28666.570001449996;3440.518327434161;3450.396180668373;3515.6484351564895;3533.2933413317182;3650.1604630935035;183958;184513;184999.99999999866;185552;185763;49904.75047624738;49986.250206246936;50265;50290;50300;32019;32976.17559560982;33097.007089787345;34523.65476345205;34631;12294.139410241376;12397.814032789516;12424.192427492184;12426.875731242571;12428.875711242952;9377.578008989612;9449.90550094504;9453.858192127123;9456.47989360578;9462.810743785085;17527.91236043837;17638.206280717142;17640.20619072145;17681.7347739784;17736.29054837792;6704.363085506869;6715.664216789141;6934.47991400647;6974.337437943387;7051.083359163378;744.7132853851198;758.4235980654743;812.1472453923475;830.9916900831035;837.9790505237418;27366;27435;27447.588286175724;27789.722102779117;27936.58095128575;775.9573223472651;907.609727817045;975.8679931279823;1003.8042581696499;1012.2104758288547;14253.928730356281;14419.78370324446;14504.419823206954;14816.777748333783;14842.628934276729;397570;400782;401395;401989;402620;48449.51550484521;48839.26741098886;48971.510284897406;49001.26498102531;49054;1052.1845569683403;1133.982990255147;1139.157023802397;1161.3902701081831;1188.1089183112665;26865.597016044383;29264.122076337742;29349.85325073361;29663;29810.552841706958;40277.1944561113;40939;40989.59010409858;41052.58947410607;41062.58937410707;301913;302309;302732;303649.48907152575;304091;24439.51120977606;24670;25200;25352.492950140524;26158.34604134912;40329.79835100806;40765;40810.59189408068;40818.591814081476;41968.58031419769;34688.132796680286;34861.82569087138;35135;35221;35454.46818297676;34166.82916585401;34363.65636343705;34465;34738;34802;34659.82670086633;35115.29769404649;35299.11752206215;35326.64673353233;35937.101572459855;30037.69962300349;31845.522317165724;32017.359652807278;32314.515282271255;32382.514262286557;23986;24275.635865461678;24373;25063.373415664024;25139.622905656797 16:32:04 INFO - PROCESS | 26775 | |2;jquery.html;326.53957919333743;355.1742199386427;363.58188083703743;365.28039761669504;380.7867594147278;12409.255444673327;13307.933460332697;14080.36638351274;15117;17365.91317043413;15673.92163039186;15952.723782097433;15973.361065557392;16028.919855400707;16204.594885127877;4908.803647854082;4920.557149856521;5051.595872330205;5080.695158290496;5082.949170508303;3628.87298944537;3804.2201348723447;5032.899342013157;5116.948830511703;5123.487651234884;3831.329517334469;3837.6354246346555;4527.20773864574;5940.702964851762;5948.524118070565;6014.609050411731;6016.578839481238;6027.276726792791;6028.3971602839365;6029.969850150765;5056.797728090872;5217.869553261161;5248.9737551312;5251.343582052244;5255.211718242256;18143.455696329132;18871.81128188728;19009.239630414766;20084.79915200844;20196.39410817663;2045.9079341429672;2067.390119914629;2263.1852533088077;2269.6595510673365;2334.871582062986;8355.998263325724;8404.369672274543;8680.34897382699;8929.73210803677;8935.151160639729;1541.352631894607;1575.8266590675016;1585.1361008250417;1591.116930103792;1625.4879712890402;66869.66565167192;67290;68278;68522.65738671274;70057.64971175163;9713.320067595341;9736.367136136128;9876.753081173028;10020.248481363928;10156.542955567018;13147.0797044206;13148.342582870819;13148.934255328853;13171.27557984301;13184.802227966587;6462.256840463313;6581.14445122131;6600.636964966877;6696.59820410777;6706.295838936939;5996.730147143302;6111.602745821508;6196.659183744938;6220.34686357935;6275.623462592259;4168.374743788396;4170.249355116048;4171.6036976487185;4196.118815048813;4297.742135471882;1799.3432397174936;1806.1781889240365;1807.6746185686702;1809.837114659687;1812.3203798575535;776.5496012312958;792.9643166057541;820.4871955028108;883.65095787165;895.1451363947415;16054.197290135446;16235.512934611981;16482.34070637185;16971.406000789913;17051.30060327228;817.7751118442357;837.3301358912845;849.0957130655955;851.3274513134678;869.8825658536143;8987.41581797181;9229.307801914749;9247.72256832296;9375.156235938799;9449.763755906157;346277;373235;393455;395758;396439;79340.6032969843;80702;81805.59097204595;84238;85058;835.2816577743137;840.6805413942662;841.2512863551444;844.1094645149426;844.8648216285242;14054.648633783829;14406.943200542099;15015.699686006437;15024.09855408679;15328.693426131193;36766.89699309025;37064.25871482609;37775;38529.2294154121;38977;49358.50641493539;49708.25437618511;51527.2270915944;51574;51944.74027629838;7118.572885626879;7339.522931009681;7387.704491820267;7392.630368481662;7456.888146677913;8962.417442866194;10719.946400267949;11053.226274160732;11054;11715.297082175097;7309.963450182715;7731.420143489148;9114.498702571422;9204.815903681754;10148.594056237964;7348.301911318416;7449.925500744923;7536.773896783106;7537.133229678657;7967.645500264853;7479;7581.658825352983;7857.567833768969;7881.487703299267;7989.402119576224;11036.137351927082;11417.429128543374;11431.314121152758;11476.13928955315;11534.365609891536;10912.945435272773;10996.23026388145;11073.169512286771;11449.656510304705;11508.712282193012 16:32:04 INFO - PROCESS | 26775 | |3;mootools.html;1228.4165021614729;1358.4701966233167;1368.8220531330926;1452.7457694903396;1620.0684606351351;7317.780466585998;7686.500377475467;7737.72917947872;7948.92051079489;7952.92047079529;7725.381969442446;7801.843963120734;7814.8046298842555;7837.255460731221;7844.41166912481;1834.4771740054086;2140;2161.2111579273533;2185.4208634711813;2197.604431202383;2219.1012639880873;2242.5627002734473;2245.494763678176;2251.9774802252014;2262.8529145605485;2187.8249740020838;2206.4483879030245;2212.3362991102686;2225.9332220033425;2269.5120549081944;2019.1923230707687;2096.276784509357;2152.1821707751033;2160.0711693971584;2161.8378621603442;2174.695542624034;2212.214663794346;2212.811910987568;2213.0705103856417;2215.6233440315023;8069.354451643854;8122.5126492410645;8126.065502467233;8126.228008339198;8127.75616731499;4431.889202769924;4582.816687332503;4585.72485650862;4587;4588.0365123324145;2819.210621026116;2890.089621769136;3010.909672709822;3021.561873528348;3024.3497648005564;916.9908300916973;928.8281667891395;940.3652534539159;954.3128947158035;957.7174733453647;10797.514111864984;10915.836262456069;10936.890631093667;10937.179711521667;10937.289076210021;3803.847846086181;3963.801809909493;4097.836086556564;4110.34234522475;4131.153113611703;8540;8543.615537300837;8553.401261911606;8555.828883422297;8563.143685631512;4459.710118842265;4479.104179164115;4482.439695038121;4482.8206871725415;4488.551144885485;3600.9099772505897;3976.8608098716404;4067.9593204067583;4144.316187829035;4147.149834284025;3369.359821633882;3387.8983630491125;3511.455724362677;3514.894553163409;3522.806245656462;1026.5585798106886;1032.6850310655323;1036.4403222259896;1036.7874585709912;1052.231870734353;863.7581477186397;863.9179277968583;864.6195673903462;875.0330884372804;875.1991927386497;5383.7846486140115;5415;5544.91682624761;5561.916571251353;5759.395263497357;851.6806197675871;889.6352495476823;889.835380454618;894.8612964990436;895.713371721043;3735.6824669902817;3815.0843797488956;3902.726809123334;3913.5890731473355;3917.118648304109;9298.163165315154;9699.563519641633;9711.028897110375;9755.756106097262;9788.755281118029;9633.710988670351;9646.131848133702;9661.32370734056;9693.9030609693;9696.466694331739;6938.930610693929;7281.5995120267835;7282.12614486265;7301.707931682673;7446.925530744732;5789.507891829073;5798.188253644577;5804.709764511842;5838.182654428469;5868.882622347614;8242.546659933521;8732.34507411934;8969.955150224207;8999.235065019546;9010.594523246338;8597.742067737978;8881.822363552563;8896.822063558564;8899.288056955298;9209.907900921173;6018.969905150446;6521.69566086775;6750.493712971645;6753.966230169014;6926.965365173141;7584;7737.883931740916;7743.728969486041;7747.845043099219;7748.883766743616;6797.864042719217;6872.41584465306;6906.516543841883;6983.441324694114;7049.048378468841;6787.728490860311;6861.588304701734;6870.45036397097;6884;6890.3798658119;6525.608463492205;6538.934610653832;6542.313057129123;6592.208934927839;6604.14146160996;4660.720356778605;4708.905821883612;4780.569748722632;4798.23228283473;4818.566329030404;4680.882977925579;4746;4776.426828780569;4806.903861922812;4916.410030796327 16:32:04 INFO - PROCESS | 26775 | |4;prototype.html;291.76804440469823;294.13377602959287;297.51802080629386;303.8708548866731;304.3092180749698;4828.541288577584;4880;4885.828995985141;4889.633277504184;4908.50914908509;4789.616830653548;4796.6882152660055;4833.226683730604;4840.515948405157;4846;3569.9464508032333;3647.74465787395;3735.5143831301893;3755.6432138946757;3769.1142581493327;3748.475213470116;3755.7370984031127;3756.380197267448;3773.792441415721;3781.2815565042556;3719.42348935914;3725.7764534127905;3727.2359166370966;3777.471154038437;3782.243551289739;3584.4264917613186;3644.453332000208;3702.6667599916136;3704.518412606344;3746.3256613809776;3309.321589074234;3318.6183588887347;3318.668133186662;3320;3320.286138480238;4655.2784318430695;4657.6273898088075;4661.160991021615;4668.11305851887;4687.9765601171775;4158.627652874544;4262.9786851065855;4264.104538047014;4289.378040184156;4293.8711838644895;4978.352814134176;4981.5055483354845;4996.150654388763;5006;5012.248162775576;3638.5088013118157;3639.763415377992;3639.890803275906;3640.1263696712863;3644.726645501598;4921.581665558432;5165.819196328111;5207.427183009904;5211.374635043781;5230.21546767984;3577.874774382884;3633.691136253448;3640.508531348287;3647.252313275772;3655.634436556323;3661.487391765155;3688.8340024698955;3690.969966518458;3706.9258614827554;3708.610595887447;4175.603317684815;4183.581641835853;4187.895302617459;4220.746755194698;4226.408302837605;4754.168020596382;4992.925106123411;5021.899562008739;5031.723255220924;5053.46938571452;1271.580378475101;1279.7248591552861;1279.9488020479077;1283.7932343597176;1283.9486420543074;797.2027972027972;808.7937575918103;811.7499051460684;826.446280991737;827.9254867061993;4278.550752170977;4416.681242347793;4441.933370999438;4442.777861106932;4443.355713421536;5179.119549676564;5188.429272779991;5198.92201616976;5218.478152184827;5223.5559977402345;4367.912641747211;4368.628666563314;4371.5628437156665;4396.868093957187;4408.4269045024585;2603.6615240018677;2740.054681135005;2749.3951330707214;2754.669439667253;2755.779537636984;4913.877153071202;4917.729524876095;4917.852464426073;4918.852434426973;4928.063667903087;5070.008694869971;5310.787568497294;5321.201819727072;5347.304850369427;5354.11657076578;4887.266909963463;4893.266010098514;4905.877353066202;4916.115099282163;4928.7289199093675;5183.222516622537;5187.340051183736;5217.895642087213;5219.5041471060185;5246.737663116753;4849.878753031202;5065.848024559269;5151.793928242828;5153.665011774223;5162.122439185272;4905.730184839868;4911.4106307243355;4916.606671466346;4927.433345165208;4929.704217746947;3769.5476542815036;3782.470454136368;3787.3372159872465;3800.0879788849925;3813.9237215256094;5062.974685126551;5184.248283998874;5187.273781670645;5196.402413722471;5219.851632640813;5032.993401319677;5038.596912246938;5043.798248070036;5046.091703493406;5048.899022019613;5005.624578156726;5010.5240002199735;5018.824341148043;5025.246213068069;5049.2173713074535;4860.854174374775;4874.171390863491;4942.751048272955;4976.825811096594;4980.551750342486;4886.65793394459;4919.434265059564;5053.614078567295;5097.592192624656;5099.311592934906;4993.375828021497;4995.850124496271;5005.224190250517;5006.899862002812;5018.749062546785 16:32:04 INFO - PROCESS | 26775 | |5;yui.html;621.16143206671;639.1946147853706;642.4346575013986;661.9536632435728;717.3973861955956;12547.498100075998;13970.720585588282;13972.720545589094;13982.510612128564;13989;13322.467101315935;13473.865261347384;13482;13493.055486115976;13504.527341543024;714.8498815248792;728.8360118973242;729.3217307903648;729.5549714674045;729.6534146280527;739.4417215002692;740.9740659076933;741.9146798118204;742.4988133010223;742.8959945607619;761.0372878308943;765.0704394161094;766.0998326965821;766.2567309709573;766.5439063757055;760.5626764610371;760.5930827007579;760.6653072648045;760.8516339313836;761.0410882288329;567.2002476508145;569.5329829539779;570.7716913234698;571.0378013048028;571.3743450921235;10967.83548246777;11100.944495277552;11139.387333696643;11185.161112916483;11265.662030139109;10727.946360268226;10933;10967.561297548089;11167.720806979809;11481.942590286995;9479.66821161263;9577.61689532418;9677.225821934227;9760.804783904283;9772.071653192936;3204.2630195055035;3217.2921957169387;3225.548423220751;3227.080282119585;3232.4828027515487;52683.47316526824;53878.191827122624;54343.45656543463;64774;65293;8367.414281000272;9254.953725231331;9263.629454821867;9296.581653825595;9759.512024398751;16984.15079246033;17640.823591764176;17661.38185163513;17950;18512.07439628013;6333.113364129025;6473.773417930444;6578.177727784027;6753.189617245962;6798.830029249309;3313.1717070732316;3506.0708912138302;3523.5771707395274;3564.554430696163;3673.779573225615;2783.7772978161697;2926.195296293536;2930.5164647833294;2931.0913616778867;2934.8114013824434;497.1796535716097;499.96250281229055;513.3070355020692;514.408430305152;521.4186182406589;474.890775121717;496.9850904472799;503.08689728143304;503.3355970119407;528.8519214619913;7783.260590243918;7847.254510821463;7912.248336408032;8128.349732021424;8275.255227029476;469.8566937084154;477.5534874891987;528.0864105098202;540.3272925208141;550.243415303957;3633.3278343506536;3696.815159242081;3778.0932576181517;3796.7342286040266;3962.6829853611644;102636;103849.48075259576;104286;107599.46200268948;111352;52898.47101528935;53659.731701342025;74786.6260668704;90756;91390;50390;74007.62996184985;76673.39959602442;76979;80946.5952670233;7202.819929501804;7301.306375894282;7328.96335518319;7648.541087534766;7717.382609391123;45437.77281113705;46359.3046104302;46517;47897.5210247907;47987.280190797865;59479.702601486715;65870.67064664645;67339.66330168514;67490.66254668696;69300.6534967322;9168.679096231599;9205.493697846683;9342.53287335617;9405.576749046173;9417.199538039344;10058.748531286776;10199.898001019896;10389.026085043817;10706.571737130427;10975.725606859893;9240.676576319796;9313.627454902;9480.051994800464;9628.422294662063;9981.051800078982;9921.801563968536;10032.849507257251;10044.397336159855;10049.296549241482;10449.268551201343;9242.491662958602;9435.481048542122;9639.325247232626;9663.178629816544;9815.165710914653;7587.582682952269;7644.617769111634;7699.961500192463;7795.883061753965;7801.258880406353;7650.8087297818;7818.179091195342;7882.408819338687;7897.644605992859;8233.259006689426 16:32:04 INFO - PROCESS | 26775 | __end_tp_report 16:32:04 INFO - PROCESS | 26775 | __start_cc_report 16:32:04 INFO - PROCESS | 26775 | _x_x_mozilla_cycle_collect,932 16:32:04 INFO - PROCESS | 26775 | __end_cc_report 16:32:04 INFO - PROCESS | 26775 | __startTimestamp1473291124781__endTimestamp 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | ------- Summary: start ------- 16:32:04 INFO - PROCESS | 26775 | Number of tests: 6 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#0] dojo.html Cycles:165 Average:4738.32 Median:4887.63 stddev:513.77 (10.5%) stddev-sans-first:487.14 16:32:04 INFO - PROCESS | 26775 | Values: 2598.2 2618.5 2630.6 2674.6 2693.1 4537.1 4613.1 4760.5 4827.9 4887.6 4659.1 4699.1 4732.9 4765.0 4842.8 4292.9 4326.2 4433.7 4460.1 4526.6 4447.4 4479.8 4493.8 4522.5 4527.8 4191.3 4229.9 4359.1 4377.7 4409.0 4278.9 4285.1 4286.4 4390.9 4473.5 4288.0 4297.6 4312.9 4313.0 4357.9 4601.3 4694.6 4764.2 4769.3 4824.9 5164.5 5175.4 5179.6 5186.8 5232.6 5187.8 5195.1 5221.3 5227.8 5282.9 4632.2 4824.3 4862.0 4875.1 4890.1 5346.2 5367.4 5371.7 5374.0 5423.1 5137.4 5151.6 5154.7 5203.6 5209.2 4911.3 4972.0 4987.8 4992.4 5211.9 4972.3 4975.4 4975.6 4976.1 5023.8 4958.8 4965.6 4966.2 4967.3 5022.4 4223.8 4348.5 4354.3 4367.5 4375.8 3808.5 3812.7 3819.1 3876.7 3880.1 4371.7 4378.0 4379.3 4417.3 4433.2 4930.4 5122.0 5129.9 5158.7 5191.9 4377.3 4417.2 4427.5 4427.6 4430.6 4654.4 4664.8 4665.5 4666.6 4726.2 4674.6 4753.8 4841.5 4858.0 4920.3 5119.0 5163.8 5178.2 5250.6 5272.3 4847.7 4854.0 4854.0 4897.0 4916.6 5109.3 5113.2 5115.3 5118.7 5185.6 5047.6 5054.2 5066.9 5188.4 5193.7 4953.3 4980.5 4986.1 5032.7 5044.5 4973.9 4975.3 4978.2 4979.3 5036.6 4863.2 4919.3 4933.7 4952.2 4973.3 5103.2 5112.3 5116.9 5154.3 5160.3 4765.3 4999.9 5000.1 5009.5 5076.8 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#1] ext.html Cycles:180 Average:44355.89 Median:35695.78 stddev:81704.60 (228.9%) stddev-sans-first:81867.56 16:32:04 INFO - PROCESS | 26775 | Values: 534.8 547.4 552.3 556.7 562.4 27464.2 36374.3 38183.8 38205.8 41869.0 38703.4 38933.6 39015.6 39075.0 39102.0 7711.2 7855.3 7859.3 7861.8 7864.5 7447.6 7524.1 7534.8 7535.3 7536.5 7529.3 7569.8 7577.5 7578.7 7579.9 7522.7 7525.4 7556.5 7559.2 7559.3 7676.7 7738.3 7738.7 7739.0 7743.6 37964.0 38331.2 38353.6 38358.8 38426.8 2858.8 2879.1 3039.5 3398.4 3440.1 27707.9 27769.3 27823.2 28323.4 28666.6 3440.5 3450.4 3515.6 3533.3 3650.2 183958.0 184513.0 185000.0 185552.0 185763.0 49904.8 49986.3 50265.0 50290.0 50300.0 32019.0 32976.2 33097.0 34523.7 34631.0 12294.1 12397.8 12424.2 12426.9 12428.9 9377.6 9449.9 9453.9 9456.5 9462.8 17527.9 17638.2 17640.2 17681.7 17736.3 6704.4 6715.7 6934.5 6974.3 7051.1 744.7 758.4 812.1 831.0 838.0 27366.0 27435.0 27447.6 27789.7 27936.6 776.0 907.6 975.9 1003.8 1012.2 14253.9 14419.8 14504.4 14816.8 14842.6 397570.0 400782.0 401395.0 401989.0 402620.0 48449.5 48839.3 48971.5 49001.3 49054.0 1052.2 1134.0 1139.2 1161.4 1188.1 26865.6 29264.1 29349.9 29663.0 29810.6 40277.2 40939.0 40989.6 41052.6 41062.6 301913.0 302309.0 302732.0 303649.5 304091.0 24439.5 24670.0 25200.0 25352.5 26158.3 40329.8 40765.0 40810.6 40818.6 41968.6 34688.1 34861.8 35135.0 35221.0 35454.5 34166.8 34363.7 34465.0 34738.0 34802.0 34659.8 35115.3 35299.1 35326.6 35937.1 30037.7 31845.5 32017.4 32314.5 32382.5 23986.0 24275.6 24373.0 25063.4 25139.6 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#2] jquery.html Cycles:180 Average:23826.84 Median:27370.58 stddev:63242.31 (231.1%) stddev-sans-first:63395.10 16:32:04 INFO - PROCESS | 26775 | Values: 326.5 355.2 363.6 365.3 380.8 12409.3 13307.9 14080.4 15117.0 17365.9 15673.9 15952.7 15973.4 16028.9 16204.6 4908.8 4920.6 5051.6 5080.7 5082.9 3628.9 3804.2 5032.9 5116.9 5123.5 3831.3 3837.6 4527.2 5940.7 5948.5 6014.6 6016.6 6027.3 6028.4 6030.0 5056.8 5217.9 5249.0 5251.3 5255.2 18143.5 18871.8 19009.2 20084.8 20196.4 2045.9 2067.4 2263.2 2269.7 2334.9 8356.0 8404.4 8680.3 8929.7 8935.2 1541.4 1575.8 1585.1 1591.1 1625.5 66869.7 67290.0 68278.0 68522.7 70057.6 9713.3 9736.4 9876.8 10020.2 10156.5 13147.1 13148.3 13148.9 13171.3 13184.8 6462.3 6581.1 6600.6 6696.6 6706.3 5996.7 6111.6 6196.7 6220.3 6275.6 4168.4 4170.2 4171.6 4196.1 4297.7 1799.3 1806.2 1807.7 1809.8 1812.3 776.5 793.0 820.5 883.7 895.1 16054.2 16235.5 16482.3 16971.4 17051.3 817.8 837.3 849.1 851.3 869.9 8987.4 9229.3 9247.7 9375.2 9449.8 346277.0 373235.0 393455.0 395758.0 396439.0 79340.6 80702.0 81805.6 84238.0 85058.0 835.3 840.7 841.3 844.1 844.9 14054.6 14406.9 15015.7 15024.1 15328.7 36766.9 37064.3 37775.0 38529.2 38977.0 49358.5 49708.3 51527.2 51574.0 51944.7 7118.6 7339.5 7387.7 7392.6 7456.9 8962.4 10719.9 11053.2 11054.0 11715.3 7310.0 7731.4 9114.5 9204.8 10148.6 7348.3 7449.9 7536.8 7537.1 7967.6 7479.0 7581.7 7857.6 7881.5 7989.4 11036.1 11417.4 11431.3 11476.1 11534.4 10912.9 10996.2 11073.2 11449.7 11508.7 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#3] mootools.html Cycles:180 Average:5173.34 Median:5793.85 stddev:2893.56 (49.9%) stddev-sans-first:2886.49 16:32:04 INFO - PROCESS | 26775 | Values: 1228.4 1358.5 1368.8 1452.7 1620.1 7317.8 7686.5 7737.7 7948.9 7952.9 7725.4 7801.8 7814.8 7837.3 7844.4 1834.5 2140.0 2161.2 2185.4 2197.6 2219.1 2242.6 2245.5 2252.0 2262.9 2187.8 2206.4 2212.3 2225.9 2269.5 2019.2 2096.3 2152.2 2160.1 2161.8 2174.7 2212.2 2212.8 2213.1 2215.6 8069.4 8122.5 8126.1 8126.2 8127.8 4431.9 4582.8 4585.7 4587.0 4588.0 2819.2 2890.1 3010.9 3021.6 3024.3 917.0 928.8 940.4 954.3 957.7 10797.5 10915.8 10936.9 10937.2 10937.3 3803.8 3963.8 4097.8 4110.3 4131.2 8540.0 8543.6 8553.4 8555.8 8563.1 4459.7 4479.1 4482.4 4482.8 4488.6 3600.9 3976.9 4068.0 4144.3 4147.1 3369.4 3387.9 3511.5 3514.9 3522.8 1026.6 1032.7 1036.4 1036.8 1052.2 863.8 863.9 864.6 875.0 875.2 5383.8 5415.0 5544.9 5561.9 5759.4 851.7 889.6 889.8 894.9 895.7 3735.7 3815.1 3902.7 3913.6 3917.1 9298.2 9699.6 9711.0 9755.8 9788.8 9633.7 9646.1 9661.3 9693.9 9696.5 6938.9 7281.6 7282.1 7301.7 7446.9 5789.5 5798.2 5804.7 5838.2 5868.9 8242.5 8732.3 8970.0 8999.2 9010.6 8597.7 8881.8 8896.8 8899.3 9209.9 6019.0 6521.7 6750.5 6754.0 6927.0 7584.0 7737.9 7743.7 7747.8 7748.9 6797.9 6872.4 6906.5 6983.4 7049.0 6787.7 6861.6 6870.5 6884.0 6890.4 6525.6 6538.9 6542.3 6592.2 6604.1 4660.7 4708.9 4780.6 4798.2 4818.6 4680.9 4746.0 4776.4 4806.9 4916.4 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#4] prototype.html Cycles:180 Average:4179.85 Median:4843.26 stddev:1218.58 (25.2%) stddev-sans-first:1186.54 16:32:04 INFO - PROCESS | 26775 | Values: 291.8 294.1 297.5 303.9 304.3 4828.5 4880.0 4885.8 4889.6 4908.5 4789.6 4796.7 4833.2 4840.5 4846.0 3569.9 3647.7 3735.5 3755.6 3769.1 3748.5 3755.7 3756.4 3773.8 3781.3 3719.4 3725.8 3727.2 3777.5 3782.2 3584.4 3644.5 3702.7 3704.5 3746.3 3309.3 3318.6 3318.7 3320.0 3320.3 4655.3 4657.6 4661.2 4668.1 4688.0 4158.6 4263.0 4264.1 4289.4 4293.9 4978.4 4981.5 4996.2 5006.0 5012.2 3638.5 3639.8 3639.9 3640.1 3644.7 4921.6 5165.8 5207.4 5211.4 5230.2 3577.9 3633.7 3640.5 3647.3 3655.6 3661.5 3688.8 3691.0 3706.9 3708.6 4175.6 4183.6 4187.9 4220.7 4226.4 4754.2 4992.9 5021.9 5031.7 5053.5 1271.6 1279.7 1279.9 1283.8 1283.9 797.2 808.8 811.7 826.4 827.9 4278.6 4416.7 4441.9 4442.8 4443.4 5179.1 5188.4 5198.9 5218.5 5223.6 4367.9 4368.6 4371.6 4396.9 4408.4 2603.7 2740.1 2749.4 2754.7 2755.8 4913.9 4917.7 4917.9 4918.9 4928.1 5070.0 5310.8 5321.2 5347.3 5354.1 4887.3 4893.3 4905.9 4916.1 4928.7 5183.2 5187.3 5217.9 5219.5 5246.7 4849.9 5065.8 5151.8 5153.7 5162.1 4905.7 4911.4 4916.6 4927.4 4929.7 3769.5 3782.5 3787.3 3800.1 3813.9 5063.0 5184.2 5187.3 5196.4 5219.9 5033.0 5038.6 5043.8 5046.1 5048.9 5005.6 5010.5 5018.8 5025.2 5049.2 4860.9 4874.2 4942.8 4976.8 4980.6 4886.7 4919.4 5053.6 5097.6 5099.3 4993.4 4995.9 5005.2 5006.9 5018.7 16:32:04 INFO - PROCESS | 26775 | 16:32:04 INFO - PROCESS | 26775 | [#5] yui.html Cycles:180 Average:17028.77 Median:30050.43 stddev:25637.59 (85.3%) stddev-sans-first:25679.91 16:32:04 INFO - PROCESS | 26775 | Values: 621.2 639.2 642.4 662.0 717.4 12547.5 13970.7 13972.7 13982.5 13989.0 13322.5 13473.9 13482.0 13493.1 13504.5 714.8 728.8 729.3 729.6 729.7 739.4 741.0 741.9 742.5 742.9 761.0 765.1 766.1 766.3 766.5 760.6 760.6 760.7 760.9 761.0 567.2 569.5 570.8 571.0 571.4 10967.8 11100.9 11139.4 11185.2 11265.7 10727.9 10933.0 10967.6 11167.7 11481.9 9479.7 9577.6 9677.2 9760.8 9772.1 3204.3 3217.3 3225.5 3227.1 3232.5 52683.5 53878.2 54343.5 64774.0 65293.0 8367.4 9255.0 9263.6 9296.6 9759.5 16984.2 17640.8 17661.4 17950.0 18512.1 6333.1 6473.8 6578.2 6753.2 6798.8 3313.2 3506.1 3523.6 3564.6 3673.8 2783.8 2926.2 2930.5 2931.1 2934.8 497.2 500.0 513.3 514.4 521.4 474.9 497.0 503.1 503.3 528.9 7783.3 7847.3 7912.2 8128.3 8275.3 469.9 477.6 528.1 540.3 550.2 3633.3 3696.8 3778.1 3796.7 3962.7 102636.0 103849.5 104286.0 107599.5 111352.0 52898.5 53659.7 74786.6 90756.0 91390.0 50390.0 74007.6 76673.4 76979.0 80946.6 7202.8 7301.3 7329.0 7648.5 7717.4 45437.8 46359.3 46517.0 47897.5 47987.3 59479.7 65870.7 67339.7 67490.7 69300.7 9168.7 9205.5 9342.5 9405.6 9417.2 10058.7 10199.9 10389.0 10706.6 10975.7 9240.7 9313.6 9480.1 9628.4 9981.1 9921.8 10032.8 10044.4 10049.3 10449.3 9242.5 9435.5 9639.3 9663.2 9815.2 7587.6 7644.6 7700.0 7795.9 7801.3 7650.8 7818.2 7882.4 7897.6 8233.3 16:32:04 INFO - PROCESS | 26775 | -------- Summary: end -------- 16:32:04 INFO - PROCESS | 26775 | 16:32:05 INFO - TEST-INFO | 26775: exit 0 16:32:05 INFO - TEST-OK | dromaeo_css | took 1121482ms 16:32:05 INFO - TEST-START | kraken 16:32:05 INFO - operating with platform_type : linux_ 16:32:05 INFO - Initialising browser for kraken test... 16:32:05 INFO - TEST-INFO | started process 27080 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphSmqs8/profile http://localhost:56056/getInfo.html) 16:32:10 INFO - PROCESS | 27080 | __metrics Screen width/height:1600/1200 16:32:10 INFO - PROCESS | 27080 | colorDepth:24 16:32:10 INFO - PROCESS | 27080 | Browser inner width/height: 1024/697 16:32:10 INFO - PROCESS | 27080 | __metrics 16:32:10 INFO - PROCESS | 27080 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:32:10 INFO - PROCESS | 27080 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:11 INFO - PROCESS | 27080 | console.error: PushService: 16:32:11 INFO - PROCESS | 27080 | stateChangeProcessEnqueue: Error transitioning state 16:32:11 INFO - PROCESS | 27080 | UnknownError 16:32:12 INFO - TEST-INFO | 27080: exit 0 16:32:12 INFO - Browser initialized. 16:32:12 INFO - Running cycle 1/1 for kraken test... 16:32:12 INFO - TEST-INFO | started process 27162 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphSmqs8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:24 INFO - PROCESS | 27162 | RSS: Main: 180330496 16:32:24 INFO - PROCESS | 27162 | 16:32:27 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56056/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:27 INFO - PROCESS | 27162 | RSS: Main: 238620672 16:32:27 INFO - PROCESS | 27162 | 16:32:30 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56056/tests/kraken/driver.html?testName=audio-dft) 16:32:31 INFO - PROCESS | 27162 | RSS: Main: 223920128 16:32:31 INFO - PROCESS | 27162 | 16:32:34 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56056/tests/kraken/driver.html?testName=audio-fft) 16:32:35 INFO - PROCESS | 27162 | RSS: Main: 303538176 16:32:35 INFO - PROCESS | 27162 | 16:32:37 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56056/tests/kraken/driver.html?testName=audio-oscillator) 16:32:37 INFO - PROCESS | 27162 | RSS: Main: 213454848 16:32:37 INFO - PROCESS | 27162 | 16:32:40 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56056/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:40 INFO - PROCESS | 27162 | RSS: Main: 216117248 16:32:40 INFO - PROCESS | 27162 | 16:32:43 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56056/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:44 INFO - PROCESS | 27162 | RSS: Main: 253911040 16:32:44 INFO - PROCESS | 27162 | 16:32:47 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56056/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:48 INFO - PROCESS | 27162 | RSS: Main: 247660544 16:32:48 INFO - PROCESS | 27162 | 16:32:51 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56056/tests/kraken/driver.html?testName=json-parse-financial) 16:32:52 INFO - PROCESS | 27162 | RSS: Main: 250949632 16:32:52 INFO - PROCESS | 27162 | 16:32:53 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56056/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:54 INFO - PROCESS | 27162 | RSS: Main: 214351872 16:32:54 INFO - PROCESS | 27162 | 16:32:56 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:56 INFO - PROCESS | 27162 | RSS: Main: 221917184 16:32:56 INFO - PROCESS | 27162 | 16:32:59 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:59 INFO - PROCESS | 27162 | RSS: Main: 222085120 16:32:59 INFO - PROCESS | 27162 | 16:33:02 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:33:03 INFO - PROCESS | 27162 | RSS: Main: 223911936 16:33:03 INFO - PROCESS | 27162 | 16:33:06 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:33:06 INFO - PROCESS | 27162 | RSS: Main: 221061120 16:33:06 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | Cycle 1(1): loaded http://localhost:56056/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56056/tests/kraken/driver.html?testName=ai-astar) 16:33:08 INFO - PROCESS | 27162 | RSS: Main: 272654336 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | __start_tp_report 16:33:08 INFO - PROCESS | 27162 | _x_x_mozilla_page_load 16:33:08 INFO - PROCESS | 27162 | _x_x_mozilla_page_load_details 16:33:08 INFO - PROCESS | 27162 | |i|pagename|runs| 16:33:08 INFO - PROCESS | 27162 | |0;ai-astar;116;115;121;116;114;117;117;115;116;132 16:33:08 INFO - PROCESS | 27162 | |1;audio-beat-detection;152;122;147;178;124;146;146;123;194;123 16:33:08 INFO - PROCESS | 27162 | |2;audio-dft;163;161;162;162;160;160;161;161;160;161 16:33:08 INFO - PROCESS | 27162 | |3;audio-fft;75;75;75;171;78;77;75;76;156;77 16:33:08 INFO - PROCESS | 27162 | |4;audio-oscillator;84;83;147;83;84;85;144;85;84;149 16:33:08 INFO - PROCESS | 27162 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 16:33:08 INFO - PROCESS | 27162 | |6;imaging-darkroom;148;148;147;148;147;146;146;146;145;146 16:33:08 INFO - PROCESS | 27162 | |7;imaging-desaturate;95;96;96;95;97;96;95;96;95;95 16:33:08 INFO - PROCESS | 27162 | |8;json-parse-financial;62;61;66;63;64;64;74;64;63;63 16:33:08 INFO - PROCESS | 27162 | |9;json-stringify-tinderbox;66;66;66;64;66;65;65;66;66;66 16:33:08 INFO - PROCESS | 27162 | |10;stanford-crypto-aes;86;83;82;81;81;82;81;83;81;81 16:33:08 INFO - PROCESS | 27162 | |11;stanford-crypto-ccm;140;135;132;138;141;134;140;131;169;153 16:33:08 INFO - PROCESS | 27162 | |12;stanford-crypto-pbkdf2;175;174;181;174;179;176;176;173;178;179 16:33:08 INFO - PROCESS | 27162 | |13;stanford-crypto-sha256-iterative;69;69;69;72;69;69;69;69;67;69 16:33:08 INFO - PROCESS | 27162 | __end_tp_report 16:33:08 INFO - PROCESS | 27162 | __start_cc_report 16:33:08 INFO - PROCESS | 27162 | _x_x_mozilla_cycle_collect,1967 16:33:08 INFO - PROCESS | 27162 | __end_cc_report 16:33:08 INFO - PROCESS | 27162 | __startTimestamp1473291188963__endTimestamp 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | ------- Summary: start ------- 16:33:08 INFO - PROCESS | 27162 | Number of tests: 14 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#0] ai-astar Cycles:10 Average:117.90 Median:116.50 stddev:5.30 (4.6%) stddev-sans-first:5.58 16:33:08 INFO - PROCESS | 27162 | Values: 116.0 115.0 121.0 116.0 114.0 117.0 117.0 115.0 116.0 132.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:146.50 stddev:24.68 (16.8%) stddev-sans-first:26.06 16:33:08 INFO - PROCESS | 27162 | Values: 152.0 122.0 147.0 178.0 124.0 146.0 146.0 123.0 194.0 123.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.78 16:33:08 INFO - PROCESS | 27162 | Values: 163.0 161.0 162.0 162.0 160.0 160.0 161.0 161.0 160.0 161.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#3] audio-fft Cycles:10 Average:93.50 Median:75.50 stddev:37.08 (49.1%) stddev-sans-first:38.72 16:33:08 INFO - PROCESS | 27162 | Values: 75.0 75.0 75.0 171.0 78.0 77.0 75.0 76.0 156.0 77.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:84.00 stddev:30.30 (36.1%) stddev-sans-first:31.37 16:33:08 INFO - PROCESS | 27162 | Values: 84.0 83.0 147.0 83.0 84.0 85.0 144.0 85.0 84.0 149.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:33:08 INFO - PROCESS | 27162 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 16:33:08 INFO - PROCESS | 27162 | Values: 148.0 148.0 147.0 148.0 147.0 146.0 146.0 146.0 145.0 146.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 16:33:08 INFO - PROCESS | 27162 | Values: 95.0 96.0 96.0 95.0 97.0 96.0 95.0 96.0 95.0 95.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:3.63 (5.7%) stddev-sans-first:3.74 16:33:08 INFO - PROCESS | 27162 | Values: 62.0 61.0 66.0 63.0 64.0 64.0 74.0 64.0 63.0 63.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 16:33:08 INFO - PROCESS | 27162 | Values: 66.0 66.0 66.0 64.0 66.0 65.0 65.0 66.0 66.0 66.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:1.60 (1.9%) stddev-sans-first:0.87 16:33:08 INFO - PROCESS | 27162 | Values: 86.0 83.0 82.0 81.0 81.0 82.0 81.0 83.0 81.0 81.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:140.00 stddev:11.57 (8.3%) stddev-sans-first:12.26 16:33:08 INFO - PROCESS | 27162 | Values: 140.0 135.0 132.0 138.0 141.0 134.0 140.0 131.0 169.0 153.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:177.00 stddev:2.64 (1.5%) stddev-sans-first:2.74 16:33:08 INFO - PROCESS | 27162 | Values: 175.0 174.0 181.0 174.0 179.0 176.0 176.0 173.0 178.0 179.0 16:33:08 INFO - PROCESS | 27162 | 16:33:08 INFO - PROCESS | 27162 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.20 (1.7%) stddev-sans-first:1.27 16:33:08 INFO - PROCESS | 27162 | Values: 69.0 69.0 69.0 72.0 69.0 69.0 69.0 69.0 67.0 69.0 16:33:08 INFO - PROCESS | 27162 | -------- Summary: end -------- 16:33:08 INFO - PROCESS | 27162 | 16:33:10 INFO - TEST-INFO | 27162: exit 0 16:33:10 INFO - TEST-OK | kraken | took 64379ms 16:33:10 INFO - SUITE-END | took 1185s 16:33:10 INFO - Completed test suite (00:19:46) 16:33:10 INFO - javascript benchmark 16:33:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2598.1555994301857, 2618.5417551928413, 2630.552806022976, 2674.598810178473, 2693.1247344613007, 4537.137943790685, 4613.054323863608, 4760.452547956986, 4827.855164345071, 4887.633427492934, 4659.138059459003, 4699.107169637767, 4732.881677958044, 4765.0, 4842.8062877484945, 4292.935605965913, 4326.178026175016, 4433.667474939376, 4460.063386688799, 4526.592606665399, 4447.4218351614245, 4479.7984090716, 4493.7977790999485, 4522.525134860842, 4527.773611319437, 4191.287481128185, 4229.936550951738, 4359.149965756678, 4377.737335759864, 4409.0, 4278.914421711548, 4285.142971405731, 4286.442762440894, 4390.912181756347, 4473.4855491618555, 4288.0351920817875, 4297.634701050383, 4312.913741725148, 4312.978435107836, 4357.891052723676, 4601.286800545888, 4694.615088548852, 4764.213904705719, 4769.260764581495, 4824.903501929977, 5164.535191832753, 5175.404828444702, 5179.559737422323, 5186.818461353872, 5232.633715639907, 5187.8184263550975, 5195.062119173859, 5221.321228240305, 5227.790888364499, 5282.867928301785, 4632.2356811126465, 4824.276358546246, 4861.951380486221, 4875.07373599015, 4890.095332363524, 5346.1713434417725, 5367.4364191759305, 5371.677699337974, 5374.0, 5423.132298832168, 5137.383513978347, 5151.5878729701535, 5154.664946778447, 5203.609729270244, 5209.244659524347, 4911.263310503382, 4972.0, 4987.775550100254, 4992.350994370709, 5211.921821172609, 4972.328735620645, 4975.402951645826, 4975.601951843843, 4976.104301225741, 5023.773930173151, 4958.826441074545, 4965.627577931669, 4966.180580204298, 4967.254911763264, 5022.42242142151, 4223.831046758157, 4348.499922508889, 4354.259775838115, 4367.45406824147, 4375.803088861009, 3808.5239345081864, 3812.694984401241, 3819.102510909934, 3876.6898648108086, 3880.0881792778673, 4371.672124590668, 4377.988281444948, 4379.321205213197, 4417.271150260171, 4433.202023635712, 4930.359053323117, 5122.026814905156, 5129.897402051938, 5158.716270605153, 5191.870203244874, 4377.277749171415, 4417.2269852775635, 4427.468703755506, 4427.5572442755465, 4430.579094986036, 4654.4414670239785, 4664.813407463731, 4665.510121437268, 4666.556677115668, 4726.220173671306, 4674.602658773906, 4753.786079626356, 4841.467438581753, 4858.0, 4920.311156438173, 5119.0273847968765, 5163.767630456714, 5178.223266510054, 5250.553702935294, 5272.2882410874045, 4847.684900481458, 4853.95146048535, 4853.975730121327, 4896.951030489792, 4916.631252655993, 5109.310243117132, 5113.207452844815, 5115.25828754821, 5118.744062796771, 5185.637005409585, 5047.570956468741, 5054.16606259963, 5066.898662026665, 5188.429272779916, 5193.7403129844115, 4953.2570114483115, 4980.5019498049905, 4986.077575648516, 5032.748362581783, 5044.470330615232, 4973.900521989613, 4975.278584605139, 4978.2034874419915, 4979.278004689228, 5036.571891389126, 4863.173260545645, 4919.335889654851, 4933.654644175017, 4952.232403977389, 4973.254011898244, 5103.18349064148, 5112.258722485293, 5116.9232461512365, 5154.278401023935, 5160.251763494347, 4765.309030190672, 4999.850004499871, 5000.074986127433, 5009.474005229398, 5076.822311219237], "value": 4704.693045115274, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.7941042698561, 547.3705238975181, 552.284791195402, 556.7188569772264, 562.4122791682692, 27464.176074717765, 36374.272514549724, 38183.80908095459, 38205.808970955106, 41869.0, 38703.41944870822, 38933.610663893356, 39015.609843901555, 39075.0, 39102.0, 7711.151773304932, 7855.293023627873, 7859.253370929752, 7861.842763144734, 7864.488808227476, 7447.627618619074, 7524.05949256343, 7534.773956781305, 7535.284148005931, 7536.547807131589, 7529.32236098751, 7569.848603027964, 7577.507462014952, 7578.734744283951, 7579.886301705479, 7522.699092036367, 7525.435592330569, 7556.47104702671, 7559.244075592424, 7559.3196612305155, 7676.654550545239, 7738.303552680286, 7738.690452381898, 7739.0, 7743.574103424309, 37964.0, 38331.23337533262, 38353.616463835286, 38358.808205959074, 38426.80786596077, 2858.7998840080995, 2879.0643041011544, 3039.452898478273, 3398.4222682144095, 3440.0539851540775, 27707.86146069277, 27769.305767355978, 27823.16530504068, 28323.433531329258, 28666.570001449996, 3440.518327434161, 3450.396180668373, 3515.6484351564895, 3533.2933413317182, 3650.1604630935035, 183958.0, 184513.0, 184999.99999999866, 185552.0, 185763.0, 49904.75047624738, 49986.250206246936, 50265.0, 50290.0, 50300.0, 32019.0, 32976.17559560982, 33097.007089787345, 34523.65476345205, 34631.0, 12294.139410241376, 12397.814032789516, 12424.192427492184, 12426.875731242571, 12428.875711242952, 9377.578008989612, 9449.90550094504, 9453.858192127123, 9456.47989360578, 9462.810743785085, 17527.91236043837, 17638.206280717142, 17640.20619072145, 17681.7347739784, 17736.29054837792, 6704.363085506869, 6715.664216789141, 6934.47991400647, 6974.337437943387, 7051.083359163378, 744.7132853851198, 758.4235980654743, 812.1472453923475, 830.9916900831035, 837.9790505237418, 27366.0, 27435.0, 27447.588286175724, 27789.722102779117, 27936.58095128575, 775.9573223472651, 907.609727817045, 975.8679931279823, 1003.8042581696499, 1012.2104758288547, 14253.928730356281, 14419.78370324446, 14504.419823206954, 14816.777748333783, 14842.628934276729, 397570.0, 400782.0, 401395.0, 401989.0, 402620.0, 48449.51550484521, 48839.26741098886, 48971.510284897406, 49001.26498102531, 49054.0, 1052.1845569683403, 1133.982990255147, 1139.157023802397, 1161.3902701081831, 1188.1089183112665, 26865.597016044383, 29264.122076337742, 29349.85325073361, 29663.0, 29810.552841706958, 40277.1944561113, 40939.0, 40989.59010409858, 41052.58947410607, 41062.58937410707, 301913.0, 302309.0, 302732.0, 303649.48907152575, 304091.0, 24439.51120977606, 24670.0, 25200.0, 25352.492950140524, 26158.34604134912, 40329.79835100806, 40765.0, 40810.59189408068, 40818.591814081476, 41968.58031419769, 34688.132796680286, 34861.82569087138, 35135.0, 35221.0, 35454.46818297676, 34166.82916585401, 34363.65636343705, 34465.0, 34738.0, 34802.0, 34659.82670086633, 35115.29769404649, 35299.11752206215, 35326.64673353233, 35937.101572459855, 30037.69962300349, 31845.522317165724, 32017.359652807278, 32314.515282271255, 32382.514262286557, 23986.0, 24275.635865461678, 24373.0, 25063.373415664024, 25139.622905656797], "value": 16615.611635809295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.53957919333743, 355.1742199386427, 363.58188083703743, 365.28039761669504, 380.7867594147278, 12409.255444673327, 13307.933460332697, 14080.36638351274, 15117.0, 17365.91317043413, 15673.92163039186, 15952.723782097433, 15973.361065557392, 16028.919855400707, 16204.594885127877, 4908.803647854082, 4920.557149856521, 5051.595872330205, 5080.695158290496, 5082.949170508303, 3628.87298944537, 3804.2201348723447, 5032.899342013157, 5116.948830511703, 5123.487651234884, 3831.329517334469, 3837.6354246346555, 4527.20773864574, 5940.702964851762, 5948.524118070565, 6014.609050411731, 6016.578839481238, 6027.276726792791, 6028.3971602839365, 6029.969850150765, 5056.797728090872, 5217.869553261161, 5248.9737551312, 5251.343582052244, 5255.211718242256, 18143.455696329132, 18871.81128188728, 19009.239630414766, 20084.79915200844, 20196.39410817663, 2045.9079341429672, 2067.390119914629, 2263.1852533088077, 2269.6595510673365, 2334.871582062986, 8355.998263325724, 8404.369672274543, 8680.34897382699, 8929.73210803677, 8935.151160639729, 1541.352631894607, 1575.8266590675016, 1585.1361008250417, 1591.116930103792, 1625.4879712890402, 66869.66565167192, 67290.0, 68278.0, 68522.65738671274, 70057.64971175163, 9713.320067595341, 9736.367136136128, 9876.753081173028, 10020.248481363928, 10156.542955567018, 13147.0797044206, 13148.342582870819, 13148.934255328853, 13171.27557984301, 13184.802227966587, 6462.256840463313, 6581.14445122131, 6600.636964966877, 6696.59820410777, 6706.295838936939, 5996.730147143302, 6111.602745821508, 6196.659183744938, 6220.34686357935, 6275.623462592259, 4168.374743788396, 4170.249355116048, 4171.6036976487185, 4196.118815048813, 4297.742135471882, 1799.3432397174936, 1806.1781889240365, 1807.6746185686702, 1809.837114659687, 1812.3203798575535, 776.5496012312958, 792.9643166057541, 820.4871955028108, 883.65095787165, 895.1451363947415, 16054.197290135446, 16235.512934611981, 16482.34070637185, 16971.406000789913, 17051.30060327228, 817.7751118442357, 837.3301358912845, 849.0957130655955, 851.3274513134678, 869.8825658536143, 8987.41581797181, 9229.307801914749, 9247.72256832296, 9375.156235938799, 9449.763755906157, 346277.0, 373235.0, 393455.0, 395758.0, 396439.0, 79340.6032969843, 80702.0, 81805.59097204595, 84238.0, 85058.0, 835.2816577743137, 840.6805413942662, 841.2512863551444, 844.1094645149426, 844.8648216285242, 14054.648633783829, 14406.943200542099, 15015.699686006437, 15024.09855408679, 15328.693426131193, 36766.89699309025, 37064.25871482609, 37775.0, 38529.2294154121, 38977.0, 49358.50641493539, 49708.25437618511, 51527.2270915944, 51574.0, 51944.74027629838, 7118.572885626879, 7339.522931009681, 7387.704491820267, 7392.630368481662, 7456.888146677913, 8962.417442866194, 10719.946400267949, 11053.226274160732, 11054.0, 11715.297082175097, 7309.963450182715, 7731.420143489148, 9114.498702571422, 9204.815903681754, 10148.594056237964, 7348.301911318416, 7449.925500744923, 7536.773896783106, 7537.133229678657, 7967.645500264853, 7479.0, 7581.658825352983, 7857.567833768969, 7881.487703299267, 7989.402119576224, 11036.137351927082, 11417.429128543374, 11431.314121152758, 11476.13928955315, 11534.365609891536, 10912.945435272773, 10996.23026388145, 11073.169512286771, 11449.656510304705, 11508.712282193012], "value": 7879.780594083143, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.4165021614729, 1358.4701966233167, 1368.8220531330926, 1452.7457694903396, 1620.0684606351351, 7317.780466585998, 7686.500377475467, 7737.72917947872, 7948.92051079489, 7952.92047079529, 7725.381969442446, 7801.843963120734, 7814.8046298842555, 7837.255460731221, 7844.41166912481, 1834.4771740054086, 2140.0, 2161.2111579273533, 2185.4208634711813, 2197.604431202383, 2219.1012639880873, 2242.5627002734473, 2245.494763678176, 2251.9774802252014, 2262.8529145605485, 2187.8249740020838, 2206.4483879030245, 2212.3362991102686, 2225.9332220033425, 2269.5120549081944, 2019.1923230707687, 2096.276784509357, 2152.1821707751033, 2160.0711693971584, 2161.8378621603442, 2174.695542624034, 2212.214663794346, 2212.811910987568, 2213.0705103856417, 2215.6233440315023, 8069.354451643854, 8122.5126492410645, 8126.065502467233, 8126.228008339198, 8127.75616731499, 4431.889202769924, 4582.816687332503, 4585.72485650862, 4587.0, 4588.0365123324145, 2819.210621026116, 2890.089621769136, 3010.909672709822, 3021.561873528348, 3024.3497648005564, 916.9908300916973, 928.8281667891395, 940.3652534539159, 954.3128947158035, 957.7174733453647, 10797.514111864984, 10915.836262456069, 10936.890631093667, 10937.179711521667, 10937.289076210021, 3803.847846086181, 3963.801809909493, 4097.836086556564, 4110.34234522475, 4131.153113611703, 8540.0, 8543.615537300837, 8553.401261911606, 8555.828883422297, 8563.143685631512, 4459.710118842265, 4479.104179164115, 4482.439695038121, 4482.8206871725415, 4488.551144885485, 3600.9099772505897, 3976.8608098716404, 4067.9593204067583, 4144.316187829035, 4147.149834284025, 3369.359821633882, 3387.8983630491125, 3511.455724362677, 3514.894553163409, 3522.806245656462, 1026.5585798106886, 1032.6850310655323, 1036.4403222259896, 1036.7874585709912, 1052.231870734353, 863.7581477186397, 863.9179277968583, 864.6195673903462, 875.0330884372804, 875.1991927386497, 5383.7846486140115, 5415.0, 5544.91682624761, 5561.916571251353, 5759.395263497357, 851.6806197675871, 889.6352495476823, 889.835380454618, 894.8612964990436, 895.713371721043, 3735.6824669902817, 3815.0843797488956, 3902.726809123334, 3913.5890731473355, 3917.118648304109, 9298.163165315154, 9699.563519641633, 9711.028897110375, 9755.756106097262, 9788.755281118029, 9633.710988670351, 9646.131848133702, 9661.32370734056, 9693.9030609693, 9696.466694331739, 6938.930610693929, 7281.5995120267835, 7282.12614486265, 7301.707931682673, 7446.925530744732, 5789.507891829073, 5798.188253644577, 5804.709764511842, 5838.182654428469, 5868.882622347614, 8242.546659933521, 8732.34507411934, 8969.955150224207, 8999.235065019546, 9010.594523246338, 8597.742067737978, 8881.822363552563, 8896.822063558564, 8899.288056955298, 9209.907900921173, 6018.969905150446, 6521.69566086775, 6750.493712971645, 6753.966230169014, 6926.965365173141, 7584.0, 7737.883931740916, 7743.728969486041, 7747.845043099219, 7748.883766743616, 6797.864042719217, 6872.41584465306, 6906.516543841883, 6983.441324694114, 7049.048378468841, 6787.728490860311, 6861.588304701734, 6870.45036397097, 6884.0, 6890.3798658119, 6525.608463492205, 6538.934610653832, 6542.313057129123, 6592.208934927839, 6604.14146160996, 4660.720356778605, 4708.905821883612, 4780.569748722632, 4798.23228283473, 4818.566329030404, 4680.882977925579, 4746.0, 4776.426828780569, 4806.903861922812, 4916.410030796327], "value": 4155.708873207813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.76804440469823, 294.13377602959287, 297.51802080629386, 303.8708548866731, 304.3092180749698, 4828.541288577584, 4880.0, 4885.828995985141, 4889.633277504184, 4908.50914908509, 4789.616830653548, 4796.6882152660055, 4833.226683730604, 4840.515948405157, 4846.0, 3569.9464508032333, 3647.74465787395, 3735.5143831301893, 3755.6432138946757, 3769.1142581493327, 3748.475213470116, 3755.7370984031127, 3756.380197267448, 3773.792441415721, 3781.2815565042556, 3719.42348935914, 3725.7764534127905, 3727.2359166370966, 3777.471154038437, 3782.243551289739, 3584.4264917613186, 3644.453332000208, 3702.6667599916136, 3704.518412606344, 3746.3256613809776, 3309.321589074234, 3318.6183588887347, 3318.668133186662, 3320.0, 3320.286138480238, 4655.2784318430695, 4657.6273898088075, 4661.160991021615, 4668.11305851887, 4687.9765601171775, 4158.627652874544, 4262.9786851065855, 4264.104538047014, 4289.378040184156, 4293.8711838644895, 4978.352814134176, 4981.5055483354845, 4996.150654388763, 5006.0, 5012.248162775576, 3638.5088013118157, 3639.763415377992, 3639.890803275906, 3640.1263696712863, 3644.726645501598, 4921.581665558432, 5165.819196328111, 5207.427183009904, 5211.374635043781, 5230.21546767984, 3577.874774382884, 3633.691136253448, 3640.508531348287, 3647.252313275772, 3655.634436556323, 3661.487391765155, 3688.8340024698955, 3690.969966518458, 3706.9258614827554, 3708.610595887447, 4175.603317684815, 4183.581641835853, 4187.895302617459, 4220.746755194698, 4226.408302837605, 4754.168020596382, 4992.925106123411, 5021.899562008739, 5031.723255220924, 5053.46938571452, 1271.580378475101, 1279.7248591552861, 1279.9488020479077, 1283.7932343597176, 1283.9486420543074, 797.2027972027972, 808.7937575918103, 811.7499051460684, 826.446280991737, 827.9254867061993, 4278.550752170977, 4416.681242347793, 4441.933370999438, 4442.777861106932, 4443.355713421536, 5179.119549676564, 5188.429272779991, 5198.92201616976, 5218.478152184827, 5223.5559977402345, 4367.912641747211, 4368.628666563314, 4371.5628437156665, 4396.868093957187, 4408.4269045024585, 2603.6615240018677, 2740.054681135005, 2749.3951330707214, 2754.669439667253, 2755.779537636984, 4913.877153071202, 4917.729524876095, 4917.852464426073, 4918.852434426973, 4928.063667903087, 5070.008694869971, 5310.787568497294, 5321.201819727072, 5347.304850369427, 5354.11657076578, 4887.266909963463, 4893.266010098514, 4905.877353066202, 4916.115099282163, 4928.7289199093675, 5183.222516622537, 5187.340051183736, 5217.895642087213, 5219.5041471060185, 5246.737663116753, 4849.878753031202, 5065.848024559269, 5151.793928242828, 5153.665011774223, 5162.122439185272, 4905.730184839868, 4911.4106307243355, 4916.606671466346, 4927.433345165208, 4929.704217746947, 3769.5476542815036, 3782.470454136368, 3787.3372159872465, 3800.0879788849925, 3813.9237215256094, 5062.974685126551, 5184.248283998874, 5187.273781670645, 5196.402413722471, 5219.851632640813, 5032.993401319677, 5038.596912246938, 5043.798248070036, 5046.091703493406, 5048.899022019613, 5005.624578156726, 5010.5240002199735, 5018.824341148043, 5025.246213068069, 5049.2173713074535, 4860.854174374775, 4874.171390863491, 4942.751048272955, 4976.825811096594, 4980.551750342486, 4886.65793394459, 4919.434265059564, 5053.614078567295, 5097.592192624656, 5099.311592934906, 4993.375828021497, 4995.850124496271, 5005.224190250517, 5006.899862002812, 5018.749062546785], "value": 3789.298567796718, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.16143206671, 639.1946147853706, 642.4346575013986, 661.9536632435728, 717.3973861955956, 12547.498100075998, 13970.720585588282, 13972.720545589094, 13982.510612128564, 13989.0, 13322.467101315935, 13473.865261347384, 13482.0, 13493.055486115976, 13504.527341543024, 714.8498815248792, 728.8360118973242, 729.3217307903648, 729.5549714674045, 729.6534146280527, 739.4417215002692, 740.9740659076933, 741.9146798118204, 742.4988133010223, 742.8959945607619, 761.0372878308943, 765.0704394161094, 766.0998326965821, 766.2567309709573, 766.5439063757055, 760.5626764610371, 760.5930827007579, 760.6653072648045, 760.8516339313836, 761.0410882288329, 567.2002476508145, 569.5329829539779, 570.7716913234698, 571.0378013048028, 571.3743450921235, 10967.83548246777, 11100.944495277552, 11139.387333696643, 11185.161112916483, 11265.662030139109, 10727.946360268226, 10933.0, 10967.561297548089, 11167.720806979809, 11481.942590286995, 9479.66821161263, 9577.61689532418, 9677.225821934227, 9760.804783904283, 9772.071653192936, 3204.2630195055035, 3217.2921957169387, 3225.548423220751, 3227.080282119585, 3232.4828027515487, 52683.47316526824, 53878.191827122624, 54343.45656543463, 64774.0, 65293.0, 8367.414281000272, 9254.953725231331, 9263.629454821867, 9296.581653825595, 9759.512024398751, 16984.15079246033, 17640.823591764176, 17661.38185163513, 17950.0, 18512.07439628013, 6333.113364129025, 6473.773417930444, 6578.177727784027, 6753.189617245962, 6798.830029249309, 3313.1717070732316, 3506.0708912138302, 3523.5771707395274, 3564.554430696163, 3673.779573225615, 2783.7772978161697, 2926.195296293536, 2930.5164647833294, 2931.0913616778867, 2934.8114013824434, 497.1796535716097, 499.96250281229055, 513.3070355020692, 514.408430305152, 521.4186182406589, 474.890775121717, 496.9850904472799, 503.08689728143304, 503.3355970119407, 528.8519214619913, 7783.260590243918, 7847.254510821463, 7912.248336408032, 8128.349732021424, 8275.255227029476, 469.8566937084154, 477.5534874891987, 528.0864105098202, 540.3272925208141, 550.243415303957, 3633.3278343506536, 3696.815159242081, 3778.0932576181517, 3796.7342286040266, 3962.6829853611644, 102636.0, 103849.48075259576, 104286.0, 107599.46200268948, 111352.0, 52898.47101528935, 53659.731701342025, 74786.6260668704, 90756.0, 91390.0, 50390.0, 74007.62996184985, 76673.39959602442, 76979.0, 80946.5952670233, 7202.819929501804, 7301.306375894282, 7328.96335518319, 7648.541087534766, 7717.382609391123, 45437.77281113705, 46359.3046104302, 46517.0, 47897.5210247907, 47987.280190797865, 59479.702601486715, 65870.67064664645, 67339.66330168514, 67490.66254668696, 69300.6534967322, 9168.679096231599, 9205.493697846683, 9342.53287335617, 9405.576749046173, 9417.199538039344, 10058.748531286776, 10199.898001019896, 10389.026085043817, 10706.571737130427, 10975.725606859893, 9240.676576319796, 9313.627454902, 9480.051994800464, 9628.422294662063, 9981.051800078982, 9921.801563968536, 10032.849507257251, 10044.397336159855, 10049.296549241482, 10449.268551201343, 9242.491662958602, 9435.481048542122, 9639.325247232626, 9663.178629816544, 9815.165710914653, 7587.582682952269, 7644.617769111634, 7699.961500192463, 7795.883061753965, 7801.258880406353, 7650.8087297818, 7818.179091195342, 7882.408819338687, 7897.644605992859, 8233.259006689426], "value": 6058.205089006887, "unit": "score"}], "name": "dromaeo_css", "value": 6235.328694220599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 121.0, 116.0, 114.0, 117.0, 117.0, 115.0, 116.0, 132.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 147.0, 178.0, 124.0, 146.0, 146.0, 123.0, 194.0, 123.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 160.0, 160.0, 161.0, 161.0, 160.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 171.0, 78.0, 77.0, 75.0, 76.0, 156.0, 77.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 147.0, 83.0, 84.0, 85.0, 144.0, 85.0, 84.0, 149.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 97.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 66.0, 63.0, 64.0, 64.0, 74.0, 64.0, 63.0, 63.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 64.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 82.0, 81.0, 81.0, 82.0, 81.0, 83.0, 81.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 132.0, 138.0, 141.0, 134.0, 140.0, 131.0, 169.0, 153.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 181.0, 174.0, 179.0, 176.0, 176.0, 173.0, 178.0, 179.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 72.0, 69.0, 69.0, 69.0, 69.0, 67.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1579.0999999999997, "alertThreshold": 2.0}]} 16:33:10 INFO - Return code: 0 16:33:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:33:10 INFO - # TBPL SUCCESS # 16:33:10 INFO - Running post-action listener: _resource_record_post_action 16:33:10 INFO - [mozharness: 2016-09-07 23:33:10.745596Z] Finished run-tests step (success) 16:33:10 INFO - Running post-run listener: _resource_record_post_run 16:33:10 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 8192; Write bytes: 403582976; Read time: 24; Write time: 296340 16:33:10 INFO - TinderboxPrint: CPU usage
13.0% 16:33:10 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 16:33:10 INFO - TinderboxPrint: I/O write bytes / time
403,582,976 / 296,340 16:33:10 INFO - TinderboxPrint: CPU idle
8,341.9 (87.0%) 16:33:10 INFO - TinderboxPrint: CPU user
1,200.9 (12.5%) 16:33:10 INFO - TinderboxPrint: Swap in / out
0 / 0 16:33:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 111161344; Read time: 0; Write time: 107112 16:33:10 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292421632; Read time: 12; Write time: 189228 16:33:11 INFO - Running post-run listener: _upload_blobber_files 16:33:11 INFO - Blob upload gear active. 16:33:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:33:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:33:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:33:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:33:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:33:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:18 INFO - (blobuploader) - INFO - Done attempting. 16:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:19 INFO - (blobuploader) - INFO - Done attempting. 16:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:20 INFO - (blobuploader) - INFO - Done attempting. 16:33:20 INFO - (blobuploader) - INFO - Iteration through files over. 16:33:20 INFO - Return code: 0 16:33:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:33:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:33:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aac8905b971af93f6e049746ecd1f593ee2d3c56de89a7935ba1b7902a5ae463ea7c0e44bd10a920965087bf21cd7f3df540e48aa2e236f0a9594b81947d794c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4ce3dd714e34e550f3fd583fde1ec192c9239061c0dad12b671462074f92c0a548026ad7085d90cfa4d11438587cd1d9952d9ed5dafa6a16aca7ae898d5d3ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b51196ac1d9c96ae7610324a06add9f20566a6f155c179f3789f4712b0aaaacbd21b719e6fc1e41e29bbbdb86ff1e63a47b0947f6503853fd1400832202cfee"} 16:33:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:33:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:33:20 INFO - Contents: 16:33:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aac8905b971af93f6e049746ecd1f593ee2d3c56de89a7935ba1b7902a5ae463ea7c0e44bd10a920965087bf21cd7f3df540e48aa2e236f0a9594b81947d794c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4ce3dd714e34e550f3fd583fde1ec192c9239061c0dad12b671462074f92c0a548026ad7085d90cfa4d11438587cd1d9952d9ed5dafa6a16aca7ae898d5d3ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b51196ac1d9c96ae7610324a06add9f20566a6f155c179f3789f4712b0aaaacbd21b719e6fc1e41e29bbbdb86ff1e63a47b0947f6503853fd1400832202cfee"} 16:33:20 INFO - Running post-run listener: copy_logs_to_upload_dir 16:33:20 INFO - Copying logs to upload dir... 16:33:20 INFO - mkdir: /builds/slave/test/build/upload/logs 16:33:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.791204 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-07 16:33:20.531607) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 16:33:20.535203) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aac8905b971af93f6e049746ecd1f593ee2d3c56de89a7935ba1b7902a5ae463ea7c0e44bd10a920965087bf21cd7f3df540e48aa2e236f0a9594b81947d794c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4ce3dd714e34e550f3fd583fde1ec192c9239061c0dad12b671462074f92c0a548026ad7085d90cfa4d11438587cd1d9952d9ed5dafa6a16aca7ae898d5d3ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b51196ac1d9c96ae7610324a06add9f20566a6f155c179f3789f4712b0aaaacbd21b719e6fc1e41e29bbbdb86ff1e63a47b0947f6503853fd1400832202cfee"} build_url:https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/RAHSLxeJSIy5X9Vj9P1_Aw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aac8905b971af93f6e049746ecd1f593ee2d3c56de89a7935ba1b7902a5ae463ea7c0e44bd10a920965087bf21cd7f3df540e48aa2e236f0a9594b81947d794c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4ce3dd714e34e550f3fd583fde1ec192c9239061c0dad12b671462074f92c0a548026ad7085d90cfa4d11438587cd1d9952d9ed5dafa6a16aca7ae898d5d3ec4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b51196ac1d9c96ae7610324a06add9f20566a6f155c179f3789f4712b0aaaacbd21b719e6fc1e41e29bbbdb86ff1e63a47b0947f6503853fd1400832202cfee"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 16:33:20.561007) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:33:20.561356) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1473288964.53127-1219261912 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:33:20.646584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 16:33:20.646917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 16:33:20.647235) ========= ========= Total master_lag: 0.15 =========