builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1473257302.56 results: success (0) buildid: 20160907004009 builduid: 4e2b6e876f594fbe91551200ebdb17c0 revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.562516) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.563051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.563330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.578083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.578423) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-07 07:08:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-09-07 07:08:23 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125601 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.717962) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.718285) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016074 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.768128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:22.768476) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --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 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-07 07:08:23,319 truncating revision to first 12 chars 2016-09-07 07:08:23,319 Setting DEBUG logging. 2016-09-07 07:08:23,319 attempt 1/10 2016-09-07 07:08:23,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a0fd1bb116a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 07:08:23,623 unpacking tar archive at: mozilla-aurora-3a0fd1bb116a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623839 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:23.405501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:23.405814) ========= script_repo_revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:23.406222) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:23.406490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 07:08:23.414809) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-07 07:08:23.415087) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:08:24 INFO - MultiFileLogger online at 20160907 07:08:24 in /builds/slave/test 07:08:24 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 07:08:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:08:24 INFO - {'append_to_log': False, 07:08:24 INFO - 'base_work_dir': '/builds/slave/test', 07:08:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:08:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:08:24 INFO - 'branch': 'Mozilla-Aurora', 07:08:24 INFO - 'buildbot_json_path': 'buildprops.json', 07:08:24 INFO - 'config_files': ('talos/linux_config.py',), 07:08:24 INFO - 'default_actions': ('clobber', 07:08:24 INFO - 'read-buildbot-config', 07:08:24 INFO - 'download-and-extract', 07:08:24 INFO - 'populate-webroot', 07:08:24 INFO - 'create-virtualenv', 07:08:24 INFO - 'install', 07:08:24 INFO - 'run-tests'), 07:08:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:08:24 INFO - 'download_minidump_stackwalk': True, 07:08:24 INFO - 'download_symbols': 'ondemand', 07:08:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:08:24 INFO - 'tooltool.py': '/tools/tooltool.py', 07:08:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:08:24 INFO - '/tools/misc-python/virtualenv.py')}, 07:08:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:08:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:08:24 INFO - 'installer_path': 'installer.exe', 07:08:24 INFO - 'log_level': 'info', 07:08:24 INFO - 'log_name': 'talos', 07:08:24 INFO - 'log_to_console': True, 07:08:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:08:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:08:24 INFO - 'opt_config_files': (), 07:08:24 INFO - 'pip_index': False, 07:08:24 INFO - 'sps_profile': False, 07:08:24 INFO - 'sps_profile_interval': 0, 07:08:24 INFO - 'suite': 'dromaeojs', 07:08:24 INFO - 'system_bits': '32', 07:08:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:08:24 INFO - 'title': 'talos-linux64-ix-037', 07:08:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:08:24 INFO - 'use_talos_json': True, 07:08:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:08:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:08:24 INFO - 'work_dir': 'build'} 07:08:24 INFO - [mozharness: 2016-09-07 14:08:24.054520Z] Running clobber step. 07:08:24 INFO - Running pre-action listener: _resource_record_pre_action 07:08:24 INFO - Running main action method: clobber 07:08:24 INFO - rmtree: /builds/slave/test/build 07:08:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:08:24 INFO - Running post-action listener: _resource_record_post_action 07:08:24 INFO - [mozharness: 2016-09-07 14:08:24.536259Z] Finished clobber step (success) 07:08:24 INFO - [mozharness: 2016-09-07 14:08:24.536360Z] Running read-buildbot-config step. 07:08:24 INFO - Running pre-action listener: _resource_record_pre_action 07:08:24 INFO - Running main action method: read_buildbot_config 07:08:24 INFO - Using buildbot properties: 07:08:24 INFO - { 07:08:24 INFO - "project": "", 07:08:24 INFO - "product": "firefox", 07:08:24 INFO - "script_repo_revision": "production", 07:08:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:08:24 INFO - "repository": "", 07:08:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:08:24 INFO - "buildid": "20160907004009", 07:08:24 INFO - "stage_platform": "linux64-pgo", 07:08:24 INFO - "basedir": "/builds/slave/test", 07:08:24 INFO - "nightly_build": "True", 07:08:24 INFO - "buildnumber": 509, 07:08:24 INFO - "slavename": "talos-linux64-ix-037", 07:08:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:08:24 INFO - "platform": "ubuntu64_hw", 07:08:24 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:08:24 INFO - "branch": "mozilla-aurora", 07:08:24 INFO - "repo_path": "releases/mozilla-aurora", 07:08:24 INFO - "revision": "3a0fd1bb116a3b79b6f33462a04144818b550ee9", 07:08:24 INFO - "pgo_build": "True", 07:08:24 INFO - "builduid": "4e2b6e876f594fbe91551200ebdb17c0", 07:08:24 INFO - "slavebuilddir": "test" 07:08:24 INFO - } 07:08:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:08:24 INFO - Running post-action listener: _resource_record_post_action 07:08:24 INFO - [mozharness: 2016-09-07 14:08:24.538896Z] Finished read-buildbot-config step (success) 07:08:24 INFO - [mozharness: 2016-09-07 14:08:24.538986Z] Running download-and-extract step. 07:08:24 INFO - Running pre-action listener: _resource_record_pre_action 07:08:24 INFO - Running main action method: download_and_extract 07:08:24 INFO - mkdir: /builds/slave/test/build/tests 07:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:08:24 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:08:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:08:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:08:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:08:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:08:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:08:24 INFO - retry: Failed, sleeping 30 seconds before retrying 07:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:08:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:08:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:08:54 INFO - retry: Failed, sleeping 60 seconds before retrying 07:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:09:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:09:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:09:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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! 07:09:54 INFO - Caught exception: HTTP Error 404: Not Found 07:09:54 INFO - Caught exception: HTTP Error 404: Not Found 07:09:54 INFO - Caught exception: HTTP Error 404: Not Found 07:09:54 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:09:54 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:09:55 INFO - Downloaded 1361 bytes. 07:09:55 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:09:55 INFO - Using the following test package requirements: 07:09:55 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:09:55 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:09:55 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:09:55 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'jsshell-linux-x86_64.zip'], 07:09:55 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:09:55 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:09:55 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:09:55 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:09:55 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:09:55 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:09:55 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:09:55 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:09: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')]} 07:09:55 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:09:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:09:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:09:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:09:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:09:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:09:55 INFO - retry: Failed, sleeping 30 seconds before retrying 07:10:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:10:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:10:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:10:25 INFO - retry: Failed, sleeping 60 seconds before retrying 07:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:11:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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! 07:11:25 INFO - Caught exception: HTTP Error 404: Not Found 07:11:25 INFO - Caught exception: HTTP Error 404: Not Found 07:11:25 INFO - Caught exception: HTTP Error 404: Not Found 07:11:25 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:26 INFO - Downloaded 21768581 bytes. 07:11:26 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'] 07:11:26 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 07:11:27 INFO - Return code: 0 07:11:27 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 07:11:27 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')]} 07:11:27 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:11:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:11:27 INFO - retry: Failed, sleeping 30 seconds before retrying 07:11:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:11:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:11:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:11:57 INFO - retry: Failed, sleeping 60 seconds before retrying 07:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:12:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:12:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:12:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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! 07:12:57 INFO - Caught exception: HTTP Error 404: Not Found 07:12:57 INFO - Caught exception: HTTP Error 404: Not Found 07:12:57 INFO - Caught exception: HTTP Error 404: Not Found 07:12:57 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:12:57 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:12:58 INFO - Downloaded 21768581 bytes. 07:12:58 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'] 07:12:58 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 07:12:59 INFO - Return code: 0 07:12:59 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')]} 07:12:59 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:12:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:12:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:12:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:12:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:12:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:12:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:12:59 INFO - retry: Failed, sleeping 30 seconds before retrying 07:13:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:13:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:13:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:13:29 INFO - retry: Failed, sleeping 60 seconds before retrying 07:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:14:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:14:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:14:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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! 07:14:29 INFO - Caught exception: HTTP Error 404: Not Found 07:14:29 INFO - Caught exception: HTTP Error 404: Not Found 07:14:29 INFO - Caught exception: HTTP Error 404: Not Found 07:14:29 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:14:30 INFO - Downloaded 14413740 bytes. 07:14:30 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'] 07:14:30 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 07:14:30 INFO - Return code: 0 07:14:30 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')]} 07:14:30 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:14:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:14:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:14:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:14:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:14:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:14:30 INFO - retry: Failed, sleeping 30 seconds before retrying 07:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:15:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:15:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:15:00 INFO - retry: Failed, sleeping 60 seconds before retrying 07:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:16:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:16:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:16:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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! 07:16:00 INFO - Caught exception: HTTP Error 404: Not Found 07:16:00 INFO - Caught exception: HTTP Error 404: Not Found 07:16:00 INFO - Caught exception: HTTP Error 404: Not Found 07:16:00 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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 07:16:02 INFO - Downloaded 63871392 bytes. 07:16:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:16:02 INFO - mkdir: /builds/slave/test/properties 07:16:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:16:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:16:02 INFO - Contents: 07:16:02 INFO - build_url:https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:16:02 INFO - Running post-action listener: _resource_record_post_action 07:16:02 INFO - Running post-action listener: set_extra_try_arguments 07:16:02 INFO - [mozharness: 2016-09-07 14:16:02.718655Z] Finished download-and-extract step (success) 07:16:02 INFO - [mozharness: 2016-09-07 14:16:02.718889Z] Running populate-webroot step. 07:16:02 INFO - Running pre-action listener: _resource_record_pre_action 07:16:02 INFO - Running main action method: populate_webroot 07:16:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:16:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:16:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:16:02 INFO - '--tppagecycles', 07:16:02 INFO - '7'], 07:16:02 INFO - 'tests': ['tsvgm']}}, 07:16:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:16:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:16:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:16:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:16:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['damp', 'tps']}, 07:16:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'tests': ['damp', 'tps']}, 07:16:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['dromaeo_dom']}, 07:16:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:16:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['basic_compositor_video']}, 07:16:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:16:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['a11yr', 07:16:02 INFO - 'ts_paint', 07:16:02 INFO - 'tpaint', 07:16:02 INFO - 'sessionrestore', 07:16:02 INFO - 'sessionrestore_no_auto_restore', 07:16:02 INFO - 'tabpaint']}, 07:16:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['tsvgx', 07:16:02 INFO - 'tsvgr_opacity', 07:16:02 INFO - 'tart', 07:16:02 INFO - 'tscrollx', 07:16:02 INFO - 'cart']}, 07:16:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:16:02 INFO - 'tsvgr_opacity', 07:16:02 INFO - 'tart', 07:16:02 INFO - 'tscrollx', 07:16:02 INFO - 'cart']}, 07:16:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'talos_options': ['--disable-e10s'], 07:16:02 INFO - 'tests': ['tp5o']}, 07:16:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'tests': ['tp5o']}, 07:16:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'talos_options': ['--disable-e10s', 07:16:02 INFO - '--xperf_path', 07:16:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:02 INFO - 'tests': ['tp5n']}, 07:16:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:02 INFO - 'talos_options': ['--xperf_path', 07:16:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:02 INFO - 'tests': ['tp5n']}}, 07:16:02 INFO - 'talos.zip': {'path': '', 07:16:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:16:02 INFO - Running post-action listener: _resource_record_post_action 07:16:02 INFO - [mozharness: 2016-09-07 14:16:02.739002Z] Finished populate-webroot step (success) 07:16:02 INFO - [mozharness: 2016-09-07 14:16:02.739093Z] Running create-virtualenv step. 07:16:02 INFO - Running pre-action listener: _resource_record_pre_action 07:16:02 INFO - Running main action method: create_virtualenv 07:16:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:16:02 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 07:16:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:16:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:16:02 INFO - Using real prefix '/usr' 07:16:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:16:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:16:05 INFO - Installing pip.................done. 07:16:05 INFO - Return code: 0 07:16:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:16:05 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')]} 07:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:05 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')]} 07:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:05 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:05 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 07:16:05 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 07:16:05 INFO - Using env: {'DISPLAY': ':0', 07:16:05 INFO - 'HOME': '/home/cltbld', 07:16:05 INFO - 'LANG': 'en_US.UTF-8', 07:16:05 INFO - 'LANGUAGE': 'en_US:en', 07:16:05 INFO - 'LOGNAME': 'cltbld', 07:16:05 INFO - 'MAIL': '/var/mail/cltbld', 07:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:05 INFO - 'MOZ_NO_REMOTE': '1', 07:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:05 INFO - 'NO_EM_RESTART': '1', 07:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:05 INFO - 'PWD': '/builds/slave/test', 07:16:05 INFO - 'SHELL': '/bin/bash', 07:16:05 INFO - 'SHLVL': '1', 07:16:05 INFO - 'TERM': 'linux', 07:16:05 INFO - 'TMOUT': '86400', 07:16:05 INFO - 'USER': 'cltbld', 07:16:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:05 INFO - '_': '/tools/buildbot/bin/python'} 07:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:05 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 07:16:05 INFO - Cleaning up... 07:16:05 INFO - Return code: 0 07:16:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:16:05 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')]} 07:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:05 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')]} 07:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:05 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:05 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 07:16:05 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 07:16:05 INFO - Using env: {'DISPLAY': ':0', 07:16:05 INFO - 'HOME': '/home/cltbld', 07:16:05 INFO - 'LANG': 'en_US.UTF-8', 07:16:05 INFO - 'LANGUAGE': 'en_US:en', 07:16:05 INFO - 'LOGNAME': 'cltbld', 07:16:05 INFO - 'MAIL': '/var/mail/cltbld', 07:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:05 INFO - 'MOZ_NO_REMOTE': '1', 07:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:05 INFO - 'NO_EM_RESTART': '1', 07:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:05 INFO - 'PWD': '/builds/slave/test', 07:16:05 INFO - 'SHELL': '/bin/bash', 07:16:05 INFO - 'SHLVL': '1', 07:16:05 INFO - 'TERM': 'linux', 07:16:05 INFO - 'TMOUT': '86400', 07:16:05 INFO - 'USER': 'cltbld', 07:16:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:05 INFO - '_': '/tools/buildbot/bin/python'} 07:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:05 INFO - Downloading/unpacking psutil>=3.1.1 07:16:05 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 07:16:05 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 07:16:05 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 07:16:05 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 07:16:05 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 07:16:05 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 07:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:16:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:07 INFO - Installing collected packages: psutil 07:16:07 INFO - Running setup.py install for psutil 07:16:07 INFO - building 'psutil._psutil_linux' extension 07:16:07 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 07:16:07 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 07:16:07 INFO - building 'psutil._psutil_posix' extension 07:16:07 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 07:16:08 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 07:16:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:08 INFO - Successfully installed psutil 07:16:08 INFO - Cleaning up... 07:16:08 INFO - Return code: 0 07:16:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:08 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:08 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 07:16:08 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 07:16:08 INFO - Using env: {'DISPLAY': ':0', 07:16:08 INFO - 'HOME': '/home/cltbld', 07:16:08 INFO - 'LANG': 'en_US.UTF-8', 07:16:08 INFO - 'LANGUAGE': 'en_US:en', 07:16:08 INFO - 'LOGNAME': 'cltbld', 07:16:08 INFO - 'MAIL': '/var/mail/cltbld', 07:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:08 INFO - 'MOZ_NO_REMOTE': '1', 07:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:08 INFO - 'NO_EM_RESTART': '1', 07:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:08 INFO - 'PWD': '/builds/slave/test', 07:16:08 INFO - 'SHELL': '/bin/bash', 07:16:08 INFO - 'SHLVL': '1', 07:16:08 INFO - 'TERM': 'linux', 07:16:08 INFO - 'TMOUT': '86400', 07:16:08 INFO - 'USER': 'cltbld', 07:16:08 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:08 INFO - '_': '/tools/buildbot/bin/python'} 07:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:16:08 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 07:16:08 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 07:16:08 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 07:16:08 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 07:16:08 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 07:16:08 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 07:16:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:16:10 INFO - Installing collected packages: mozsystemmonitor 07:16:10 INFO - Running setup.py install for mozsystemmonitor 07:16:10 INFO - Successfully installed mozsystemmonitor 07:16:10 INFO - Cleaning up... 07:16:10 INFO - Return code: 0 07:16:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:16:10 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')]} 07:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:10 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')]} 07:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:10 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:10 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 07:16:10 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 07:16:10 INFO - Using env: {'DISPLAY': ':0', 07:16:10 INFO - 'HOME': '/home/cltbld', 07:16:10 INFO - 'LANG': 'en_US.UTF-8', 07:16:10 INFO - 'LANGUAGE': 'en_US:en', 07:16:10 INFO - 'LOGNAME': 'cltbld', 07:16:10 INFO - 'MAIL': '/var/mail/cltbld', 07:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:10 INFO - 'MOZ_NO_REMOTE': '1', 07:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:10 INFO - 'NO_EM_RESTART': '1', 07:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:10 INFO - 'PWD': '/builds/slave/test', 07:16:10 INFO - 'SHELL': '/bin/bash', 07:16:10 INFO - 'SHLVL': '1', 07:16:10 INFO - 'TERM': 'linux', 07:16:10 INFO - 'TMOUT': '86400', 07:16:10 INFO - 'USER': 'cltbld', 07:16:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:10 INFO - '_': '/tools/buildbot/bin/python'} 07:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:16:10 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 07:16:10 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 07:16:10 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 07:16:10 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 07:16:10 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 07:16:10 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 07:16:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:16:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:16:12 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 07:16:12 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 07:16:12 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 07:16:12 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 07:16:12 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 07:16:12 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 07:16:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:16:13 INFO - warning: no files found matching '*.txt' 07:16:13 INFO - no previously-included directories found matching 'build' 07:16:13 INFO - no previously-included directories found matching 'dist' 07:16:13 INFO - no previously-included directories found matching '.git*' 07:16:13 INFO - Installing collected packages: jsonschema, functools32 07:16:13 INFO - Running setup.py install for functools32 07:16:13 INFO - warning: no files found matching '*.txt' 07:16:13 INFO - no previously-included directories found matching 'build' 07:16:13 INFO - no previously-included directories found matching 'dist' 07:16:13 INFO - no previously-included directories found matching '.git*' 07:16:13 INFO - Successfully installed jsonschema functools32 07:16:13 INFO - Cleaning up... 07:16:13 INFO - Return code: 0 07:16:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:16:13 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')]} 07:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 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')]} 07:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:13 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 07:16:13 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 07:16:13 INFO - Using env: {'DISPLAY': ':0', 07:16:13 INFO - 'HOME': '/home/cltbld', 07:16:13 INFO - 'LANG': 'en_US.UTF-8', 07:16:13 INFO - 'LANGUAGE': 'en_US:en', 07:16:13 INFO - 'LOGNAME': 'cltbld', 07:16:13 INFO - 'MAIL': '/var/mail/cltbld', 07:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:13 INFO - 'MOZ_NO_REMOTE': '1', 07:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:13 INFO - 'NO_EM_RESTART': '1', 07:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:13 INFO - 'PWD': '/builds/slave/test', 07:16:13 INFO - 'SHELL': '/bin/bash', 07:16:13 INFO - 'SHLVL': '1', 07:16:13 INFO - 'TERM': 'linux', 07:16:13 INFO - 'TMOUT': '86400', 07:16:13 INFO - 'USER': 'cltbld', 07:16:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:13 INFO - '_': '/tools/buildbot/bin/python'} 07:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:16:13 INFO - Cleaning up... 07:16:13 INFO - Return code: 0 07:16:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:16:13 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')]} 07:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 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')]} 07:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:13 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 07:16:13 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 07:16:13 INFO - Using env: {'DISPLAY': ':0', 07:16:13 INFO - 'HOME': '/home/cltbld', 07:16:13 INFO - 'LANG': 'en_US.UTF-8', 07:16:13 INFO - 'LANGUAGE': 'en_US:en', 07:16:13 INFO - 'LOGNAME': 'cltbld', 07:16:13 INFO - 'MAIL': '/var/mail/cltbld', 07:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:13 INFO - 'MOZ_NO_REMOTE': '1', 07:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:13 INFO - 'NO_EM_RESTART': '1', 07:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:13 INFO - 'PWD': '/builds/slave/test', 07:16:13 INFO - 'SHELL': '/bin/bash', 07:16:13 INFO - 'SHLVL': '1', 07:16:13 INFO - 'TERM': 'linux', 07:16:13 INFO - 'TMOUT': '86400', 07:16:13 INFO - 'USER': 'cltbld', 07:16:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:13 INFO - '_': '/tools/buildbot/bin/python'} 07:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:16:13 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 07:16:13 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 07:16:13 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 07:16:13 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 07:16:13 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 07:16:13 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 07:16:15 INFO - Downloading blobuploader-1.2.4.tar.gz 07:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:16:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:16:15 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 07:16:15 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 07:16:15 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 07:16:15 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 07:16:15 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 07:16:15 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 07:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:16:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:16:16 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 07:16:16 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 07:16:16 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 07:16:16 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 07:16:16 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 07:16:16 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 07:16:16 INFO - Downloading docopt-0.6.1.tar.gz 07:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:16:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:16:16 INFO - Running setup.py install for blobuploader 07:16:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:16:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:16:16 INFO - Running setup.py install for requests 07:16:16 INFO - Running setup.py install for docopt 07:16:16 INFO - Successfully installed blobuploader requests docopt 07:16:16 INFO - Cleaning up... 07:16:16 INFO - Return code: 0 07:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:16 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')]} 07:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:16 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')]} 07:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:16 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:16 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 07:16:16 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 07:16:16 INFO - Using env: {'DISPLAY': ':0', 07:16:16 INFO - 'HOME': '/home/cltbld', 07:16:16 INFO - 'LANG': 'en_US.UTF-8', 07:16:16 INFO - 'LANGUAGE': 'en_US:en', 07:16:16 INFO - 'LOGNAME': 'cltbld', 07:16:16 INFO - 'MAIL': '/var/mail/cltbld', 07:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:16 INFO - 'MOZ_NO_REMOTE': '1', 07:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:16 INFO - 'NO_EM_RESTART': '1', 07:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:16 INFO - 'PWD': '/builds/slave/test', 07:16:16 INFO - 'SHELL': '/bin/bash', 07:16:16 INFO - 'SHLVL': '1', 07:16:16 INFO - 'TERM': 'linux', 07:16:16 INFO - 'TMOUT': '86400', 07:16:16 INFO - 'USER': 'cltbld', 07:16:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:16 INFO - '_': '/tools/buildbot/bin/python'} 07:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:17 INFO - Running setup.py (path:/tmp/pip-8Z6X4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:17 INFO - Running setup.py (path:/tmp/pip-ykjSuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:17 INFO - Running setup.py (path:/tmp/pip-CxfTOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:17 INFO - Running setup.py (path:/tmp/pip-pjBTmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:17 INFO - Running setup.py (path:/tmp/pip-n5Cflu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:17 INFO - Running setup.py (path:/tmp/pip-HMjq12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:17 INFO - Running setup.py (path:/tmp/pip-0tjF0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:17 INFO - Running setup.py (path:/tmp/pip-2COD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:17 INFO - Running setup.py (path:/tmp/pip-uW4FdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:17 INFO - Running setup.py (path:/tmp/pip-NhmdFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:17 INFO - Running setup.py (path:/tmp/pip-FGgjbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:17 INFO - Running setup.py (path:/tmp/pip-6tXm9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:18 INFO - Running setup.py (path:/tmp/pip-uRdYlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:18 INFO - Running setup.py (path:/tmp/pip-DnrpEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:18 INFO - Running setup.py (path:/tmp/pip-1njit2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:18 INFO - Running setup.py (path:/tmp/pip-t7ntVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:18 INFO - Running setup.py (path:/tmp/pip-J9epT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:16:18 INFO - Running setup.py install for manifestparser 07:16:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:16:18 INFO - Running setup.py install for mozcrash 07:16:18 INFO - Running setup.py install for mozdebug 07:16:18 INFO - Running setup.py install for mozdevice 07:16:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:16:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:16:18 INFO - Running setup.py install for mozfile 07:16:18 INFO - Running setup.py install for mozhttpd 07:16:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozinfo 07:16:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozInstall 07:16:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozleak 07:16:19 INFO - Running setup.py install for mozlog 07:16:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for moznetwork 07:16:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozprocess 07:16:19 INFO - Running setup.py install for mozprofile 07:16:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozrunner 07:16:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:16:19 INFO - Running setup.py install for mozscreenshot 07:16:19 INFO - Running setup.py install for moztest 07:16:20 INFO - Running setup.py install for mozversion 07:16:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:16:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:16:20 INFO - Cleaning up... 07:16:20 INFO - Return code: 0 07:16:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:20 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:20 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 07:16:20 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 07:16:20 INFO - Using env: {'DISPLAY': ':0', 07:16:20 INFO - 'HOME': '/home/cltbld', 07:16:20 INFO - 'LANG': 'en_US.UTF-8', 07:16:20 INFO - 'LANGUAGE': 'en_US:en', 07:16:20 INFO - 'LOGNAME': 'cltbld', 07:16:20 INFO - 'MAIL': '/var/mail/cltbld', 07:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:20 INFO - 'MOZ_NO_REMOTE': '1', 07:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:20 INFO - 'NO_EM_RESTART': '1', 07:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:20 INFO - 'PWD': '/builds/slave/test', 07:16:20 INFO - 'SHELL': '/bin/bash', 07:16:20 INFO - 'SHLVL': '1', 07:16:20 INFO - 'TERM': 'linux', 07:16:20 INFO - 'TMOUT': '86400', 07:16:20 INFO - 'USER': 'cltbld', 07:16:20 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:20 INFO - '_': '/tools/buildbot/bin/python'} 07:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:20 INFO - Running setup.py (path:/tmp/pip-CDsKr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:20 INFO - Running setup.py (path:/tmp/pip-DQLHER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:20 INFO - Running setup.py (path:/tmp/pip-c2jgTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:20 INFO - Running setup.py (path:/tmp/pip-sN4lxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:20 INFO - Running setup.py (path:/tmp/pip-Cwo2NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:20 INFO - Running setup.py (path:/tmp/pip-ZzswYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:20 INFO - Running setup.py (path:/tmp/pip-6sNdPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:20 INFO - Running setup.py (path:/tmp/pip-ZTBg5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:20 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)) 07:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:20 INFO - Running setup.py (path:/tmp/pip-nbjiJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:21 INFO - Running setup.py (path:/tmp/pip-DNCJXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:21 INFO - Running setup.py (path:/tmp/pip-WSdj7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:21 INFO - Running setup.py (path:/tmp/pip-GsZUM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:21 INFO - Running setup.py (path:/tmp/pip-1NTJXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:21 INFO - Running setup.py (path:/tmp/pip-Wh4K32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:21 INFO - Running setup.py (path:/tmp/pip-LIbJPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:21 INFO - Running setup.py (path:/tmp/pip-EZb5ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:21 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)) 07:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:21 INFO - Running setup.py (path:/tmp/pip-3as2Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:21 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)) 07:16:21 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)) 07:16:21 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)) 07:16:21 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)) 07:16:21 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)) 07:16:21 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)) 07:16:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:16:21 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 07:16:21 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 07:16:21 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 07:16:21 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 07:16:21 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 07:16:21 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 07:16:23 INFO - Downloading blessings-1.6.tar.gz 07:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:16:23 INFO - Installing collected packages: blessings 07:16:23 INFO - Running setup.py install for blessings 07:16:23 INFO - Successfully installed blessings 07:16:23 INFO - Cleaning up... 07:16:23 INFO - Return code: 0 07:16:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:23 INFO - Reading from file tmpfile_stdout 07:16:23 INFO - Current package versions: 07:16:23 INFO - argparse == 1.2.1 07:16:23 INFO - blessings == 1.6 07:16:23 INFO - blobuploader == 1.2.4 07:16:23 INFO - docopt == 0.6.1 07:16:23 INFO - functools32 == 3.2.3-2 07:16:23 INFO - jsonschema == 2.5.1 07:16:23 INFO - manifestparser == 1.1 07:16:23 INFO - mozInstall == 1.12 07:16:23 INFO - mozcrash == 0.17 07:16:23 INFO - mozdebug == 0.1 07:16:23 INFO - mozdevice == 0.48 07:16:23 INFO - mozfile == 1.2 07:16:23 INFO - mozhttpd == 0.7 07:16:23 INFO - mozinfo == 0.9 07:16:23 INFO - mozleak == 0.1 07:16:23 INFO - mozlog == 3.3 07:16:23 INFO - moznetwork == 0.27 07:16:23 INFO - mozprocess == 0.23 07:16:23 INFO - mozprofile == 0.28 07:16:23 INFO - mozrunner == 6.12 07:16:23 INFO - mozscreenshot == 0.1 07:16:23 INFO - mozsystemmonitor == 0.3 07:16:23 INFO - moztest == 0.7 07:16:23 INFO - mozversion == 1.4 07:16:23 INFO - psutil == 3.1.1 07:16:23 INFO - requests == 1.2.3 07:16:23 INFO - wsgiref == 0.1.2 07:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:23 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')]} 07:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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')]} 07:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:23 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 07:16:23 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 07:16:23 INFO - Using env: {'DISPLAY': ':0', 07:16:23 INFO - 'HOME': '/home/cltbld', 07:16:23 INFO - 'LANG': 'en_US.UTF-8', 07:16:23 INFO - 'LANGUAGE': 'en_US:en', 07:16:23 INFO - 'LOGNAME': 'cltbld', 07:16:23 INFO - 'MAIL': '/var/mail/cltbld', 07:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:23 INFO - 'MOZ_NO_REMOTE': '1', 07:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:23 INFO - 'NO_EM_RESTART': '1', 07:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:23 INFO - 'PWD': '/builds/slave/test', 07:16:23 INFO - 'SHELL': '/bin/bash', 07:16:23 INFO - 'SHLVL': '1', 07:16:23 INFO - 'TERM': 'linux', 07:16:24 INFO - 'TMOUT': '86400', 07:16:24 INFO - 'USER': 'cltbld', 07:16:24 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:24 INFO - '_': '/tools/buildbot/bin/python'} 07:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 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)) 07:16:24 INFO - Cleaning up... 07:16:24 INFO - Return code: 0 07:16:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:24 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 0x1b60030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e21180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', '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 07:16:24 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 07:16:24 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 07:16:24 INFO - Using env: {'DISPLAY': ':0', 07:16:24 INFO - 'HOME': '/home/cltbld', 07:16:24 INFO - 'LANG': 'en_US.UTF-8', 07:16:24 INFO - 'LANGUAGE': 'en_US:en', 07:16:24 INFO - 'LOGNAME': 'cltbld', 07:16:24 INFO - 'MAIL': '/var/mail/cltbld', 07:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:24 INFO - 'MOZ_NO_REMOTE': '1', 07:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:24 INFO - 'NO_EM_RESTART': '1', 07:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:24 INFO - 'PWD': '/builds/slave/test', 07:16:24 INFO - 'SHELL': '/bin/bash', 07:16:24 INFO - 'SHLVL': '1', 07:16:24 INFO - 'TERM': 'linux', 07:16:24 INFO - 'TMOUT': '86400', 07:16:24 INFO - 'USER': 'cltbld', 07:16:24 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:24 INFO - '_': '/tools/buildbot/bin/python'} 07:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:16:24 INFO - Cleaning up... 07:16:24 INFO - Return code: 0 07:16:24 INFO - Running post-action listener: _resource_record_post_action 07:16:24 INFO - Running post-action listener: _start_resource_monitoring 07:16:24 INFO - Starting resource monitoring. 07:16:24 INFO - [mozharness: 2016-09-07 14:16:24.462449Z] Finished create-virtualenv step (success) 07:16:24 INFO - [mozharness: 2016-09-07 14:16:24.462879Z] Running install step. 07:16:24 INFO - Running pre-action listener: _resource_record_pre_action 07:16:24 INFO - Running main action method: install 07:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:24 INFO - Reading from file tmpfile_stdout 07:16:24 INFO - Detecting whether we're running mozinstall >=1.0... 07:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:16:24 INFO - Reading from file tmpfile_stdout 07:16:24 INFO - Output received: 07:16:24 INFO - Usage: mozinstall [options] installer 07:16:24 INFO - Options: 07:16:24 INFO - -h, --help show this help message and exit 07:16:24 INFO - -d DEST, --destination=DEST 07:16:24 INFO - Directory to install application into. [default: 07:16:24 INFO - "/builds/slave/test"] 07:16:24 INFO - --app=APP Application being installed. [default: firefox] 07:16:24 INFO - mkdir: /builds/slave/test/build/application 07:16:24 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'] 07:16:24 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 07:16:41 INFO - Reading from file tmpfile_stdout 07:16:41 INFO - Output received: 07:16:41 INFO - /builds/slave/test/build/application/firefox/firefox 07:16:41 INFO - Running post-action listener: _resource_record_post_action 07:16:41 INFO - [mozharness: 2016-09-07 14:16:41.403824Z] Finished install step (success) 07:16:41 INFO - [mozharness: 2016-09-07 14:16:41.404105Z] Running run-tests step. 07:16:41 INFO - Running pre-action listener: _resource_record_pre_action 07:16:41 INFO - Running main action method: run_tests 07:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:16:41 INFO - Python 2.7.3 07:16:41 INFO - Return code: 0 07:16:41 INFO - grabbing minidump binary from tooltool 07:16:41 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')]} 07:16:41 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 0x1e1f3b0>, '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 0x1e202b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e20740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:16:41 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 07:16:41 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 07:16:41 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 07:16:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:16:41 INFO - Return code: 0 07:16:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:16:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:16:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:16:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:16:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:16:41 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/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 07:16:41 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/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 07:16:41 INFO - Using env: {'DISPLAY': ':0', 07:16:41 INFO - 'HOME': '/home/cltbld', 07:16:41 INFO - 'LANG': 'en_US.UTF-8', 07:16:41 INFO - 'LANGUAGE': 'en_US:en', 07:16:41 INFO - 'LOGNAME': 'cltbld', 07:16:41 INFO - 'MAIL': '/var/mail/cltbld', 07:16:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:16:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:41 INFO - 'MOZ_NO_REMOTE': '1', 07:16:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:16:41 INFO - 'NO_EM_RESTART': '1', 07:16:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:41 INFO - 'PWD': '/builds/slave/test', 07:16:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:16:41 INFO - 'SHELL': '/bin/bash', 07:16:41 INFO - 'SHLVL': '1', 07:16:41 INFO - 'TERM': 'linux', 07:16:41 INFO - 'TMOUT': '86400', 07:16:41 INFO - 'USER': 'cltbld', 07:16:41 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988', 07:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:41 INFO - '_': '/tools/buildbot/bin/python'} 07:16:41 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/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 07:16:41 INFO - mozversion application_buildid: 20160907004009 07:16:41 INFO - mozversion application_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 07:16:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:16:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:16:41 INFO - mozversion application_name: Firefox 07:16:41 INFO - mozversion application_remotingname: firefox-dev 07:16:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:16:41 INFO - mozversion application_vendor: Mozilla 07:16:41 INFO - mozversion application_version: 50.0a2 07:16:41 INFO - mozversion platform_buildid: 20160907004009 07:16:41 INFO - mozversion platform_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 07:16:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:16:41 INFO - mozversion platform_version: 50.0a2 07:16:41 INFO - using testdate: 1473257801 07:16:41 INFO - actual date: 1473257801 07:16:41 INFO - starting webserver on 'localhost:35301' 07:16:41 INFO - SUITE-START | Running 2 tests 07:16:41 INFO - TEST-START | dromaeo_css 07:16:41 INFO - operating with platform_type : linux_ 07:16:41 INFO - Initialising browser for dromaeo_css test... 07:16:41 INFO - TEST-INFO | started process 13657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDIPhc/profile http://localhost:35301/getInfo.html) 07:16:45 INFO - PROCESS | 13657 | __metrics Screen width/height:1600/1200 07:16:45 INFO - PROCESS | 13657 | colorDepth:24 07:16:45 INFO - PROCESS | 13657 | Browser inner width/height: 1024/697 07:16:45 INFO - PROCESS | 13657 | __metrics 07:16:46 INFO - PROCESS | 13657 | 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" 07:16:46 INFO - PROCESS | 13657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:47 INFO - TEST-INFO | 13657: exit 0 07:16:47 INFO - Browser initialized. 07:16:47 INFO - Running cycle 1/1 for dromaeo_css test... 07:16:47 INFO - TEST-INFO | started process 13745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDIPhc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:16:59 INFO - PROCESS | 13745 | RSS: Main: 144891904 07:16:59 INFO - PROCESS | 13745 | 07:17:49 INFO - PROCESS | 13745 | 1473257869983 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] 07:19:58 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35301/tests/dromaeo/cssquery-ext.html) 07:20:00 INFO - PROCESS | 13745 | RSS: Main: 275816448 07:20:00 INFO - PROCESS | 13745 | 07:23:02 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-ext.html (next: http://localhost:35301/tests/dromaeo/cssquery-jquery.html) 07:23:02 INFO - PROCESS | 13745 | RSS: Main: 222466048 07:23:02 INFO - PROCESS | 13745 | 07:26:04 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35301/tests/dromaeo/cssquery-mootools.html) 07:26:05 INFO - PROCESS | 13745 | RSS: Main: 175648768 07:26:05 INFO - PROCESS | 13745 | 07:29:07 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35301/tests/dromaeo/cssquery-prototype.html) 07:29:08 INFO - PROCESS | 13745 | RSS: Main: 174772224 07:29:08 INFO - PROCESS | 13745 | 07:32:10 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35301/tests/dromaeo/cssquery-yui.html) 07:32:11 INFO - PROCESS | 13745 | RSS: Main: 175964160 07:32:11 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:35301/tests/dromaeo/cssquery-yui.html (next: http://localhost:35301/tests/dromaeo/cssquery-dojo.html) 07:35:14 INFO - PROCESS | 13745 | RSS: Main: 250019840 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | __start_tp_report 07:35:14 INFO - PROCESS | 13745 | _x_x_mozilla_page_load 07:35:14 INFO - PROCESS | 13745 | _x_x_mozilla_page_load_details 07:35:14 INFO - PROCESS | 13745 | |i|pagename|runs| 07:35:14 INFO - PROCESS | 13745 | |0;dojo.html;3217.951730724039;3237.7409807215417;3248.6101667799867;3286.605607327121;3286.786358886672;5586.916196257054;5670.3479099903525;5671.149327600857;5679.659220446771;5681.943180568193;5556.749946252418;5623.578231632623;5624.3813180550105;5641.379448260688;5668.886622267552;4964.751762411883;5012.448630650625;5082.288479612848;5103.591712662978;5161.81933632321;5043.167877300241;5136.178211486163;5141.30592370029;5146.253793199984;5149.098907691139;4963.528464795839;4984.601231901457;4987.102321582114;5003.4246061702825;5129.435762066169;5077.517635824591;5094.006668699605;5107.978404319114;5111.28442018114;5143.254228136937;4949.604031677457;4980.228064649985;4991.226359914219;5003.724795136266;5004.474530174352;5452.1276595744885;5454.809081682161;5460.180972854064;5503.972480137573;5510.1459273812625;5966.254218222723;5970.6716130612795;5996.040633498619;6001.099835024737;6002.6398416094735;5980.611260268069;6002.309734380558;6005.309389420186;6043.123747056652;6079.47244165084;5644.209810626516;5706.828795136111;5711.114777209539;5712;5718.456746609066;5889.057750759858;6190.164327815777;6190.907136392958;6198.504119670506;6199.225096862892;5751.1085781703905;5754.625949313281;5759.94240057594;5840.88318233633;5881.088431293157;5937.139114828412;5940.673262970492;5969.31352894414;5975.014122669798;5978.671173085435;5723.055695810228;5903.940960590425;5908.231929849178;5910.527157827363;5915.940840591625;5870.442307980735;5892.381299963527;5905.1142328650185;5966.791162309385;6001.939980600226;4585.747783871919;4587.518310577341;4587.610053145453;4593.081383723269;4618.56123668251;3963;4104.610062044101;4104.835806567764;4122.732022418533;4134.689898257582;5588.636738611977;5642.04085305499;5650.858728531737;5675.460831221028;5685.459881311269;5903.291605007341;5939.821805345847;5944.375840536682;5947.732352044169;5967.940320596826;5630.915536266959;5641.661500309994;5645.5483561314995;5649.604527683105;5650.29371328584;4976.0794253063295;5099.59203263738;5143.61422893277;5145.15105007677;5212.166053431509;6209.6895155242055;6219.626822390671;6222.222222222223;6224.284207316216;6225.8132256032395;5975.34271230173;5988.251468566429;6033.487153591994;6046.637201767732;6070.423309785563;6012.218411606463;6107.206063211932;6128.533679014772;6132.78385080153;6190.133381326708;5936.079907614327;5938.61399009063;5944.346121926498;5949.91075133882;5951.196588460502;6039.63762174271;6041.305249896318;6043.033114701627;6046.606970546901;6049.879002420015;5684.147377893349;5831.387704290988;5842.7078646066675;5852.4440178183;5930.199423077829;5879.118132280192;5887.293524777054;5893.852653683693;5894.616849904743;5913.6747478887355;5789.305283365855;5793.15999180125;5833.4749872511675;5837.445442682938;5850.180974663465;5747.396523364986;5848.5028772553105;5850.970745146247;5876.236089308362;5905.822825315247;5768.105943578752;5783.16144159103;5786.161006654096;5822.592418530662;5846.064629659234 07:35:14 INFO - PROCESS | 13745 | |1;ext.html;600.4715850051954;637.4167636612499;645.303072681504;665.2981104934292;674.1505702814452;34356;44515.55484445159;45926.77036614821;45931;46051;47341;47845.76077119618;47891.760541197335;47914.76042619791;47960.52039479613;8455.450395724289;9215.493147876865;9336.299777516679;9368;11137.164712646543;10935.453227338641;11290.096792256641;11303.378314192716;11310.547578096866;11355.318680879174;11448.484818183202;11483.13876459269;11500.194986350958;11503.07975361969;11505.597304094317;11305.773884522263;11390.373529455877;11408.600698975495;11412;11428.542858285658;11091.83362249567;11094.889051109467;11181.105511559055;11188.608398706006;11190.66428007161;44645.55354446446;45031.549684503065;45058.098838023056;45082;45085.774571127266;3660.744484014105;3897.279003384355;3983.1237127831832;4001.6198461146437;4024.557298697141;34379.31241375183;34493.655063449296;34573;34648.65351346479;34805.651943480494;4489.147062058199;4519.367288579601;4519.570640789153;4521.095780843844;4557.931631025537;351110;351599;351880;355029;355623.2218838915;60175;60552.69723651354;60641.696791516646;60662.39337606567;60767.39232607706;38827;39441.802790985865;39461;41682.58317416787;63407;19313.03434828253;19539.902300488407;19546.218151274075;19558.413247602595;19604.509887252934;13855.307234638227;15112.168830714196;15239.314230859416;15385.615359616098;15410.152441615819;20367;20386.592268154553;20481.078351474218;20563;20711.896440518;7121.643917804089;7395.926040739631;7419.369353604896;7467;7686.269804368576;989.6387818446216;1110.2062025651699;1135.7728454309172;1157.3750174905456;1178.8821117888142;34570.13574660654;34720;34819.30361392758;34914.30171396609;35027.82486087604;1120.2102517724984;1155.1509640414288;1167.416291854073;1169.1698893785508;1241.2862604002735;17168.31326746941;17893.19480623375;18060.638787224183;18163.72754408685;18243.36148234832;599891;603838;605088;605226;605304;61856;62496.37503624996;62500.374996249455;62505.062424062766;62561.374386256466;1359.2524111738505;1453.128123126116;1457.2932127917952;1513.9697206055816;1520.9239538023055;32662.34675306528;35266;35276.29447411089;35341;35755.46366804448;52177.7391113042;52680.20979685231;52808.471915281894;52872.20691689705;52921;433059;434336;435950;436180;527326;44268;46332.7683361581;46781;47408.762956185;47917;51891.2216316748;52577.7371113142;52861.7356913213;53368.199477007096;53532.46467535275;43608.78195609002;44139.55860441483;44245;44272.450464233596;45418.09163816771;43622.781886090364;43835.56164438443;44183.55816441795;44237.33643995407;45562.772186138856;44432.555674444135;44614.55385446104;44829.77585112053;44966.77516612396;45390.773046134556;35931.82034089813;38747.80626096964;39151.608483914795;39298.6070139295;40043.599564003984;29610.907550038464;29710.554341685325;30058.398832022798;30101.69898300989;30171.849140754155 07:35:14 INFO - PROCESS | 13745 | |2;jquery.html;387.4943199125144;404.33083247225835;413.1860235336387;420.84428761358294;433.6292469938203;15666.84333156668;20101.798982010176;20261.0882510287;20383.49041273967;21253.893730531363;19477;19768.9011554942;19895.30366437175;20853.687194692055;21235.893820530877;5502.614816962814;5517.172424136372;5605.663660180382;5645.32256129263;5645.8588535286535;4122.42286079949;4340.587644173798;5521.1442226454965;5555;5618.438156184369;4885.4381746099125;6151.354107818682;6209.937900620981;6280.748770049193;6281.371862813728;6221.906671399933;6274.623522588637;6328.272248691414;6356.173697419352;6361.936380636181;6148.354422785588;6161;6167.599106058092;6170.136180934673;6171.166892469504;22494.66258006131;23429.531409371717;23615.409614759596;24600.630990534977;25135.748642513707;2481.888315025828;2483.7143728471096;2541.250331152296;2546.2106746908516;3006.2484378905274;10731.409772462512;11008.174386920933;11079.169062320278;12551.560695375709;15222.314995825103;1903.1816318982833;1927.026851440028;1929.3150931419284;1987.21505005522;2020.8484363672783;63254;75216;77897.61051194764;85006;95047;11430.14273929442;11655.88344116565;11945.283283003047;12056.156069075081;12134.453949572115;16853.915730421268;16876.324947002227;17095.658086838193;17148.39980600673;17152.48542543726;8671.869921951176;9051.27589792828;9053.456792592466;9187;9214.861777073349;8815.382923195442;8863.113688631085;8910.777230569287;8936.46381217129;8995.820083598292;4912.189488734319;5003.499650034967;5005.27423523587;5227.60792940531;5255.5795536357;1986.4040787763613;2042.6323261813016;2044.1312442212;2099.7900209979184;2147.699322094908;943.9433633981984;1013.3919648211014;1029.3669393323053;1031.4430207687765;1086.9021788039115;20872.58254834895;21066.157353705985;21101.894490527447;21130.154793808382;21494.14023439045;1012.2205901455985;1027.917766578687;1028.1517747858047;1055.514463346869;1070.978580428387;9591.328606997582;11886.346250956107;11972.281663100242;11983.161178717406;12166.756664866652;530368;531663;536850;544090;546583;91796.54101729582;93865;94133.5293323529;94717;95565.5221723887;1054.288355360129;1055.3878750324793;1075.6020272499225;1096.714854137914;1105.5688281570194;18281.542961426072;18627;19211.807881921563;19441.805581943998;19542.706859397404;44132.558674412845;44755.55244447644;44958;45529;45731.54268457273;60383.09425358711;64470.35529644831;64971;65098;66427.33572664211;8738.868916966123;9170.312226582899;9180.908190918006;9239.445633262027;9260;12613.810792838298;13991.930040349733;14135.505257315945;14168;14220.502282420066;12118;12274.202176858476;12372.690682733004;12440.751184976069;12468.438920248793;12242.143049986771;12273.140880138304;12374.195677280948;12437.75124497523;12658;12182;12331.753364932472;12577.559785407468;12652.43064062101;12750.107492475438;8341.499510029418;8626.78433039179;8763.60563774619;8817.294616430798;8953.418027828171;12028.939855300667;12156.878431215575;12299.754004919672;12321.44553495077;12332.445039973401 07:35:14 INFO - PROCESS | 13745 | |3;mootools.html;1445.2051371745538;1542.5372388283515;1573.1111921764202;1753.25486668166;1865.4496923407603;7660.157382687907;8832.381733278673;9042.095790420955;9055.366124371289;9325.253979681625;9074.274058075354;9141.085891410856;9156.267498600113;9159.450432974027;9166.495842728647;2439.5852705040097;2462.4213309872166;2465.0386349323776;2468.3458883395915;2470.654108424822;2388.581998150317;2448.2899959011866;2455.2020593307248;2463.938401539958;2464.9014039438403;2429.9149529766373;2438.8536687798787;2451.595486744685;2465.1988103866224;2469.6295555666616;2400.759924007585;2411.8794060297005;2417.153996101359;2422.236995346469;2432.914847980312;2379.690640216778;2402.60357041088;2408.145108486497;2410.0841680161425;2410.7468715784767;8917.866232006525;9409.952950235274;9635.373700709502;9643.710688679352;9647;5519.061759500894;5646.717664116819;5648.406917273668;5655.349634792011;5660.405657405954;3579.3199292134673;3596.5863925648364;3633.563972323338;3650.3976843820737;3701.68535674468;1174.307158776326;1175.8882906123904;1191.5714719090545;1210.382704820539;1219.530480764912;13651.180929144282;13717.657058573515;13789.793153102712;13805;13808.1024733393;4441.156180325728;4627.722336659811;4738.47876733559;4748.287756836502;4761.666683332202;10779.838302425469;10784.89215107839;10785.622503212508;10787.730306742395;10789.19081068923;5585.301837270341;5591.720413979285;5595.8880822384135;5596.552275817925;5597;4414.713043652152;4439.778011099432;4883;5126.307948426915;5229.555487783581;3972.2254160439015;3989.082511022423;4086.366613174963;4086.7547947123267;4093.570175131628;1122.0967121467133;1178.723000094977;1182.148852825964;1188.0554958807768;1189.79178643737;869.2744901370774;976.6679329028165;1016.8271393862913;1020.8928062553473;1046.9528871200662;6173.876522469525;6314.273858506239;6326.525510586633;6344.904826427607;6736.7642132525125;433.11860364158787;436.23876335794245;447.9059397526491;448.8272015274143;450.1020464173994;4054.8986275343354;4158.313878210122;4162.1259535497275;4163.3130533462245;4182.205380977604;11643;12169.817452738216;12195.512179512898;12210.877891220975;12233.816492752616;12112.273263604213;12139.27164370123;12141;12142.332171730463;12142.635720928387;8527;8962;8970.955145224363;9063.81872362549;9080.772980675403;5887.106428827877;6052.273727152769;6474;6557.049227862029;6669.266380698023;10705.304155229886;10806.729831754268;10923.289986150874;11002;11004.724881878017;9662.516874156123;11085.778284434105;11210.215284930302;11402.087832973508;11494.367809770543;9059.501727405055;9209.079092090737;9229.723108306762;9330;9343.81312373735;9318.207952324128;9436.197923176607;9451.858222126812;9452.10205030521;9461.905380946379;8252.298554622925;8274.420790544846;8529.445586036887;8643.135686431306;8733.475991440533;8221.424500285162;8293.709720159766;8487.618057187317;8504.319654427754;8560.14398560139;7234.6020968847215;7263.745768898063;7320;7358.926410735824;7391.186969433251;5361.27622770921;5421.457854214547;5495.230667706444;5551.1673249011355;5621.718914054362;5271;5558.888822223452;5635;5726;5919.6152250103605 07:35:14 INFO - PROCESS | 13745 | |4;prototype.html;348.97382696297774;350.2924093331471;353.7894952503263;357.3086078438221;358.4658858301131;5456.39979602244;5515.779368825247;5524.22660827484;5554.611177217596;5578.525825304854;5446.782128714856;5467.152591348337;5483.2049352843815;5486.670799752018;5501.614886957915;3866.6466736642233;4127.257093723121;4310.633596144316;4312.698111132222;4347.78261086946;4300.741955482681;4305.870823875289;4309.0735491869245;4310.741355518663;4342.54403287655;4123.216588848124;4326.74039557626;4343.391925130484;4350.456192975878;4362.25841606926;4252.702925607704;4307.612314891675;4310.663694254791;4338.3058710606465;4343.348497725335;3862.671672907806;3866.864659736924;3874.128321127752;3886.319894018564;3887;5218.739063046871;5330.733463326818;5355.357357117171;5360.544353729938;5422.132458806572;4929.285253638202;4933.30933669283;4945.233488809205;4955.900881982377;4976.850694479172;5647.661140331552;5658.207850900877;5664.716764161817;5724.885502289931;5776.768929242825;4102;4234.750748529192;4238.724482908601;4274.658027357835;4274.935875961863;5678.545716342724;5806.912896306559;5968.582199246054;5978.760849566012;6001.849953751147;4295.548967358445;4300.118475712471;4327.091310824699;4336.978315108404;4349.934750978738;4352.521222665504;4376.059147283349;4385.429894113745;4410.095930334273;4411.8014689338415;4857.562819346205;4944.678595891256;4947;4951.9752401237765;4954.727489988085;5687.573431992534;5690.487856092972;5705.74324155414;5726.682862941547;5776.971115144397;1486.7844162596364;1505.119505089513;1511.833698293187;1526.702293052844;1527.633367991674;947.8483442649143;974.9032338619054;979.5543027922279;989.366805244644;1000.5342173715396;4914.004914004909;4919.950800492021;4923.90152196954;4934.580560652313;4962;5144.61415393847;5153.45888681683;5158.871028224325;5179.922301165486;5216.66091704038;4634.536546345406;4940.283658869515;4991.950080499221;5013.974930125399;5025.497450254945;2749.9550170935318;2765.6681198255937;2766.9308267293477;2770.7367800058964;2776.625570342693;5334.91997620036;5366.424116424138;5371.227494926665;5371.979323928441;5380;5020.117455954017;5144.254083157921;5146.279520867081;5224.373075230997;5231.42454330031;5223.529882310534;5301.905747229823;5329.54698850602;5352.518273355417;5406;5156.716380599103;5158.8392611662075;5162.373852236534;5181.937702770998;5244.947550524446;4972.5276098770555;5096.796128154832;5125.205593133071;5145.4340022598235;5151.2788209649925;5358.356997160366;5819.301683797971;5929.970350148222;6078.756849726138;6105.572609917263;4297.140571885573;4312.504062032906;4315.503717072577;4326.3943047973935;4336.34954756789;5717.857053573527;5903.763849445974;5944.791932282349;5994.730237139252;6018.157457955802;5773.134029895381;5783.0915797787475;5784.334801497714;5790.681512516852;5804.2744656917885;5717.885642287214;5728.598998070262;5763.769449222152;5810.767569297181;5811.663317436929;5479.75341109659;5731.481157493185;5736.971315143398;5775.162601422686;5778.595498315078;5817.534597232296;5821.621594596338;5833.970830145992;5846.239988801429;5858.677772722371;5533.667979921218;5715.657060576213;5741.971290143522;5759.366469688414;5802.1296805479515 07:35:14 INFO - PROCESS | 13745 | |5;yui.html;748.8202831320484;789.9605019749011;812.1229072601589;821.4783612406125;951.3055469507261;15903.12532810695;17055.317787288524;17102.65794684107;17103.315867365323;17123.828761712393;15592.220388980533;15696.293666784994;15706.21468926552;15719;15737.84262157378;722.093772315743;848.4485084694936;857.3098655582254;877.1053525404084;881.3654168998311;875.4922145155795;878.0736323179044;878.802269489363;880.6257340630239;881.3389957531851;927.5733162745146;928.7538802217417;929.5584597316288;929.7768535551453;931.1293940165932;764.1212605503688;906.5920335848862;914.2640174659364;917.3716004536847;925.5973651461636;673.3401266758555;677.617146312332;678.1082876018021;678.8404724889647;679.3987321220756;15731.449399271085;15754.527364179094;15757.60605984848;15759.92120039404;15783.526494205193;14239;14362.569122926328;14957.476488322965;15138.394464221417;15218.695626087527;12848.935755321258;12928.806067908989;13098.2141071536;13107.934460327733;13177.670558236121;4589.885252868705;4668.95331046692;4732.73969931657;4739.052189562067;4751.453582837983;68142.65928670374;70278.64860675664;70348;80125.19874801235;81694;12208.755824883452;12227.449764760608;12461.376931153405;12536.874631253753;12707.618771436872;20566;20569;20573.58852822965;20577.07403166861;20585.485362865747;9097.40866843653;9179.357444978921;9193.586288617029;9389.953050234704;9494.572744226527;4968.105740966588;4995.425526064477;5095.337606111256;5122.103631864484;5233.2935053768015;3343.866245350159;3451.6375780543185;3454.6372630873398;3455.170759017868;3489.668481494254;669.7086767256162;673.565550220115;694.5103701890155;698.1900994845954;712.9251428599922;742.2651574940878;765.7894079128173;772.791346336486;797.4856217739525;805.2269820971816;9806;9811.716827653874;9921.553530091163;9973.551190196458;10110.747231319128;601.575888998264;660.2539130782185;675.6689222281046;741.9517731347445;788.1999770233218;5064.746762661852;5100.285959965608;5264.157734762496;5276.709780962084;5340.092184328673;36116.63883361133;36352.63647363545;73120.26879731135;116550;119842;103340.48329758304;104468.47765761122;104585.47707261414;105148.47425762822;105429;76709.23290767022;77246;90694.54652726826;92321.53839230762;94418;10147;10448.738781530523;10570.41862697559;11247;11531.942340288246;50403;50421.74789126031;52015.73992130015;52136.739316303174;52988.4701152999;72950.63524682343;75047.62476187803;78996.60501697454;80008;80579;11708.356040417519;12080.577179798665;12239.571614993432;12769.233845969271;13102.803457947948;14638.121712697272;15437.99306090246;15984.920075399548;16205.59486012859;16321.18394080315;14882.627934301729;14979.850201498282;15697.450589229322;15768.369265229263;16005.519834404986;12875;14448;14490.202805164996;14572.198529080579;14623.488177913721;13955.790663140264;14656.193909335088;15339.76990345168;15412.460563880659;15456.613584660474;11842.703932401759;12312.322822244505;12629.305388203737;12852;12896.742065158833;11775.941120294687;12074.215176013531;12211.26732396059;12667.619971400873;12921.935390322988 07:35:14 INFO - PROCESS | 13745 | __end_tp_report 07:35:14 INFO - PROCESS | 13745 | __start_cc_report 07:35:14 INFO - PROCESS | 13745 | _x_x_mozilla_cycle_collect,1082 07:35:14 INFO - PROCESS | 13745 | __end_cc_report 07:35:14 INFO - PROCESS | 13745 | __startTimestamp1473258914361__endTimestamp 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | ------- Summary: start ------- 07:35:14 INFO - PROCESS | 13745 | Number of tests: 6 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#0] dojo.html Cycles:165 Average:5553.63 Median:5783.16 stddev:630.91 (10.9%) stddev-sans-first:605.65 07:35:14 INFO - PROCESS | 13745 | Values: 3218.0 3237.7 3248.6 3286.6 3286.8 5586.9 5670.3 5671.1 5679.7 5681.9 5556.7 5623.6 5624.4 5641.4 5668.9 4964.8 5012.4 5082.3 5103.6 5161.8 5043.2 5136.2 5141.3 5146.3 5149.1 4963.5 4984.6 4987.1 5003.4 5129.4 5077.5 5094.0 5108.0 5111.3 5143.3 4949.6 4980.2 4991.2 5003.7 5004.5 5452.1 5454.8 5460.2 5504.0 5510.1 5966.3 5970.7 5996.0 6001.1 6002.6 5980.6 6002.3 6005.3 6043.1 6079.5 5644.2 5706.8 5711.1 5712.0 5718.5 5889.1 6190.2 6190.9 6198.5 6199.2 5751.1 5754.6 5759.9 5840.9 5881.1 5937.1 5940.7 5969.3 5975.0 5978.7 5723.1 5903.9 5908.2 5910.5 5915.9 5870.4 5892.4 5905.1 5966.8 6001.9 4585.7 4587.5 4587.6 4593.1 4618.6 3963.0 4104.6 4104.8 4122.7 4134.7 5588.6 5642.0 5650.9 5675.5 5685.5 5903.3 5939.8 5944.4 5947.7 5967.9 5630.9 5641.7 5645.5 5649.6 5650.3 4976.1 5099.6 5143.6 5145.2 5212.2 6209.7 6219.6 6222.2 6224.3 6225.8 5975.3 5988.3 6033.5 6046.6 6070.4 6012.2 6107.2 6128.5 6132.8 6190.1 5936.1 5938.6 5944.3 5949.9 5951.2 6039.6 6041.3 6043.0 6046.6 6049.9 5684.1 5831.4 5842.7 5852.4 5930.2 5879.1 5887.3 5893.9 5894.6 5913.7 5789.3 5793.2 5833.5 5837.4 5850.2 5747.4 5848.5 5851.0 5876.2 5905.8 5768.1 5783.2 5786.2 5822.6 5846.1 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#1] ext.html Cycles:180 Average:64450.63 Median:3992.37 stddev:127750.52 (3199.9%) stddev-sans-first:128018.94 07:35:14 INFO - PROCESS | 13745 | Values: 600.5 637.4 645.3 665.3 674.2 34356.0 44515.6 45926.8 45931.0 46051.0 47341.0 47845.8 47891.8 47914.8 47960.5 8455.5 9215.5 9336.3 9368.0 11137.2 10935.5 11290.1 11303.4 11310.5 11355.3 11448.5 11483.1 11500.2 11503.1 11505.6 11305.8 11390.4 11408.6 11412.0 11428.5 11091.8 11094.9 11181.1 11188.6 11190.7 44645.6 45031.5 45058.1 45082.0 45085.8 3660.7 3897.3 3983.1 4001.6 4024.6 34379.3 34493.7 34573.0 34648.7 34805.7 4489.1 4519.4 4519.6 4521.1 4557.9 351110.0 351599.0 351880.0 355029.0 355623.2 60175.0 60552.7 60641.7 60662.4 60767.4 38827.0 39441.8 39461.0 41682.6 63407.0 19313.0 19539.9 19546.2 19558.4 19604.5 13855.3 15112.2 15239.3 15385.6 15410.2 20367.0 20386.6 20481.1 20563.0 20711.9 7121.6 7395.9 7419.4 7467.0 7686.3 989.6 1110.2 1135.8 1157.4 1178.9 34570.1 34720.0 34819.3 34914.3 35027.8 1120.2 1155.2 1167.4 1169.2 1241.3 17168.3 17893.2 18060.6 18163.7 18243.4 599891.0 603838.0 605088.0 605226.0 605304.0 61856.0 62496.4 62500.4 62505.1 62561.4 1359.3 1453.1 1457.3 1514.0 1520.9 32662.3 35266.0 35276.3 35341.0 35755.5 52177.7 52680.2 52808.5 52872.2 52921.0 433059.0 434336.0 435950.0 436180.0 527326.0 44268.0 46332.8 46781.0 47408.8 47917.0 51891.2 52577.7 52861.7 53368.2 53532.5 43608.8 44139.6 44245.0 44272.5 45418.1 43622.8 43835.6 44183.6 44237.3 45562.8 44432.6 44614.6 44829.8 44966.8 45390.8 35931.8 38747.8 39151.6 39298.6 40043.6 29610.9 29710.6 30058.4 30101.7 30171.8 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#2] jquery.html Cycles:180 Average:31115.28 Median:24108.02 stddev:88463.30 (366.9%) stddev-sans-first:88681.37 07:35:14 INFO - PROCESS | 13745 | Values: 387.5 404.3 413.2 420.8 433.6 15666.8 20101.8 20261.1 20383.5 21253.9 19477.0 19768.9 19895.3 20853.7 21235.9 5502.6 5517.2 5605.7 5645.3 5645.9 4122.4 4340.6 5521.1 5555.0 5618.4 4885.4 6151.4 6209.9 6280.7 6281.4 6221.9 6274.6 6328.3 6356.2 6361.9 6148.4 6161.0 6167.6 6170.1 6171.2 22494.7 23429.5 23615.4 24600.6 25135.7 2481.9 2483.7 2541.3 2546.2 3006.2 10731.4 11008.2 11079.2 12551.6 15222.3 1903.2 1927.0 1929.3 1987.2 2020.8 63254.0 75216.0 77897.6 85006.0 95047.0 11430.1 11655.9 11945.3 12056.2 12134.5 16853.9 16876.3 17095.7 17148.4 17152.5 8671.9 9051.3 9053.5 9187.0 9214.9 8815.4 8863.1 8910.8 8936.5 8995.8 4912.2 5003.5 5005.3 5227.6 5255.6 1986.4 2042.6 2044.1 2099.8 2147.7 943.9 1013.4 1029.4 1031.4 1086.9 20872.6 21066.2 21101.9 21130.2 21494.1 1012.2 1027.9 1028.2 1055.5 1071.0 9591.3 11886.3 11972.3 11983.2 12166.8 530368.0 531663.0 536850.0 544090.0 546583.0 91796.5 93865.0 94133.5 94717.0 95565.5 1054.3 1055.4 1075.6 1096.7 1105.6 18281.5 18627.0 19211.8 19441.8 19542.7 44132.6 44755.6 44958.0 45529.0 45731.5 60383.1 64470.4 64971.0 65098.0 66427.3 8738.9 9170.3 9180.9 9239.4 9260.0 12613.8 13991.9 14135.5 14168.0 14220.5 12118.0 12274.2 12372.7 12440.8 12468.4 12242.1 12273.1 12374.2 12437.8 12658.0 12182.0 12331.8 12577.6 12652.4 12750.1 8341.5 8626.8 8763.6 8817.3 8953.4 12028.9 12156.9 12299.8 12321.4 12332.4 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#3] mootools.html Cycles:180 Average:6215.06 Median:4440.47 stddev:3669.64 (82.6%) stddev-sans-first:3662.43 07:35:14 INFO - PROCESS | 13745 | Values: 1445.2 1542.5 1573.1 1753.3 1865.4 7660.2 8832.4 9042.1 9055.4 9325.3 9074.3 9141.1 9156.3 9159.5 9166.5 2439.6 2462.4 2465.0 2468.3 2470.7 2388.6 2448.3 2455.2 2463.9 2464.9 2429.9 2438.9 2451.6 2465.2 2469.6 2400.8 2411.9 2417.2 2422.2 2432.9 2379.7 2402.6 2408.1 2410.1 2410.7 8917.9 9410.0 9635.4 9643.7 9647.0 5519.1 5646.7 5648.4 5655.3 5660.4 3579.3 3596.6 3633.6 3650.4 3701.7 1174.3 1175.9 1191.6 1210.4 1219.5 13651.2 13717.7 13789.8 13805.0 13808.1 4441.2 4627.7 4738.5 4748.3 4761.7 10779.8 10784.9 10785.6 10787.7 10789.2 5585.3 5591.7 5595.9 5596.6 5597.0 4414.7 4439.8 4883.0 5126.3 5229.6 3972.2 3989.1 4086.4 4086.8 4093.6 1122.1 1178.7 1182.1 1188.1 1189.8 869.3 976.7 1016.8 1020.9 1047.0 6173.9 6314.3 6326.5 6344.9 6736.8 433.1 436.2 447.9 448.8 450.1 4054.9 4158.3 4162.1 4163.3 4182.2 11643.0 12169.8 12195.5 12210.9 12233.8 12112.3 12139.3 12141.0 12142.3 12142.6 8527.0 8962.0 8971.0 9063.8 9080.8 5887.1 6052.3 6474.0 6557.0 6669.3 10705.3 10806.7 10923.3 11002.0 11004.7 9662.5 11085.8 11210.2 11402.1 11494.4 9059.5 9209.1 9229.7 9330.0 9343.8 9318.2 9436.2 9451.9 9452.1 9461.9 8252.3 8274.4 8529.4 8643.1 8733.5 8221.4 8293.7 8487.6 8504.3 8560.1 7234.6 7263.7 7320.0 7358.9 7391.2 5361.3 5421.5 5495.2 5551.2 5621.7 5271.0 5558.9 5635.0 5726.0 5919.6 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#4] prototype.html Cycles:180 Average:4696.99 Median:5155.09 stddev:1340.35 (26.0%) stddev-sans-first:1303.77 07:35:14 INFO - PROCESS | 13745 | Values: 349.0 350.3 353.8 357.3 358.5 5456.4 5515.8 5524.2 5554.6 5578.5 5446.8 5467.2 5483.2 5486.7 5501.6 3866.6 4127.3 4310.6 4312.7 4347.8 4300.7 4305.9 4309.1 4310.7 4342.5 4123.2 4326.7 4343.4 4350.5 4362.3 4252.7 4307.6 4310.7 4338.3 4343.3 3862.7 3866.9 3874.1 3886.3 3887.0 5218.7 5330.7 5355.4 5360.5 5422.1 4929.3 4933.3 4945.2 4955.9 4976.9 5647.7 5658.2 5664.7 5724.9 5776.8 4102.0 4234.8 4238.7 4274.7 4274.9 5678.5 5806.9 5968.6 5978.8 6001.8 4295.5 4300.1 4327.1 4337.0 4349.9 4352.5 4376.1 4385.4 4410.1 4411.8 4857.6 4944.7 4947.0 4952.0 4954.7 5687.6 5690.5 5705.7 5726.7 5777.0 1486.8 1505.1 1511.8 1526.7 1527.6 947.8 974.9 979.6 989.4 1000.5 4914.0 4920.0 4923.9 4934.6 4962.0 5144.6 5153.5 5158.9 5179.9 5216.7 4634.5 4940.3 4992.0 5014.0 5025.5 2750.0 2765.7 2766.9 2770.7 2776.6 5334.9 5366.4 5371.2 5372.0 5380.0 5020.1 5144.3 5146.3 5224.4 5231.4 5223.5 5301.9 5329.5 5352.5 5406.0 5156.7 5158.8 5162.4 5181.9 5244.9 4972.5 5096.8 5125.2 5145.4 5151.3 5358.4 5819.3 5930.0 6078.8 6105.6 4297.1 4312.5 4315.5 4326.4 4336.3 5717.9 5903.8 5944.8 5994.7 6018.2 5773.1 5783.1 5784.3 5790.7 5804.3 5717.9 5728.6 5763.8 5810.8 5811.7 5479.8 5731.5 5737.0 5775.2 5778.6 5817.5 5821.6 5834.0 5846.2 5858.7 5533.7 5715.7 5742.0 5759.4 5802.1 07:35:14 INFO - PROCESS | 13745 | 07:35:14 INFO - PROCESS | 13745 | [#5] yui.html Cycles:180 Average:20355.33 Median:4629.42 stddev:28181.02 (608.7%) stddev-sans-first:28221.62 07:35:14 INFO - PROCESS | 13745 | Values: 748.8 790.0 812.1 821.5 951.3 15903.1 17055.3 17102.7 17103.3 17123.8 15592.2 15696.3 15706.2 15719.0 15737.8 722.1 848.4 857.3 877.1 881.4 875.5 878.1 878.8 880.6 881.3 927.6 928.8 929.6 929.8 931.1 764.1 906.6 914.3 917.4 925.6 673.3 677.6 678.1 678.8 679.4 15731.4 15754.5 15757.6 15759.9 15783.5 14239.0 14362.6 14957.5 15138.4 15218.7 12848.9 12928.8 13098.2 13107.9 13177.7 4589.9 4669.0 4732.7 4739.1 4751.5 68142.7 70278.6 70348.0 80125.2 81694.0 12208.8 12227.4 12461.4 12536.9 12707.6 20566.0 20569.0 20573.6 20577.1 20585.5 9097.4 9179.4 9193.6 9390.0 9494.6 4968.1 4995.4 5095.3 5122.1 5233.3 3343.9 3451.6 3454.6 3455.2 3489.7 669.7 673.6 694.5 698.2 712.9 742.3 765.8 772.8 797.5 805.2 9806.0 9811.7 9921.6 9973.6 10110.7 601.6 660.3 675.7 742.0 788.2 5064.7 5100.3 5264.2 5276.7 5340.1 36116.6 36352.6 73120.3 116550.0 119842.0 103340.5 104468.5 104585.5 105148.5 105429.0 76709.2 77246.0 90694.5 92321.5 94418.0 10147.0 10448.7 10570.4 11247.0 11531.9 50403.0 50421.7 52015.7 52136.7 52988.5 72950.6 75047.6 78996.6 80008.0 80579.0 11708.4 12080.6 12239.6 12769.2 13102.8 14638.1 15438.0 15984.9 16205.6 16321.2 14882.6 14979.9 15697.5 15768.4 16005.5 12875.0 14448.0 14490.2 14572.2 14623.5 13955.8 14656.2 15339.8 15412.5 15456.6 11842.7 12312.3 12629.3 12852.0 12896.7 11775.9 12074.2 12211.3 12667.6 12921.9 07:35:14 INFO - PROCESS | 13745 | -------- Summary: end -------- 07:35:14 INFO - PROCESS | 13745 | 07:35:15 INFO - TEST-INFO | 13745: exit 0 07:35:15 INFO - TEST-OK | dromaeo_css | took 1113468ms 07:35:15 INFO - TEST-START | kraken 07:35:15 INFO - operating with platform_type : linux_ 07:35:15 INFO - Initialising browser for kraken test... 07:35:15 INFO - TEST-INFO | started process 13991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZU5zHu/profile http://localhost:35301/getInfo.html) 07:35:18 INFO - PROCESS | 13991 | __metrics Screen width/height:1600/1200 07:35:18 INFO - PROCESS | 13991 | colorDepth:24 07:35:18 INFO - PROCESS | 13991 | Browser inner width/height: 1024/697 07:35:18 INFO - PROCESS | 13991 | __metrics 07:35:19 INFO - PROCESS | 13991 | 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" 07:35:19 INFO - PROCESS | 13991 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:35:20 INFO - TEST-INFO | 13991: exit 0 07:35:20 INFO - Browser initialized. 07:35:20 INFO - Running cycle 1/1 for kraken test... 07:35:20 INFO - TEST-INFO | started process 14079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZU5zHu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:35:32 INFO - PROCESS | 14079 | RSS: Main: 145174528 07:35:32 INFO - PROCESS | 14079 | 07:35:34 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35301/tests/kraken/driver.html?testName=audio-beat-detection) 07:35:35 INFO - PROCESS | 14079 | RSS: Main: 191213568 07:35:35 INFO - PROCESS | 14079 | 07:35:38 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35301/tests/kraken/driver.html?testName=audio-dft) 07:35:38 INFO - PROCESS | 14079 | RSS: Main: 181305344 07:35:38 INFO - PROCESS | 14079 | 07:35:41 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35301/tests/kraken/driver.html?testName=audio-fft) 07:35:42 INFO - PROCESS | 14079 | RSS: Main: 269029376 07:35:42 INFO - PROCESS | 14079 | 07:35:44 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35301/tests/kraken/driver.html?testName=audio-oscillator) 07:35:45 INFO - PROCESS | 14079 | RSS: Main: 175624192 07:35:45 INFO - PROCESS | 14079 | 07:35:47 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35301/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:35:47 INFO - PROCESS | 14079 | RSS: Main: 176861184 07:35:47 INFO - PROCESS | 14079 | 07:35:50 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35301/tests/kraken/driver.html?testName=imaging-darkroom) 07:35:51 INFO - PROCESS | 14079 | RSS: Main: 212496384 07:35:51 INFO - PROCESS | 14079 | 07:35:54 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35301/tests/kraken/driver.html?testName=imaging-desaturate) 07:35:55 INFO - PROCESS | 14079 | RSS: Main: 211832832 07:35:55 INFO - PROCESS | 14079 | 07:35:58 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35301/tests/kraken/driver.html?testName=json-parse-financial) 07:35:58 INFO - PROCESS | 14079 | RSS: Main: 212168704 07:35:58 INFO - PROCESS | 14079 | 07:36:00 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35301/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:36:00 INFO - PROCESS | 14079 | RSS: Main: 175906816 07:36:00 INFO - PROCESS | 14079 | 07:36:02 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:36:03 INFO - PROCESS | 14079 | RSS: Main: 181977088 07:36:03 INFO - PROCESS | 14079 | 07:36:05 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:36:05 INFO - PROCESS | 14079 | RSS: Main: 185307136 07:36:05 INFO - PROCESS | 14079 | 07:36:08 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:36:08 INFO - PROCESS | 14079 | RSS: Main: 180146176 07:36:08 INFO - PROCESS | 14079 | 07:36:11 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:36:11 INFO - PROCESS | 14079 | RSS: Main: 183685120 07:36:11 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | Cycle 1(1): loaded http://localhost:35301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35301/tests/kraken/driver.html?testName=ai-astar) 07:36:13 INFO - PROCESS | 14079 | RSS: Main: 235397120 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | __start_tp_report 07:36:13 INFO - PROCESS | 14079 | _x_x_mozilla_page_load 07:36:13 INFO - PROCESS | 14079 | _x_x_mozilla_page_load_details 07:36:13 INFO - PROCESS | 14079 | |i|pagename|runs| 07:36:13 INFO - PROCESS | 14079 | |0;ai-astar;103;111;112;110;110;112;111;112;111;113 07:36:13 INFO - PROCESS | 14079 | |1;audio-beat-detection;141;116;121;171;115;123;151;120;158;115 07:36:13 INFO - PROCESS | 14079 | |2;audio-dft;162;160;160;162;161;161;160;161;161;161 07:36:13 INFO - PROCESS | 14079 | |3;audio-fft;74;74;75;111;75;75;75;75;103;74 07:36:13 INFO - PROCESS | 14079 | |4;audio-oscillator;84;83;127;83;85;83;120;83;84;129 07:36:13 INFO - PROCESS | 14079 | |5;imaging-gaussian-blur;117;116;116;117;117;117;116;116;117;116 07:36:13 INFO - PROCESS | 14079 | |6;imaging-darkroom;145;145;146;145;146;146;146;146;145;145 07:36:13 INFO - PROCESS | 14079 | |7;imaging-desaturate;95;95;95;95;94;94;94;94;95;94 07:36:13 INFO - PROCESS | 14079 | |8;json-parse-financial;52;50;53;50;50;51;49;50;50;50 07:36:13 INFO - PROCESS | 14079 | |9;json-stringify-tinderbox;59;61;60;60;60;60;59;59;59;60 07:36:13 INFO - PROCESS | 14079 | |10;stanford-crypto-aes;72;73;73;72;72;72;76;72;71;72 07:36:13 INFO - PROCESS | 14079 | |11;stanford-crypto-ccm;131;124;129;125;115;115;115;116;160;122 07:36:13 INFO - PROCESS | 14079 | |12;stanford-crypto-pbkdf2;150;152;149;151;151;156;155;152;153;153 07:36:13 INFO - PROCESS | 14079 | |13;stanford-crypto-sha256-iterative;73;65;62;62;60;64;62;63;62;59 07:36:13 INFO - PROCESS | 14079 | __end_tp_report 07:36:13 INFO - PROCESS | 14079 | __start_cc_report 07:36:13 INFO - PROCESS | 14079 | _x_x_mozilla_cycle_collect,1589 07:36:13 INFO - PROCESS | 14079 | __end_cc_report 07:36:13 INFO - PROCESS | 14079 | __startTimestamp1473258973889__endTimestamp 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | ------- Summary: start ------- 07:36:13 INFO - PROCESS | 14079 | Number of tests: 14 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.80 (2.5%) stddev-sans-first:1.00 07:36:13 INFO - PROCESS | 14079 | Values: 103.0 111.0 112.0 110.0 110.0 112.0 111.0 112.0 111.0 113.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:132.00 stddev:20.57 (15.6%) stddev-sans-first:21.61 07:36:13 INFO - PROCESS | 14079 | Values: 141.0 116.0 121.0 171.0 115.0 123.0 151.0 120.0 158.0 115.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 07:36:13 INFO - PROCESS | 14079 | Values: 162.0 160.0 160.0 162.0 161.0 161.0 160.0 161.0 161.0 161.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.79 (18.4%) stddev-sans-first:14.38 07:36:13 INFO - PROCESS | 14079 | Values: 74.0 74.0 75.0 111.0 75.0 75.0 75.0 75.0 103.0 74.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:83.00 stddev:20.31 (24.5%) stddev-sans-first:21.06 07:36:13 INFO - PROCESS | 14079 | Values: 84.0 83.0 127.0 83.0 85.0 83.0 120.0 83.0 84.0 129.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 07:36:13 INFO - PROCESS | 14079 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 07:36:13 INFO - PROCESS | 14079 | Values: 145.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:36:13 INFO - PROCESS | 14079 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:1.18 (2.4%) stddev-sans-first:1.12 07:36:13 INFO - PROCESS | 14079 | Values: 52.0 50.0 53.0 50.0 50.0 51.0 49.0 50.0 50.0 50.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:36:13 INFO - PROCESS | 14079 | Values: 59.0 61.0 60.0 60.0 60.0 60.0 59.0 59.0 59.0 60.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#10] stanford-crypto-aes Cycles:10 Average:72.50 Median:72.00 stddev:1.35 (1.9%) stddev-sans-first:1.42 07:36:13 INFO - PROCESS | 14079 | Values: 72.0 73.0 73.0 72.0 72.0 72.0 76.0 72.0 71.0 72.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:124.50 stddev:13.61 (10.9%) stddev-sans-first:14.28 07:36:13 INFO - PROCESS | 14079 | Values: 131.0 124.0 129.0 125.0 115.0 115.0 115.0 116.0 160.0 122.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:152.50 stddev:2.15 (1.4%) stddev-sans-first:2.13 07:36:13 INFO - PROCESS | 14079 | Values: 150.0 152.0 149.0 151.0 151.0 156.0 155.0 152.0 153.0 153.0 07:36:13 INFO - PROCESS | 14079 | 07:36:13 INFO - PROCESS | 14079 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:62.50 stddev:3.85 (6.2%) stddev-sans-first:1.83 07:36:13 INFO - PROCESS | 14079 | Values: 73.0 65.0 62.0 62.0 60.0 64.0 62.0 63.0 62.0 59.0 07:36:13 INFO - PROCESS | 14079 | -------- Summary: end -------- 07:36:13 INFO - PROCESS | 14079 | 07:36:14 INFO - TEST-INFO | 14079: exit 0 07:36:14 INFO - TEST-OK | kraken | took 59759ms 07:36:14 INFO - SUITE-END | took 1173s 07:36:15 INFO - Completed test suite (00:19:33) 07:36:15 INFO - javascript benchmark 07:36:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.951730724039, 3237.7409807215417, 3248.6101667799867, 3286.605607327121, 3286.786358886672, 5586.916196257054, 5670.3479099903525, 5671.149327600857, 5679.659220446771, 5681.943180568193, 5556.749946252418, 5623.578231632623, 5624.3813180550105, 5641.379448260688, 5668.886622267552, 4964.751762411883, 5012.448630650625, 5082.288479612848, 5103.591712662978, 5161.81933632321, 5043.167877300241, 5136.178211486163, 5141.30592370029, 5146.253793199984, 5149.098907691139, 4963.528464795839, 4984.601231901457, 4987.102321582114, 5003.4246061702825, 5129.435762066169, 5077.517635824591, 5094.006668699605, 5107.978404319114, 5111.28442018114, 5143.254228136937, 4949.604031677457, 4980.228064649985, 4991.226359914219, 5003.724795136266, 5004.474530174352, 5452.1276595744885, 5454.809081682161, 5460.180972854064, 5503.972480137573, 5510.1459273812625, 5966.254218222723, 5970.6716130612795, 5996.040633498619, 6001.099835024737, 6002.6398416094735, 5980.611260268069, 6002.309734380558, 6005.309389420186, 6043.123747056652, 6079.47244165084, 5644.209810626516, 5706.828795136111, 5711.114777209539, 5712.0, 5718.456746609066, 5889.057750759858, 6190.164327815777, 6190.907136392958, 6198.504119670506, 6199.225096862892, 5751.1085781703905, 5754.625949313281, 5759.94240057594, 5840.88318233633, 5881.088431293157, 5937.139114828412, 5940.673262970492, 5969.31352894414, 5975.014122669798, 5978.671173085435, 5723.055695810228, 5903.940960590425, 5908.231929849178, 5910.527157827363, 5915.940840591625, 5870.442307980735, 5892.381299963527, 5905.1142328650185, 5966.791162309385, 6001.939980600226, 4585.747783871919, 4587.518310577341, 4587.610053145453, 4593.081383723269, 4618.56123668251, 3963.0, 4104.610062044101, 4104.835806567764, 4122.732022418533, 4134.689898257582, 5588.636738611977, 5642.04085305499, 5650.858728531737, 5675.460831221028, 5685.459881311269, 5903.291605007341, 5939.821805345847, 5944.375840536682, 5947.732352044169, 5967.940320596826, 5630.915536266959, 5641.661500309994, 5645.5483561314995, 5649.604527683105, 5650.29371328584, 4976.0794253063295, 5099.59203263738, 5143.61422893277, 5145.15105007677, 5212.166053431509, 6209.6895155242055, 6219.626822390671, 6222.222222222223, 6224.284207316216, 6225.8132256032395, 5975.34271230173, 5988.251468566429, 6033.487153591994, 6046.637201767732, 6070.423309785563, 6012.218411606463, 6107.206063211932, 6128.533679014772, 6132.78385080153, 6190.133381326708, 5936.079907614327, 5938.61399009063, 5944.346121926498, 5949.91075133882, 5951.196588460502, 6039.63762174271, 6041.305249896318, 6043.033114701627, 6046.606970546901, 6049.879002420015, 5684.147377893349, 5831.387704290988, 5842.7078646066675, 5852.4440178183, 5930.199423077829, 5879.118132280192, 5887.293524777054, 5893.852653683693, 5894.616849904743, 5913.6747478887355, 5789.305283365855, 5793.15999180125, 5833.4749872511675, 5837.445442682938, 5850.180974663465, 5747.396523364986, 5848.5028772553105, 5850.970745146247, 5876.236089308362, 5905.822825315247, 5768.105943578752, 5783.16144159103, 5786.161006654096, 5822.592418530662, 5846.064629659234], "value": 5511.1166656845235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.4715850051954, 637.4167636612499, 645.303072681504, 665.2981104934292, 674.1505702814452, 34356.0, 44515.55484445159, 45926.77036614821, 45931.0, 46051.0, 47341.0, 47845.76077119618, 47891.760541197335, 47914.76042619791, 47960.52039479613, 8455.450395724289, 9215.493147876865, 9336.299777516679, 9368.0, 11137.164712646543, 10935.453227338641, 11290.096792256641, 11303.378314192716, 11310.547578096866, 11355.318680879174, 11448.484818183202, 11483.13876459269, 11500.194986350958, 11503.07975361969, 11505.597304094317, 11305.773884522263, 11390.373529455877, 11408.600698975495, 11412.0, 11428.542858285658, 11091.83362249567, 11094.889051109467, 11181.105511559055, 11188.608398706006, 11190.66428007161, 44645.55354446446, 45031.549684503065, 45058.098838023056, 45082.0, 45085.774571127266, 3660.744484014105, 3897.279003384355, 3983.1237127831832, 4001.6198461146437, 4024.557298697141, 34379.31241375183, 34493.655063449296, 34573.0, 34648.65351346479, 34805.651943480494, 4489.147062058199, 4519.367288579601, 4519.570640789153, 4521.095780843844, 4557.931631025537, 351110.0, 351599.0, 351880.0, 355029.0, 355623.2218838915, 60175.0, 60552.69723651354, 60641.696791516646, 60662.39337606567, 60767.39232607706, 38827.0, 39441.802790985865, 39461.0, 41682.58317416787, 63407.0, 19313.03434828253, 19539.902300488407, 19546.218151274075, 19558.413247602595, 19604.509887252934, 13855.307234638227, 15112.168830714196, 15239.314230859416, 15385.615359616098, 15410.152441615819, 20367.0, 20386.592268154553, 20481.078351474218, 20563.0, 20711.896440518, 7121.643917804089, 7395.926040739631, 7419.369353604896, 7467.0, 7686.269804368576, 989.6387818446216, 1110.2062025651699, 1135.7728454309172, 1157.3750174905456, 1178.8821117888142, 34570.13574660654, 34720.0, 34819.30361392758, 34914.30171396609, 35027.82486087604, 1120.2102517724984, 1155.1509640414288, 1167.416291854073, 1169.1698893785508, 1241.2862604002735, 17168.31326746941, 17893.19480623375, 18060.638787224183, 18163.72754408685, 18243.36148234832, 599891.0, 603838.0, 605088.0, 605226.0, 605304.0, 61856.0, 62496.37503624996, 62500.374996249455, 62505.062424062766, 62561.374386256466, 1359.2524111738505, 1453.128123126116, 1457.2932127917952, 1513.9697206055816, 1520.9239538023055, 32662.34675306528, 35266.0, 35276.29447411089, 35341.0, 35755.46366804448, 52177.7391113042, 52680.20979685231, 52808.471915281894, 52872.20691689705, 52921.0, 433059.0, 434336.0, 435950.0, 436180.0, 527326.0, 44268.0, 46332.7683361581, 46781.0, 47408.762956185, 47917.0, 51891.2216316748, 52577.7371113142, 52861.7356913213, 53368.199477007096, 53532.46467535275, 43608.78195609002, 44139.55860441483, 44245.0, 44272.450464233596, 45418.09163816771, 43622.781886090364, 43835.56164438443, 44183.55816441795, 44237.33643995407, 45562.772186138856, 44432.555674444135, 44614.55385446104, 44829.77585112053, 44966.77516612396, 45390.773046134556, 35931.82034089813, 38747.80626096964, 39151.608483914795, 39298.6070139295, 40043.599564003984, 29610.907550038464, 29710.554341685325, 30058.398832022798, 30101.69898300989, 30171.849140754155], "value": 22042.2253377799, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4943199125144, 404.33083247225835, 413.1860235336387, 420.84428761358294, 433.6292469938203, 15666.84333156668, 20101.798982010176, 20261.0882510287, 20383.49041273967, 21253.893730531363, 19477.0, 19768.9011554942, 19895.30366437175, 20853.687194692055, 21235.893820530877, 5502.614816962814, 5517.172424136372, 5605.663660180382, 5645.32256129263, 5645.8588535286535, 4122.42286079949, 4340.587644173798, 5521.1442226454965, 5555.0, 5618.438156184369, 4885.4381746099125, 6151.354107818682, 6209.937900620981, 6280.748770049193, 6281.371862813728, 6221.906671399933, 6274.623522588637, 6328.272248691414, 6356.173697419352, 6361.936380636181, 6148.354422785588, 6161.0, 6167.599106058092, 6170.136180934673, 6171.166892469504, 22494.66258006131, 23429.531409371717, 23615.409614759596, 24600.630990534977, 25135.748642513707, 2481.888315025828, 2483.7143728471096, 2541.250331152296, 2546.2106746908516, 3006.2484378905274, 10731.409772462512, 11008.174386920933, 11079.169062320278, 12551.560695375709, 15222.314995825103, 1903.1816318982833, 1927.026851440028, 1929.3150931419284, 1987.21505005522, 2020.8484363672783, 63254.0, 75216.0, 77897.61051194764, 85006.0, 95047.0, 11430.14273929442, 11655.88344116565, 11945.283283003047, 12056.156069075081, 12134.453949572115, 16853.915730421268, 16876.324947002227, 17095.658086838193, 17148.39980600673, 17152.48542543726, 8671.869921951176, 9051.27589792828, 9053.456792592466, 9187.0, 9214.861777073349, 8815.382923195442, 8863.113688631085, 8910.777230569287, 8936.46381217129, 8995.820083598292, 4912.189488734319, 5003.499650034967, 5005.27423523587, 5227.60792940531, 5255.5795536357, 1986.4040787763613, 2042.6323261813016, 2044.1312442212, 2099.7900209979184, 2147.699322094908, 943.9433633981984, 1013.3919648211014, 1029.3669393323053, 1031.4430207687765, 1086.9021788039115, 20872.58254834895, 21066.157353705985, 21101.894490527447, 21130.154793808382, 21494.14023439045, 1012.2205901455985, 1027.917766578687, 1028.1517747858047, 1055.514463346869, 1070.978580428387, 9591.328606997582, 11886.346250956107, 11972.281663100242, 11983.161178717406, 12166.756664866652, 530368.0, 531663.0, 536850.0, 544090.0, 546583.0, 91796.54101729582, 93865.0, 94133.5293323529, 94717.0, 95565.5221723887, 1054.288355360129, 1055.3878750324793, 1075.6020272499225, 1096.714854137914, 1105.5688281570194, 18281.542961426072, 18627.0, 19211.807881921563, 19441.805581943998, 19542.706859397404, 44132.558674412845, 44755.55244447644, 44958.0, 45529.0, 45731.54268457273, 60383.09425358711, 64470.35529644831, 64971.0, 65098.0, 66427.33572664211, 8738.868916966123, 9170.312226582899, 9180.908190918006, 9239.445633262027, 9260.0, 12613.810792838298, 13991.930040349733, 14135.505257315945, 14168.0, 14220.502282420066, 12118.0, 12274.202176858476, 12372.690682733004, 12440.751184976069, 12468.438920248793, 12242.143049986771, 12273.140880138304, 12374.195677280948, 12437.75124497523, 12658.0, 12182.0, 12331.753364932472, 12577.559785407468, 12652.43064062101, 12750.107492475438, 8341.499510029418, 8626.78433039179, 8763.60563774619, 8817.294616430798, 8953.418027828171, 12028.939855300667, 12156.878431215575, 12299.754004919672, 12321.44553495077, 12332.445039973401], "value": 9762.816408670968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.2051371745538, 1542.5372388283515, 1573.1111921764202, 1753.25486668166, 1865.4496923407603, 7660.157382687907, 8832.381733278673, 9042.095790420955, 9055.366124371289, 9325.253979681625, 9074.274058075354, 9141.085891410856, 9156.267498600113, 9159.450432974027, 9166.495842728647, 2439.5852705040097, 2462.4213309872166, 2465.0386349323776, 2468.3458883395915, 2470.654108424822, 2388.581998150317, 2448.2899959011866, 2455.2020593307248, 2463.938401539958, 2464.9014039438403, 2429.9149529766373, 2438.8536687798787, 2451.595486744685, 2465.1988103866224, 2469.6295555666616, 2400.759924007585, 2411.8794060297005, 2417.153996101359, 2422.236995346469, 2432.914847980312, 2379.690640216778, 2402.60357041088, 2408.145108486497, 2410.0841680161425, 2410.7468715784767, 8917.866232006525, 9409.952950235274, 9635.373700709502, 9643.710688679352, 9647.0, 5519.061759500894, 5646.717664116819, 5648.406917273668, 5655.349634792011, 5660.405657405954, 3579.3199292134673, 3596.5863925648364, 3633.563972323338, 3650.3976843820737, 3701.68535674468, 1174.307158776326, 1175.8882906123904, 1191.5714719090545, 1210.382704820539, 1219.530480764912, 13651.180929144282, 13717.657058573515, 13789.793153102712, 13805.0, 13808.1024733393, 4441.156180325728, 4627.722336659811, 4738.47876733559, 4748.287756836502, 4761.666683332202, 10779.838302425469, 10784.89215107839, 10785.622503212508, 10787.730306742395, 10789.19081068923, 5585.301837270341, 5591.720413979285, 5595.8880822384135, 5596.552275817925, 5597.0, 4414.713043652152, 4439.778011099432, 4883.0, 5126.307948426915, 5229.555487783581, 3972.2254160439015, 3989.082511022423, 4086.366613174963, 4086.7547947123267, 4093.570175131628, 1122.0967121467133, 1178.723000094977, 1182.148852825964, 1188.0554958807768, 1189.79178643737, 869.2744901370774, 976.6679329028165, 1016.8271393862913, 1020.8928062553473, 1046.9528871200662, 6173.876522469525, 6314.273858506239, 6326.525510586633, 6344.904826427607, 6736.7642132525125, 433.11860364158787, 436.23876335794245, 447.9059397526491, 448.8272015274143, 450.1020464173994, 4054.8986275343354, 4158.313878210122, 4162.1259535497275, 4163.3130533462245, 4182.205380977604, 11643.0, 12169.817452738216, 12195.512179512898, 12210.877891220975, 12233.816492752616, 12112.273263604213, 12139.27164370123, 12141.0, 12142.332171730463, 12142.635720928387, 8527.0, 8962.0, 8970.955145224363, 9063.81872362549, 9080.772980675403, 5887.106428827877, 6052.273727152769, 6474.0, 6557.049227862029, 6669.266380698023, 10705.304155229886, 10806.729831754268, 10923.289986150874, 11002.0, 11004.724881878017, 9662.516874156123, 11085.778284434105, 11210.215284930302, 11402.087832973508, 11494.367809770543, 9059.501727405055, 9209.079092090737, 9229.723108306762, 9330.0, 9343.81312373735, 9318.207952324128, 9436.197923176607, 9451.858222126812, 9452.10205030521, 9461.905380946379, 8252.298554622925, 8274.420790544846, 8529.445586036887, 8643.135686431306, 8733.475991440533, 8221.424500285162, 8293.709720159766, 8487.618057187317, 8504.319654427754, 8560.14398560139, 7234.6020968847215, 7263.745768898063, 7320.0, 7358.926410735824, 7391.186969433251, 5361.27622770921, 5421.457854214547, 5495.230667706444, 5551.1673249011355, 5621.718914054362, 5271.0, 5558.888822223452, 5635.0, 5726.0, 5919.6152250103605], "value": 4813.075457664853, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.97382696297774, 350.2924093331471, 353.7894952503263, 357.3086078438221, 358.4658858301131, 5456.39979602244, 5515.779368825247, 5524.22660827484, 5554.611177217596, 5578.525825304854, 5446.782128714856, 5467.152591348337, 5483.2049352843815, 5486.670799752018, 5501.614886957915, 3866.6466736642233, 4127.257093723121, 4310.633596144316, 4312.698111132222, 4347.78261086946, 4300.741955482681, 4305.870823875289, 4309.0735491869245, 4310.741355518663, 4342.54403287655, 4123.216588848124, 4326.74039557626, 4343.391925130484, 4350.456192975878, 4362.25841606926, 4252.702925607704, 4307.612314891675, 4310.663694254791, 4338.3058710606465, 4343.348497725335, 3862.671672907806, 3866.864659736924, 3874.128321127752, 3886.319894018564, 3887.0, 5218.739063046871, 5330.733463326818, 5355.357357117171, 5360.544353729938, 5422.132458806572, 4929.285253638202, 4933.30933669283, 4945.233488809205, 4955.900881982377, 4976.850694479172, 5647.661140331552, 5658.207850900877, 5664.716764161817, 5724.885502289931, 5776.768929242825, 4102.0, 4234.750748529192, 4238.724482908601, 4274.658027357835, 4274.935875961863, 5678.545716342724, 5806.912896306559, 5968.582199246054, 5978.760849566012, 6001.849953751147, 4295.548967358445, 4300.118475712471, 4327.091310824699, 4336.978315108404, 4349.934750978738, 4352.521222665504, 4376.059147283349, 4385.429894113745, 4410.095930334273, 4411.8014689338415, 4857.562819346205, 4944.678595891256, 4947.0, 4951.9752401237765, 4954.727489988085, 5687.573431992534, 5690.487856092972, 5705.74324155414, 5726.682862941547, 5776.971115144397, 1486.7844162596364, 1505.119505089513, 1511.833698293187, 1526.702293052844, 1527.633367991674, 947.8483442649143, 974.9032338619054, 979.5543027922279, 989.366805244644, 1000.5342173715396, 4914.004914004909, 4919.950800492021, 4923.90152196954, 4934.580560652313, 4962.0, 5144.61415393847, 5153.45888681683, 5158.871028224325, 5179.922301165486, 5216.66091704038, 4634.536546345406, 4940.283658869515, 4991.950080499221, 5013.974930125399, 5025.497450254945, 2749.9550170935318, 2765.6681198255937, 2766.9308267293477, 2770.7367800058964, 2776.625570342693, 5334.91997620036, 5366.424116424138, 5371.227494926665, 5371.979323928441, 5380.0, 5020.117455954017, 5144.254083157921, 5146.279520867081, 5224.373075230997, 5231.42454330031, 5223.529882310534, 5301.905747229823, 5329.54698850602, 5352.518273355417, 5406.0, 5156.716380599103, 5158.8392611662075, 5162.373852236534, 5181.937702770998, 5244.947550524446, 4972.5276098770555, 5096.796128154832, 5125.205593133071, 5145.4340022598235, 5151.2788209649925, 5358.356997160366, 5819.301683797971, 5929.970350148222, 6078.756849726138, 6105.572609917263, 4297.140571885573, 4312.504062032906, 4315.503717072577, 4326.3943047973935, 4336.34954756789, 5717.857053573527, 5903.763849445974, 5944.791932282349, 5994.730237139252, 6018.157457955802, 5773.134029895381, 5783.0915797787475, 5784.334801497714, 5790.681512516852, 5804.2744656917885, 5717.885642287214, 5728.598998070262, 5763.769449222152, 5810.767569297181, 5811.663317436929, 5479.75341109659, 5731.481157493185, 5736.971315143398, 5775.162601422686, 5778.595498315078, 5817.534597232296, 5821.621594596338, 5833.970830145992, 5846.239988801429, 5858.677772722371, 5533.667979921218, 5715.657060576213, 5741.971290143522, 5759.366469688414, 5802.1296805479515], "value": 4277.47490893202, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.8202831320484, 789.9605019749011, 812.1229072601589, 821.4783612406125, 951.3055469507261, 15903.12532810695, 17055.317787288524, 17102.65794684107, 17103.315867365323, 17123.828761712393, 15592.220388980533, 15696.293666784994, 15706.21468926552, 15719.0, 15737.84262157378, 722.093772315743, 848.4485084694936, 857.3098655582254, 877.1053525404084, 881.3654168998311, 875.4922145155795, 878.0736323179044, 878.802269489363, 880.6257340630239, 881.3389957531851, 927.5733162745146, 928.7538802217417, 929.5584597316288, 929.7768535551453, 931.1293940165932, 764.1212605503688, 906.5920335848862, 914.2640174659364, 917.3716004536847, 925.5973651461636, 673.3401266758555, 677.617146312332, 678.1082876018021, 678.8404724889647, 679.3987321220756, 15731.449399271085, 15754.527364179094, 15757.60605984848, 15759.92120039404, 15783.526494205193, 14239.0, 14362.569122926328, 14957.476488322965, 15138.394464221417, 15218.695626087527, 12848.935755321258, 12928.806067908989, 13098.2141071536, 13107.934460327733, 13177.670558236121, 4589.885252868705, 4668.95331046692, 4732.73969931657, 4739.052189562067, 4751.453582837983, 68142.65928670374, 70278.64860675664, 70348.0, 80125.19874801235, 81694.0, 12208.755824883452, 12227.449764760608, 12461.376931153405, 12536.874631253753, 12707.618771436872, 20566.0, 20569.0, 20573.58852822965, 20577.07403166861, 20585.485362865747, 9097.40866843653, 9179.357444978921, 9193.586288617029, 9389.953050234704, 9494.572744226527, 4968.105740966588, 4995.425526064477, 5095.337606111256, 5122.103631864484, 5233.2935053768015, 3343.866245350159, 3451.6375780543185, 3454.6372630873398, 3455.170759017868, 3489.668481494254, 669.7086767256162, 673.565550220115, 694.5103701890155, 698.1900994845954, 712.9251428599922, 742.2651574940878, 765.7894079128173, 772.791346336486, 797.4856217739525, 805.2269820971816, 9806.0, 9811.716827653874, 9921.553530091163, 9973.551190196458, 10110.747231319128, 601.575888998264, 660.2539130782185, 675.6689222281046, 741.9517731347445, 788.1999770233218, 5064.746762661852, 5100.285959965608, 5264.157734762496, 5276.709780962084, 5340.092184328673, 36116.63883361133, 36352.63647363545, 73120.26879731135, 116550.0, 119842.0, 103340.48329758304, 104468.47765761122, 104585.47707261414, 105148.47425762822, 105429.0, 76709.23290767022, 77246.0, 90694.54652726826, 92321.53839230762, 94418.0, 10147.0, 10448.738781530523, 10570.41862697559, 11247.0, 11531.942340288246, 50403.0, 50421.74789126031, 52015.73992130015, 52136.739316303174, 52988.4701152999, 72950.63524682343, 75047.62476187803, 78996.60501697454, 80008.0, 80579.0, 11708.356040417519, 12080.577179798665, 12239.571614993432, 12769.233845969271, 13102.803457947948, 14638.121712697272, 15437.99306090246, 15984.920075399548, 16205.59486012859, 16321.18394080315, 14882.627934301729, 14979.850201498282, 15697.450589229322, 15768.369265229263, 16005.519834404986, 12875.0, 14448.0, 14490.202805164996, 14572.198529080579, 14623.488177913721, 13955.790663140264, 14656.193909335088, 15339.76990345168, 15412.460563880659, 15456.613584660474, 11842.703932401759, 12312.322822244505, 12629.305388203737, 12852.0, 12896.742065158833, 11775.941120294687, 12074.215176013531, 12211.26732396059, 12667.619971400873, 12921.935390322988], "value": 7916.9744113513425, "unit": "score"}], "name": "dromaeo_css", "value": 7604.098682445473, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 110.0, 110.0, 112.0, 111.0, 112.0, 111.0, 113.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 121.0, 171.0, 115.0, 123.0, 151.0, 120.0, 158.0, 115.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 162.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 111.0, 75.0, 75.0, 75.0, 75.0, 103.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 127.0, 83.0, 85.0, 83.0, 120.0, 83.0, 84.0, 129.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 53.0, 50.0, 50.0, 51.0, 49.0, 50.0, 50.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 72.0, 72.0, 72.0, 76.0, 72.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 124.0, 129.0, 125.0, 115.0, 115.0, 115.0, 116.0, 160.0, 122.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 149.0, 151.0, 151.0, 156.0, 155.0, 152.0, 153.0, 153.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 65.0, 62.0, 62.0, 60.0, 64.0, 62.0, 63.0, 62.0, 59.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1461.5000000000002, "alertThreshold": 2.0}]} 07:36:15 INFO - Return code: 0 07:36:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:36:15 INFO - # TBPL SUCCESS # 07:36:15 INFO - Running post-action listener: _resource_record_post_action 07:36:15 INFO - [mozharness: 2016-09-07 14:36:15.175149Z] Finished run-tests step (success) 07:36:15 INFO - Running post-run listener: _resource_record_post_run 07:36:15 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2310144; Write bytes: 323436544; Read time: 428; Write time: 226664 07:36:15 INFO - TinderboxPrint: CPU usage
13.0% 07:36:15 INFO - TinderboxPrint: I/O read bytes / time
2,310,144 / 428 07:36:15 INFO - TinderboxPrint: I/O write bytes / time
323,436,544 / 226,664 07:36:15 INFO - TinderboxPrint: CPU idle
8,262.0 (87.0%) 07:36:15 INFO - TinderboxPrint: CPU user
1,189.5 (12.5%) 07:36:15 INFO - TinderboxPrint: Swap in / out
0 / 0 07:36:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77164544; Read time: 0; Write time: 81752 07:36:15 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 2310144; Write bytes: 243806208; Read time: 428; Write time: 144668 07:36:15 INFO - Running post-run listener: _upload_blobber_files 07:36:15 INFO - Blob upload gear active. 07:36:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:36:15 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 07:36:15 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'] 07:36:15 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 07:36:16 INFO - (blobuploader) - INFO - Open directory for files ... 07:36:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:36:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:17 INFO - (blobuploader) - INFO - Done attempting. 07:36:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:36:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:17 INFO - (blobuploader) - INFO - Done attempting. 07:36:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:36:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:36:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:23 INFO - (blobuploader) - INFO - Done attempting. 07:36:23 INFO - (blobuploader) - INFO - Iteration through files over. 07:36:23 INFO - Return code: 0 07:36:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:36:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:36:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46153c69cc301a039ecde71a960e57186d40f0bae7404e9f201f2e12c67f9ffb8ac37c9c21ffd32992f5ba4fdfabddbe3ebdee9d4f0a2c7c0f0c4d4f1227d261", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb4cfa0f2140701f89ab687af3737e2f1628b18aab7fe941e97264526eb052b0ee8b686daa9eeaa0db0edb8b2db3b1afb079cd6d4bac3c4ef71ec2d2ab4448a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc101c8aa1a64e2983e08350fb70c835b88b8fb96d7d36dbcfdd6f14c97f57eee4b36dc564abb08d0c8f5f8952f6fedb512275899188e1f1b9191f4f812644c9"} 07:36:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:36:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:36:23 INFO - Contents: 07:36:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46153c69cc301a039ecde71a960e57186d40f0bae7404e9f201f2e12c67f9ffb8ac37c9c21ffd32992f5ba4fdfabddbe3ebdee9d4f0a2c7c0f0c4d4f1227d261", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb4cfa0f2140701f89ab687af3737e2f1628b18aab7fe941e97264526eb052b0ee8b686daa9eeaa0db0edb8b2db3b1afb079cd6d4bac3c4ef71ec2d2ab4448a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc101c8aa1a64e2983e08350fb70c835b88b8fb96d7d36dbcfdd6f14c97f57eee4b36dc564abb08d0c8f5f8952f6fedb512275899188e1f1b9191f4f812644c9"} 07:36:23 INFO - Running post-run listener: copy_logs_to_upload_dir 07:36:23 INFO - Copying logs to upload dir... 07:36:23 INFO - mkdir: /builds/slave/test/build/upload/logs 07:36:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.833732 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-07 07:36:23.268992) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 07:36:23.275566) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 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/46153c69cc301a039ecde71a960e57186d40f0bae7404e9f201f2e12c67f9ffb8ac37c9c21ffd32992f5ba4fdfabddbe3ebdee9d4f0a2c7c0f0c4d4f1227d261", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb4cfa0f2140701f89ab687af3737e2f1628b18aab7fe941e97264526eb052b0ee8b686daa9eeaa0db0edb8b2db3b1afb079cd6d4bac3c4ef71ec2d2ab4448a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc101c8aa1a64e2983e08350fb70c835b88b8fb96d7d36dbcfdd6f14c97f57eee4b36dc564abb08d0c8f5f8952f6fedb512275899188e1f1b9191f4f812644c9"} build_url:https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/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/46153c69cc301a039ecde71a960e57186d40f0bae7404e9f201f2e12c67f9ffb8ac37c9c21ffd32992f5ba4fdfabddbe3ebdee9d4f0a2c7c0f0c4d4f1227d261", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb4cfa0f2140701f89ab687af3737e2f1628b18aab7fe941e97264526eb052b0ee8b686daa9eeaa0db0edb8b2db3b1afb079cd6d4bac3c4ef71ec2d2ab4448a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc101c8aa1a64e2983e08350fb70c835b88b8fb96d7d36dbcfdd6f14c97f57eee4b36dc564abb08d0c8f5f8952f6fedb512275899188e1f1b9191f4f812644c9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 07:36:23.299790) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:36:23.300149) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473257302.277149-1022835988 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004393 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 07:36:23.382995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 07:36:23.383304) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 07:36:23.383596) ========= ========= Total master_lag: 0.17 =========