builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1471048618.63 results: success (0) buildid: 20160812152716 builduid: b7a4ce95e34e40a2bd78b40604882bb1 revision: 50153501e96e8cb48d63f09c83ace1a129f3fa73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.635223) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.635691) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.635971) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.651116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.651475) ========= 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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-12 17:36:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-08-12 17:36:59 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128094 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.793650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.793971) ========= 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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020304 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 17:36:58.843311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 17:36:58.843667) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 50153501e96e8cb48d63f09c83ace1a129f3fa73 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 50153501e96e8cb48d63f09c83ace1a129f3fa73 --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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-12 17:36:59,732 truncating revision to first 12 chars 2016-08-12 17:36:59,732 Setting DEBUG logging. 2016-08-12 17:36:59,732 attempt 1/10 2016-08-12 17:36:59,732 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/50153501e96e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 17:37:00,735 unpacking tar archive at: mozilla-aurora-50153501e96e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.350989 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 17:37:00.208492) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 17:37:00.208842) ========= script_repo_revision: 50153501e96e8cb48d63f09c83ace1a129f3fa73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 17:37:00.209265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 17:37:00.209541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 17:37:00.220794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-12 17:37:00.221192) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:37:01 INFO - MultiFileLogger online at 20160812 17:37:01 in /builds/slave/test 17:37:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:37:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:01 INFO - {'append_to_log': False, 17:37:01 INFO - 'base_work_dir': '/builds/slave/test', 17:37:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:37:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:01 INFO - 'branch': 'Mozilla-Aurora', 17:37:01 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:01 INFO - 'config_files': ('talos/linux_config.py',), 17:37:01 INFO - 'default_actions': ('clobber', 17:37:01 INFO - 'read-buildbot-config', 17:37:01 INFO - 'download-and-extract', 17:37:01 INFO - 'populate-webroot', 17:37:01 INFO - 'create-virtualenv', 17:37:01 INFO - 'install', 17:37:01 INFO - 'run-tests'), 17:37:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:01 INFO - 'download_minidump_stackwalk': True, 17:37:01 INFO - 'download_symbols': 'ondemand', 17:37:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:01 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:01 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:01 INFO - 'installer_path': 'installer.exe', 17:37:01 INFO - 'log_level': 'info', 17:37:01 INFO - 'log_name': 'talos', 17:37:01 INFO - 'log_to_console': True, 17:37:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:37:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:37:01 INFO - 'opt_config_files': (), 17:37:01 INFO - 'pip_index': False, 17:37:01 INFO - 'sps_profile': False, 17:37:01 INFO - 'sps_profile_interval': 0, 17:37:01 INFO - 'suite': 'dromaeojs', 17:37:01 INFO - 'system_bits': '32', 17:37:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:01 INFO - 'title': 'talos-linux64-ix-025', 17:37:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:01 INFO - 'use_talos_json': True, 17:37:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:01 INFO - 'work_dir': 'build'} 17:37:01 INFO - [mozharness: 2016-08-13 00:37:01.190895Z] Running clobber step. 17:37:01 INFO - Running pre-action listener: _resource_record_pre_action 17:37:01 INFO - Running main action method: clobber 17:37:01 INFO - rmtree: /builds/slave/test/build 17:37:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:02 INFO - Running post-action listener: _resource_record_post_action 17:37:02 INFO - [mozharness: 2016-08-13 00:37:02.164819Z] Finished clobber step (success) 17:37:02 INFO - [mozharness: 2016-08-13 00:37:02.164918Z] Running read-buildbot-config step. 17:37:02 INFO - Running pre-action listener: _resource_record_pre_action 17:37:02 INFO - Running main action method: read_buildbot_config 17:37:02 INFO - Using buildbot properties: 17:37:02 INFO - { 17:37:02 INFO - "project": "", 17:37:02 INFO - "product": "firefox", 17:37:02 INFO - "script_repo_revision": "production", 17:37:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:37:02 INFO - "repository": "", 17:37:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:37:02 INFO - "buildid": "20160812152716", 17:37:02 INFO - "pgo_build": "True", 17:37:02 INFO - "basedir": "/builds/slave/test", 17:37:02 INFO - "buildnumber": 464, 17:37:02 INFO - "slavename": "talos-linux64-ix-025", 17:37:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:37:02 INFO - "platform": "ubuntu64_hw", 17:37:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:37:02 INFO - "branch": "mozilla-aurora", 17:37:02 INFO - "repo_path": "releases/mozilla-aurora", 17:37:02 INFO - "revision": "50153501e96e8cb48d63f09c83ace1a129f3fa73", 17:37:02 INFO - "stage_platform": "linux64-pgo", 17:37:02 INFO - "builduid": "b7a4ce95e34e40a2bd78b40604882bb1", 17:37:02 INFO - "slavebuilddir": "test" 17:37:02 INFO - } 17:37:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 17:37:02 INFO - Running post-action listener: _resource_record_post_action 17:37:02 INFO - [mozharness: 2016-08-13 00:37:02.167345Z] Finished read-buildbot-config step (success) 17:37:02 INFO - [mozharness: 2016-08-13 00:37:02.167432Z] Running download-and-extract step. 17:37:02 INFO - Running pre-action listener: _resource_record_pre_action 17:37:02 INFO - Running main action method: download_and_extract 17:37:02 INFO - mkdir: /builds/slave/test/build/tests 17:37:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:02 INFO - https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:37:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:37:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:37:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:37:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:37:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:37:02 INFO - retry: Failed, sleeping 30 seconds before retrying 17:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:37:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:37:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:37:32 INFO - retry: Failed, sleeping 60 seconds before retrying 17:38:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:38:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:38:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:38:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 17:38:32 INFO - Caught exception: HTTP Error 404: Not Found 17:38:32 INFO - Caught exception: HTTP Error 404: Not Found 17:38:32 INFO - Caught exception: HTTP Error 404: Not Found 17:38:32 INFO - trying https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:38:32 INFO - Downloading https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:38:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:38:32 INFO - Downloaded 1361 bytes. 17:38:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:38:32 INFO - Using the following test package requirements: 17:38:32 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:38:32 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:38:32 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:38:32 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'jsshell-linux-x86_64.zip'], 17:38:32 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:38:32 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:38:32 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:38:32 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:38:32 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:38:32 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:38:32 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:38:32 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:38: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:32 INFO - https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:38:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:38:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:38:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:38:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:38:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:38:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:38:32 INFO - retry: Failed, sleeping 30 seconds before retrying 17:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:39:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:39:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:39:02 INFO - retry: Failed, sleeping 60 seconds before retrying 17:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:40:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:40:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 17:40:02 INFO - Caught exception: HTTP Error 404: Not Found 17:40:02 INFO - Caught exception: HTTP Error 404: Not Found 17:40:02 INFO - Caught exception: HTTP Error 404: Not Found 17:40:02 INFO - trying https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:02 INFO - Downloading https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:40:03 INFO - Downloaded 21765249 bytes. 17:40:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:40:04 INFO - Return code: 0 17:40:04 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:40:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:04 INFO - https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:40:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:40:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:40:04 INFO - retry: Failed, sleeping 30 seconds before retrying 17:40:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:40:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:40:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:40:35 INFO - retry: Failed, sleeping 60 seconds before retrying 17:41:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:41:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:41:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:41:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 17:41:35 INFO - Caught exception: HTTP Error 404: Not Found 17:41:35 INFO - Caught exception: HTTP Error 404: Not Found 17:41:35 INFO - Caught exception: HTTP Error 404: Not Found 17:41:35 INFO - trying https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:41:35 INFO - Downloading https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:41:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:41:35 INFO - Downloaded 21765249 bytes. 17:41:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:41:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:41:37 INFO - Return code: 0 17:41:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:41:37 INFO - https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:41:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:41:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:41:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:41:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:41:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:41:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:41:37 INFO - retry: Failed, sleeping 30 seconds before retrying 17:42:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:42:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:42:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:42:07 INFO - retry: Failed, sleeping 60 seconds before retrying 17:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:43:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:43:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:43:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 17:43:07 INFO - Caught exception: HTTP Error 404: Not Found 17:43:07 INFO - Caught exception: HTTP Error 404: Not Found 17:43:07 INFO - Caught exception: HTTP Error 404: Not Found 17:43:07 INFO - trying https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:43:07 INFO - Downloading https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:43:08 INFO - Downloaded 14413723 bytes. 17:43:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:43:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:43:08 INFO - Return code: 0 17:43:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:43:08 INFO - https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:43:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:43:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:43:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:43:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:43:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:43:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:43:08 INFO - retry: Failed, sleeping 30 seconds before retrying 17:43:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:43:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:43:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:43:38 INFO - retry: Failed, sleeping 60 seconds before retrying 17:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:44:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:44:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:44:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 17:44:38 INFO - Caught exception: HTTP Error 404: Not Found 17:44:38 INFO - Caught exception: HTTP Error 404: Not Found 17:44:38 INFO - Caught exception: HTTP Error 404: Not Found 17:44:38 INFO - trying https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:44:38 INFO - Downloading https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:44:39 INFO - Downloaded 63756904 bytes. 17:44:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:44:39 INFO - mkdir: /builds/slave/test/properties 17:44:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:39 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:39 INFO - Contents: 17:44:39 INFO - build_url:https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:44:40 INFO - Running post-action listener: _resource_record_post_action 17:44:40 INFO - Running post-action listener: set_extra_try_arguments 17:44:40 INFO - [mozharness: 2016-08-13 00:44:40.586703Z] Finished download-and-extract step (success) 17:44:40 INFO - [mozharness: 2016-08-13 00:44:40.586937Z] Running populate-webroot step. 17:44:40 INFO - Running pre-action listener: _resource_record_pre_action 17:44:40 INFO - Running main action method: populate_webroot 17:44:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:44:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:44:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:44:40 INFO - '--tppagecycles', 17:44:40 INFO - '7'], 17:44:40 INFO - 'tests': ['tsvgm']}}, 17:44:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:44:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:44:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['damp', 'tps']}, 17:44:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['damp', 'tps']}, 17:44:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['dromaeo_dom']}, 17:44:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['basic_compositor_video']}, 17:44:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:44:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tsvgx', 17:44:40 INFO - 'tsvgr_opacity', 17:44:40 INFO - 'tart', 17:44:40 INFO - 'tscrollx', 17:44:40 INFO - 'cart']}, 17:44:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:40 INFO - 'tsvgr_opacity', 17:44:40 INFO - 'tart', 17:44:40 INFO - 'tscrollx', 17:44:40 INFO - 'cart']}, 17:44:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tp5o']}, 17:44:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['tp5o']}, 17:44:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s', 17:44:40 INFO - '--xperf_path', 17:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:40 INFO - 'tests': ['tp5n']}, 17:44:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--xperf_path', 17:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:40 INFO - 'tests': ['tp5n']}}, 17:44:40 INFO - 'talos.zip': {'path': '', 17:44:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:44:40 INFO - Running post-action listener: _resource_record_post_action 17:44:40 INFO - [mozharness: 2016-08-13 00:44:40.606764Z] Finished populate-webroot step (success) 17:44:40 INFO - [mozharness: 2016-08-13 00:44:40.606853Z] Running create-virtualenv step. 17:44:40 INFO - Running pre-action listener: _resource_record_pre_action 17:44:40 INFO - Running main action method: create_virtualenv 17:44:40 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:40 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 17:44:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:40 INFO - Using real prefix '/usr' 17:44:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:41 INFO - Installing distribute.............................................................................................................................................................................................done. 17:44:43 INFO - Installing pip.................done. 17:44:43 INFO - Return code: 0 17:44:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:44:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:43 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 17:44:43 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 17:44:43 INFO - Using env: {'DISPLAY': ':0', 17:44:43 INFO - 'HOME': '/home/cltbld', 17:44:43 INFO - 'LANG': 'en_US.UTF-8', 17:44:43 INFO - 'LANGUAGE': 'en_US:en', 17:44:43 INFO - 'LOGNAME': 'cltbld', 17:44:43 INFO - 'MAIL': '/var/mail/cltbld', 17:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:43 INFO - 'MOZ_NO_REMOTE': '1', 17:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:43 INFO - 'NO_EM_RESTART': '1', 17:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:43 INFO - 'PWD': '/builds/slave/test', 17:44:43 INFO - 'SHELL': '/bin/bash', 17:44:43 INFO - 'SHLVL': '1', 17:44:43 INFO - 'TERM': 'linux', 17:44:43 INFO - 'TMOUT': '86400', 17:44:43 INFO - 'USER': 'cltbld', 17:44:43 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:43 INFO - '_': '/tools/buildbot/bin/python'} 17:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:43 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 17:44:43 INFO - Cleaning up... 17:44:43 INFO - Return code: 0 17:44:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:44:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:43 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 17:44:43 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 17:44:43 INFO - Using env: {'DISPLAY': ':0', 17:44:43 INFO - 'HOME': '/home/cltbld', 17:44:43 INFO - 'LANG': 'en_US.UTF-8', 17:44:43 INFO - 'LANGUAGE': 'en_US:en', 17:44:43 INFO - 'LOGNAME': 'cltbld', 17:44:43 INFO - 'MAIL': '/var/mail/cltbld', 17:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:43 INFO - 'MOZ_NO_REMOTE': '1', 17:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:43 INFO - 'NO_EM_RESTART': '1', 17:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:43 INFO - 'PWD': '/builds/slave/test', 17:44:43 INFO - 'SHELL': '/bin/bash', 17:44:43 INFO - 'SHLVL': '1', 17:44:43 INFO - 'TERM': 'linux', 17:44:43 INFO - 'TMOUT': '86400', 17:44:43 INFO - 'USER': 'cltbld', 17:44:43 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:43 INFO - '_': '/tools/buildbot/bin/python'} 17:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:43 INFO - Downloading/unpacking psutil>=3.1.1 17:44:43 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 17:44:43 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 17:44:43 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 17:44:43 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 17:44:43 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 17:44:43 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 17:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:46 INFO - Installing collected packages: psutil 17:44:46 INFO - Running setup.py install for psutil 17:44:46 INFO - building 'psutil._psutil_linux' extension 17:44:46 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 17:44:46 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 17:44:46 INFO - building 'psutil._psutil_posix' extension 17:44:46 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 17:44:46 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 17:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:47 INFO - Successfully installed psutil 17:44:47 INFO - Cleaning up... 17:44:47 INFO - Return code: 0 17:44:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:44:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:47 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:47 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 17:44:47 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 17:44:47 INFO - Using env: {'DISPLAY': ':0', 17:44:47 INFO - 'HOME': '/home/cltbld', 17:44:47 INFO - 'LANG': 'en_US.UTF-8', 17:44:47 INFO - 'LANGUAGE': 'en_US:en', 17:44:47 INFO - 'LOGNAME': 'cltbld', 17:44:47 INFO - 'MAIL': '/var/mail/cltbld', 17:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:47 INFO - 'MOZ_NO_REMOTE': '1', 17:44:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:47 INFO - 'NO_EM_RESTART': '1', 17:44:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:47 INFO - 'PWD': '/builds/slave/test', 17:44:47 INFO - 'SHELL': '/bin/bash', 17:44:47 INFO - 'SHLVL': '1', 17:44:47 INFO - 'TERM': 'linux', 17:44:47 INFO - 'TMOUT': '86400', 17:44:47 INFO - 'USER': 'cltbld', 17:44:47 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:47 INFO - '_': '/tools/buildbot/bin/python'} 17:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:44:47 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 17:44:47 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 17:44:47 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 17:44:47 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 17:44:47 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 17:44:47 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 17:44:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:44:49 INFO - Installing collected packages: mozsystemmonitor 17:44:49 INFO - Running setup.py install for mozsystemmonitor 17:44:49 INFO - Successfully installed mozsystemmonitor 17:44:49 INFO - Cleaning up... 17:44:49 INFO - Return code: 0 17:44:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:44:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:49 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 17:44:49 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 17:44:49 INFO - Using env: {'DISPLAY': ':0', 17:44:49 INFO - 'HOME': '/home/cltbld', 17:44:49 INFO - 'LANG': 'en_US.UTF-8', 17:44:49 INFO - 'LANGUAGE': 'en_US:en', 17:44:49 INFO - 'LOGNAME': 'cltbld', 17:44:49 INFO - 'MAIL': '/var/mail/cltbld', 17:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:49 INFO - 'MOZ_NO_REMOTE': '1', 17:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:49 INFO - 'NO_EM_RESTART': '1', 17:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:49 INFO - 'PWD': '/builds/slave/test', 17:44:49 INFO - 'SHELL': '/bin/bash', 17:44:49 INFO - 'SHLVL': '1', 17:44:49 INFO - 'TERM': 'linux', 17:44:49 INFO - 'TMOUT': '86400', 17:44:49 INFO - 'USER': 'cltbld', 17:44:49 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:49 INFO - '_': '/tools/buildbot/bin/python'} 17:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:49 INFO - Downloading/unpacking jsonschema==2.5.1 17:44:49 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 17:44:49 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 17:44:49 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 17:44:49 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 17:44:49 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 17:44:49 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 17:44:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:44:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:44:51 INFO - warning: no files found matching '*.txt' 17:44:51 INFO - no previously-included directories found matching 'build' 17:44:51 INFO - no previously-included directories found matching 'dist' 17:44:51 INFO - no previously-included directories found matching '.git*' 17:44:51 INFO - Installing collected packages: jsonschema, functools32 17:44:51 INFO - Running setup.py install for functools32 17:44:51 INFO - warning: no files found matching '*.txt' 17:44:51 INFO - no previously-included directories found matching 'build' 17:44:51 INFO - no previously-included directories found matching 'dist' 17:44:51 INFO - no previously-included directories found matching '.git*' 17:44:51 INFO - Successfully installed jsonschema functools32 17:44:51 INFO - Cleaning up... 17:44:51 INFO - Return code: 0 17:44:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44: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', '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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:44: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 functools32==3.2.3-2 17:44:51 INFO - Using env: {'DISPLAY': ':0', 17:44:51 INFO - 'HOME': '/home/cltbld', 17:44:51 INFO - 'LANG': 'en_US.UTF-8', 17:44:51 INFO - 'LANGUAGE': 'en_US:en', 17:44:51 INFO - 'LOGNAME': 'cltbld', 17:44:51 INFO - 'MAIL': '/var/mail/cltbld', 17:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:51 INFO - 'MOZ_NO_REMOTE': '1', 17:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:51 INFO - 'NO_EM_RESTART': '1', 17:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:51 INFO - 'PWD': '/builds/slave/test', 17:44:51 INFO - 'SHELL': '/bin/bash', 17:44:51 INFO - 'SHLVL': '1', 17:44:51 INFO - 'TERM': 'linux', 17:44:51 INFO - 'TMOUT': '86400', 17:44:51 INFO - 'USER': 'cltbld', 17:44:51 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:51 INFO - '_': '/tools/buildbot/bin/python'} 17:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:44:52 INFO - Cleaning up... 17:44:52 INFO - Return code: 0 17:44:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:44:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:52 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:52 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 17:44:52 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 17:44:52 INFO - Using env: {'DISPLAY': ':0', 17:44:52 INFO - 'HOME': '/home/cltbld', 17:44:52 INFO - 'LANG': 'en_US.UTF-8', 17:44:52 INFO - 'LANGUAGE': 'en_US:en', 17:44:52 INFO - 'LOGNAME': 'cltbld', 17:44:52 INFO - 'MAIL': '/var/mail/cltbld', 17:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:52 INFO - 'MOZ_NO_REMOTE': '1', 17:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:52 INFO - 'NO_EM_RESTART': '1', 17:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:52 INFO - 'PWD': '/builds/slave/test', 17:44:52 INFO - 'SHELL': '/bin/bash', 17:44:52 INFO - 'SHLVL': '1', 17:44:52 INFO - 'TERM': 'linux', 17:44:52 INFO - 'TMOUT': '86400', 17:44:52 INFO - 'USER': 'cltbld', 17:44:52 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:52 INFO - '_': '/tools/buildbot/bin/python'} 17:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:52 INFO - Downloading/unpacking blobuploader==1.2.4 17:44:52 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 17:44:52 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 17:44:52 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 17:44:52 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 17:44:52 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 17:44:52 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 17:44:54 INFO - Downloading blobuploader-1.2.4.tar.gz 17:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:44:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44:55 INFO - Downloading docopt-0.6.1.tar.gz 17:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:44:55 INFO - Installing collected packages: blobuploader, requests, docopt 17:44:55 INFO - Running setup.py install for blobuploader 17:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:44:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:44:55 INFO - Running setup.py install for requests 17:44:55 INFO - Running setup.py install for docopt 17:44:55 INFO - Successfully installed blobuploader requests docopt 17:44:55 INFO - Cleaning up... 17:44:55 INFO - Return code: 0 17:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:55 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:55 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 17:44:55 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 17:44:55 INFO - Using env: {'DISPLAY': ':0', 17:44:55 INFO - 'HOME': '/home/cltbld', 17:44:55 INFO - 'LANG': 'en_US.UTF-8', 17:44:55 INFO - 'LANGUAGE': 'en_US:en', 17:44:55 INFO - 'LOGNAME': 'cltbld', 17:44:55 INFO - 'MAIL': '/var/mail/cltbld', 17:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:55 INFO - 'MOZ_NO_REMOTE': '1', 17:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:55 INFO - 'NO_EM_RESTART': '1', 17:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:55 INFO - 'PWD': '/builds/slave/test', 17:44:55 INFO - 'SHELL': '/bin/bash', 17:44:55 INFO - 'SHLVL': '1', 17:44:55 INFO - 'TERM': 'linux', 17:44:55 INFO - 'TMOUT': '86400', 17:44:55 INFO - 'USER': 'cltbld', 17:44:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:55 INFO - '_': '/tools/buildbot/bin/python'} 17:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:55 INFO - Running setup.py (path:/tmp/pip-H97GEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:55 INFO - Running setup.py (path:/tmp/pip-MIc0a0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:56 INFO - Running setup.py (path:/tmp/pip-ZgKcf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:56 INFO - Running setup.py (path:/tmp/pip-VCR2PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:56 INFO - Running setup.py (path:/tmp/pip-QskBvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:56 INFO - Running setup.py (path:/tmp/pip-vC6LTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:56 INFO - Running setup.py (path:/tmp/pip-Qby4zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:56 INFO - Running setup.py (path:/tmp/pip-NftLoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:56 INFO - Running setup.py (path:/tmp/pip-lwbzzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:56 INFO - Running setup.py (path:/tmp/pip-pWEFar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:56 INFO - Running setup.py (path:/tmp/pip-11TnpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:56 INFO - Running setup.py (path:/tmp/pip-SApAuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:56 INFO - Running setup.py (path:/tmp/pip-QiQyis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:56 INFO - Running setup.py (path:/tmp/pip-Yd5rvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:56 INFO - Running setup.py (path:/tmp/pip-Uy_G0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:57 INFO - Running setup.py (path:/tmp/pip-duDcda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:57 INFO - Running setup.py (path:/tmp/pip-k7ixhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:44:57 INFO - Running setup.py install for manifestparser 17:44:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozcrash 17:44:57 INFO - Running setup.py install for mozdebug 17:44:57 INFO - Running setup.py install for mozdevice 17:44:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozfile 17:44:57 INFO - Running setup.py install for mozhttpd 17:44:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozinfo 17:44:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozInstall 17:44:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozleak 17:44:58 INFO - Running setup.py install for mozlog 17:44:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for moznetwork 17:44:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozprocess 17:44:58 INFO - Running setup.py install for mozprofile 17:44:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozrunner 17:44:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozscreenshot 17:44:58 INFO - Running setup.py install for moztest 17:44:58 INFO - Running setup.py install for mozversion 17:44:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:44:58 INFO - Cleaning up... 17:44:58 INFO - Return code: 0 17:44:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:58 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:44:58 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 17:44:58 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 17:44:58 INFO - Using env: {'DISPLAY': ':0', 17:44:58 INFO - 'HOME': '/home/cltbld', 17:44:58 INFO - 'LANG': 'en_US.UTF-8', 17:44:58 INFO - 'LANGUAGE': 'en_US:en', 17:44:58 INFO - 'LOGNAME': 'cltbld', 17:44:58 INFO - 'MAIL': '/var/mail/cltbld', 17:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:58 INFO - 'MOZ_NO_REMOTE': '1', 17:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:44:58 INFO - 'NO_EM_RESTART': '1', 17:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:58 INFO - 'PWD': '/builds/slave/test', 17:44:58 INFO - 'SHELL': '/bin/bash', 17:44:58 INFO - 'SHLVL': '1', 17:44:58 INFO - 'TERM': 'linux', 17:44:58 INFO - 'TMOUT': '86400', 17:44:58 INFO - 'USER': 'cltbld', 17:44:58 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:58 INFO - '_': '/tools/buildbot/bin/python'} 17:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:59 INFO - Running setup.py (path:/tmp/pip-seXHVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:59 INFO - Running setup.py (path:/tmp/pip-4oNJ0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:59 INFO - Running setup.py (path:/tmp/pip-BGx4tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:59 INFO - Running setup.py (path:/tmp/pip-aYIhSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:59 INFO - Running setup.py (path:/tmp/pip-DQ_7yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:59 INFO - Running setup.py (path:/tmp/pip-CyyybJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:59 INFO - Running setup.py (path:/tmp/pip-accoZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:59 INFO - Running setup.py (path:/tmp/pip-tnZgAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:59 INFO - Running setup.py (path:/tmp/pip-TUtTPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:59 INFO - Running setup.py (path:/tmp/pip-6NegNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:59 INFO - Running setup.py (path:/tmp/pip-0iDUAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:59 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)) 17:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:00 INFO - Running setup.py (path:/tmp/pip-tt4JT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:00 INFO - Running setup.py (path:/tmp/pip-kiKYOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:00 INFO - Running setup.py (path:/tmp/pip-f6Eev0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:00 INFO - Running setup.py (path:/tmp/pip-J0OzHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:00 INFO - Running setup.py (path:/tmp/pip-zRDPp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:00 INFO - Running setup.py (path:/tmp/pip-sB2YUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 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)) 17:45:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:45:00 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 17:45:00 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 17:45:00 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 17:45:00 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 17:45:00 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 17:45:00 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 17:45:02 INFO - Downloading blessings-1.6.tar.gz 17:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:45:02 INFO - Installing collected packages: blessings 17:45:02 INFO - Running setup.py install for blessings 17:45:02 INFO - Successfully installed blessings 17:45:02 INFO - Cleaning up... 17:45:02 INFO - Return code: 0 17:45:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:45:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:02 INFO - Reading from file tmpfile_stdout 17:45:02 INFO - Current package versions: 17:45:02 INFO - argparse == 1.2.1 17:45:02 INFO - blessings == 1.6 17:45:02 INFO - blobuploader == 1.2.4 17:45:02 INFO - docopt == 0.6.1 17:45:02 INFO - functools32 == 3.2.3-2 17:45:02 INFO - jsonschema == 2.5.1 17:45:02 INFO - manifestparser == 1.1 17:45:02 INFO - mozInstall == 1.12 17:45:02 INFO - mozcrash == 0.17 17:45:02 INFO - mozdebug == 0.1 17:45:02 INFO - mozdevice == 0.48 17:45:02 INFO - mozfile == 1.2 17:45:02 INFO - mozhttpd == 0.7 17:45:02 INFO - mozinfo == 0.9 17:45:02 INFO - mozleak == 0.1 17:45:02 INFO - mozlog == 3.3 17:45:02 INFO - moznetwork == 0.27 17:45:02 INFO - mozprocess == 0.23 17:45:02 INFO - mozprofile == 0.28 17:45:02 INFO - mozrunner == 6.12 17:45:02 INFO - mozscreenshot == 0.1 17:45:02 INFO - mozsystemmonitor == 0.3 17:45:02 INFO - moztest == 0.7 17:45:02 INFO - mozversion == 1.4 17:45:02 INFO - psutil == 3.1.1 17:45:02 INFO - requests == 1.2.3 17:45:02 INFO - wsgiref == 0.1.2 17:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:02 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:45:02 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 17:45:02 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 17:45:02 INFO - Using env: {'DISPLAY': ':0', 17:45:02 INFO - 'HOME': '/home/cltbld', 17:45:02 INFO - 'LANG': 'en_US.UTF-8', 17:45:02 INFO - 'LANGUAGE': 'en_US:en', 17:45:02 INFO - 'LOGNAME': 'cltbld', 17:45:02 INFO - 'MAIL': '/var/mail/cltbld', 17:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:02 INFO - 'MOZ_NO_REMOTE': '1', 17:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:02 INFO - 'NO_EM_RESTART': '1', 17:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:02 INFO - 'PWD': '/builds/slave/test', 17:45:02 INFO - 'SHELL': '/bin/bash', 17:45:02 INFO - 'SHLVL': '1', 17:45:02 INFO - 'TERM': 'linux', 17:45:02 INFO - 'TMOUT': '86400', 17:45:02 INFO - 'USER': 'cltbld', 17:45:02 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:02 INFO - '_': '/tools/buildbot/bin/python'} 17:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 INFO - Cleaning up... 17:45:03 INFO - Return code: 0 17:45:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:03 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 0x2512030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, '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': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', '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 17:45:03 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 17:45:03 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 17:45:03 INFO - Using env: {'DISPLAY': ':0', 17:45:03 INFO - 'HOME': '/home/cltbld', 17:45:03 INFO - 'LANG': 'en_US.UTF-8', 17:45:03 INFO - 'LANGUAGE': 'en_US:en', 17:45:03 INFO - 'LOGNAME': 'cltbld', 17:45:03 INFO - 'MAIL': '/var/mail/cltbld', 17:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:03 INFO - 'MOZ_NO_REMOTE': '1', 17:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:03 INFO - 'NO_EM_RESTART': '1', 17:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:03 INFO - 'PWD': '/builds/slave/test', 17:45:03 INFO - 'SHELL': '/bin/bash', 17:45:03 INFO - 'SHLVL': '1', 17:45:03 INFO - 'TERM': 'linux', 17:45:03 INFO - 'TMOUT': '86400', 17:45:03 INFO - 'USER': 'cltbld', 17:45:03 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:03 INFO - '_': '/tools/buildbot/bin/python'} 17:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:45:03 INFO - Cleaning up... 17:45:03 INFO - Return code: 0 17:45:03 INFO - Running post-action listener: _resource_record_post_action 17:45:03 INFO - Running post-action listener: _start_resource_monitoring 17:45:03 INFO - Starting resource monitoring. 17:45:03 INFO - [mozharness: 2016-08-13 00:45:03.246586Z] Finished create-virtualenv step (success) 17:45:03 INFO - [mozharness: 2016-08-13 00:45:03.247056Z] Running install step. 17:45:03 INFO - Running pre-action listener: _resource_record_pre_action 17:45:03 INFO - Running main action method: install 17:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:03 INFO - Reading from file tmpfile_stdout 17:45:03 INFO - Detecting whether we're running mozinstall >=1.0... 17:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:45:03 INFO - Reading from file tmpfile_stdout 17:45:03 INFO - Output received: 17:45:03 INFO - Usage: mozinstall [options] installer 17:45:03 INFO - Options: 17:45:03 INFO - -h, --help show this help message and exit 17:45:03 INFO - -d DEST, --destination=DEST 17:45:03 INFO - Directory to install application into. [default: 17:45:03 INFO - "/builds/slave/test"] 17:45:03 INFO - --app=APP Application being installed. [default: firefox] 17:45:03 INFO - mkdir: /builds/slave/test/build/application 17:45:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:45:20 INFO - Reading from file tmpfile_stdout 17:45:20 INFO - Output received: 17:45:20 INFO - /builds/slave/test/build/application/firefox/firefox 17:45:20 INFO - Running post-action listener: _resource_record_post_action 17:45:20 INFO - [mozharness: 2016-08-13 00:45:20.123299Z] Finished install step (success) 17:45:20 INFO - [mozharness: 2016-08-13 00:45:20.123574Z] Running run-tests step. 17:45:20 INFO - Running pre-action listener: _resource_record_pre_action 17:45:20 INFO - Running main action method: run_tests 17:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:20 INFO - Python 2.7.3 17:45:20 INFO - Return code: 0 17:45:20 INFO - grabbing minidump binary from tooltool 17:45:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:20 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 0x27d13b0>, '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 0x27d22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:20 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 17:45:20 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 17:45:20 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 17:45:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:20 INFO - Return code: 0 17:45:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:45:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:45:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 17:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 17:45:20 INFO - Using env: {'DISPLAY': ':0', 17:45:20 INFO - 'HOME': '/home/cltbld', 17:45:20 INFO - 'LANG': 'en_US.UTF-8', 17:45:20 INFO - 'LANGUAGE': 'en_US:en', 17:45:20 INFO - 'LOGNAME': 'cltbld', 17:45:20 INFO - 'MAIL': '/var/mail/cltbld', 17:45:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:20 INFO - 'MOZ_NO_REMOTE': '1', 17:45:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:20 INFO - 'NO_EM_RESTART': '1', 17:45:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:20 INFO - 'PWD': '/builds/slave/test', 17:45:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:20 INFO - 'SHELL': '/bin/bash', 17:45:20 INFO - 'SHLVL': '1', 17:45:20 INFO - 'TERM': 'linux', 17:45:20 INFO - 'TMOUT': '86400', 17:45:20 INFO - 'USER': 'cltbld', 17:45:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471048618.693080-902302048', 17:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:20 INFO - '_': '/tools/buildbot/bin/python'} 17:45:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 17:45:20 INFO - mozversion application_buildid: 20160812152716 17:45:20 INFO - mozversion application_changeset: 50153501e96e8cb48d63f09c83ace1a129f3fa73 17:45:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:45:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:20 INFO - mozversion application_name: Firefox 17:45:20 INFO - mozversion application_remotingname: firefox-dev 17:45:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:45:20 INFO - mozversion application_vendor: Mozilla 17:45:20 INFO - mozversion application_version: 50.0a2 17:45:20 INFO - mozversion platform_buildid: 20160812152716 17:45:20 INFO - mozversion platform_changeset: 50153501e96e8cb48d63f09c83ace1a129f3fa73 17:45:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:45:20 INFO - mozversion platform_version: 50.0a2 17:45:20 INFO - using testdate: 1471049120 17:45:20 INFO - actual date: 1471049120 17:45:20 INFO - starting webserver on 'localhost:48407' 17:45:20 INFO - SUITE-START | Running 2 tests 17:45:20 INFO - TEST-START | dromaeo_css 17:45:20 INFO - operating with platform_type : linux_ 17:45:20 INFO - Initialising browser for dromaeo_css test... 17:45:20 INFO - TEST-INFO | started process 13971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEHvCeM/profile http://localhost:48407/getInfo.html) 17:45:24 INFO - PROCESS | 13971 | __metrics Screen width/height:1600/1200 17:45:24 INFO - PROCESS | 13971 | colorDepth:24 17:45:24 INFO - PROCESS | 13971 | Browser inner width/height: 1024/697 17:45:24 INFO - PROCESS | 13971 | __metrics 17:45:25 INFO - PROCESS | 13971 | 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" 17:45:25 INFO - PROCESS | 13971 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:45:26 INFO - TEST-INFO | 13971: exit 0 17:45:26 INFO - Browser initialized. 17:45:26 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:26 INFO - TEST-INFO | started process 14062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEHvCeM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:38 INFO - PROCESS | 14062 | RSS: Main: 145457152 17:45:38 INFO - PROCESS | 14062 | 17:46:28 INFO - PROCESS | 14062 | 1471049188896 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] 17:48:37 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48407/tests/dromaeo/cssquery-ext.html) 17:48:39 INFO - PROCESS | 14062 | RSS: Main: 223121408 17:48:39 INFO - PROCESS | 14062 | 17:51:41 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-ext.html (next: http://localhost:48407/tests/dromaeo/cssquery-jquery.html) 17:51:41 INFO - PROCESS | 14062 | RSS: Main: 234766336 17:51:41 INFO - PROCESS | 14062 | 17:54:43 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48407/tests/dromaeo/cssquery-mootools.html) 17:54:44 INFO - PROCESS | 14062 | RSS: Main: 173772800 17:54:44 INFO - PROCESS | 14062 | 17:57:46 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48407/tests/dromaeo/cssquery-prototype.html) 17:57:46 INFO - PROCESS | 14062 | RSS: Main: 176439296 17:57:46 INFO - PROCESS | 14062 | 18:00:49 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48407/tests/dromaeo/cssquery-yui.html) 18:00:50 INFO - PROCESS | 14062 | RSS: Main: 173064192 18:00:50 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | Cycle 1(1): loaded http://localhost:48407/tests/dromaeo/cssquery-yui.html (next: http://localhost:48407/tests/dromaeo/cssquery-dojo.html) 18:03:53 INFO - PROCESS | 14062 | RSS: Main: 264290304 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | __start_tp_report 18:03:53 INFO - PROCESS | 14062 | _x_x_mozilla_page_load 18:03:53 INFO - PROCESS | 14062 | _x_x_mozilla_page_load_details 18:03:53 INFO - PROCESS | 14062 | |i|pagename|runs| 18:03:53 INFO - PROCESS | 14062 | |0;dojo.html;3298.4887342461916;3302.0754188827123;3304.256542277987;3329.7336213102953;3354;5623.268975033245;5736.110902810065;5766.567507436938;5772.769089236436;5776.913346299809;5738.454846789558;5820.970895145519;5825.1844741736195;5837.211976383187;5838.299404071507;5043.24351347299;5174.767135478903;5249.370075590934;5283.207518872162;5309.893802123975;5065.746712664352;5162.225666150089;5188.766505507261;5215.713135777531;5217.686938783685;5043.798248070073;5075.111855425304;5085.160948443502;5095.898082038357;5123.513266239701;5159.432462429129;5180.974095129519;5187.144121219994;5187.273781670569;5200.219967004941;5024.145895197825;5083.567896728782;5088.363954505687;5093.2105523643895;5118.897622047575;5529.585281103893;5583.106702927512;5587.078132108198;5594.021046316879;5625;6065.150878876961;6097.237845269341;6100.252520231485;6103.542234332443;6108.7251073701345;5938.495227905633;6051.152838602599;6087.3259853540185;6101.816945491598;6144.96927515364;5740.741666625012;5752.137179423078;5761.884762304772;5762.193292938992;5765.538756899438;6234.688265586793;6284.057391391237;6298.244210694655;6302.338254483213;6304.747810087545;5897.49871260939;5941.1088336749835;5952.821415357546;5953.285605727254;6038.607490513103;6085.370593687403;6099.961038571871;6105.9694701526205;6110.114033465123;6113.29387775398;6024.367441418675;6028.156058151862;6032.457078862835;6036.0644100164545;6045.72794224261;5902.173695682608;5956.106584012433;6014.398560143951;6059;6072.423119803611;4660.04469083837;4692.812287508462;4694.108119457293;4698.2952557116705;4712.882177945578;4186.099988502487;4197.664186865043;4204.348326009474;4224.049588842548;4227.788610569459;5622.943770562324;5781.653100813965;5783.913241301384;5811.651300921958;5870.941290587125;6140.570160088841;6140.815775526741;6146.139540464339;6146.323904370515;6150.108234306001;5858.941410585925;5859.062549991981;5861.560382971209;5863.442972917566;5887.087501437284;5174.353205849269;5268.9736551317765;5285.656432331886;5301.787928482818;5384.326959130109;6386.584871983307;6405.615663060231;6428.77499287532;6438.645874476949;6452.967735161388;6158.476529495043;6162.753489860355;6167.876642467215;6185.41238582334;6189.84525386869;6031.035034394476;6263.154474146114;6266.530010249341;6283.748650053946;6363.649999250086;6060.212172417558;6061.484773794277;6069.119977603311;6069.939300607114;6103.481204097524;6155.815325540241;6159.630422174684;6163.9383606163365;6163.938360616516;6168.228971378578;5780.739866705924;5904.940950590439;5926.822195334147;5977.6114552553945;6069.423404776539;5960.821175364746;5995.730192141277;6002.819915402545;6004.549658775697;6011.939880601138;5946;5954;6003.8198854034445;6006.129111278928;6006.759729610942;5901.940980590139;5966.403359663999;6017.247844019497;6017.909731353946;6102.664353460602;5880.970595146997;5895.793647222326;5896.8820623586425;5936.762529498945;5938.61399009063 18:03:53 INFO - PROCESS | 14062 | |1;ext.html;618.3723520626564;628.6134027573042;657.4904449051984;663.6051549328148;672.5359501943661;34866.825665871664;38660.806695966516;38744.225115497706;44352.334714979304;46149.538504614946;49059;49674.75162624191;49700;49784.502154978436;49802.50197498024;9272.953635231814;9433.76415589609;9436.056394360543;9436.386634868712;9437.15065644092;8626.137386261355;9211.723648290563;9308.674196403128;10978.945105274463;11229.438528073604;11455.14086443516;11525.48135333912;11531.481083351227;11532.71168220797;11540.076793856513;11336.206465547415;11379.260348077349;11401.315921044681;11401.600943967005;11409.258398204132;11261.718457038558;11352.886471135349;11356.148288878285;11362.26145300561;11362.71593210168;45585.316220256726;46064.769676151736;46213;46221;46236.075278494245;3901.395283731026;4042.1713548722737;4095.8566450174103;4358.237308471005;4648.1168577970075;34700.13249668753;34743;34792;35069.64930350689;35173.94478165659;4617.930731039037;4647.488776234612;4708.905821883577;4749.596284315838;4787.616990640775;369184;372689;374122;375300.1234993835;375778;63364.366356336766;63673.68163159154;63726.36273637297;63744.36255637477;63802.36197638057;38987.41518877162;39802.80098599489;39856.40215396771;42384;64945.35054649393;19471.610567788564;19688.409347719306;19705.310314138937;19706.211751530063;19709.211631534865;15009.924950375178;15120.54638360851;15191.164485953379;15193.240337983278;15216.467423640119;18845.81154188468;19210.231590736494;20545.178192872118;21152.576948460945;21217.363479095653;7276.745313913988;7416.0729908761405;7453.813654658677;7601.391888648895;7649.808754781175;968.8304546704298;1058.539535302146;1059.750958524746;1067.9839802402971;1152.4698638626157;34843;34901.65098349035;34997.65002349995;35076.473852892224;35342.64657353394;1216.975660486803;1223.1865809236936;1227.2084505494086;1229.0106464296327;1231.2920070959055;17991.190396432194;18467;18519.72220416695;18584.81415185858;18585.53536161607;621157;631816;632354;632813;633435;64248.67875660592;64847.35152648413;64907.35092649013;64925.67537162284;64999.675001625634;1344.0524430276632;1442.7114577084415;1476.7415702252063;1477.623206082442;1482.458902500558;34296.31407371889;36491;36603.63396366109;36610.45084323791;36612.450813238356;54834.17748733692;55419;55441;55567.166492501834;55639.44360556343;465236;467820;467989;469114;470819;32533.186670332485;33283.50074748929;33432;33761.324773504886;33988.660113399535;52801;53172.46827531675;53273;53561.73219133879;54030.729846350514;43033.83501752252;44293.55706442894;44594.777026114665;45902;46751;44285.33571996358;45025;45662.77168614136;46610.300845488026;47272.7636361816;44042.898927527065;44063.55936440723;44550;44865.77567112144;44955.32567011432;38144.427833583075;39751.80124099477;39945.80027099846;40159.32847325127;40561.18877622372;30047.69952300449;30180.5472917902;30495.54256686107;30588.036179183317;30953.987990780526 18:03:53 INFO - PROCESS | 14062 | |2;jquery.html;396.6489656653861;405.663299461447;420.7832966022499;428.4580006292041;440.091211647947;18058.90970545147;20275.898620506912;20827.27104551341;20914;20979.265725699603;20293.28973485928;20366.287179948667;20647.17411303546;20672.586548269024;20842.166313347487;5413.106837371839;5729.255196824428;5750.02249617566;5754.251947246852;5763.5101016413655;4207.284761590537;4386.34204869269;5683.658980461165;6242.9687851560675;6477.54657173998;6352.1742173517605;6570.5729127606555;6577.835554111138;6580.967095164518;6584.637844918528;6444.130042444281;6478.870422591545;6514.185726784152;6547.279799222106;6563.146790917198;5592;5650.717464126818;5695.515881150107;5788.797392091256;5802.622829516068;25222.117225897007;25365.23904282856;27276;27614.585781213296;28084.297892552644;2522.545941730488;2940.338423854637;3117.830813444958;3458.4985177149424;3560.5549306336707;16359.509214723459;17182.570435739082;17681.027543485103;17826.37607683738;17982.730259046126;2180.4875854174256;2256.7968882800465;2274.442761523421;2330.790228879392;2349.9647505287435;89848.5507572458;98745;100008.99999999927;101409;104003;18107.18517666708;18209.63580728378;18803.435896923114;18981.905090474458;19020.61958760817;34246.82876585651;34248;34290.65709342925;34339.313213735586;34372;10549.419781912069;10551.364538496546;10575.471226438647;10630.893691063146;11155.163362747664;8573.142685731502;8573.185547373114;8614.91385086141;9002.684906028258;9007.954960225157;9068.410553314014;9580.808383832284;9608.327417080738;9762.658306959222;9808.9019109808;3335.5497007903623;3423.281110966675;3584.426491761306;3662.1393972416463;3716.7769933804057;1060.6499855047816;1062.1661995333625;1086.1365214654368;1097.5993762276871;1105.7733164701217;20609.690854637192;21164.36506904795;21284.574308513744;21592.136314547555;21604.891975540337;1021.3820638513592;1034.9793004140026;1075.5590208014676;1086.6468397770757;1097.676185525256;11133.443327833576;11961.521539138337;12092;12164.5134194633;12186.451609677586;177440;179029;179490;181438;181951;57153;94224.52887735517;99419;99517;99819.50090249503;1027.4349107990574;1051.264115119404;1059.1209296284067;1080.6920027792041;1089.656758121205;18474.70677052647;18520.259189632263;19132.808671913102;19243.61512769765;19330.323438679578;46435;47067.29399058948;48156.75921620509;48208;48259.75870120627;64991.67504162449;65529.672351637935;66104.66947665231;66563;67689;7708.576028318272;8667;8679.783005424915;8796.252318552995;8871.112888711077;11399.829002565135;13026.934865325613;13353.53262635803;13593.59219223425;13851.653708657364;11796;11895.405229738652;11897.940510297392;12044.819327709916;12273.263604183778;11540.24988375753;11612.825807613062;11809;11847.052235820942;11947.522099116286;11222.831657524981;11841.5855445059;11880.82178767302;12087.939560302493;12098;7807.609619519115;7956.9602151988865;7998;8061.153578874134;8201.83596328082;9441.52792360393;10080.344777589433;11283;11432.48553815097;11692.47383867745 18:03:53 INFO - PROCESS | 14062 | |3;mootools.html;1465.538355418044;1533.3866453418634;1592.1800272859475;1789.2038043070838;1916.587933594277;8951.194392504673;9361.063893610635;9363.11050450207;9601.47191904445;9643.324967252294;9345.205657519102;9461.053894610535;9476.478793666345;9485;9485.573149208285;2481.0944005437977;2499.3626625210545;2499.575072237724;2504.8371855829405;2505.08564374004;2425.890834912433;2510.410053637394;2513.246026192144;2514.509670614231;2514.7485251474795;2474.505098980202;2483.888225029869;2534.3284029732135;2538.0736031348515;2539.4794067216267;2397.928062158138;2410.168491870311;2413.6862207913036;2462.9507409851703;2463.7782599566126;2362.9645555316683;2390.6414037894283;2392.748761380065;2397.4605713714272;2413.179518963561;9319.673811416636;9511.857322140173;9524.476153811538;9565.282603804742;9596.904030959671;5341.893162136775;5568.220449137124;5585.692786896719;5590.748416321276;5594.720263986825;3452.9654703452893;3497.9475307870403;3574.285142971416;3576.856925722968;3588.766730162531;1180.3979970215166;1198.6583823610229;1204;1204.2894692131597;2255.458689914425;12634.431450584745;12809.743805123846;13302.733945321137;13336.933315333361;13354.399052042585;4867.269909513601;4923.729194894315;4987.177115775857;5014.34813474246;5026.271190677331;10326.535305911251;10445.11216546602;10456;10461.633842815465;10462.215333849992;5493.285872836505;5500.4224556420995;5508.55931525477;5509.586780991442;5511.751971161308;4317.568243175658;4444.533324000933;5006.6495345325475;5063.670861393997;5156.587473002151;4082.9591704083173;4088.632023117934;4121.113960498507;4172.70791044624;4214.831406743696;1173.372245848467;1204.2834513464475;1207.8369420128347;1208.6374087773809;1212.3938030984507;1052.905238528536;1064.292245656645;1073.6993641780314;1074.8495210670512;1091.5415525479318;6182.381761823782;6371.681415929185;6428;6497.057926600616;6567.770128045591;452.19734970427754;454.6567341657028;457.34371177360595;467.04489319342025;471.87495313741886;4234.491860976703;4391.560843915583;4479.977600111979;4606.239970404846;4641.95358046422;11495.080393568494;12015.879841201651;12016.038716902627;12021.579244726567;12062.939685301693;11118.444077796077;11626.76746465083;11955.64133076009;11975.64073077809;12001.2199207053;8798.34012449056;8971.147740964729;8983.146601072756;8996.59515321812;8999.235065019546;6319.526035547445;6493.740250389931;6551.44312733404;6658.300878407697;6803.523753337417;10538.841917371092;10858.619948301772;11046.447677616248;11056.778864422506;11115.777684446428;9744.464054477072;10802.837957430487;10963.561457541928;11271.549138034386;11338.829917551078;8543.487390756574;8692.608832602422;8727.476351418934;8781.956090219508;8798.47209167452;9245.482468387434;9252.768680783034;9329.253659707072;9338.719838404859;9340.486273255035;8414.537200454033;8470.915290847013;8569.742907712778;8573.742787716128;8598.097199793932;8289.668413263402;8304.916950830415;8365.498070115813;8443.324534037385;8504.404691671523;7806.648700808591;7825.608719564113;7887.40844436681;7887.487313324616;7911.604419779103;5611.186377975252;5676.659400435987;5707.6290041147195;5718.628289161191;5876.676782776988;5492.560595152298;5747.195392645117;5779.884402312015;5797.130430435468;5809.070548712186 18:03:53 INFO - PROCESS | 14062 | |4;prototype.html;339.9864005439783;346.25036795338093;348.17656242985345;355.71187338256;357.52270718590665;5541.279633647625;5650.886982260352;5651.9152212716845;5679.488846003859;5704.942950570493;5549.197655184126;5585.413531579186;5602.943970560294;5625.971870140654;5629.465200805927;3834.6740527055235;4110.445089912861;4325.178216138924;4332.393464914914;4347.456567929009;4322.7406355618605;4326.502452217988;4328.696991210617;4337.436133302666;4381.18947994622;4339.481181586435;4347.217500849846;4350.73895566267;4369.541198174194;4387.232234358974;4154.210699967011;4259.957400425987;4313.374560688682;4324.891877703051;4334.263175260214;3811.3901775715754;3817.0533028155564;3822.7767114523363;3825.9234815303817;3871.2257548490416;5410.864728381783;5473.480019398151;5475.315585551806;5477.370102438191;5479.2602998595075;4810.134175848346;4899.510048995107;4900.240462728283;4951.356323677899;4987.351644286256;5758.869218389181;5772.30732312121;5783.971080144573;5793.2179155814265;5822.883542329172;4229.97885010573;4232.4286221360035;4242.8727138185595;4246.363045543162;4254.936175957363;5658.207850900877;5757.251557297524;5854.97072514639;6004.879902401971;6086.1783659205885;4130.855420060284;4147.81334839933;4150.605692459211;4187.853425130106;4194.49666040071;4254;4258.8296468140925;4279.40088387626;4281.272183728774;4287.656987440997;4736.573708366194;4907.779149938261;4908.619319629976;4929.827456039022;4930.1865192243595;5849.596096936704;5851;5870.178175055496;5876.941230587725;5897.056470964625;1490.9478168264059;1510.8791296696238;1511.2368254031644;1518.7569988801738;1519.3163076615567;922.686286662538;945.6312038305067;948.8719022931954;959.64972784933;968.7820240445843;5004.874878128076;5095.694258344512;5104.69371837691;5104.846854594368;5107.438181799999;5201.271821944931;5241.371035475691;5272.92090618641;5287.3126493556365;5300.270360764132;5054.69671819692;5085.389753229636;5125.846224613193;5342.5993050521365;5765.509931655773;3161.65221825599;3170.732925957951;3174.904752857418;3174.904752857418;3183;6124.969375153096;6212.099245609452;6227.346128656426;6263.185785847811;6289.842753931189;6030.5778595497895;6030.879382412415;6032.426919442646;6075.210222671025;6098.54260930432;6155.599886007395;6165.0444181152;6173.382661733791;6175.84560386003;6197.96901015492;5777.335606405317;5886.499647530008;5911.201987731602;5920.911186332295;5978.861097923625;5878.441548052928;5907.49786268172;5912.704364781658;5923.703814809329;5923.911141332797;5735.193414074553;5878.647281163144;5900.822975310747;5985.610935289193;6152.661603611666;4221.493420789525;4246.150769845982;4280.828766849416;4287.571242875688;4317.417148684887;5991.85427650487;6055.333913269449;6099.75600975956;6104.847378815565;6112.419320164578;5670.4329567044615;5902.940970590239;5907.970460147843;5915.852103697442;5941.732622032106;5826.796062137805;5828.970855145697;5830.154627579062;5872.324682661549;5875.94124058771;5813.680247586254;5826.756698670957;5841.474267315962;5855.443732845373;5865.917599252204;5940.138679891305;5958.7616495341435;5963.761449541969;5975.342712301556;5982.295045911894;5550.08423610116;5720.570957178145;5822.243108395882;5866.67733274674;5881.147233651182 18:03:53 INFO - PROCESS | 14062 | |5;yui.html;860.3418384935521;878.3719640457076;891.8350105230531;919.2554031234696;940.8635747816567;16307.347706091741;17467.563310917216;17575.209115589798;17609.823901760978;17620.911895440506;16293.103879286635;16529;16537.255823487943;16568.66862662746;16604;739.988900166498;864.6973559254274;885.0308911741648;886.6276164011099;912.7535565397338;901.1484147480634;905.950172740499;907.4827348411408;908.1009800297691;909.0954500272205;958.7123862841155;959.6785076999214;960.8318544254762;961;961.903809619036;788.8185717285028;914.8947870994854;933.1461712533024;933.276710549323;959.8128364968834;692.5325405351367;692.9411000065;693.2097408953797;693.4937495628169;693.5873155472536;15297.694046119015;15396.153211573359;15408.152551609657;15419.768703469457;15547.378104875792;15349.46276880315;15516.069035857885;15619.921900390425;15722.528324150293;15765.763513547307;14015.64960875976;14231.644208894757;14431.49489767853;14584.270786460635;14747.410103595843;4467.709598876095;4487.932681009787;4551.704139230939;4580.679352445331;4605.700629459108;81414;85547.14452855516;86511;86586;87335.81660458476;12384.56654017105;12393.814092788616;12430.192037517532;12458.377081145907;12510.687232819253;23311.650325245137;23525.88237058804;23849.880750596138;23873.16443924489;23884.7611523886;9284;9330.626774929062;9373.765655858659;9418.576164072496;9483.762905927408;5132.589392848563;5153.5361817436615;5211.921821172686;5290.761915713802;5466.125419932791;3783.3379158647126;3949.4273330366937;3951.585083566242;3951.68386529077;3955.4660120883314;752.9096508419025;772.818387678895;773.389022672094;788.7949133225403;788.9211078892065;727.4035291061278;778.7702627724722;814.8003739083844;837.0833936839151;848.6987119572462;9827.4103553787;10685.57257709683;10815.513301901434;10836;10879.782404352027;684.2439104789236;775.9883601745978;785.4187900953253;785.4933567848706;837.4598384042258;5074.391073071181;5137.12668846297;5147.871303217449;5164.690118592896;5256.1327380982475;68368;70136.298637014;70299.2970070303;80611.19388806155;80680;100370;100653;101542;103898;106503;85963;88858.55570722235;90773.63839542412;91077.54461227784;91307;10915.290506117077;11081.27971681838;11213.71965700864;11221.495032723711;11317.547298107984;51838.740806295726;52222;52558;53159.20261196163;53879;74006.2599374021;78274;81046;83282;83935.580322098;13106.2136271824;13441.193528388329;13614;13828.585142445743;13975.231362274766;15421;15780;16293.185340732678;16385.50843474698;16936.66126677484;14327.28363581796;14462.710745785236;15059.548213553611;15594.220288985733;15627.453039143575;14207.147571145764;14342.713145736818;14556.781648275497;15502.767458487906;15743.212839358215;14221.64445888861;14762.704745904806;15264.084154950739;15439.691206176038;15503.457378991681;11776.587819426279;11821.290722556674;11828.881711182778;11830.82253766177;12639.115261931576;11779.938510760754;11861;11966.940165299118;12108.273503589813;12252.326122063372 18:03:53 INFO - PROCESS | 14062 | __end_tp_report 18:03:53 INFO - PROCESS | 14062 | __start_cc_report 18:03:53 INFO - PROCESS | 14062 | _x_x_mozilla_cycle_collect,1092 18:03:53 INFO - PROCESS | 14062 | __end_cc_report 18:03:53 INFO - PROCESS | 14062 | __startTimestamp1471050233360__endTimestamp 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | ------- Summary: start ------- 18:03:53 INFO - PROCESS | 14062 | Number of tests: 6 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#0] dojo.html Cycles:165 Average:5676.86 Median:5904.94 stddev:647.80 (11.0%) stddev-sans-first:622.34 18:03:53 INFO - PROCESS | 14062 | Values: 3298.5 3302.1 3304.3 3329.7 3354.0 5623.3 5736.1 5766.6 5772.8 5776.9 5738.5 5821.0 5825.2 5837.2 5838.3 5043.2 5174.8 5249.4 5283.2 5309.9 5065.7 5162.2 5188.8 5215.7 5217.7 5043.8 5075.1 5085.2 5095.9 5123.5 5159.4 5181.0 5187.1 5187.3 5200.2 5024.1 5083.6 5088.4 5093.2 5118.9 5529.6 5583.1 5587.1 5594.0 5625.0 6065.2 6097.2 6100.3 6103.5 6108.7 5938.5 6051.2 6087.3 6101.8 6145.0 5740.7 5752.1 5761.9 5762.2 5765.5 6234.7 6284.1 6298.2 6302.3 6304.7 5897.5 5941.1 5952.8 5953.3 6038.6 6085.4 6100.0 6106.0 6110.1 6113.3 6024.4 6028.2 6032.5 6036.1 6045.7 5902.2 5956.1 6014.4 6059.0 6072.4 4660.0 4692.8 4694.1 4698.3 4712.9 4186.1 4197.7 4204.3 4224.0 4227.8 5622.9 5781.7 5783.9 5811.7 5870.9 6140.6 6140.8 6146.1 6146.3 6150.1 5858.9 5859.1 5861.6 5863.4 5887.1 5174.4 5269.0 5285.7 5301.8 5384.3 6386.6 6405.6 6428.8 6438.6 6453.0 6158.5 6162.8 6167.9 6185.4 6189.8 6031.0 6263.2 6266.5 6283.7 6363.6 6060.2 6061.5 6069.1 6069.9 6103.5 6155.8 6159.6 6163.9 6163.9 6168.2 5780.7 5904.9 5926.8 5977.6 6069.4 5960.8 5995.7 6002.8 6004.5 6011.9 5946.0 5954.0 6003.8 6006.1 6006.8 5901.9 5966.4 6017.2 6017.9 6102.7 5881.0 5895.8 5896.9 5936.8 5938.6 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#1] ext.html Cycles:180 Average:66195.84 Median:40052.56 stddev:133316.74 (332.9%) stddev-sans-first:133599.81 18:03:53 INFO - PROCESS | 14062 | Values: 618.4 628.6 657.5 663.6 672.5 34866.8 38660.8 38744.2 44352.3 46149.5 49059.0 49674.8 49700.0 49784.5 49802.5 9273.0 9433.8 9436.1 9436.4 9437.2 8626.1 9211.7 9308.7 10978.9 11229.4 11455.1 11525.5 11531.5 11532.7 11540.1 11336.2 11379.3 11401.3 11401.6 11409.3 11261.7 11352.9 11356.1 11362.3 11362.7 45585.3 46064.8 46213.0 46221.0 46236.1 3901.4 4042.2 4095.9 4358.2 4648.1 34700.1 34743.0 34792.0 35069.6 35173.9 4617.9 4647.5 4708.9 4749.6 4787.6 369184.0 372689.0 374122.0 375300.1 375778.0 63364.4 63673.7 63726.4 63744.4 63802.4 38987.4 39802.8 39856.4 42384.0 64945.4 19471.6 19688.4 19705.3 19706.2 19709.2 15009.9 15120.5 15191.2 15193.2 15216.5 18845.8 19210.2 20545.2 21152.6 21217.4 7276.7 7416.1 7453.8 7601.4 7649.8 968.8 1058.5 1059.8 1068.0 1152.5 34843.0 34901.7 34997.7 35076.5 35342.6 1217.0 1223.2 1227.2 1229.0 1231.3 17991.2 18467.0 18519.7 18584.8 18585.5 621157.0 631816.0 632354.0 632813.0 633435.0 64248.7 64847.4 64907.4 64925.7 64999.7 1344.1 1442.7 1476.7 1477.6 1482.5 34296.3 36491.0 36603.6 36610.5 36612.5 54834.2 55419.0 55441.0 55567.2 55639.4 465236.0 467820.0 467989.0 469114.0 470819.0 32533.2 33283.5 33432.0 33761.3 33988.7 52801.0 53172.5 53273.0 53561.7 54030.7 43033.8 44293.6 44594.8 45902.0 46751.0 44285.3 45025.0 45662.8 46610.3 47272.8 44042.9 44063.6 44550.0 44865.8 44955.3 38144.4 39751.8 39945.8 40159.3 40561.2 30047.7 30180.5 30495.5 30588.0 30954.0 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#2] jquery.html Cycles:180 Average:22771.35 Median:2340.38 stddev:35125.29 (1500.8%) stddev-sans-first:35183.65 18:03:53 INFO - PROCESS | 14062 | Values: 396.6 405.7 420.8 428.5 440.1 18058.9 20275.9 20827.3 20914.0 20979.3 20293.3 20366.3 20647.2 20672.6 20842.2 5413.1 5729.3 5750.0 5754.3 5763.5 4207.3 4386.3 5683.7 6243.0 6477.5 6352.2 6570.6 6577.8 6581.0 6584.6 6444.1 6478.9 6514.2 6547.3 6563.1 5592.0 5650.7 5695.5 5788.8 5802.6 25222.1 25365.2 27276.0 27614.6 28084.3 2522.5 2940.3 3117.8 3458.5 3560.6 16359.5 17182.6 17681.0 17826.4 17982.7 2180.5 2256.8 2274.4 2330.8 2350.0 89848.6 98745.0 100009.0 101409.0 104003.0 18107.2 18209.6 18803.4 18981.9 19020.6 34246.8 34248.0 34290.7 34339.3 34372.0 10549.4 10551.4 10575.5 10630.9 11155.2 8573.1 8573.2 8614.9 9002.7 9008.0 9068.4 9580.8 9608.3 9762.7 9808.9 3335.5 3423.3 3584.4 3662.1 3716.8 1060.6 1062.2 1086.1 1097.6 1105.8 20609.7 21164.4 21284.6 21592.1 21604.9 1021.4 1035.0 1075.6 1086.6 1097.7 11133.4 11961.5 12092.0 12164.5 12186.5 177440.0 179029.0 179490.0 181438.0 181951.0 57153.0 94224.5 99419.0 99517.0 99819.5 1027.4 1051.3 1059.1 1080.7 1089.7 18474.7 18520.3 19132.8 19243.6 19330.3 46435.0 47067.3 48156.8 48208.0 48259.8 64991.7 65529.7 66104.7 66563.0 67689.0 7708.6 8667.0 8679.8 8796.3 8871.1 11399.8 13026.9 13353.5 13593.6 13851.7 11796.0 11895.4 11897.9 12044.8 12273.3 11540.2 11612.8 11809.0 11847.1 11947.5 11222.8 11841.6 11880.8 12087.9 12098.0 7807.6 7957.0 7998.0 8061.2 8201.8 9441.5 10080.3 11283.0 11432.5 11692.5 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#3] mootools.html Cycles:180 Average:6235.78 Median:456.00 stddev:3578.69 (784.8%) stddev-sans-first:3570.77 18:03:53 INFO - PROCESS | 14062 | Values: 1465.5 1533.4 1592.2 1789.2 1916.6 8951.2 9361.1 9363.1 9601.5 9643.3 9345.2 9461.1 9476.5 9485.0 9485.6 2481.1 2499.4 2499.6 2504.8 2505.1 2425.9 2510.4 2513.2 2514.5 2514.7 2474.5 2483.9 2534.3 2538.1 2539.5 2397.9 2410.2 2413.7 2463.0 2463.8 2363.0 2390.6 2392.7 2397.5 2413.2 9319.7 9511.9 9524.5 9565.3 9596.9 5341.9 5568.2 5585.7 5590.7 5594.7 3453.0 3497.9 3574.3 3576.9 3588.8 1180.4 1198.7 1204.0 1204.3 2255.5 12634.4 12809.7 13302.7 13336.9 13354.4 4867.3 4923.7 4987.2 5014.3 5026.3 10326.5 10445.1 10456.0 10461.6 10462.2 5493.3 5500.4 5508.6 5509.6 5511.8 4317.6 4444.5 5006.6 5063.7 5156.6 4083.0 4088.6 4121.1 4172.7 4214.8 1173.4 1204.3 1207.8 1208.6 1212.4 1052.9 1064.3 1073.7 1074.8 1091.5 6182.4 6371.7 6428.0 6497.1 6567.8 452.2 454.7 457.3 467.0 471.9 4234.5 4391.6 4480.0 4606.2 4642.0 11495.1 12015.9 12016.0 12021.6 12062.9 11118.4 11626.8 11955.6 11975.6 12001.2 8798.3 8971.1 8983.1 8996.6 8999.2 6319.5 6493.7 6551.4 6658.3 6803.5 10538.8 10858.6 11046.4 11056.8 11115.8 9744.5 10802.8 10963.6 11271.5 11338.8 8543.5 8692.6 8727.5 8782.0 8798.5 9245.5 9252.8 9329.3 9338.7 9340.5 8414.5 8470.9 8569.7 8573.7 8598.1 8289.7 8304.9 8365.5 8443.3 8504.4 7806.6 7825.6 7887.4 7887.5 7911.6 5611.2 5676.7 5707.6 5718.6 5876.7 5492.6 5747.2 5779.9 5797.1 5809.1 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#4] prototype.html Cycles:180 Average:4864.33 Median:5549.64 stddev:1432.48 (25.8%) stddev-sans-first:1395.66 18:03:53 INFO - PROCESS | 14062 | Values: 340.0 346.3 348.2 355.7 357.5 5541.3 5650.9 5651.9 5679.5 5704.9 5549.2 5585.4 5602.9 5626.0 5629.5 3834.7 4110.4 4325.2 4332.4 4347.5 4322.7 4326.5 4328.7 4337.4 4381.2 4339.5 4347.2 4350.7 4369.5 4387.2 4154.2 4260.0 4313.4 4324.9 4334.3 3811.4 3817.1 3822.8 3825.9 3871.2 5410.9 5473.5 5475.3 5477.4 5479.3 4810.1 4899.5 4900.2 4951.4 4987.4 5758.9 5772.3 5784.0 5793.2 5822.9 4230.0 4232.4 4242.9 4246.4 4254.9 5658.2 5757.3 5855.0 6004.9 6086.2 4130.9 4147.8 4150.6 4187.9 4194.5 4254.0 4258.8 4279.4 4281.3 4287.7 4736.6 4907.8 4908.6 4929.8 4930.2 5849.6 5851.0 5870.2 5876.9 5897.1 1490.9 1510.9 1511.2 1518.8 1519.3 922.7 945.6 948.9 959.6 968.8 5004.9 5095.7 5104.7 5104.8 5107.4 5201.3 5241.4 5272.9 5287.3 5300.3 5054.7 5085.4 5125.8 5342.6 5765.5 3161.7 3170.7 3174.9 3174.9 3183.0 6125.0 6212.1 6227.3 6263.2 6289.8 6030.6 6030.9 6032.4 6075.2 6098.5 6155.6 6165.0 6173.4 6175.8 6198.0 5777.3 5886.5 5911.2 5920.9 5978.9 5878.4 5907.5 5912.7 5923.7 5923.9 5735.2 5878.6 5900.8 5985.6 6152.7 4221.5 4246.2 4280.8 4287.6 4317.4 5991.9 6055.3 6099.8 6104.8 6112.4 5670.4 5902.9 5908.0 5915.9 5941.7 5826.8 5829.0 5830.2 5872.3 5875.9 5813.7 5826.8 5841.5 5855.4 5865.9 5940.1 5958.8 5963.8 5975.3 5982.3 5550.1 5720.6 5822.2 5866.7 5881.1 18:03:53 INFO - PROCESS | 14062 | 18:03:53 INFO - PROCESS | 14062 | [#5] yui.html Cycles:180 Average:21003.07 Median:4519.82 stddev:28224.36 (624.5%) stddev-sans-first:28263.01 18:03:53 INFO - PROCESS | 14062 | Values: 860.3 878.4 891.8 919.3 940.9 16307.3 17467.6 17575.2 17609.8 17620.9 16293.1 16529.0 16537.3 16568.7 16604.0 740.0 864.7 885.0 886.6 912.8 901.1 906.0 907.5 908.1 909.1 958.7 959.7 960.8 961.0 961.9 788.8 914.9 933.1 933.3 959.8 692.5 692.9 693.2 693.5 693.6 15297.7 15396.2 15408.2 15419.8 15547.4 15349.5 15516.1 15619.9 15722.5 15765.8 14015.6 14231.6 14431.5 14584.3 14747.4 4467.7 4487.9 4551.7 4580.7 4605.7 81414.0 85547.1 86511.0 86586.0 87335.8 12384.6 12393.8 12430.2 12458.4 12510.7 23311.7 23525.9 23849.9 23873.2 23884.8 9284.0 9330.6 9373.8 9418.6 9483.8 5132.6 5153.5 5211.9 5290.8 5466.1 3783.3 3949.4 3951.6 3951.7 3955.5 752.9 772.8 773.4 788.8 788.9 727.4 778.8 814.8 837.1 848.7 9827.4 10685.6 10815.5 10836.0 10879.8 684.2 776.0 785.4 785.5 837.5 5074.4 5137.1 5147.9 5164.7 5256.1 68368.0 70136.3 70299.3 80611.2 80680.0 100370.0 100653.0 101542.0 103898.0 106503.0 85963.0 88858.6 90773.6 91077.5 91307.0 10915.3 11081.3 11213.7 11221.5 11317.5 51838.7 52222.0 52558.0 53159.2 53879.0 74006.3 78274.0 81046.0 83282.0 83935.6 13106.2 13441.2 13614.0 13828.6 13975.2 15421.0 15780.0 16293.2 16385.5 16936.7 14327.3 14462.7 15059.5 15594.2 15627.5 14207.1 14342.7 14556.8 15502.8 15743.2 14221.6 14762.7 15264.1 15439.7 15503.5 11776.6 11821.3 11828.9 11830.8 12639.1 11779.9 11861.0 11966.9 12108.3 12252.3 18:03:53 INFO - PROCESS | 14062 | -------- Summary: end -------- 18:03:53 INFO - PROCESS | 14062 | 18:03:54 INFO - TEST-INFO | 14062: exit 0 18:03:54 INFO - TEST-OK | dromaeo_css | took 1113660ms 18:03:54 INFO - TEST-START | kraken 18:03:54 INFO - operating with platform_type : linux_ 18:03:54 INFO - Initialising browser for kraken test... 18:03:54 INFO - TEST-INFO | started process 14268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWdH0fq/profile http://localhost:48407/getInfo.html) 18:03:58 INFO - PROCESS | 14268 | __metrics Screen width/height:1600/1200 18:03:58 INFO - PROCESS | 14268 | colorDepth:24 18:03:58 INFO - PROCESS | 14268 | Browser inner width/height: 1024/697 18:03:58 INFO - PROCESS | 14268 | __metrics 18:03:59 INFO - PROCESS | 14268 | 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" 18:03:59 INFO - PROCESS | 14268 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:03:59 INFO - TEST-INFO | 14268: exit 0 18:03:59 INFO - Browser initialized. 18:03:59 INFO - Running cycle 1/1 for kraken test... 18:03:59 INFO - TEST-INFO | started process 14356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWdH0fq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:11 INFO - PROCESS | 14356 | RSS: Main: 145510400 18:04:11 INFO - PROCESS | 14356 | 18:04:14 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48407/tests/kraken/driver.html?testName=audio-beat-detection) 18:04:14 INFO - PROCESS | 14356 | RSS: Main: 192045056 18:04:14 INFO - PROCESS | 14356 | 18:04:17 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48407/tests/kraken/driver.html?testName=audio-dft) 18:04:18 INFO - PROCESS | 14356 | RSS: Main: 180678656 18:04:18 INFO - PROCESS | 14356 | 18:04:20 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48407/tests/kraken/driver.html?testName=audio-fft) 18:04:21 INFO - PROCESS | 14356 | RSS: Main: 268226560 18:04:21 INFO - PROCESS | 14356 | 18:04:23 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48407/tests/kraken/driver.html?testName=audio-oscillator) 18:04:24 INFO - PROCESS | 14356 | RSS: Main: 176074752 18:04:24 INFO - PROCESS | 14356 | 18:04:26 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48407/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:04:26 INFO - PROCESS | 14356 | RSS: Main: 177201152 18:04:26 INFO - PROCESS | 14356 | 18:04:29 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48407/tests/kraken/driver.html?testName=imaging-darkroom) 18:04:30 INFO - PROCESS | 14356 | RSS: Main: 212897792 18:04:30 INFO - PROCESS | 14356 | 18:04:33 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48407/tests/kraken/driver.html?testName=imaging-desaturate) 18:04:34 INFO - PROCESS | 14356 | RSS: Main: 212299776 18:04:34 INFO - PROCESS | 14356 | 18:04:37 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48407/tests/kraken/driver.html?testName=json-parse-financial) 18:04:37 INFO - PROCESS | 14356 | RSS: Main: 212242432 18:04:37 INFO - PROCESS | 14356 | 18:04:39 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48407/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:04:40 INFO - PROCESS | 14356 | RSS: Main: 176226304 18:04:40 INFO - PROCESS | 14356 | 18:04:41 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:04:42 INFO - PROCESS | 14356 | RSS: Main: 181616640 18:04:42 INFO - PROCESS | 14356 | 18:04:44 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:04:45 INFO - PROCESS | 14356 | RSS: Main: 187224064 18:04:45 INFO - PROCESS | 14356 | 18:04:47 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:04:48 INFO - PROCESS | 14356 | RSS: Main: 181444608 18:04:48 INFO - PROCESS | 14356 | 18:04:50 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:04:51 INFO - PROCESS | 14356 | RSS: Main: 184164352 18:04:51 INFO - PROCESS | 14356 | 18:04:52 INFO - PROCESS | 14356 | Cycle 1(1): loaded http://localhost:48407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48407/tests/kraken/driver.html?testName=ai-astar) 18:04:53 INFO - PROCESS | 14356 | RSS: Main: 235970560 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | __start_tp_report 18:04:53 INFO - PROCESS | 14356 | _x_x_mozilla_page_load 18:04:53 INFO - PROCESS | 14356 | _x_x_mozilla_page_load_details 18:04:53 INFO - PROCESS | 14356 | |i|pagename|runs| 18:04:53 INFO - PROCESS | 14356 | |0;ai-astar;101;110;110;110;110;108;111;108;108;109 18:04:53 INFO - PROCESS | 14356 | |1;audio-beat-detection;138;116;120;176;118;118;141;119;153;115 18:04:53 INFO - PROCESS | 14356 | |2;audio-dft;161;161;161;161;161;161;162;162;160;160 18:04:53 INFO - PROCESS | 14356 | |3;audio-fft;75;76;76;130;74;75;75;76;104;74 18:04:53 INFO - PROCESS | 14356 | |4;audio-oscillator;83;84;112;83;83;83;121;84;83;130 18:04:53 INFO - PROCESS | 14356 | |5;imaging-gaussian-blur;117;117;116;116;117;116;117;117;117;117 18:04:53 INFO - PROCESS | 14356 | |6;imaging-darkroom;145;146;146;145;146;146;147;146;145;147 18:04:53 INFO - PROCESS | 14356 | |7;imaging-desaturate;95;95;95;95;95;94;94;95;95;94 18:04:53 INFO - PROCESS | 14356 | |8;json-parse-financial;51;52;51;52;51;52;50;51;51;51 18:04:53 INFO - PROCESS | 14356 | |9;json-stringify-tinderbox;59;59;58;59;59;58;60;58;60;59 18:04:53 INFO - PROCESS | 14356 | |10;stanford-crypto-aes;74;72;72;72;73;73;72;78;74;75 18:04:53 INFO - PROCESS | 14356 | |11;stanford-crypto-ccm;140;116;125;116;115;115;122;115;163;117 18:04:53 INFO - PROCESS | 14356 | |12;stanford-crypto-pbkdf2;149;148;150;148;150;152;148;148;150;150 18:04:53 INFO - PROCESS | 14356 | |13;stanford-crypto-sha256-iterative;61;61;62;60;62;61;62;60;60;61 18:04:53 INFO - PROCESS | 14356 | __end_tp_report 18:04:53 INFO - PROCESS | 14356 | __start_cc_report 18:04:53 INFO - PROCESS | 14356 | _x_x_mozilla_cycle_collect,1667 18:04:53 INFO - PROCESS | 14356 | __end_cc_report 18:04:53 INFO - PROCESS | 14356 | __startTimestamp1471050293142__endTimestamp 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | ------- Summary: start ------- 18:04:53 INFO - PROCESS | 14356 | Number of tests: 14 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:2.84 (2.6%) stddev-sans-first:1.12 18:04:53 INFO - PROCESS | 14356 | Values: 101.0 110.0 110.0 110.0 110.0 108.0 111.0 108.0 108.0 109.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:129.00 stddev:20.39 (15.8%) stddev-sans-first:21.48 18:04:53 INFO - PROCESS | 14356 | Values: 138.0 116.0 120.0 176.0 118.0 118.0 141.0 119.0 153.0 115.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 18:04:53 INFO - PROCESS | 14356 | Values: 161.0 161.0 161.0 161.0 161.0 161.0 162.0 162.0 160.0 160.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#3] audio-fft Cycles:10 Average:83.50 Median:75.00 stddev:18.70 (24.9%) stddev-sans-first:19.58 18:04:53 INFO - PROCESS | 14356 | Values: 75.0 76.0 76.0 130.0 74.0 75.0 75.0 76.0 104.0 74.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:83.00 stddev:18.71 (22.5%) stddev-sans-first:19.37 18:04:53 INFO - PROCESS | 14356 | Values: 83.0 84.0 112.0 83.0 83.0 83.0 121.0 84.0 83.0 130.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:04:53 INFO - PROCESS | 14356 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 18:04:53 INFO - PROCESS | 14356 | Values: 145.0 146.0 146.0 145.0 146.0 146.0 147.0 146.0 145.0 147.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:04:53 INFO - PROCESS | 14356 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 18:04:53 INFO - PROCESS | 14356 | Values: 51.0 52.0 51.0 52.0 51.0 52.0 50.0 51.0 51.0 51.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 18:04:53 INFO - PROCESS | 14356 | Values: 59.0 59.0 58.0 59.0 59.0 58.0 60.0 58.0 60.0 59.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.50 stddev:1.90 (2.6%) stddev-sans-first:2.01 18:04:53 INFO - PROCESS | 14356 | Values: 74.0 72.0 72.0 72.0 73.0 73.0 72.0 78.0 74.0 75.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:119.50 stddev:15.64 (13.1%) stddev-sans-first:15.53 18:04:53 INFO - PROCESS | 14356 | Values: 140.0 116.0 125.0 116.0 115.0 115.0 122.0 115.0 163.0 117.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.30 Median:150.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 18:04:53 INFO - PROCESS | 14356 | Values: 149.0 148.0 150.0 148.0 150.0 152.0 148.0 148.0 150.0 150.0 18:04:53 INFO - PROCESS | 14356 | 18:04:53 INFO - PROCESS | 14356 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:04:53 INFO - PROCESS | 14356 | Values: 61.0 61.0 62.0 60.0 62.0 61.0 62.0 60.0 60.0 61.0 18:04:53 INFO - PROCESS | 14356 | -------- Summary: end -------- 18:04:53 INFO - PROCESS | 14356 | 18:04:54 INFO - TEST-INFO | 14356: exit 0 18:04:54 INFO - TEST-OK | kraken | took 60109ms 18:04:54 INFO - SUITE-END | took 1173s 18:04:54 INFO - Completed test suite (00:19:33) 18:04:54 INFO - javascript benchmark 18:04:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.4887342461916, 3302.0754188827123, 3304.256542277987, 3329.7336213102953, 3354.0, 5623.268975033245, 5736.110902810065, 5766.567507436938, 5772.769089236436, 5776.913346299809, 5738.454846789558, 5820.970895145519, 5825.1844741736195, 5837.211976383187, 5838.299404071507, 5043.24351347299, 5174.767135478903, 5249.370075590934, 5283.207518872162, 5309.893802123975, 5065.746712664352, 5162.225666150089, 5188.766505507261, 5215.713135777531, 5217.686938783685, 5043.798248070073, 5075.111855425304, 5085.160948443502, 5095.898082038357, 5123.513266239701, 5159.432462429129, 5180.974095129519, 5187.144121219994, 5187.273781670569, 5200.219967004941, 5024.145895197825, 5083.567896728782, 5088.363954505687, 5093.2105523643895, 5118.897622047575, 5529.585281103893, 5583.106702927512, 5587.078132108198, 5594.021046316879, 5625.0, 6065.150878876961, 6097.237845269341, 6100.252520231485, 6103.542234332443, 6108.7251073701345, 5938.495227905633, 6051.152838602599, 6087.3259853540185, 6101.816945491598, 6144.96927515364, 5740.741666625012, 5752.137179423078, 5761.884762304772, 5762.193292938992, 5765.538756899438, 6234.688265586793, 6284.057391391237, 6298.244210694655, 6302.338254483213, 6304.747810087545, 5897.49871260939, 5941.1088336749835, 5952.821415357546, 5953.285605727254, 6038.607490513103, 6085.370593687403, 6099.961038571871, 6105.9694701526205, 6110.114033465123, 6113.29387775398, 6024.367441418675, 6028.156058151862, 6032.457078862835, 6036.0644100164545, 6045.72794224261, 5902.173695682608, 5956.106584012433, 6014.398560143951, 6059.0, 6072.423119803611, 4660.04469083837, 4692.812287508462, 4694.108119457293, 4698.2952557116705, 4712.882177945578, 4186.099988502487, 4197.664186865043, 4204.348326009474, 4224.049588842548, 4227.788610569459, 5622.943770562324, 5781.653100813965, 5783.913241301384, 5811.651300921958, 5870.941290587125, 6140.570160088841, 6140.815775526741, 6146.139540464339, 6146.323904370515, 6150.108234306001, 5858.941410585925, 5859.062549991981, 5861.560382971209, 5863.442972917566, 5887.087501437284, 5174.353205849269, 5268.9736551317765, 5285.656432331886, 5301.787928482818, 5384.326959130109, 6386.584871983307, 6405.615663060231, 6428.77499287532, 6438.645874476949, 6452.967735161388, 6158.476529495043, 6162.753489860355, 6167.876642467215, 6185.41238582334, 6189.84525386869, 6031.035034394476, 6263.154474146114, 6266.530010249341, 6283.748650053946, 6363.649999250086, 6060.212172417558, 6061.484773794277, 6069.119977603311, 6069.939300607114, 6103.481204097524, 6155.815325540241, 6159.630422174684, 6163.9383606163365, 6163.938360616516, 6168.228971378578, 5780.739866705924, 5904.940950590439, 5926.822195334147, 5977.6114552553945, 6069.423404776539, 5960.821175364746, 5995.730192141277, 6002.819915402545, 6004.549658775697, 6011.939880601138, 5946.0, 5954.0, 6003.8198854034445, 6006.129111278928, 6006.759729610942, 5901.940980590139, 5966.403359663999, 6017.247844019497, 6017.909731353946, 6102.664353460602, 5880.970595146997, 5895.793647222326, 5896.8820623586425, 5936.762529498945, 5938.61399009063], "value": 5633.0614713076675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.3723520626564, 628.6134027573042, 657.4904449051984, 663.6051549328148, 672.5359501943661, 34866.825665871664, 38660.806695966516, 38744.225115497706, 44352.334714979304, 46149.538504614946, 49059.0, 49674.75162624191, 49700.0, 49784.502154978436, 49802.50197498024, 9272.953635231814, 9433.76415589609, 9436.056394360543, 9436.386634868712, 9437.15065644092, 8626.137386261355, 9211.723648290563, 9308.674196403128, 10978.945105274463, 11229.438528073604, 11455.14086443516, 11525.48135333912, 11531.481083351227, 11532.71168220797, 11540.076793856513, 11336.206465547415, 11379.260348077349, 11401.315921044681, 11401.600943967005, 11409.258398204132, 11261.718457038558, 11352.886471135349, 11356.148288878285, 11362.26145300561, 11362.71593210168, 45585.316220256726, 46064.769676151736, 46213.0, 46221.0, 46236.075278494245, 3901.395283731026, 4042.1713548722737, 4095.8566450174103, 4358.237308471005, 4648.1168577970075, 34700.13249668753, 34743.0, 34792.0, 35069.64930350689, 35173.94478165659, 4617.930731039037, 4647.488776234612, 4708.905821883577, 4749.596284315838, 4787.616990640775, 369184.0, 372689.0, 374122.0, 375300.1234993835, 375778.0, 63364.366356336766, 63673.68163159154, 63726.36273637297, 63744.36255637477, 63802.36197638057, 38987.41518877162, 39802.80098599489, 39856.40215396771, 42384.0, 64945.35054649393, 19471.610567788564, 19688.409347719306, 19705.310314138937, 19706.211751530063, 19709.211631534865, 15009.924950375178, 15120.54638360851, 15191.164485953379, 15193.240337983278, 15216.467423640119, 18845.81154188468, 19210.231590736494, 20545.178192872118, 21152.576948460945, 21217.363479095653, 7276.745313913988, 7416.0729908761405, 7453.813654658677, 7601.391888648895, 7649.808754781175, 968.8304546704298, 1058.539535302146, 1059.750958524746, 1067.9839802402971, 1152.4698638626157, 34843.0, 34901.65098349035, 34997.65002349995, 35076.473852892224, 35342.64657353394, 1216.975660486803, 1223.1865809236936, 1227.2084505494086, 1229.0106464296327, 1231.2920070959055, 17991.190396432194, 18467.0, 18519.72220416695, 18584.81415185858, 18585.53536161607, 621157.0, 631816.0, 632354.0, 632813.0, 633435.0, 64248.67875660592, 64847.35152648413, 64907.35092649013, 64925.67537162284, 64999.675001625634, 1344.0524430276632, 1442.7114577084415, 1476.7415702252063, 1477.623206082442, 1482.458902500558, 34296.31407371889, 36491.0, 36603.63396366109, 36610.45084323791, 36612.450813238356, 54834.17748733692, 55419.0, 55441.0, 55567.166492501834, 55639.44360556343, 465236.0, 467820.0, 467989.0, 469114.0, 470819.0, 32533.186670332485, 33283.50074748929, 33432.0, 33761.324773504886, 33988.660113399535, 52801.0, 53172.46827531675, 53273.0, 53561.73219133879, 54030.729846350514, 43033.83501752252, 44293.55706442894, 44594.777026114665, 45902.0, 46751.0, 44285.33571996358, 45025.0, 45662.77168614136, 46610.300845488026, 47272.7636361816, 44042.898927527065, 44063.55936440723, 44550.0, 44865.77567112144, 44955.32567011432, 38144.427833583075, 39751.80124099477, 39945.80027099846, 40159.32847325127, 40561.18877622372, 30047.69952300449, 30180.5472917902, 30495.54256686107, 30588.036179183317, 30953.987990780526], "value": 22109.865090513154, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.6489656653861, 405.663299461447, 420.7832966022499, 428.4580006292041, 440.091211647947, 18058.90970545147, 20275.898620506912, 20827.27104551341, 20914.0, 20979.265725699603, 20293.28973485928, 20366.287179948667, 20647.17411303546, 20672.586548269024, 20842.166313347487, 5413.106837371839, 5729.255196824428, 5750.02249617566, 5754.251947246852, 5763.5101016413655, 4207.284761590537, 4386.34204869269, 5683.658980461165, 6242.9687851560675, 6477.54657173998, 6352.1742173517605, 6570.5729127606555, 6577.835554111138, 6580.967095164518, 6584.637844918528, 6444.130042444281, 6478.870422591545, 6514.185726784152, 6547.279799222106, 6563.146790917198, 5592.0, 5650.717464126818, 5695.515881150107, 5788.797392091256, 5802.622829516068, 25222.117225897007, 25365.23904282856, 27276.0, 27614.585781213296, 28084.297892552644, 2522.545941730488, 2940.338423854637, 3117.830813444958, 3458.4985177149424, 3560.5549306336707, 16359.509214723459, 17182.570435739082, 17681.027543485103, 17826.37607683738, 17982.730259046126, 2180.4875854174256, 2256.7968882800465, 2274.442761523421, 2330.790228879392, 2349.9647505287435, 89848.5507572458, 98745.0, 100008.99999999927, 101409.0, 104003.0, 18107.18517666708, 18209.63580728378, 18803.435896923114, 18981.905090474458, 19020.61958760817, 34246.82876585651, 34248.0, 34290.65709342925, 34339.313213735586, 34372.0, 10549.419781912069, 10551.364538496546, 10575.471226438647, 10630.893691063146, 11155.163362747664, 8573.142685731502, 8573.185547373114, 8614.91385086141, 9002.684906028258, 9007.954960225157, 9068.410553314014, 9580.808383832284, 9608.327417080738, 9762.658306959222, 9808.9019109808, 3335.5497007903623, 3423.281110966675, 3584.426491761306, 3662.1393972416463, 3716.7769933804057, 1060.6499855047816, 1062.1661995333625, 1086.1365214654368, 1097.5993762276871, 1105.7733164701217, 20609.690854637192, 21164.36506904795, 21284.574308513744, 21592.136314547555, 21604.891975540337, 1021.3820638513592, 1034.9793004140026, 1075.5590208014676, 1086.6468397770757, 1097.676185525256, 11133.443327833576, 11961.521539138337, 12092.0, 12164.5134194633, 12186.451609677586, 177440.0, 179029.0, 179490.0, 181438.0, 181951.0, 57153.0, 94224.52887735517, 99419.0, 99517.0, 99819.50090249503, 1027.4349107990574, 1051.264115119404, 1059.1209296284067, 1080.6920027792041, 1089.656758121205, 18474.70677052647, 18520.259189632263, 19132.808671913102, 19243.61512769765, 19330.323438679578, 46435.0, 47067.29399058948, 48156.75921620509, 48208.0, 48259.75870120627, 64991.67504162449, 65529.672351637935, 66104.66947665231, 66563.0, 67689.0, 7708.576028318272, 8667.0, 8679.783005424915, 8796.252318552995, 8871.112888711077, 11399.829002565135, 13026.934865325613, 13353.53262635803, 13593.59219223425, 13851.653708657364, 11796.0, 11895.405229738652, 11897.940510297392, 12044.819327709916, 12273.263604183778, 11540.24988375753, 11612.825807613062, 11809.0, 11847.052235820942, 11947.522099116286, 11222.831657524981, 11841.5855445059, 11880.82178767302, 12087.939560302493, 12098.0, 7807.609619519115, 7956.9602151988865, 7998.0, 8061.153578874134, 8201.83596328082, 9441.52792360393, 10080.344777589433, 11283.0, 11432.48553815097, 11692.47383867745], "value": 10401.551224508336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.538355418044, 1533.3866453418634, 1592.1800272859475, 1789.2038043070838, 1916.587933594277, 8951.194392504673, 9361.063893610635, 9363.11050450207, 9601.47191904445, 9643.324967252294, 9345.205657519102, 9461.053894610535, 9476.478793666345, 9485.0, 9485.573149208285, 2481.0944005437977, 2499.3626625210545, 2499.575072237724, 2504.8371855829405, 2505.08564374004, 2425.890834912433, 2510.410053637394, 2513.246026192144, 2514.509670614231, 2514.7485251474795, 2474.505098980202, 2483.888225029869, 2534.3284029732135, 2538.0736031348515, 2539.4794067216267, 2397.928062158138, 2410.168491870311, 2413.6862207913036, 2462.9507409851703, 2463.7782599566126, 2362.9645555316683, 2390.6414037894283, 2392.748761380065, 2397.4605713714272, 2413.179518963561, 9319.673811416636, 9511.857322140173, 9524.476153811538, 9565.282603804742, 9596.904030959671, 5341.893162136775, 5568.220449137124, 5585.692786896719, 5590.748416321276, 5594.720263986825, 3452.9654703452893, 3497.9475307870403, 3574.285142971416, 3576.856925722968, 3588.766730162531, 1180.3979970215166, 1198.6583823610229, 1204.0, 1204.2894692131597, 2255.458689914425, 12634.431450584745, 12809.743805123846, 13302.733945321137, 13336.933315333361, 13354.399052042585, 4867.269909513601, 4923.729194894315, 4987.177115775857, 5014.34813474246, 5026.271190677331, 10326.535305911251, 10445.11216546602, 10456.0, 10461.633842815465, 10462.215333849992, 5493.285872836505, 5500.4224556420995, 5508.55931525477, 5509.586780991442, 5511.751971161308, 4317.568243175658, 4444.533324000933, 5006.6495345325475, 5063.670861393997, 5156.587473002151, 4082.9591704083173, 4088.632023117934, 4121.113960498507, 4172.70791044624, 4214.831406743696, 1173.372245848467, 1204.2834513464475, 1207.8369420128347, 1208.6374087773809, 1212.3938030984507, 1052.905238528536, 1064.292245656645, 1073.6993641780314, 1074.8495210670512, 1091.5415525479318, 6182.381761823782, 6371.681415929185, 6428.0, 6497.057926600616, 6567.770128045591, 452.19734970427754, 454.6567341657028, 457.34371177360595, 467.04489319342025, 471.87495313741886, 4234.491860976703, 4391.560843915583, 4479.977600111979, 4606.239970404846, 4641.95358046422, 11495.080393568494, 12015.879841201651, 12016.038716902627, 12021.579244726567, 12062.939685301693, 11118.444077796077, 11626.76746465083, 11955.64133076009, 11975.64073077809, 12001.2199207053, 8798.34012449056, 8971.147740964729, 8983.146601072756, 8996.59515321812, 8999.235065019546, 6319.526035547445, 6493.740250389931, 6551.44312733404, 6658.300878407697, 6803.523753337417, 10538.841917371092, 10858.619948301772, 11046.447677616248, 11056.778864422506, 11115.777684446428, 9744.464054477072, 10802.837957430487, 10963.561457541928, 11271.549138034386, 11338.829917551078, 8543.487390756574, 8692.608832602422, 8727.476351418934, 8781.956090219508, 8798.47209167452, 9245.482468387434, 9252.768680783034, 9329.253659707072, 9338.719838404859, 9340.486273255035, 8414.537200454033, 8470.915290847013, 8569.742907712778, 8573.742787716128, 8598.097199793932, 8289.668413263402, 8304.916950830415, 8365.498070115813, 8443.324534037385, 8504.404691671523, 7806.648700808591, 7825.608719564113, 7887.40844436681, 7887.487313324616, 7911.604419779103, 5611.186377975252, 5676.659400435987, 5707.6290041147195, 5718.628289161191, 5876.676782776988, 5492.560595152298, 5747.195392645117, 5779.884402312015, 5797.130430435468, 5809.070548712186], "value": 4887.7159447411495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.9864005439783, 346.25036795338093, 348.17656242985345, 355.71187338256, 357.52270718590665, 5541.279633647625, 5650.886982260352, 5651.9152212716845, 5679.488846003859, 5704.942950570493, 5549.197655184126, 5585.413531579186, 5602.943970560294, 5625.971870140654, 5629.465200805927, 3834.6740527055235, 4110.445089912861, 4325.178216138924, 4332.393464914914, 4347.456567929009, 4322.7406355618605, 4326.502452217988, 4328.696991210617, 4337.436133302666, 4381.18947994622, 4339.481181586435, 4347.217500849846, 4350.73895566267, 4369.541198174194, 4387.232234358974, 4154.210699967011, 4259.957400425987, 4313.374560688682, 4324.891877703051, 4334.263175260214, 3811.3901775715754, 3817.0533028155564, 3822.7767114523363, 3825.9234815303817, 3871.2257548490416, 5410.864728381783, 5473.480019398151, 5475.315585551806, 5477.370102438191, 5479.2602998595075, 4810.134175848346, 4899.510048995107, 4900.240462728283, 4951.356323677899, 4987.351644286256, 5758.869218389181, 5772.30732312121, 5783.971080144573, 5793.2179155814265, 5822.883542329172, 4229.97885010573, 4232.4286221360035, 4242.8727138185595, 4246.363045543162, 4254.936175957363, 5658.207850900877, 5757.251557297524, 5854.97072514639, 6004.879902401971, 6086.1783659205885, 4130.855420060284, 4147.81334839933, 4150.605692459211, 4187.853425130106, 4194.49666040071, 4254.0, 4258.8296468140925, 4279.40088387626, 4281.272183728774, 4287.656987440997, 4736.573708366194, 4907.779149938261, 4908.619319629976, 4929.827456039022, 4930.1865192243595, 5849.596096936704, 5851.0, 5870.178175055496, 5876.941230587725, 5897.056470964625, 1490.9478168264059, 1510.8791296696238, 1511.2368254031644, 1518.7569988801738, 1519.3163076615567, 922.686286662538, 945.6312038305067, 948.8719022931954, 959.64972784933, 968.7820240445843, 5004.874878128076, 5095.694258344512, 5104.69371837691, 5104.846854594368, 5107.438181799999, 5201.271821944931, 5241.371035475691, 5272.92090618641, 5287.3126493556365, 5300.270360764132, 5054.69671819692, 5085.389753229636, 5125.846224613193, 5342.5993050521365, 5765.509931655773, 3161.65221825599, 3170.732925957951, 3174.904752857418, 3174.904752857418, 3183.0, 6124.969375153096, 6212.099245609452, 6227.346128656426, 6263.185785847811, 6289.842753931189, 6030.5778595497895, 6030.879382412415, 6032.426919442646, 6075.210222671025, 6098.54260930432, 6155.599886007395, 6165.0444181152, 6173.382661733791, 6175.84560386003, 6197.96901015492, 5777.335606405317, 5886.499647530008, 5911.201987731602, 5920.911186332295, 5978.861097923625, 5878.441548052928, 5907.49786268172, 5912.704364781658, 5923.703814809329, 5923.911141332797, 5735.193414074553, 5878.647281163144, 5900.822975310747, 5985.610935289193, 6152.661603611666, 4221.493420789525, 4246.150769845982, 4280.828766849416, 4287.571242875688, 4317.417148684887, 5991.85427650487, 6055.333913269449, 6099.75600975956, 6104.847378815565, 6112.419320164578, 5670.4329567044615, 5902.940970590239, 5907.970460147843, 5915.852103697442, 5941.732622032106, 5826.796062137805, 5828.970855145697, 5830.154627579062, 5872.324682661549, 5875.94124058771, 5813.680247586254, 5826.756698670957, 5841.474267315962, 5855.443732845373, 5865.917599252204, 5940.138679891305, 5958.7616495341435, 5963.761449541969, 5975.342712301556, 5982.295045911894, 5550.08423610116, 5720.570957178145, 5822.243108395882, 5866.67733274674, 5881.147233651182], "value": 4408.246127564324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.3418384935521, 878.3719640457076, 891.8350105230531, 919.2554031234696, 940.8635747816567, 16307.347706091741, 17467.563310917216, 17575.209115589798, 17609.823901760978, 17620.911895440506, 16293.103879286635, 16529.0, 16537.255823487943, 16568.66862662746, 16604.0, 739.988900166498, 864.6973559254274, 885.0308911741648, 886.6276164011099, 912.7535565397338, 901.1484147480634, 905.950172740499, 907.4827348411408, 908.1009800297691, 909.0954500272205, 958.7123862841155, 959.6785076999214, 960.8318544254762, 961.0, 961.903809619036, 788.8185717285028, 914.8947870994854, 933.1461712533024, 933.276710549323, 959.8128364968834, 692.5325405351367, 692.9411000065, 693.2097408953797, 693.4937495628169, 693.5873155472536, 15297.694046119015, 15396.153211573359, 15408.152551609657, 15419.768703469457, 15547.378104875792, 15349.46276880315, 15516.069035857885, 15619.921900390425, 15722.528324150293, 15765.763513547307, 14015.64960875976, 14231.644208894757, 14431.49489767853, 14584.270786460635, 14747.410103595843, 4467.709598876095, 4487.932681009787, 4551.704139230939, 4580.679352445331, 4605.700629459108, 81414.0, 85547.14452855516, 86511.0, 86586.0, 87335.81660458476, 12384.56654017105, 12393.814092788616, 12430.192037517532, 12458.377081145907, 12510.687232819253, 23311.650325245137, 23525.88237058804, 23849.880750596138, 23873.16443924489, 23884.7611523886, 9284.0, 9330.626774929062, 9373.765655858659, 9418.576164072496, 9483.762905927408, 5132.589392848563, 5153.5361817436615, 5211.921821172686, 5290.761915713802, 5466.125419932791, 3783.3379158647126, 3949.4273330366937, 3951.585083566242, 3951.68386529077, 3955.4660120883314, 752.9096508419025, 772.818387678895, 773.389022672094, 788.7949133225403, 788.9211078892065, 727.4035291061278, 778.7702627724722, 814.8003739083844, 837.0833936839151, 848.6987119572462, 9827.4103553787, 10685.57257709683, 10815.513301901434, 10836.0, 10879.782404352027, 684.2439104789236, 775.9883601745978, 785.4187900953253, 785.4933567848706, 837.4598384042258, 5074.391073071181, 5137.12668846297, 5147.871303217449, 5164.690118592896, 5256.1327380982475, 68368.0, 70136.298637014, 70299.2970070303, 80611.19388806155, 80680.0, 100370.0, 100653.0, 101542.0, 103898.0, 106503.0, 85963.0, 88858.55570722235, 90773.63839542412, 91077.54461227784, 91307.0, 10915.290506117077, 11081.27971681838, 11213.71965700864, 11221.495032723711, 11317.547298107984, 51838.740806295726, 52222.0, 52558.0, 53159.20261196163, 53879.0, 74006.2599374021, 78274.0, 81046.0, 83282.0, 83935.580322098, 13106.2136271824, 13441.193528388329, 13614.0, 13828.585142445743, 13975.231362274766, 15421.0, 15780.0, 16293.185340732678, 16385.50843474698, 16936.66126677484, 14327.28363581796, 14462.710745785236, 15059.548213553611, 15594.220288985733, 15627.453039143575, 14207.147571145764, 14342.713145736818, 14556.781648275497, 15502.767458487906, 15743.212839358215, 14221.64445888861, 14762.704745904806, 15264.084154950739, 15439.691206176038, 15503.457378991681, 11776.587819426279, 11821.290722556674, 11828.881711182778, 11830.82253766177, 12639.115261931576, 11779.938510760754, 11861.0, 11966.940165299118, 12108.273503589813, 12252.326122063372], "value": 8210.857989568642, "unit": "score"}], "name": "dromaeo_css", "value": 7822.984099503242, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 110.0, 108.0, 111.0, 108.0, 108.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 116.0, 120.0, 176.0, 118.0, 118.0, 141.0, 119.0, 153.0, 115.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 130.0, 74.0, 75.0, 75.0, 76.0, 104.0, 74.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 112.0, 83.0, 83.0, 83.0, 121.0, 84.0, 83.0, 130.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 51.0, 52.0, 51.0, 52.0, 50.0, 51.0, 51.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 58.0, 60.0, 58.0, 60.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 72.0, 72.0, 73.0, 73.0, 72.0, 78.0, 74.0, 75.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 125.0, 116.0, 115.0, 115.0, 122.0, 115.0, 163.0, 117.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 148.0, 150.0, 152.0, 148.0, 148.0, 150.0, 150.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 60.0, 62.0, 61.0, 62.0, 60.0, 60.0, 61.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1454.6000000000001, "alertThreshold": 2.0}]} 18:04:54 INFO - Return code: 0 18:04:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:04:54 INFO - # TBPL SUCCESS # 18:04:54 INFO - Running post-action listener: _resource_record_post_action 18:04:54 INFO - [mozharness: 2016-08-13 01:04:54.447584Z] Finished run-tests step (success) 18:04:54 INFO - Running post-run listener: _resource_record_post_run 18:04:54 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327188480; Read time: 8; Write time: 217316 18:04:54 INFO - TinderboxPrint: CPU usage
12.9% 18:04:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:04:54 INFO - TinderboxPrint: I/O write bytes / time
327,188,480 / 217,316 18:04:54 INFO - TinderboxPrint: CPU idle
8,274.7 (87.0%) 18:04:54 INFO - TinderboxPrint: CPU user
1,185.2 (12.5%) 18:04:54 INFO - TinderboxPrint: Swap in / out
0 / 0 18:04:54 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78536704; Read time: 0; Write time: 45712 18:04:54 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248041472; Read time: 8; Write time: 171604 18:04:55 INFO - Running post-run listener: _upload_blobber_files 18:04:55 INFO - Blob upload gear active. 18:04:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:04:55 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 18:04:55 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:04:55 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:04:55 INFO - (blobuploader) - INFO - Open directory for files ... 18:04:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:04:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:04:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:04:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:04:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:04:56 INFO - (blobuploader) - INFO - Done attempting. 18:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:00 INFO - (blobuploader) - INFO - Done attempting. 18:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:05:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:01 INFO - (blobuploader) - INFO - Done attempting. 18:05:01 INFO - (blobuploader) - INFO - Iteration through files over. 18:05:01 INFO - Return code: 0 18:05:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:05:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:05:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a91e7930aedcc8f28889d39c5ebfbfa4ef9e330328987d7c8520b33d5067e4e06291ae45298a47a6918cbcad4c103e254f71b2b5797d766075ce61053df4702", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/715269728461b0bd5086f6c2b0191f362ff9d51c9f668968cfcf7df9210ee84908899d2c16827d5c58c41dac4043328c2f6b07109373afcdcc8d8024b03df0f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce3579fb5dd297c3a561bd42785128b64c086a5e555e59daa5376df0b675b41e8c84e0255e165ccd89379e7b7fa59a9eb28ab4e9df9c3012b71f9aa58d808231"} 18:05:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:05:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:05:01 INFO - Contents: 18:05:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a91e7930aedcc8f28889d39c5ebfbfa4ef9e330328987d7c8520b33d5067e4e06291ae45298a47a6918cbcad4c103e254f71b2b5797d766075ce61053df4702", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/715269728461b0bd5086f6c2b0191f362ff9d51c9f668968cfcf7df9210ee84908899d2c16827d5c58c41dac4043328c2f6b07109373afcdcc8d8024b03df0f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce3579fb5dd297c3a561bd42785128b64c086a5e555e59daa5376df0b675b41e8c84e0255e165ccd89379e7b7fa59a9eb28ab4e9df9c3012b71f9aa58d808231"} 18:05:01 INFO - Running post-run listener: copy_logs_to_upload_dir 18:05:01 INFO - Copying logs to upload dir... 18:05:01 INFO - mkdir: /builds/slave/test/build/upload/logs 18:05:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.439934 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-12 18:05:00.659764) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 18:05:00.663569) ========= 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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a91e7930aedcc8f28889d39c5ebfbfa4ef9e330328987d7c8520b33d5067e4e06291ae45298a47a6918cbcad4c103e254f71b2b5797d766075ce61053df4702", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/715269728461b0bd5086f6c2b0191f362ff9d51c9f668968cfcf7df9210ee84908899d2c16827d5c58c41dac4043328c2f6b07109373afcdcc8d8024b03df0f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce3579fb5dd297c3a561bd42785128b64c086a5e555e59daa5376df0b675b41e8c84e0255e165ccd89379e7b7fa59a9eb28ab4e9df9c3012b71f9aa58d808231"} build_url:https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/F8jmALBQR2S2st2MIcys-A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a91e7930aedcc8f28889d39c5ebfbfa4ef9e330328987d7c8520b33d5067e4e06291ae45298a47a6918cbcad4c103e254f71b2b5797d766075ce61053df4702", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/715269728461b0bd5086f6c2b0191f362ff9d51c9f668968cfcf7df9210ee84908899d2c16827d5c58c41dac4043328c2f6b07109373afcdcc8d8024b03df0f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce3579fb5dd297c3a561bd42785128b64c086a5e555e59daa5376df0b675b41e8c84e0255e165ccd89379e7b7fa59a9eb28ab4e9df9c3012b71f9aa58d808231"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 18:05:00.691968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:05:00.692362) ========= 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=232498196343f89f466a4a9a000001c8-1471048618.693080-902302048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:05:00.773414) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 18:05:00.773829) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 18:05:00.774281) ========= ========= Total master_lag: 0.15 =========