builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1472737945.55 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.550271) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.550645) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.550935) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.081715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.082006) ========= 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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:26-- 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% 224M=0s 2016-09-01 06:52:26 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.196879) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.197309) ========= 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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018377 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.262833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.263138) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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 bac8c738419eb9ff1fe0947120e879707d19c97b --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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:26,921 truncating revision to first 12 chars 2016-09-01 06:52:26,921 Setting DEBUG logging. 2016-09-01 06:52:26,921 attempt 1/10 2016-09-01 06:52:26,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bac8c738419e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:27,208 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623531 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.916935) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.917251) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.917638) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.917906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.927552) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-01 06:52:26.928263) ========= /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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:27 INFO - MultiFileLogger online at 20160901 06:52:27 in /builds/slave/test 06:52:27 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 06:52:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:27 INFO - {'append_to_log': False, 06:52:27 INFO - 'base_work_dir': '/builds/slave/test', 06:52:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:27 INFO - 'branch': 'Mozilla-Aurora', 06:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:27 INFO - 'config_files': ('talos/linux_config.py',), 06:52:27 INFO - 'default_actions': ('clobber', 06:52:27 INFO - 'read-buildbot-config', 06:52:27 INFO - 'download-and-extract', 06:52:27 INFO - 'populate-webroot', 06:52:27 INFO - 'create-virtualenv', 06:52:27 INFO - 'install', 06:52:27 INFO - 'run-tests'), 06:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:27 INFO - 'download_minidump_stackwalk': True, 06:52:27 INFO - 'download_symbols': 'ondemand', 06:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:27 INFO - 'installer_path': 'installer.exe', 06:52:27 INFO - 'log_level': 'info', 06:52:27 INFO - 'log_name': 'talos', 06:52:27 INFO - 'log_to_console': True, 06:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:27 INFO - 'opt_config_files': (), 06:52:27 INFO - 'pip_index': False, 06:52:27 INFO - 'sps_profile': False, 06:52:27 INFO - 'sps_profile_interval': 0, 06:52:27 INFO - 'suite': 'dromaeojs', 06:52:27 INFO - 'system_bits': '32', 06:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:27 INFO - 'title': 'talos-linux64-ix-017', 06:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:27 INFO - 'use_talos_json': True, 06:52:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:27 INFO - 'work_dir': 'build'} 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.665767Z] Running clobber step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: clobber 06:52:27 INFO - rmtree: /builds/slave/test/build 06:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.129648Z] Finished clobber step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.129748Z] Running read-buildbot-config step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: read_buildbot_config 06:52:28 INFO - Using buildbot properties: 06:52:28 INFO - { 06:52:28 INFO - "project": "", 06:52:28 INFO - "product": "firefox", 06:52:28 INFO - "script_repo_revision": "production", 06:52:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:52:28 INFO - "repository": "", 06:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:52:28 INFO - "buildid": "20160830104522", 06:52:28 INFO - "pgo_build": "True", 06:52:28 INFO - "basedir": "/builds/slave/test", 06:52:28 INFO - "buildnumber": 723, 06:52:28 INFO - "slavename": "talos-linux64-ix-017", 06:52:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:28 INFO - "platform": "ubuntu64_hw", 06:52:28 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:28 INFO - "branch": "mozilla-aurora", 06:52:28 INFO - "repo_path": "releases/mozilla-aurora", 06:52:28 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:28 INFO - "stage_platform": "linux64-pgo", 06:52:28 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:28 INFO - "slavebuilddir": "test" 06:52:28 INFO - } 06:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.132174Z] Finished read-buildbot-config step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.132262Z] Running download-and-extract step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: download_and_extract 06:52:28 INFO - mkdir: /builds/slave/test/build/tests 06:52:28 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')]} 06:52:28 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:58 INFO - Downloaded 1361 bytes. 06:53:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Using the following test package requirements: 06:53:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'jsshell-linux-x86_64.zip'], 06:53:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:58 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:54:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:28 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:29 INFO - Downloaded 21769513 bytes. 06:55:29 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'] 06:55:29 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 06:55:30 INFO - Return code: 0 06:55:30 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 06:55: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')]} 06:55:30 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:30 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:56:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:00 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:01 INFO - Downloaded 21769513 bytes. 06:57:01 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'] 06:57:01 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 06:57:03 INFO - Return code: 0 06:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:03 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:03 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:33 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:34 INFO - Downloaded 14413723 bytes. 06:58:34 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'] 06:58:34 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 06:58:34 INFO - Return code: 0 06:58:34 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')]} 06:58:34 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:59:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:04 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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:00:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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:00:05 INFO - Downloaded 63889067 bytes. 07:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - mkdir: /builds/slave/test/properties 07:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:05 INFO - Contents: 07:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - Running post-action listener: set_extra_try_arguments 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.316177Z] Finished download-and-extract step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.316407Z] Running populate-webroot step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: populate_webroot 07:00:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:06 INFO - '--tppagecycles', 07:00:06 INFO - '7'], 07:00:06 INFO - 'tests': ['tsvgm']}}, 07:00:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s', 07:00:06 INFO - '--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}, 07:00:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}}, 07:00:06 INFO - 'talos.zip': {'path': '', 07:00:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.338399Z] Finished populate-webroot step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.338489Z] Running create-virtualenv step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: create_virtualenv 07:00:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:06 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:00:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:06 INFO - Using real prefix '/usr' 07:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:08 INFO - Installing pip.................done. 07:00:08 INFO - Return code: 0 07:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00: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:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00: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', 'pip>=1.5'] in /builds/slave/test/build 07:00: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 pip>=1.5 07:00:08 INFO - Using env: {'DISPLAY': ':0', 07:00:08 INFO - 'HOME': '/home/cltbld', 07:00:08 INFO - 'LANG': 'en_US.UTF-8', 07:00:08 INFO - 'LANGUAGE': 'en_US:en', 07:00:08 INFO - 'LOGNAME': 'cltbld', 07:00:08 INFO - 'MAIL': '/var/mail/cltbld', 07:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:08 INFO - 'MOZ_NO_REMOTE': '1', 07:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:08 INFO - 'NO_EM_RESTART': '1', 07:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:08 INFO - 'PWD': '/builds/slave/test', 07:00:08 INFO - 'SHELL': '/bin/bash', 07:00:08 INFO - 'SHLVL': '1', 07:00:08 INFO - 'TERM': 'linux', 07:00:08 INFO - 'TMOUT': '86400', 07:00:08 INFO - 'USER': 'cltbld', 07:00:08 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:08 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 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:00:09 INFO - Cleaning up... 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:09 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:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:09 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:00:09 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:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 INFO - Downloading/unpacking psutil>=3.1.1 07:00:09 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:00:09 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:00:09 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:00:09 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:00:09 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:00:09 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:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:11 INFO - Installing collected packages: psutil 07:00:11 INFO - Running setup.py install for psutil 07:00:11 INFO - building 'psutil._psutil_linux' extension 07:00:11 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:00:11 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:00:11 INFO - building 'psutil._psutil_posix' extension 07:00:11 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:00:11 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:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:11 INFO - Successfully installed psutil 07:00:11 INFO - Cleaning up... 07:00:11 INFO - Return code: 0 07:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:11 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:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:11 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:00:11 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:00:11 INFO - Using env: {'DISPLAY': ':0', 07:00:11 INFO - 'HOME': '/home/cltbld', 07:00:11 INFO - 'LANG': 'en_US.UTF-8', 07:00:11 INFO - 'LANGUAGE': 'en_US:en', 07:00:11 INFO - 'LOGNAME': 'cltbld', 07:00:11 INFO - 'MAIL': '/var/mail/cltbld', 07:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:11 INFO - 'MOZ_NO_REMOTE': '1', 07:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:11 INFO - 'NO_EM_RESTART': '1', 07:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:11 INFO - 'PWD': '/builds/slave/test', 07:00:11 INFO - 'SHELL': '/bin/bash', 07:00:11 INFO - 'SHLVL': '1', 07:00:11 INFO - 'TERM': 'linux', 07:00:11 INFO - 'TMOUT': '86400', 07:00:11 INFO - 'USER': 'cltbld', 07:00:11 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:11 INFO - '_': '/tools/buildbot/bin/python'} 07:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00: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:00: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:00: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:00: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:00: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:00: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:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:14 INFO - Installing collected packages: mozsystemmonitor 07:00:14 INFO - Running setup.py install for mozsystemmonitor 07:00:14 INFO - Successfully installed mozsystemmonitor 07:00:14 INFO - Cleaning up... 07:00:14 INFO - Return code: 0 07:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:14 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:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:14 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:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:14 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:14 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:00:14 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:00:14 INFO - Using env: {'DISPLAY': ':0', 07:00:14 INFO - 'HOME': '/home/cltbld', 07:00:14 INFO - 'LANG': 'en_US.UTF-8', 07:00:14 INFO - 'LANGUAGE': 'en_US:en', 07:00:14 INFO - 'LOGNAME': 'cltbld', 07:00:14 INFO - 'MAIL': '/var/mail/cltbld', 07:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:14 INFO - 'MOZ_NO_REMOTE': '1', 07:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:14 INFO - 'NO_EM_RESTART': '1', 07:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:14 INFO - 'PWD': '/builds/slave/test', 07:00:14 INFO - 'SHELL': '/bin/bash', 07:00:14 INFO - 'SHLVL': '1', 07:00:14 INFO - 'TERM': 'linux', 07:00:14 INFO - 'TMOUT': '86400', 07:00:14 INFO - 'USER': 'cltbld', 07:00:14 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:14 INFO - '_': '/tools/buildbot/bin/python'} 07:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:14 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:00:14 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:00:14 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:00:14 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:00:14 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:00:14 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:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00: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:00: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:00: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:00: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:00: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:00: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:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:16 INFO - warning: no files found matching '*.txt' 07:00:16 INFO - no previously-included directories found matching 'build' 07:00:16 INFO - no previously-included directories found matching 'dist' 07:00:16 INFO - no previously-included directories found matching '.git*' 07:00:16 INFO - Installing collected packages: jsonschema, functools32 07:00:16 INFO - Running setup.py install for functools32 07:00:16 INFO - warning: no files found matching '*.txt' 07:00:16 INFO - no previously-included directories found matching 'build' 07:00:16 INFO - no previously-included directories found matching 'dist' 07:00:16 INFO - no previously-included directories found matching '.git*' 07:00:16 INFO - Successfully installed jsonschema functools32 07:00:16 INFO - Cleaning up... 07:00:16 INFO - Return code: 0 07:00:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00: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:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:16 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:00:16 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:00:16 INFO - Using env: {'DISPLAY': ':0', 07:00:16 INFO - 'HOME': '/home/cltbld', 07:00:16 INFO - 'LANG': 'en_US.UTF-8', 07:00:16 INFO - 'LANGUAGE': 'en_US:en', 07:00:16 INFO - 'LOGNAME': 'cltbld', 07:00:16 INFO - 'MAIL': '/var/mail/cltbld', 07:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:16 INFO - 'MOZ_NO_REMOTE': '1', 07:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:16 INFO - 'NO_EM_RESTART': '1', 07:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:16 INFO - 'PWD': '/builds/slave/test', 07:00:16 INFO - 'SHELL': '/bin/bash', 07:00:16 INFO - 'SHLVL': '1', 07:00:16 INFO - 'TERM': 'linux', 07:00:16 INFO - 'TMOUT': '86400', 07:00:16 INFO - 'USER': 'cltbld', 07:00:16 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:16 INFO - '_': '/tools/buildbot/bin/python'} 07:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:17 INFO - Cleaning up... 07:00:17 INFO - Return code: 0 07:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:17 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:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:17 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:00:17 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:00:17 INFO - Using env: {'DISPLAY': ':0', 07:00:17 INFO - 'HOME': '/home/cltbld', 07:00:17 INFO - 'LANG': 'en_US.UTF-8', 07:00:17 INFO - 'LANGUAGE': 'en_US:en', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MAIL': '/var/mail/cltbld', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SHLVL': '1', 07:00:17 INFO - 'TERM': 'linux', 07:00:17 INFO - 'TMOUT': '86400', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - '_': '/tools/buildbot/bin/python'} 07:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:17 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:00:17 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:00:17 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:00:17 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:00:17 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:00:17 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:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:19 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:00:19 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:00:19 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:00:19 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:00:19 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:00:19 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:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:19 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:00:19 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:00:19 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:00:19 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:00:19 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:00:19 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:00:19 INFO - Downloading docopt-0.6.1.tar.gz 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:20 INFO - Running setup.py install for blobuploader 07:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:20 INFO - Running setup.py install for requests 07:00:20 INFO - Running setup.py install for docopt 07:00:20 INFO - Successfully installed blobuploader requests docopt 07:00:20 INFO - Cleaning up... 07:00:20 INFO - Return code: 0 07:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:20 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:20 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:00:20 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:00:20 INFO - Using env: {'DISPLAY': ':0', 07:00:20 INFO - 'HOME': '/home/cltbld', 07:00:20 INFO - 'LANG': 'en_US.UTF-8', 07:00:20 INFO - 'LANGUAGE': 'en_US:en', 07:00:20 INFO - 'LOGNAME': 'cltbld', 07:00:20 INFO - 'MAIL': '/var/mail/cltbld', 07:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:20 INFO - 'MOZ_NO_REMOTE': '1', 07:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:20 INFO - 'NO_EM_RESTART': '1', 07:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:20 INFO - 'PWD': '/builds/slave/test', 07:00:20 INFO - 'SHELL': '/bin/bash', 07:00:20 INFO - 'SHLVL': '1', 07:00:20 INFO - 'TERM': 'linux', 07:00:20 INFO - 'TMOUT': '86400', 07:00:20 INFO - 'USER': 'cltbld', 07:00:20 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:20 INFO - '_': '/tools/buildbot/bin/python'} 07:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:20 INFO - Running setup.py (path:/tmp/pip-WH_Qo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:20 INFO - Running setup.py (path:/tmp/pip-xR6McN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:20 INFO - Running setup.py (path:/tmp/pip-UNY11W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Running setup.py (path:/tmp/pip-x8S_9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Running setup.py (path:/tmp/pip-jYZeXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Running setup.py (path:/tmp/pip-sE94hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Running setup.py (path:/tmp/pip-k0feIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:21 INFO - Running setup.py (path:/tmp/pip-jtuLN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:21 INFO - Running setup.py (path:/tmp/pip-ZIulU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:21 INFO - Running setup.py (path:/tmp/pip-YBHVJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:21 INFO - Running setup.py (path:/tmp/pip-OGyiqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:21 INFO - Running setup.py (path:/tmp/pip-Ajto98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Running setup.py (path:/tmp/pip-dhcy_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:21 INFO - Running setup.py (path:/tmp/pip-hEYHu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:21 INFO - Running setup.py (path:/tmp/pip-GjMxgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:21 INFO - Running setup.py (path:/tmp/pip-4_yK3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:21 INFO - Running setup.py (path:/tmp/pip-tYJ26p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:22 INFO - Running setup.py install for manifestparser 07:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozcrash 07:00:22 INFO - Running setup.py install for mozdebug 07:00:22 INFO - Running setup.py install for mozdevice 07:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozfile 07:00:22 INFO - Running setup.py install for mozhttpd 07:00:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozinfo 07:00:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozInstall 07:00:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozleak 07:00:22 INFO - Running setup.py install for mozlog 07:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for moznetwork 07:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozprocess 07:00:23 INFO - Running setup.py install for mozprofile 07:00:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozrunner 07:00:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozscreenshot 07:00:23 INFO - Running setup.py install for moztest 07:00:23 INFO - Running setup.py install for mozversion 07:00:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:23 INFO - Cleaning up... 07:00:23 INFO - Return code: 0 07:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:23 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:23 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:00:23 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:00:23 INFO - Using env: {'DISPLAY': ':0', 07:00:23 INFO - 'HOME': '/home/cltbld', 07:00:23 INFO - 'LANG': 'en_US.UTF-8', 07:00:23 INFO - 'LANGUAGE': 'en_US:en', 07:00:23 INFO - 'LOGNAME': 'cltbld', 07:00:23 INFO - 'MAIL': '/var/mail/cltbld', 07:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:23 INFO - 'MOZ_NO_REMOTE': '1', 07:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:23 INFO - 'NO_EM_RESTART': '1', 07:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:23 INFO - 'PWD': '/builds/slave/test', 07:00:23 INFO - 'SHELL': '/bin/bash', 07:00:23 INFO - 'SHLVL': '1', 07:00:23 INFO - 'TERM': 'linux', 07:00:23 INFO - 'TMOUT': '86400', 07:00:23 INFO - 'USER': 'cltbld', 07:00:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:23 INFO - '_': '/tools/buildbot/bin/python'} 07:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 INFO - Running setup.py (path:/tmp/pip-C5RkeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 INFO - Running setup.py (path:/tmp/pip-Xz3iJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 INFO - Running setup.py (path:/tmp/pip-vK_tsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:24 INFO - Running setup.py (path:/tmp/pip-oYJayZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:24 INFO - Running setup.py (path:/tmp/pip-jYeYq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:24 INFO - Running setup.py (path:/tmp/pip-huS9Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:24 INFO - Running setup.py (path:/tmp/pip-4sdgTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:24 INFO - Running setup.py (path:/tmp/pip-xDHv42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:24 INFO - Running setup.py (path:/tmp/pip-mVYTOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:24 INFO - Running setup.py (path:/tmp/pip-04E0bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:24 INFO - Running setup.py (path:/tmp/pip-TCHY4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:24 INFO - Running setup.py (path:/tmp/pip-6ahSl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:24 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:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:24 INFO - Running setup.py (path:/tmp/pip-pY9qth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:25 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:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 INFO - Running setup.py (path:/tmp/pip-4AuFvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 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:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 INFO - Running setup.py (path:/tmp/pip-UuPPLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 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:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:25 INFO - Running setup.py (path:/tmp/pip-HjMvzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:25 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:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:25 INFO - Running setup.py (path:/tmp/pip-FSQ6mM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:25 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:00:25 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:00:25 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:00:25 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:00:25 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:00:25 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:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:25 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:00:25 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:00:25 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:00:25 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:00:25 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:00:25 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:00:27 INFO - Downloading blessings-1.6.tar.gz 07:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:27 INFO - Installing collected packages: blessings 07:00:27 INFO - Running setup.py install for blessings 07:00:27 INFO - Successfully installed blessings 07:00:27 INFO - Cleaning up... 07:00:27 INFO - Return code: 0 07:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:27 INFO - Reading from file tmpfile_stdout 07:00:27 INFO - Current package versions: 07:00:27 INFO - argparse == 1.2.1 07:00:27 INFO - blessings == 1.6 07:00:27 INFO - blobuploader == 1.2.4 07:00:27 INFO - docopt == 0.6.1 07:00:27 INFO - functools32 == 3.2.3-2 07:00:27 INFO - jsonschema == 2.5.1 07:00:27 INFO - manifestparser == 1.1 07:00:27 INFO - mozInstall == 1.12 07:00:27 INFO - mozcrash == 0.17 07:00:27 INFO - mozdebug == 0.1 07:00:27 INFO - mozdevice == 0.48 07:00:27 INFO - mozfile == 1.2 07:00:27 INFO - mozhttpd == 0.7 07:00:27 INFO - mozinfo == 0.9 07:00:27 INFO - mozleak == 0.1 07:00:27 INFO - mozlog == 3.3 07:00:27 INFO - moznetwork == 0.27 07:00:27 INFO - mozprocess == 0.23 07:00:27 INFO - mozprofile == 0.28 07:00:27 INFO - mozrunner == 6.12 07:00:27 INFO - mozscreenshot == 0.1 07:00:27 INFO - mozsystemmonitor == 0.3 07:00:27 INFO - moztest == 0.7 07:00:27 INFO - mozversion == 1.4 07:00:27 INFO - psutil == 3.1.1 07:00:27 INFO - requests == 1.2.3 07:00:27 INFO - wsgiref == 0.1.2 07:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:27 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:27 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:00:27 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:00:27 INFO - Using env: {'DISPLAY': ':0', 07:00:27 INFO - 'HOME': '/home/cltbld', 07:00:27 INFO - 'LANG': 'en_US.UTF-8', 07:00:27 INFO - 'LANGUAGE': 'en_US:en', 07:00:27 INFO - 'LOGNAME': 'cltbld', 07:00:27 INFO - 'MAIL': '/var/mail/cltbld', 07:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:27 INFO - 'MOZ_NO_REMOTE': '1', 07:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:27 INFO - 'NO_EM_RESTART': '1', 07:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:27 INFO - 'PWD': '/builds/slave/test', 07:00:27 INFO - 'SHELL': '/bin/bash', 07:00:27 INFO - 'SHLVL': '1', 07:00:27 INFO - 'TERM': 'linux', 07:00:27 INFO - 'TMOUT': '86400', 07:00:27 INFO - 'USER': 'cltbld', 07:00:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:27 INFO - '_': '/tools/buildbot/bin/python'} 07:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 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:00:27 INFO - Cleaning up... 07:00:27 INFO - Return code: 0 07:00:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00: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:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:27 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 0x2841030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b02180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, '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': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', '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:00:27 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:00:27 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:00:27 INFO - Using env: {'DISPLAY': ':0', 07:00:27 INFO - 'HOME': '/home/cltbld', 07:00:27 INFO - 'LANG': 'en_US.UTF-8', 07:00:27 INFO - 'LANGUAGE': 'en_US:en', 07:00:27 INFO - 'LOGNAME': 'cltbld', 07:00:27 INFO - 'MAIL': '/var/mail/cltbld', 07:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:27 INFO - 'MOZ_NO_REMOTE': '1', 07:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:27 INFO - 'NO_EM_RESTART': '1', 07:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:27 INFO - 'PWD': '/builds/slave/test', 07:00:27 INFO - 'SHELL': '/bin/bash', 07:00:27 INFO - 'SHLVL': '1', 07:00:27 INFO - 'TERM': 'linux', 07:00:27 INFO - 'TMOUT': '86400', 07:00:27 INFO - 'USER': 'cltbld', 07:00:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:27 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Running post-action listener: _resource_record_post_action 07:00:28 INFO - Running post-action listener: _start_resource_monitoring 07:00:28 INFO - Starting resource monitoring. 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.113229Z] Finished create-virtualenv step (success) 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.113660Z] Running install step. 07:00:28 INFO - Running pre-action listener: _resource_record_pre_action 07:00:28 INFO - Running main action method: install 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Output received: 07:00:28 INFO - Usage: mozinstall [options] installer 07:00:28 INFO - Options: 07:00:28 INFO - -h, --help show this help message and exit 07:00:28 INFO - -d DEST, --destination=DEST 07:00:28 INFO - Directory to install application into. [default: 07:00:28 INFO - "/builds/slave/test"] 07:00:28 INFO - --app=APP Application being installed. [default: firefox] 07:00:28 INFO - mkdir: /builds/slave/test/build/application 07:00:28 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:00:28 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:00:45 INFO - Reading from file tmpfile_stdout 07:00:45 INFO - Output received: 07:00:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.028631Z] Finished install step (success) 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.028905Z] Running run-tests step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: run_tests 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:45 INFO - Python 2.7.3 07:00:45 INFO - Return code: 0 07:00:45 INFO - grabbing minidump binary from tooltool 07:00:45 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:00:45 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 0x2b003b0>, '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 0x2b012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b01740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:45 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:00:45 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:00:45 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:00:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:45 INFO - Return code: 0 07:00:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:45 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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:00:45 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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:00:45 INFO - Using env: {'DISPLAY': ':0', 07:00:45 INFO - 'HOME': '/home/cltbld', 07:00:45 INFO - 'LANG': 'en_US.UTF-8', 07:00:45 INFO - 'LANGUAGE': 'en_US:en', 07:00:45 INFO - 'LOGNAME': 'cltbld', 07:00:45 INFO - 'MAIL': '/var/mail/cltbld', 07:00:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:45 INFO - 'MOZ_NO_REMOTE': '1', 07:00:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:45 INFO - 'NO_EM_RESTART': '1', 07:00:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:45 INFO - 'PWD': '/builds/slave/test', 07:00:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:45 INFO - 'SHELL': '/bin/bash', 07:00:45 INFO - 'SHLVL': '1', 07:00:45 INFO - 'TERM': 'linux', 07:00:45 INFO - 'TMOUT': '86400', 07:00:45 INFO - 'USER': 'cltbld', 07:00:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705', 07:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:45 INFO - '_': '/tools/buildbot/bin/python'} 07:00:45 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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:00:45 INFO - mozversion application_buildid: 20160830104522 07:00:45 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:45 INFO - mozversion application_name: Firefox 07:00:45 INFO - mozversion application_remotingname: firefox-dev 07:00:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:45 INFO - mozversion application_vendor: Mozilla 07:00:45 INFO - mozversion application_version: 50.0a2 07:00:45 INFO - mozversion platform_buildid: 20160830104522 07:00:45 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:45 INFO - mozversion platform_version: 50.0a2 07:00:45 INFO - using testdate: 1472738445 07:00:45 INFO - actual date: 1472738445 07:00:45 INFO - starting webserver on 'localhost:56798' 07:00:45 INFO - SUITE-START | Running 2 tests 07:00:45 INFO - TEST-START | dromaeo_css 07:00:45 INFO - operating with platform_type : linux_ 07:00:45 INFO - Initialising browser for dromaeo_css test... 07:00:45 INFO - TEST-INFO | started process 13728 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOmEDVp/profile http://localhost:56798/getInfo.html) 07:00:48 INFO - PROCESS | 13728 | __metrics Screen width/height:1600/1200 07:00:48 INFO - PROCESS | 13728 | colorDepth:24 07:00:48 INFO - PROCESS | 13728 | Browser inner width/height: 1024/697 07:00:48 INFO - PROCESS | 13728 | __metrics 07:00:49 INFO - PROCESS | 13728 | 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:00:49 INFO - PROCESS | 13728 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:50 INFO - PROCESS | 13728 | console.error: PushService: 07:00:50 INFO - PROCESS | 13728 | stateChangeProcessEnqueue: Error transitioning state 07:00:50 INFO - PROCESS | 13728 | UnknownError 07:00:50 INFO - TEST-INFO | 13728: exit 0 07:00:50 INFO - Browser initialized. 07:00:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:50 INFO - TEST-INFO | started process 13815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOmEDVp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:02 INFO - PROCESS | 13815 | RSS: Main: 145469440 07:01:02 INFO - PROCESS | 13815 | 07:01:53 INFO - PROCESS | 13815 | 1472738513154 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:04:02 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56798/tests/dromaeo/cssquery-ext.html) 07:04:03 INFO - PROCESS | 13815 | RSS: Main: 279007232 07:04:03 INFO - PROCESS | 13815 | 07:07:05 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-ext.html (next: http://localhost:56798/tests/dromaeo/cssquery-jquery.html) 07:07:06 INFO - PROCESS | 13815 | RSS: Main: 233938944 07:07:06 INFO - PROCESS | 13815 | 07:10:08 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56798/tests/dromaeo/cssquery-mootools.html) 07:10:08 INFO - PROCESS | 13815 | RSS: Main: 228765696 07:10:08 INFO - PROCESS | 13815 | 07:13:10 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56798/tests/dromaeo/cssquery-prototype.html) 07:13:11 INFO - PROCESS | 13815 | RSS: Main: 175521792 07:13:11 INFO - PROCESS | 13815 | 07:16:14 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56798/tests/dromaeo/cssquery-yui.html) 07:16:14 INFO - PROCESS | 13815 | RSS: Main: 176603136 07:16:14 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | Cycle 1(1): loaded http://localhost:56798/tests/dromaeo/cssquery-yui.html (next: http://localhost:56798/tests/dromaeo/cssquery-dojo.html) 07:19:17 INFO - PROCESS | 13815 | RSS: Main: 269836288 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | __start_tp_report 07:19:17 INFO - PROCESS | 13815 | _x_x_mozilla_page_load 07:19:17 INFO - PROCESS | 13815 | _x_x_mozilla_page_load_details 07:19:17 INFO - PROCESS | 13815 | |i|pagename|runs| 07:19:17 INFO - PROCESS | 13815 | |0;dojo.html;3194.313222657129;3233.094733474628;3246.1722260823485;3261.0216934919517;3284.86860525579;5621.887562248758;5722.513586345161;5723.170140329649;5759.769609215627;5772.682502462358;5704.857378565538;5760.222369980052;5769.682667453289;5769.913451298224;5778.653280803154;5024.321716568272;5100.872478188056;5223.242629818674;5227.47725227478;5272.23552584875;5163.32876726027;5215.217717342391;5215.426303106655;5225.163973764198;5225.529702326789;5130.692158470485;5175.974120129394;5203.609729270301;5212.348456442945;5213.582913366921;5303.018941495817;5308.283381743454;5318.680879147263;5326.973365133188;5350.946490535122;5034.09386310464;5080.390353157608;5141.562967147797;5197.558207552363;5209.426963034063;5536.058869992071;5563.332400111972;5579.0515612346;5579.302587176603;5595.272614560122;6086.891497794028;6091.847703807396;6092.200348866185;6098.237720284965;6098.755070639291;5981.58128930975;6013.1581578579035;6018;6056.848578785522;6127.785527506516;5692.77228910843;5708.543316534668;5718.1708652246025;5721.313442386899;5725.713714314267;6232.688365581703;6240.500759939194;6242.219722534684;6250.093736408196;6250.718717657716;5810.796622118206;5903.58674892762;5967.46292833647;5969.462748352582;6056.909146362808;6112.572119951561;6115.727150604525;6115.818436018176;6117.979653188475;6119.296280927751;6077.058056001327;6084.574079814459;6087.05650624154;6088.908666370008;6112.083187521907;5768.336641286306;5935.910961335583;6072.696365181723;6078.817635470943;6145.354737752472;4664.813407463664;4699.083678682692;4699.483056863743;4723.952760472352;4729.101470720552;4176.895577610584;4183.393407955829;4193.056562273525;4217.072244106292;4220.008298049956;5595.160725891149;5687.886242275132;5709.7716091356715;5740.196372507852;5789.768409263666;6068.059450785136;6068.787592434332;6070.3019152797115;6076.118962750376;6077.392260773887;5720.056190728483;5728.083506638918;5730.656160630376;5731.627444216113;5734.598578099493;5191.351081114861;5196.714180720097;5235.188545775411;5238;5294.311739473921;6323.30443651198;6347.079673447324;6348.04779283102;6348.460380867586;6352.14246076783;6054.54590905675;6075.1798507200965;6112.083187521907;6115.6330620162935;6120.449159575482;6095.878082438415;6165.753369865155;6247.281562620356;6271.216097987752;6358.745650173942;6007.8197654070445;6025.728842202024;6035.275766907824;6041.909371359346;6045.244344456943;6150.477209437069;6153.384661533811;6157.1072194532435;6162.506999440124;6164.260288765377;5838.824835254949;5902.321233058254;5955.136505206808;5967.373425790403;5973.223480947449;5976.372480889444;6019.976603977247;6027.397260273938;6027.969860150846;6048.455638992512;5936.525077993837;5988;5990.580659353804;5994.910076348945;5996.850078748066;5835.8832823342445;5958.463738263577;6021.729022193924;6024.246969128859;6038.184845045863;5926.970365148147;5929.258842644669;5931.7627294907725;5932.584719069624;5959.880802383841 07:19:17 INFO - PROCESS | 13815 | |1;ext.html;590.2474345209857;637.9904301435478;648.5589798936721;657.3492242679748;662.5494663628731;37816.24367512648;44659;45539.54460455399;45712.54287457124;49825.25262121071;47317.52682473174;47729.28406073903;47808;47924.281135782905;47948.28077578839;8610.354223433236;9311.534423278843;9449;9457.763555911088;11062.336259824397;10909.672709818718;11316.943415282913;11358.71603209918;11362;11365.602203922865;11452.427378631077;11505.022073123795;11508.136889733261;11508.136889733303;11512.021478174323;11306.152038597138;11360.091192704605;11379.943100284445;11386.259893106922;11408.486618102206;11232.606858759904;11291;11311.434428278635;11330.60342887995;11331.32012079278;44817;45018.54981450176;45236.773816130706;45254.547454525695;45305.093898122184;3879.5926427724967;3924.529056513237;4339.761313127777;4401.053773438726;4651.046535460223;33912.6608733912;34134.48798268028;34482.31035379304;34692.653073469446;36755.63244367549;4148.875533733993;4288.69979101463;4289.506706728735;4310.051788606533;4322.373255877911;391599;398062.0096899526;398076;399623;400312;62788.37211627916;62950.99999999908;63029.68485157545;63034.36965630377;63063.6846815763;39543.40684889672;40273.59726402757;40348.596514034485;42428.363574546405;42480.150396991885;19873.60252794936;20131.49671258201;20143.697844532344;20148.697769533468;20167.69748453745;12918.095733298578;14212.857871421153;14471.927640361731;14594.854051459562;15287;20223.494412639506;20467.18131274762;20695.689564656543;20835.479113021993;20866.47833804137;7441.293077157661;7453.180150183475;7684.047011343233;7747.457677962489;7841.45109842317;995.85560093786;1045.440689231273;1079.740862193068;1081.9829360401197;1104.1608377633056;34936.650633493846;35009.82495087508;35392;35462.29075418529;35791.284174316366;1136.693092864922;1157.6584907452323;1188.95838645647;1216.1790791215894;1245.2217364147407;18114.81885181158;18184;18208.63582728338;18279.177437015365;18493.81506184921;592178;593741;596619;598426;598809;65354;65510.34489655042;65960.34039659542;65983.34016659771;66055;1332.7534406134896;1456.3664805809506;1503.5339044896118;1509.524499782565;1520.042373304833;34307.14232144217;35862.4040134648;36546.45180322351;36614.63385366112;36763;62074.689626551575;62712;62894.371056290685;62905.685471572346;62975;584807;589983;590566;591248;593067;29915.102546923627;30433.543496848008;31018.68981310158;32332.515012274365;32773.508397374535;51264.23103653523;52673.20990185227;52681.736591316796;52746;54194.45805542052;43497;44274.778626106665;44435.33346999862;44902.77548612236;46287;44106;44116.55883441125;44805.55194448014;45777.77111114423;45968;44899.326510101724;45187.32219016652;46071.539284606726;46284.305735414666;47986.28020579764;37186.25627487489;39246.41130383104;40416.59583404128;40434.191316172924;40932;29876.850615746782;29985;29989.250268742584;30009;30520.542191867586 07:19:17 INFO - PROCESS | 13815 | |2;jquery.html;393.92909276330283;406.22613920481473;416.8895242760668;419.220250334378;421.65213698698574;15990.520284391472;19900.10449529771;20312.796872031275;20600.69098963515;21159.365219043415;20653.896730516364;20683.586328273464;20871.060802263895;20983;21033.47416314589;5212.8696782580455;5875.794347197849;5928.55535834812;5932.792352267671;5933.762649494015;4053;5794.362620111784;5872.7063646817705;5884.735186916599;5922.88154236915;5956.374580669032;6098.481629061535;6104.755809767604;6114.082887566856;6120.571559990803;5913.704314784265;6095.634261944276;6128.785492507741;6153.630782153085;6153.969230153865;5981.252343457068;5999.130126131687;5999.400059994009;6000.249968753906;6022.066579680157;15073.698526029419;15279.083255004734;23447;23756.40608984772;24328;2395.472995940908;2484.925452236436;2622.5672763993916;2695;3006.4738670732536;10769.569217231301;10771.892281077246;11029.338239705643;11116.277441966327;11172.832407513893;1966.4985428715586;2002.6595478768645;2016.3950814755515;2038.4699978005826;2050.7334046574;82631.17368826295;83556.07667007764;83944;88746.5562672189;89294.5535272326;11130.332180069221;11304.208705390543;11519.539218431337;11671.241369310968;11809.996150327146;16179.676406471805;16199.43301984425;16215.432459863849;16215.83784162167;16239.59401014984;8851.734447966572;8854.734357969272;8929.687460938836;8954.373193876496;9058;8836.337274704294;8849.115088491228;8849.51327676971;9012.324075694218;9046.592903319366;4769.785359658824;4821.614270858323;4844.854654360375;5011.3986321640905;5082.551472830226;2007.5382661987533;2048.9692654610194;2051.169276443043;2057.9588408231753;2117.89410529476;937.5827756648363;953.4470007395694;965.652365148546;992.6525715999487;1105.2263415609107;20556.383308500768;20633;21198.258060967793;21255.36233912985;21466.463338416354;970.8786401699788;972.397113789455;998.8351921932945;1069.1019543583272;1076.8330908709163;10737.35575865435;11639.301641901344;11737.35444550558;11807.822882656767;11835.526578936919;467417;468368;473878;474657;521236;90674;92174;95164;95364.52317738505;98822.5058874701;1057.4395570347729;1077.7305673581604;1097.8792332843382;1102.3716481605568;1107.8227483602423;18785.530361741065;18963.525911852314;18988.145533450752;19158.32945846889;19164.425067248005;44065.559344406145;44226;44355.33466998062;45338.319925200485;46119.538804611526;59463.40536594752;61361.386386135564;62884.68557657182;63596.04605931007;64083;8884.689035883972;9030.232430243503;9049.411788233743;9108.681196158102;9185.632574696938;11865;13823.377947992565;14040.78938815939;14208.715825683636;14229.928850355682;11382.260153090023;12224.388780561114;12455.813162802746;12534.059945503941;12691.619251422471;11914.166008379678;12056.276623402624;12242.265464072185;12478.18891772032;12704.872951270369;12209.816852747394;12462.626121216377;12474;12546.560870369492;12698.873011269769;8983.640654373752;9006.189442950166;9077.137671921157;9141.817163656822;9370.531473426438;9521.523923803921;12063.276203427822;12350.135490515577;12539;12559.246445213317 07:19:17 INFO - PROCESS | 13815 | |3;mootools.html;1439.5753252790425;1553.471819581342;1611.4843250159947;1827;1940.2239104358246;9069.138431848973;9316.441013539192;9372.671956481525;9700.708978730632;9736.70789876303;9448.48033358165;9590.472524011177;9590.760230994229;9597.568109435075;9604.567794449249;2459.557279689655;2489.22833921484;2490.0537795637633;2490.750924907513;2491.9127830525936;2401.86789726565;2525.4191535946743;2526.3810366460243;2528.266802627236;2530.4053547416343;2482.8510289382693;2502.0367158643962;2506.5989441689335;2507.636392723054;2508.76166764158;2397.976020239793;2404.711434627856;2404.9398765030837;2433.6471211674298;2442.963355549668;2382.737898831127;2406.7472915343988;2413.662087307778;2414.855108693484;2433.8904749286326;9162.908370916271;9212.263018958467;9428.764280892963;9467.66863159793;9524.52377381135;5431.131019036935;5685.545156387479;5685.886282274331;5692.8861422771315;5816.214811000503;3577.964220357789;3606.5311509503595;3610.332088563624;3675.9632403675887;3683.079230192452;1037.258360272409;1041.2034793382993;1054.620336678795;1062.8937106289386;1063.3141623652778;13239.40422680972;13381.866181338257;13421.0605257632;13501.662458438519;13656.726865462635;4831.396075490564;4882.536159064884;4888.899997500534;4990.575801056951;4993.900121997613;10625.5218515167;10628.256022078534;10683.83974240387;10695.197860160364;10745.51645175969;5570.498655121059;5575.52608028314;5579.2746942897165;5584.748686309126;5603.159526071122;4772.403449568804;4773.427188737304;5138.922916156261;5242.606804489678;5284.313039304866;4204.726692764961;4208.926723685502;4249.617534421917;4282.678799090081;4296.37702533131;1266.6073517209695;1278.6803299175206;1285.5115056278555;1292.3215311961296;1301.5509649171022;1077.849101125843;1085.137315833914;1086.1690806533009;1088.5754555723274;1098.873629532598;6174.598651087665;6482.870342593122;6516.348365163541;6689.698963546554;6749.966250168816;500.65704992080543;502.9170186919117;513.8689634143269;513.9743012849343;515.4253007896243;4504.144212599595;4572.748498832596;4676.08816280822;4710.152172608895;4809.591184749265;11653.242539234923;12099.57651482194;12112.09159313055;12113.818292725617;12184.573539926238;11456.369899655432;11667.183297169286;11783.35191564455;12027.21823081497;12289.324087175113;9046.592903319366;9215.170634642915;9251.491167985825;9280.535973201448;9294.581743821544;6665.433438157617;6779.830504237433;6787.966060169865;6864.24493305746;6921.169459664671;10574.471276435994;10645.254832161674;10733.677989660322;10790.514426850654;10897.891021090005;9675.758106047406;10496.47517624131;11269.71825704364;11541.942290288494;11581.768364632491;8104.392170587347;8212.425130240827;8640.438371505832;8647.524386158822;8659.090795466385;9288.210502107126;9585.616575337188;9590.376625519599;9646.42121472714;9649.276304277068;8158.551279679675;8198.467099638743;8205.79485512867;8277.875831862406;8533.189347012023;8133.389995750224;8185.877211841708;8363.121872203332;8364.330853531581;8573.399862009848;7639.847203056019;7692;7694.692212311445;7710.92289077102;7829.021372328459;5603.495685388335;5719.227904233042;5757.251557297524;5765.94234057654;5908.822735317947;5782.710864456844;5850.707464626837;5912.615679980788;5967.6121052131475;6025.337212906489 07:19:17 INFO - PROCESS | 13815 | |4;prototype.html;350.05485189987036;350.1893117433142;353.78242380935717;362.10198707206126;362.39118281287426;5529;5544.22380866679;5560.2493663355435;5588.457251950363;5605.691686957221;5499.862503437416;5513.862153446166;5518.862028449291;5521.3926468088575;5539.141433077869;3886.805659717017;4121.074037433418;4332.805023773922;4336.609705126538;4355.216061108999;4331.635534806542;4344.630706389945;4344.652427805784;4344.9137715571105;4345.78271086446;4331.029381631351;4338.266891858125;4343.478782546099;4346.565633341006;4347.673924455663;4153.646940010088;4274.572542745716;4307.91384172318;4324.329728892027;4351.129774045172;3863.555691095532;3875.1862108957152;3882.514685664292;3892.3577609694366;3914.138889444346;5335.413104558495;5365.899990501976;5366.980273747976;5367.973160134214;5376.51611354976;4755.096531658974;4907.754612269372;4931.901361972777;4934.915677701437;4936.136176169192;5691.117876729114;5693.572982026323;5698.065496269424;5710.428957104298;5729.656220626775;4201.70588058836;4215.3044747616605;4218;4234.640055595278;4246.426732391118;5635.690037047961;5718.828435146911;5812.982728022579;5926.881462370771;5939.613925094898;4324.113556720865;4329.588689074533;4348.565143485626;4349.630281426114;4350.195213885441;4390.758508282075;4398.890027749268;4400.471943366816;4431.401760762255;4432.556744325606;4866.31871537985;4971.1549036663855;4979.850604481871;4980.651354405157;4986.276989836526;5747.712614369265;5772;5777.30672319316;5778.855528611818;5784.219130417424;1520.4678362573231;1523.649560601068;1528.6331280492602;1529.5411376586978;1540.8151021877447;915.5397141559203;975.9804803903883;976.5019839881711;978.280963491833;992.980140397188;4951.093828875909;4951.777170027357;4972.776225069881;4989.351384320088;4996.250562415594;5147.588192944556;5196.350456192976;5207.427183009866;5221.963434260641;5255.211718242218;4930.372976917609;4932.950670493321;4953.678010929278;5245.63613460503;5658.292713410824;3155.8106513608795;3157.1160075178987;3168.5722427472456;3181.984090079535;3192.760542959241;6009.939900601025;6156.753729850845;6165.106059621419;6169.753209871555;6207.317195108534;5991.610545314541;6012.316551365625;6012.338642749294;6012.699365031731;6087.269527656621;6183.752649893954;6185.536084793658;6187.3503282155625;6187.659678717624;6194.845128871814;5677.347105082803;5934.643921364732;5964.194833697396;5971.193888824953;5974.9402505974385;5889.322727886177;5911.201987731773;5938.851528711817;5957.285125784761;5971.373005834324;5931.673757943182;5938.673372964528;6116.35778243278;6200.441960223601;6218.471429928507;4362.498312693954;4413.360062790942;4437.689361744776;4457.930096776815;4460.9107817844115;5811.912821307768;6002.879942401215;6003.309619393651;6004.159417681615;6034.366391528826;5825.417458254141;5833.241678581757;5843.444872737083;5845.298564172326;5846.626042880005;5808.15781711641;5814.622049566765;5828.184054232495;5837.5329973601165;5845.912311315248;5546.278983732251;5731.76766995098;5792.304923409218;5802.1296805479515;5810.622309549865;5883.558733094949;5911.408859114054;5920;5935.881282374242;5941.792037278675;5524.309461317335;5729.140628905531;5746;5757.157709532909;5767.7404516796005 07:19:17 INFO - PROCESS | 13815 | |5;yui.html;812.9268365847076;819.1235378145383;882;891.140049851893;930.9759264808712;16976.74534881976;17586.82413175868;17813.643727125484;17830.375936842214;17847.910760446197;16297.51107466775;16400;16931;17390.13049347534;17494.737578936325;743.2418932688654;881.7178502879082;891.3404080980076;898.1826537850558;900.4912224593116;887.1749273175976;892.8392889279927;894.6063731957918;894.9015608283083;896.2695403246366;939.9342046056779;941.8587211918233;943.7121677888241;944.244604316544;944.2870632672323;766.7738017284914;918.7840857398506;922.6309476209502;923.2798417234568;924.7456949338919;686.5811854768638;695.9060526828864;696.3036963036963;696.4846013949641;696.8083776961325;16962.745558816627;16997.40509082176;17011.404600838912;17016.914915425466;17022.148892555324;15237.61905952349;15315;15388.923055384652;15569.92215038929;15857.36570537177;14393.496227631982;14404.92797536016;14412.567622971328;14418.783718244234;14629;4668.929966050512;4702.6473014524045;4737.265723812815;4803.807847686088;4824.662273640846;89326.55336723274;90104;90217.54891225502;90493;90948.54525727329;12087.516499340103;12189.207701499374;12204.938975305244;12236.082293827998;12425.565105221274;22858.771412285998;22921.426964325692;23074.538509230057;23081.88459057694;23097.422564436023;9143.569913932892;9158.313126515539;9370.85943710845;9393.483358415353;9442.95278523603;4933.414795277214;4980.850574482772;5005.799768009311;5033.949660503348;5134.922976155361;3625.220577575834;3736.178040831013;3749.062734316421;3758;3772.9434058489146;641.9165508483867;642.2902692524693;646.7380710812129;655.947524198063;662.0235153149163;707.7982774909229;719.5071376107279;727.9017332660129;738.9408847292203;758.0637912178455;10050.195984321237;10160.390376577428;10236.59053637861;10347.379157250589;10387.896121038844;739.789160089372;750.2534977697226;753.7663324369463;754.5435011817772;766.8197973476229;4999.800007999639;5031.823886163967;5036.7733451994745;5067.1639179535705;5071.949280507148;128091;128689;128931.35534322268;131298;132497.33751331375;67404;69203.30796692069;86370.56814716011;88682.11317886868;94764.52617736867;59861.401385985584;61062.69468652628;63212.683936580026;78426;92149;10248.897511024794;10431.37411755297;10555.788884222426;10733.946330268298;10743.194260430342;37245.441318380785;42919.57080429156;51424.22863657123;51697.7415112922;52253.216201756244;77120;79385.20614793779;79507.60246198732;79724.202757974;83014;14380.56858294253;14429.35067921967;14558.286978886093;14785.92607036958;14861.108333500024;16513.256903439134;16722.581935451224;16791.41230056942;17310.394136205174;17897;14631.268436577915;15035.699286014436;15490.07059576429;15670.529884103495;16549.91725041367;14833.406663733329;15000.699986000438;15375.538733838002;16355.100469473817;16383.836161638232;14722.116672999655;14731.558053257992;15519.301631426388;15557.76663350028;15795.921020394824;10755.680571349;10952.78094438091;11308.264962777394;11513.136514761594;11546.0763138947;10734.302270352402;11826.58606948787;11899.524019039141;12531.93734031324;12705.872941270838 07:19:17 INFO - PROCESS | 13815 | __end_tp_report 07:19:17 INFO - PROCESS | 13815 | __start_cc_report 07:19:17 INFO - PROCESS | 13815 | _x_x_mozilla_cycle_collect,1133 07:19:17 INFO - PROCESS | 13815 | __end_cc_report 07:19:17 INFO - PROCESS | 13815 | __startTimestamp1472739557751__endTimestamp 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | ------- Summary: start ------- 07:19:17 INFO - PROCESS | 13815 | Number of tests: 6 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#0] dojo.html Cycles:165 Average:5663.14 Median:5929.26 stddev:641.83 (10.8%) stddev-sans-first:613.89 07:19:17 INFO - PROCESS | 13815 | Values: 3194.3 3233.1 3246.2 3261.0 3284.9 5621.9 5722.5 5723.2 5759.8 5772.7 5704.9 5760.2 5769.7 5769.9 5778.7 5024.3 5100.9 5223.2 5227.5 5272.2 5163.3 5215.2 5215.4 5225.2 5225.5 5130.7 5176.0 5203.6 5212.3 5213.6 5303.0 5308.3 5318.7 5327.0 5350.9 5034.1 5080.4 5141.6 5197.6 5209.4 5536.1 5563.3 5579.1 5579.3 5595.3 6086.9 6091.8 6092.2 6098.2 6098.8 5981.6 6013.2 6018.0 6056.8 6127.8 5692.8 5708.5 5718.2 5721.3 5725.7 6232.7 6240.5 6242.2 6250.1 6250.7 5810.8 5903.6 5967.5 5969.5 6056.9 6112.6 6115.7 6115.8 6118.0 6119.3 6077.1 6084.6 6087.1 6088.9 6112.1 5768.3 5935.9 6072.7 6078.8 6145.4 4664.8 4699.1 4699.5 4724.0 4729.1 4176.9 4183.4 4193.1 4217.1 4220.0 5595.2 5687.9 5709.8 5740.2 5789.8 6068.1 6068.8 6070.3 6076.1 6077.4 5720.1 5728.1 5730.7 5731.6 5734.6 5191.4 5196.7 5235.2 5238.0 5294.3 6323.3 6347.1 6348.0 6348.5 6352.1 6054.5 6075.2 6112.1 6115.6 6120.4 6095.9 6165.8 6247.3 6271.2 6358.7 6007.8 6025.7 6035.3 6041.9 6045.2 6150.5 6153.4 6157.1 6162.5 6164.3 5838.8 5902.3 5955.1 5967.4 5973.2 5976.4 6020.0 6027.4 6028.0 6048.5 5936.5 5988.0 5990.6 5994.9 5996.9 5835.9 5958.5 6021.7 6024.2 6038.2 5927.0 5929.3 5931.8 5932.6 5959.9 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#1] ext.html Cycles:180 Average:69384.03 Median:220292.80 stddev:142443.33 (64.7%) stddev-sans-first:142749.28 07:19:17 INFO - PROCESS | 13815 | Values: 590.2 638.0 648.6 657.3 662.5 37816.2 44659.0 45539.5 45712.5 49825.3 47317.5 47729.3 47808.0 47924.3 47948.3 8610.4 9311.5 9449.0 9457.8 11062.3 10909.7 11316.9 11358.7 11362.0 11365.6 11452.4 11505.0 11508.1 11508.1 11512.0 11306.2 11360.1 11379.9 11386.3 11408.5 11232.6 11291.0 11311.4 11330.6 11331.3 44817.0 45018.5 45236.8 45254.5 45305.1 3879.6 3924.5 4339.8 4401.1 4651.0 33912.7 34134.5 34482.3 34692.7 36755.6 4148.9 4288.7 4289.5 4310.1 4322.4 391599.0 398062.0 398076.0 399623.0 400312.0 62788.4 62951.0 63029.7 63034.4 63063.7 39543.4 40273.6 40348.6 42428.4 42480.2 19873.6 20131.5 20143.7 20148.7 20167.7 12918.1 14212.9 14471.9 14594.9 15287.0 20223.5 20467.2 20695.7 20835.5 20866.5 7441.3 7453.2 7684.0 7747.5 7841.5 995.9 1045.4 1079.7 1082.0 1104.2 34936.7 35009.8 35392.0 35462.3 35791.3 1136.7 1157.7 1189.0 1216.2 1245.2 18114.8 18184.0 18208.6 18279.2 18493.8 592178.0 593741.0 596619.0 598426.0 598809.0 65354.0 65510.3 65960.3 65983.3 66055.0 1332.8 1456.4 1503.5 1509.5 1520.0 34307.1 35862.4 36546.5 36614.6 36763.0 62074.7 62712.0 62894.4 62905.7 62975.0 584807.0 589983.0 590566.0 591248.0 593067.0 29915.1 30433.5 31018.7 32332.5 32773.5 51264.2 52673.2 52681.7 52746.0 54194.5 43497.0 44274.8 44435.3 44902.8 46287.0 44106.0 44116.6 44805.6 45777.8 45968.0 44899.3 45187.3 46071.5 46284.3 47986.3 37186.3 39246.4 40416.6 40434.2 40932.0 29876.9 29985.0 29989.3 30009.0 30520.5 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#2] jquery.html Cycles:180 Average:29511.18 Median:2850.74 stddev:79579.60 (2791.5%) stddev-sans-first:79772.81 07:19:17 INFO - PROCESS | 13815 | Values: 393.9 406.2 416.9 419.2 421.7 15990.5 19900.1 20312.8 20600.7 21159.4 20653.9 20683.6 20871.1 20983.0 21033.5 5212.9 5875.8 5928.6 5932.8 5933.8 4053.0 5794.4 5872.7 5884.7 5922.9 5956.4 6098.5 6104.8 6114.1 6120.6 5913.7 6095.6 6128.8 6153.6 6154.0 5981.3 5999.1 5999.4 6000.2 6022.1 15073.7 15279.1 23447.0 23756.4 24328.0 2395.5 2484.9 2622.6 2695.0 3006.5 10769.6 10771.9 11029.3 11116.3 11172.8 1966.5 2002.7 2016.4 2038.5 2050.7 82631.2 83556.1 83944.0 88746.6 89294.6 11130.3 11304.2 11519.5 11671.2 11810.0 16179.7 16199.4 16215.4 16215.8 16239.6 8851.7 8854.7 8929.7 8954.4 9058.0 8836.3 8849.1 8849.5 9012.3 9046.6 4769.8 4821.6 4844.9 5011.4 5082.6 2007.5 2049.0 2051.2 2058.0 2117.9 937.6 953.4 965.7 992.7 1105.2 20556.4 20633.0 21198.3 21255.4 21466.5 970.9 972.4 998.8 1069.1 1076.8 10737.4 11639.3 11737.4 11807.8 11835.5 467417.0 468368.0 473878.0 474657.0 521236.0 90674.0 92174.0 95164.0 95364.5 98822.5 1057.4 1077.7 1097.9 1102.4 1107.8 18785.5 18963.5 18988.1 19158.3 19164.4 44065.6 44226.0 44355.3 45338.3 46119.5 59463.4 61361.4 62884.7 63596.0 64083.0 8884.7 9030.2 9049.4 9108.7 9185.6 11865.0 13823.4 14040.8 14208.7 14229.9 11382.3 12224.4 12455.8 12534.1 12691.6 11914.2 12056.3 12242.3 12478.2 12704.9 12209.8 12462.6 12474.0 12546.6 12698.9 8983.6 9006.2 9077.1 9141.8 9370.5 9521.5 12063.3 12350.1 12539.0 12559.2 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#3] mootools.html Cycles:180 Average:6282.81 Median:4820.49 stddev:3611.49 (74.9%) stddev-sans-first:3603.28 07:19:17 INFO - PROCESS | 13815 | Values: 1439.6 1553.5 1611.5 1827.0 1940.2 9069.1 9316.4 9372.7 9700.7 9736.7 9448.5 9590.5 9590.8 9597.6 9604.6 2459.6 2489.2 2490.1 2490.8 2491.9 2401.9 2525.4 2526.4 2528.3 2530.4 2482.9 2502.0 2506.6 2507.6 2508.8 2398.0 2404.7 2404.9 2433.6 2443.0 2382.7 2406.7 2413.7 2414.9 2433.9 9162.9 9212.3 9428.8 9467.7 9524.5 5431.1 5685.5 5685.9 5692.9 5816.2 3578.0 3606.5 3610.3 3676.0 3683.1 1037.3 1041.2 1054.6 1062.9 1063.3 13239.4 13381.9 13421.1 13501.7 13656.7 4831.4 4882.5 4888.9 4990.6 4993.9 10625.5 10628.3 10683.8 10695.2 10745.5 5570.5 5575.5 5579.3 5584.7 5603.2 4772.4 4773.4 5138.9 5242.6 5284.3 4204.7 4208.9 4249.6 4282.7 4296.4 1266.6 1278.7 1285.5 1292.3 1301.6 1077.8 1085.1 1086.2 1088.6 1098.9 6174.6 6482.9 6516.3 6689.7 6750.0 500.7 502.9 513.9 514.0 515.4 4504.1 4572.7 4676.1 4710.2 4809.6 11653.2 12099.6 12112.1 12113.8 12184.6 11456.4 11667.2 11783.4 12027.2 12289.3 9046.6 9215.2 9251.5 9280.5 9294.6 6665.4 6779.8 6788.0 6864.2 6921.2 10574.5 10645.3 10733.7 10790.5 10897.9 9675.8 10496.5 11269.7 11541.9 11581.8 8104.4 8212.4 8640.4 8647.5 8659.1 9288.2 9585.6 9590.4 9646.4 9649.3 8158.6 8198.5 8205.8 8277.9 8533.2 8133.4 8185.9 8363.1 8364.3 8573.4 7639.8 7692.0 7694.7 7710.9 7829.0 5603.5 5719.2 5757.3 5765.9 5908.8 5782.7 5850.7 5912.6 5967.6 6025.3 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#4] prototype.html Cycles:180 Average:4851.49 Median:5516.36 stddev:1408.04 (25.5%) stddev-sans-first:1370.86 07:19:17 INFO - PROCESS | 13815 | Values: 350.1 350.2 353.8 362.1 362.4 5529.0 5544.2 5560.2 5588.5 5605.7 5499.9 5513.9 5518.9 5521.4 5539.1 3886.8 4121.1 4332.8 4336.6 4355.2 4331.6 4344.6 4344.7 4344.9 4345.8 4331.0 4338.3 4343.5 4346.6 4347.7 4153.6 4274.6 4307.9 4324.3 4351.1 3863.6 3875.2 3882.5 3892.4 3914.1 5335.4 5365.9 5367.0 5368.0 5376.5 4755.1 4907.8 4931.9 4934.9 4936.1 5691.1 5693.6 5698.1 5710.4 5729.7 4201.7 4215.3 4218.0 4234.6 4246.4 5635.7 5718.8 5813.0 5926.9 5939.6 4324.1 4329.6 4348.6 4349.6 4350.2 4390.8 4398.9 4400.5 4431.4 4432.6 4866.3 4971.2 4979.9 4980.7 4986.3 5747.7 5772.0 5777.3 5778.9 5784.2 1520.5 1523.6 1528.6 1529.5 1540.8 915.5 976.0 976.5 978.3 993.0 4951.1 4951.8 4972.8 4989.4 4996.3 5147.6 5196.4 5207.4 5222.0 5255.2 4930.4 4933.0 4953.7 5245.6 5658.3 3155.8 3157.1 3168.6 3182.0 3192.8 6009.9 6156.8 6165.1 6169.8 6207.3 5991.6 6012.3 6012.3 6012.7 6087.3 6183.8 6185.5 6187.4 6187.7 6194.8 5677.3 5934.6 5964.2 5971.2 5974.9 5889.3 5911.2 5938.9 5957.3 5971.4 5931.7 5938.7 6116.4 6200.4 6218.5 4362.5 4413.4 4437.7 4457.9 4460.9 5811.9 6002.9 6003.3 6004.2 6034.4 5825.4 5833.2 5843.4 5845.3 5846.6 5808.2 5814.6 5828.2 5837.5 5845.9 5546.3 5731.8 5792.3 5802.1 5810.6 5883.6 5911.4 5920.0 5935.9 5941.8 5524.3 5729.1 5746.0 5757.2 5767.7 07:19:17 INFO - PROCESS | 13815 | 07:19:17 INFO - PROCESS | 13815 | [#5] yui.html Cycles:180 Average:21469.95 Median:23346.26 stddev:30294.84 (129.8%) stddev-sans-first:30340.12 07:19:17 INFO - PROCESS | 13815 | Values: 812.9 819.1 882.0 891.1 931.0 16976.7 17586.8 17813.6 17830.4 17847.9 16297.5 16400.0 16931.0 17390.1 17494.7 743.2 881.7 891.3 898.2 900.5 887.2 892.8 894.6 894.9 896.3 939.9 941.9 943.7 944.2 944.3 766.8 918.8 922.6 923.3 924.7 686.6 695.9 696.3 696.5 696.8 16962.7 16997.4 17011.4 17016.9 17022.1 15237.6 15315.0 15388.9 15569.9 15857.4 14393.5 14404.9 14412.6 14418.8 14629.0 4668.9 4702.6 4737.3 4803.8 4824.7 89326.6 90104.0 90217.5 90493.0 90948.5 12087.5 12189.2 12204.9 12236.1 12425.6 22858.8 22921.4 23074.5 23081.9 23097.4 9143.6 9158.3 9370.9 9393.5 9443.0 4933.4 4980.9 5005.8 5033.9 5134.9 3625.2 3736.2 3749.1 3758.0 3772.9 641.9 642.3 646.7 655.9 662.0 707.8 719.5 727.9 738.9 758.1 10050.2 10160.4 10236.6 10347.4 10387.9 739.8 750.3 753.8 754.5 766.8 4999.8 5031.8 5036.8 5067.2 5071.9 128091.0 128689.0 128931.4 131298.0 132497.3 67404.0 69203.3 86370.6 88682.1 94764.5 59861.4 61062.7 63212.7 78426.0 92149.0 10248.9 10431.4 10555.8 10733.9 10743.2 37245.4 42919.6 51424.2 51697.7 52253.2 77120.0 79385.2 79507.6 79724.2 83014.0 14380.6 14429.4 14558.3 14785.9 14861.1 16513.3 16722.6 16791.4 17310.4 17897.0 14631.3 15035.7 15490.1 15670.5 16549.9 14833.4 15000.7 15375.5 16355.1 16383.8 14722.1 14731.6 15519.3 15557.8 15795.9 10755.7 10952.8 11308.3 11513.1 11546.1 10734.3 11826.6 11899.5 12531.9 12705.9 07:19:17 INFO - PROCESS | 13815 | -------- Summary: end -------- 07:19:17 INFO - PROCESS | 13815 | 07:19:18 INFO - TEST-INFO | 13815: exit 0 07:19:18 INFO - TEST-OK | dromaeo_css | took 1113239ms 07:19:18 INFO - TEST-START | kraken 07:19:18 INFO - operating with platform_type : linux_ 07:19:18 INFO - Initialising browser for kraken test... 07:19:18 INFO - TEST-INFO | started process 14018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZBPFDs/profile http://localhost:56798/getInfo.html) 07:19:22 INFO - PROCESS | 14018 | __metrics Screen width/height:1600/1200 07:19:22 INFO - PROCESS | 14018 | colorDepth:24 07:19:22 INFO - PROCESS | 14018 | Browser inner width/height: 1024/697 07:19:22 INFO - PROCESS | 14018 | __metrics 07:19:22 INFO - PROCESS | 14018 | 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:19:22 INFO - PROCESS | 14018 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:23 INFO - TEST-INFO | 14018: exit 0 07:19:23 INFO - Browser initialized. 07:19:23 INFO - Running cycle 1/1 for kraken test... 07:19:23 INFO - TEST-INFO | started process 14111 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZBPFDs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:35 INFO - PROCESS | 14111 | RSS: Main: 145412096 07:19:35 INFO - PROCESS | 14111 | 07:19:38 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56798/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:39 INFO - PROCESS | 14111 | RSS: Main: 191303680 07:19:39 INFO - PROCESS | 14111 | 07:19:41 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56798/tests/kraken/driver.html?testName=audio-dft) 07:19:42 INFO - PROCESS | 14111 | RSS: Main: 181096448 07:19:42 INFO - PROCESS | 14111 | 07:19:44 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56798/tests/kraken/driver.html?testName=audio-fft) 07:19:45 INFO - PROCESS | 14111 | RSS: Main: 270475264 07:19:45 INFO - PROCESS | 14111 | 07:19:47 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56798/tests/kraken/driver.html?testName=audio-oscillator) 07:19:48 INFO - PROCESS | 14111 | RSS: Main: 175239168 07:19:48 INFO - PROCESS | 14111 | 07:19:50 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56798/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:50 INFO - PROCESS | 14111 | RSS: Main: 176877568 07:19:50 INFO - PROCESS | 14111 | 07:19:53 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56798/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:54 INFO - PROCESS | 14111 | RSS: Main: 212660224 07:19:54 INFO - PROCESS | 14111 | 07:19:57 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56798/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:58 INFO - PROCESS | 14111 | RSS: Main: 211361792 07:19:58 INFO - PROCESS | 14111 | 07:20:01 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56798/tests/kraken/driver.html?testName=json-parse-financial) 07:20:01 INFO - PROCESS | 14111 | RSS: Main: 211755008 07:20:01 INFO - PROCESS | 14111 | 07:20:03 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56798/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:04 INFO - PROCESS | 14111 | RSS: Main: 176422912 07:20:04 INFO - PROCESS | 14111 | 07:20:05 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:06 INFO - PROCESS | 14111 | RSS: Main: 181780480 07:20:06 INFO - PROCESS | 14111 | 07:20:08 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:09 INFO - PROCESS | 14111 | RSS: Main: 186888192 07:20:09 INFO - PROCESS | 14111 | 07:20:11 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:12 INFO - PROCESS | 14111 | RSS: Main: 181596160 07:20:12 INFO - PROCESS | 14111 | 07:20:14 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:15 INFO - PROCESS | 14111 | RSS: Main: 184872960 07:20:15 INFO - PROCESS | 14111 | 07:20:16 INFO - PROCESS | 14111 | Cycle 1(1): loaded http://localhost:56798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56798/tests/kraken/driver.html?testName=ai-astar) 07:20:17 INFO - PROCESS | 14111 | RSS: Main: 235528192 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | __start_tp_report 07:20:17 INFO - PROCESS | 14111 | _x_x_mozilla_page_load 07:20:17 INFO - PROCESS | 14111 | _x_x_mozilla_page_load_details 07:20:17 INFO - PROCESS | 14111 | |i|pagename|runs| 07:20:17 INFO - PROCESS | 14111 | |0;ai-astar;101;110;110;110;111;110;109;109;109;109 07:20:17 INFO - PROCESS | 14111 | |1;audio-beat-detection;144;114;116;171;116;120;146;114;151;114 07:20:17 INFO - PROCESS | 14111 | |2;audio-dft;161;160;160;159;160;162;160;160;161;161 07:20:17 INFO - PROCESS | 14111 | |3;audio-fft;75;76;75;115;76;77;73;76;98;74 07:20:17 INFO - PROCESS | 14111 | |4;audio-oscillator;83;82;129;82;82;83;111;84;83;128 07:20:17 INFO - PROCESS | 14111 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;117;117 07:20:17 INFO - PROCESS | 14111 | |6;imaging-darkroom;146;146;146;147;147;148;146;147;146;146 07:20:17 INFO - PROCESS | 14111 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 07:20:17 INFO - PROCESS | 14111 | |8;json-parse-financial;52;51;52;50;49;51;51;51;50;50 07:20:17 INFO - PROCESS | 14111 | |9;json-stringify-tinderbox;59;60;60;60;62;60;61;60;59;59 07:20:17 INFO - PROCESS | 14111 | |10;stanford-crypto-aes;72;73;74;72;72;72;72;72;74;75 07:20:17 INFO - PROCESS | 14111 | |11;stanford-crypto-ccm;120;117;119;124;129;116;118;124;163;127 07:20:17 INFO - PROCESS | 14111 | |12;stanford-crypto-pbkdf2;158;153;152;161;150;151;153;152;159;152 07:20:17 INFO - PROCESS | 14111 | |13;stanford-crypto-sha256-iterative;64;62;61;61;61;63;63;65;62;62 07:20:17 INFO - PROCESS | 14111 | __end_tp_report 07:20:17 INFO - PROCESS | 14111 | __start_cc_report 07:20:17 INFO - PROCESS | 14111 | _x_x_mozilla_cycle_collect,1636 07:20:17 INFO - PROCESS | 14111 | __end_cc_report 07:20:17 INFO - PROCESS | 14111 | __startTimestamp1472739617249__endTimestamp 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | ------- Summary: start ------- 07:20:17 INFO - PROCESS | 14111 | Number of tests: 14 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:2.82 (2.6%) stddev-sans-first:0.71 07:20:17 INFO - PROCESS | 14111 | Values: 101.0 110.0 110.0 110.0 111.0 110.0 109.0 109.0 109.0 109.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:132.00 stddev:20.63 (15.6%) stddev-sans-first:21.30 07:20:17 INFO - PROCESS | 14111 | Values: 144.0 114.0 116.0 171.0 116.0 120.0 146.0 114.0 151.0 114.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 07:20:17 INFO - PROCESS | 14111 | Values: 161.0 160.0 160.0 159.0 160.0 162.0 160.0 160.0 161.0 161.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:13.82 (18.2%) stddev-sans-first:14.45 07:20:17 INFO - PROCESS | 14111 | Values: 75.0 76.0 75.0 115.0 76.0 77.0 73.0 76.0 98.0 74.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:82.50 stddev:19.89 (24.1%) stddev-sans-first:20.64 07:20:17 INFO - PROCESS | 14111 | Values: 83.0 82.0 129.0 82.0 82.0 83.0 111.0 84.0 83.0 128.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:20:17 INFO - PROCESS | 14111 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 07:20:17 INFO - PROCESS | 14111 | Values: 146.0 146.0 146.0 147.0 147.0 148.0 146.0 147.0 146.0 146.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:20:17 INFO - PROCESS | 14111 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.95 (1.9%) stddev-sans-first:0.88 07:20:17 INFO - PROCESS | 14111 | Values: 52.0 51.0 52.0 50.0 49.0 51.0 51.0 51.0 50.0 50.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.94 (1.6%) stddev-sans-first:0.93 07:20:17 INFO - PROCESS | 14111 | Values: 59.0 60.0 60.0 60.0 62.0 60.0 61.0 60.0 59.0 59.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:72.50 stddev:1.14 (1.6%) stddev-sans-first:1.17 07:20:17 INFO - PROCESS | 14111 | Values: 72.0 73.0 74.0 72.0 72.0 72.0 72.0 72.0 74.0 75.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:124.00 stddev:13.81 (11.1%) stddev-sans-first:14.49 07:20:17 INFO - PROCESS | 14111 | Values: 120.0 117.0 119.0 124.0 129.0 116.0 118.0 124.0 163.0 127.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:153.00 stddev:3.78 (2.5%) stddev-sans-first:3.74 07:20:17 INFO - PROCESS | 14111 | Values: 158.0 153.0 152.0 161.0 150.0 151.0 153.0 152.0 159.0 152.0 07:20:17 INFO - PROCESS | 14111 | 07:20:17 INFO - PROCESS | 14111 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:1.35 (2.2%) stddev-sans-first:1.30 07:20:17 INFO - PROCESS | 14111 | Values: 64.0 62.0 61.0 61.0 61.0 63.0 63.0 65.0 62.0 62.0 07:20:17 INFO - PROCESS | 14111 | -------- Summary: end -------- 07:20:17 INFO - PROCESS | 14111 | 07:20:18 INFO - TEST-INFO | 14111: exit 0 07:20:18 INFO - TEST-OK | kraken | took 59817ms 07:20:18 INFO - SUITE-END | took 1173s 07:20:18 INFO - Completed test suite (00:19:33) 07:20:18 INFO - javascript benchmark 07:20:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.313222657129, 3233.094733474628, 3246.1722260823485, 3261.0216934919517, 3284.86860525579, 5621.887562248758, 5722.513586345161, 5723.170140329649, 5759.769609215627, 5772.682502462358, 5704.857378565538, 5760.222369980052, 5769.682667453289, 5769.913451298224, 5778.653280803154, 5024.321716568272, 5100.872478188056, 5223.242629818674, 5227.47725227478, 5272.23552584875, 5163.32876726027, 5215.217717342391, 5215.426303106655, 5225.163973764198, 5225.529702326789, 5130.692158470485, 5175.974120129394, 5203.609729270301, 5212.348456442945, 5213.582913366921, 5303.018941495817, 5308.283381743454, 5318.680879147263, 5326.973365133188, 5350.946490535122, 5034.09386310464, 5080.390353157608, 5141.562967147797, 5197.558207552363, 5209.426963034063, 5536.058869992071, 5563.332400111972, 5579.0515612346, 5579.302587176603, 5595.272614560122, 6086.891497794028, 6091.847703807396, 6092.200348866185, 6098.237720284965, 6098.755070639291, 5981.58128930975, 6013.1581578579035, 6018.0, 6056.848578785522, 6127.785527506516, 5692.77228910843, 5708.543316534668, 5718.1708652246025, 5721.313442386899, 5725.713714314267, 6232.688365581703, 6240.500759939194, 6242.219722534684, 6250.093736408196, 6250.718717657716, 5810.796622118206, 5903.58674892762, 5967.46292833647, 5969.462748352582, 6056.909146362808, 6112.572119951561, 6115.727150604525, 6115.818436018176, 6117.979653188475, 6119.296280927751, 6077.058056001327, 6084.574079814459, 6087.05650624154, 6088.908666370008, 6112.083187521907, 5768.336641286306, 5935.910961335583, 6072.696365181723, 6078.817635470943, 6145.354737752472, 4664.813407463664, 4699.083678682692, 4699.483056863743, 4723.952760472352, 4729.101470720552, 4176.895577610584, 4183.393407955829, 4193.056562273525, 4217.072244106292, 4220.008298049956, 5595.160725891149, 5687.886242275132, 5709.7716091356715, 5740.196372507852, 5789.768409263666, 6068.059450785136, 6068.787592434332, 6070.3019152797115, 6076.118962750376, 6077.392260773887, 5720.056190728483, 5728.083506638918, 5730.656160630376, 5731.627444216113, 5734.598578099493, 5191.351081114861, 5196.714180720097, 5235.188545775411, 5238.0, 5294.311739473921, 6323.30443651198, 6347.079673447324, 6348.04779283102, 6348.460380867586, 6352.14246076783, 6054.54590905675, 6075.1798507200965, 6112.083187521907, 6115.6330620162935, 6120.449159575482, 6095.878082438415, 6165.753369865155, 6247.281562620356, 6271.216097987752, 6358.745650173942, 6007.8197654070445, 6025.728842202024, 6035.275766907824, 6041.909371359346, 6045.244344456943, 6150.477209437069, 6153.384661533811, 6157.1072194532435, 6162.506999440124, 6164.260288765377, 5838.824835254949, 5902.321233058254, 5955.136505206808, 5967.373425790403, 5973.223480947449, 5976.372480889444, 6019.976603977247, 6027.397260273938, 6027.969860150846, 6048.455638992512, 5936.525077993837, 5988.0, 5990.580659353804, 5994.910076348945, 5996.850078748066, 5835.8832823342445, 5958.463738263577, 6021.729022193924, 6024.246969128859, 6038.184845045863, 5926.970365148147, 5929.258842644669, 5931.7627294907725, 5932.584719069624, 5959.880802383841], "value": 5619.4038342309705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.2474345209857, 637.9904301435478, 648.5589798936721, 657.3492242679748, 662.5494663628731, 37816.24367512648, 44659.0, 45539.54460455399, 45712.54287457124, 49825.25262121071, 47317.52682473174, 47729.28406073903, 47808.0, 47924.281135782905, 47948.28077578839, 8610.354223433236, 9311.534423278843, 9449.0, 9457.763555911088, 11062.336259824397, 10909.672709818718, 11316.943415282913, 11358.71603209918, 11362.0, 11365.602203922865, 11452.427378631077, 11505.022073123795, 11508.136889733261, 11508.136889733303, 11512.021478174323, 11306.152038597138, 11360.091192704605, 11379.943100284445, 11386.259893106922, 11408.486618102206, 11232.606858759904, 11291.0, 11311.434428278635, 11330.60342887995, 11331.32012079278, 44817.0, 45018.54981450176, 45236.773816130706, 45254.547454525695, 45305.093898122184, 3879.5926427724967, 3924.529056513237, 4339.761313127777, 4401.053773438726, 4651.046535460223, 33912.6608733912, 34134.48798268028, 34482.31035379304, 34692.653073469446, 36755.63244367549, 4148.875533733993, 4288.69979101463, 4289.506706728735, 4310.051788606533, 4322.373255877911, 391599.0, 398062.0096899526, 398076.0, 399623.0, 400312.0, 62788.37211627916, 62950.99999999908, 63029.68485157545, 63034.36965630377, 63063.6846815763, 39543.40684889672, 40273.59726402757, 40348.596514034485, 42428.363574546405, 42480.150396991885, 19873.60252794936, 20131.49671258201, 20143.697844532344, 20148.697769533468, 20167.69748453745, 12918.095733298578, 14212.857871421153, 14471.927640361731, 14594.854051459562, 15287.0, 20223.494412639506, 20467.18131274762, 20695.689564656543, 20835.479113021993, 20866.47833804137, 7441.293077157661, 7453.180150183475, 7684.047011343233, 7747.457677962489, 7841.45109842317, 995.85560093786, 1045.440689231273, 1079.740862193068, 1081.9829360401197, 1104.1608377633056, 34936.650633493846, 35009.82495087508, 35392.0, 35462.29075418529, 35791.284174316366, 1136.693092864922, 1157.6584907452323, 1188.95838645647, 1216.1790791215894, 1245.2217364147407, 18114.81885181158, 18184.0, 18208.63582728338, 18279.177437015365, 18493.81506184921, 592178.0, 593741.0, 596619.0, 598426.0, 598809.0, 65354.0, 65510.34489655042, 65960.34039659542, 65983.34016659771, 66055.0, 1332.7534406134896, 1456.3664805809506, 1503.5339044896118, 1509.524499782565, 1520.042373304833, 34307.14232144217, 35862.4040134648, 36546.45180322351, 36614.63385366112, 36763.0, 62074.689626551575, 62712.0, 62894.371056290685, 62905.685471572346, 62975.0, 584807.0, 589983.0, 590566.0, 591248.0, 593067.0, 29915.102546923627, 30433.543496848008, 31018.68981310158, 32332.515012274365, 32773.508397374535, 51264.23103653523, 52673.20990185227, 52681.736591316796, 52746.0, 54194.45805542052, 43497.0, 44274.778626106665, 44435.33346999862, 44902.77548612236, 46287.0, 44106.0, 44116.55883441125, 44805.55194448014, 45777.77111114423, 45968.0, 44899.326510101724, 45187.32219016652, 46071.539284606726, 46284.305735414666, 47986.28020579764, 37186.25627487489, 39246.41130383104, 40416.59583404128, 40434.191316172924, 40932.0, 29876.850615746782, 29985.0, 29989.250268742584, 30009.0, 30520.542191867586], "value": 22264.39330226417, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.92909276330283, 406.22613920481473, 416.8895242760668, 419.220250334378, 421.65213698698574, 15990.520284391472, 19900.10449529771, 20312.796872031275, 20600.69098963515, 21159.365219043415, 20653.896730516364, 20683.586328273464, 20871.060802263895, 20983.0, 21033.47416314589, 5212.8696782580455, 5875.794347197849, 5928.55535834812, 5932.792352267671, 5933.762649494015, 4053.0, 5794.362620111784, 5872.7063646817705, 5884.735186916599, 5922.88154236915, 5956.374580669032, 6098.481629061535, 6104.755809767604, 6114.082887566856, 6120.571559990803, 5913.704314784265, 6095.634261944276, 6128.785492507741, 6153.630782153085, 6153.969230153865, 5981.252343457068, 5999.130126131687, 5999.400059994009, 6000.249968753906, 6022.066579680157, 15073.698526029419, 15279.083255004734, 23447.0, 23756.40608984772, 24328.0, 2395.472995940908, 2484.925452236436, 2622.5672763993916, 2695.0, 3006.4738670732536, 10769.569217231301, 10771.892281077246, 11029.338239705643, 11116.277441966327, 11172.832407513893, 1966.4985428715586, 2002.6595478768645, 2016.3950814755515, 2038.4699978005826, 2050.7334046574, 82631.17368826295, 83556.07667007764, 83944.0, 88746.5562672189, 89294.5535272326, 11130.332180069221, 11304.208705390543, 11519.539218431337, 11671.241369310968, 11809.996150327146, 16179.676406471805, 16199.43301984425, 16215.432459863849, 16215.83784162167, 16239.59401014984, 8851.734447966572, 8854.734357969272, 8929.687460938836, 8954.373193876496, 9058.0, 8836.337274704294, 8849.115088491228, 8849.51327676971, 9012.324075694218, 9046.592903319366, 4769.785359658824, 4821.614270858323, 4844.854654360375, 5011.3986321640905, 5082.551472830226, 2007.5382661987533, 2048.9692654610194, 2051.169276443043, 2057.9588408231753, 2117.89410529476, 937.5827756648363, 953.4470007395694, 965.652365148546, 992.6525715999487, 1105.2263415609107, 20556.383308500768, 20633.0, 21198.258060967793, 21255.36233912985, 21466.463338416354, 970.8786401699788, 972.397113789455, 998.8351921932945, 1069.1019543583272, 1076.8330908709163, 10737.35575865435, 11639.301641901344, 11737.35444550558, 11807.822882656767, 11835.526578936919, 467417.0, 468368.0, 473878.0, 474657.0, 521236.0, 90674.0, 92174.0, 95164.0, 95364.52317738505, 98822.5058874701, 1057.4395570347729, 1077.7305673581604, 1097.8792332843382, 1102.3716481605568, 1107.8227483602423, 18785.530361741065, 18963.525911852314, 18988.145533450752, 19158.32945846889, 19164.425067248005, 44065.559344406145, 44226.0, 44355.33466998062, 45338.319925200485, 46119.538804611526, 59463.40536594752, 61361.386386135564, 62884.68557657182, 63596.04605931007, 64083.0, 8884.689035883972, 9030.232430243503, 9049.411788233743, 9108.681196158102, 9185.632574696938, 11865.0, 13823.377947992565, 14040.78938815939, 14208.715825683636, 14229.928850355682, 11382.260153090023, 12224.388780561114, 12455.813162802746, 12534.059945503941, 12691.619251422471, 11914.166008379678, 12056.276623402624, 12242.265464072185, 12478.18891772032, 12704.872951270369, 12209.816852747394, 12462.626121216377, 12474.0, 12546.560870369492, 12698.873011269769, 8983.640654373752, 9006.189442950166, 9077.137671921157, 9141.817163656822, 9370.531473426438, 9521.523923803921, 12063.276203427822, 12350.135490515577, 12539.0, 12559.246445213317], "value": 9663.700733208172, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.5753252790425, 1553.471819581342, 1611.4843250159947, 1827.0, 1940.2239104358246, 9069.138431848973, 9316.441013539192, 9372.671956481525, 9700.708978730632, 9736.70789876303, 9448.48033358165, 9590.472524011177, 9590.760230994229, 9597.568109435075, 9604.567794449249, 2459.557279689655, 2489.22833921484, 2490.0537795637633, 2490.750924907513, 2491.9127830525936, 2401.86789726565, 2525.4191535946743, 2526.3810366460243, 2528.266802627236, 2530.4053547416343, 2482.8510289382693, 2502.0367158643962, 2506.5989441689335, 2507.636392723054, 2508.76166764158, 2397.976020239793, 2404.711434627856, 2404.9398765030837, 2433.6471211674298, 2442.963355549668, 2382.737898831127, 2406.7472915343988, 2413.662087307778, 2414.855108693484, 2433.8904749286326, 9162.908370916271, 9212.263018958467, 9428.764280892963, 9467.66863159793, 9524.52377381135, 5431.131019036935, 5685.545156387479, 5685.886282274331, 5692.8861422771315, 5816.214811000503, 3577.964220357789, 3606.5311509503595, 3610.332088563624, 3675.9632403675887, 3683.079230192452, 1037.258360272409, 1041.2034793382993, 1054.620336678795, 1062.8937106289386, 1063.3141623652778, 13239.40422680972, 13381.866181338257, 13421.0605257632, 13501.662458438519, 13656.726865462635, 4831.396075490564, 4882.536159064884, 4888.899997500534, 4990.575801056951, 4993.900121997613, 10625.5218515167, 10628.256022078534, 10683.83974240387, 10695.197860160364, 10745.51645175969, 5570.498655121059, 5575.52608028314, 5579.2746942897165, 5584.748686309126, 5603.159526071122, 4772.403449568804, 4773.427188737304, 5138.922916156261, 5242.606804489678, 5284.313039304866, 4204.726692764961, 4208.926723685502, 4249.617534421917, 4282.678799090081, 4296.37702533131, 1266.6073517209695, 1278.6803299175206, 1285.5115056278555, 1292.3215311961296, 1301.5509649171022, 1077.849101125843, 1085.137315833914, 1086.1690806533009, 1088.5754555723274, 1098.873629532598, 6174.598651087665, 6482.870342593122, 6516.348365163541, 6689.698963546554, 6749.966250168816, 500.65704992080543, 502.9170186919117, 513.8689634143269, 513.9743012849343, 515.4253007896243, 4504.144212599595, 4572.748498832596, 4676.08816280822, 4710.152172608895, 4809.591184749265, 11653.242539234923, 12099.57651482194, 12112.09159313055, 12113.818292725617, 12184.573539926238, 11456.369899655432, 11667.183297169286, 11783.35191564455, 12027.21823081497, 12289.324087175113, 9046.592903319366, 9215.170634642915, 9251.491167985825, 9280.535973201448, 9294.581743821544, 6665.433438157617, 6779.830504237433, 6787.966060169865, 6864.24493305746, 6921.169459664671, 10574.471276435994, 10645.254832161674, 10733.677989660322, 10790.514426850654, 10897.891021090005, 9675.758106047406, 10496.47517624131, 11269.71825704364, 11541.942290288494, 11581.768364632491, 8104.392170587347, 8212.425130240827, 8640.438371505832, 8647.524386158822, 8659.090795466385, 9288.210502107126, 9585.616575337188, 9590.376625519599, 9646.42121472714, 9649.276304277068, 8158.551279679675, 8198.467099638743, 8205.79485512867, 8277.875831862406, 8533.189347012023, 8133.389995750224, 8185.877211841708, 8363.121872203332, 8364.330853531581, 8573.399862009848, 7639.847203056019, 7692.0, 7694.692212311445, 7710.92289077102, 7829.021372328459, 5603.495685388335, 5719.227904233042, 5757.251557297524, 5765.94234057654, 5908.822735317947, 5782.710864456844, 5850.707464626837, 5912.615679980788, 5967.6121052131475, 6025.337212906489], "value": 4913.015749332068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.05485189987036, 350.1893117433142, 353.78242380935717, 362.10198707206126, 362.39118281287426, 5529.0, 5544.22380866679, 5560.2493663355435, 5588.457251950363, 5605.691686957221, 5499.862503437416, 5513.862153446166, 5518.862028449291, 5521.3926468088575, 5539.141433077869, 3886.805659717017, 4121.074037433418, 4332.805023773922, 4336.609705126538, 4355.216061108999, 4331.635534806542, 4344.630706389945, 4344.652427805784, 4344.9137715571105, 4345.78271086446, 4331.029381631351, 4338.266891858125, 4343.478782546099, 4346.565633341006, 4347.673924455663, 4153.646940010088, 4274.572542745716, 4307.91384172318, 4324.329728892027, 4351.129774045172, 3863.555691095532, 3875.1862108957152, 3882.514685664292, 3892.3577609694366, 3914.138889444346, 5335.413104558495, 5365.899990501976, 5366.980273747976, 5367.973160134214, 5376.51611354976, 4755.096531658974, 4907.754612269372, 4931.901361972777, 4934.915677701437, 4936.136176169192, 5691.117876729114, 5693.572982026323, 5698.065496269424, 5710.428957104298, 5729.656220626775, 4201.70588058836, 4215.3044747616605, 4218.0, 4234.640055595278, 4246.426732391118, 5635.690037047961, 5718.828435146911, 5812.982728022579, 5926.881462370771, 5939.613925094898, 4324.113556720865, 4329.588689074533, 4348.565143485626, 4349.630281426114, 4350.195213885441, 4390.758508282075, 4398.890027749268, 4400.471943366816, 4431.401760762255, 4432.556744325606, 4866.31871537985, 4971.1549036663855, 4979.850604481871, 4980.651354405157, 4986.276989836526, 5747.712614369265, 5772.0, 5777.30672319316, 5778.855528611818, 5784.219130417424, 1520.4678362573231, 1523.649560601068, 1528.6331280492602, 1529.5411376586978, 1540.8151021877447, 915.5397141559203, 975.9804803903883, 976.5019839881711, 978.280963491833, 992.980140397188, 4951.093828875909, 4951.777170027357, 4972.776225069881, 4989.351384320088, 4996.250562415594, 5147.588192944556, 5196.350456192976, 5207.427183009866, 5221.963434260641, 5255.211718242218, 4930.372976917609, 4932.950670493321, 4953.678010929278, 5245.63613460503, 5658.292713410824, 3155.8106513608795, 3157.1160075178987, 3168.5722427472456, 3181.984090079535, 3192.760542959241, 6009.939900601025, 6156.753729850845, 6165.106059621419, 6169.753209871555, 6207.317195108534, 5991.610545314541, 6012.316551365625, 6012.338642749294, 6012.699365031731, 6087.269527656621, 6183.752649893954, 6185.536084793658, 6187.3503282155625, 6187.659678717624, 6194.845128871814, 5677.347105082803, 5934.643921364732, 5964.194833697396, 5971.193888824953, 5974.9402505974385, 5889.322727886177, 5911.201987731773, 5938.851528711817, 5957.285125784761, 5971.373005834324, 5931.673757943182, 5938.673372964528, 6116.35778243278, 6200.441960223601, 6218.471429928507, 4362.498312693954, 4413.360062790942, 4437.689361744776, 4457.930096776815, 4460.9107817844115, 5811.912821307768, 6002.879942401215, 6003.309619393651, 6004.159417681615, 6034.366391528826, 5825.417458254141, 5833.241678581757, 5843.444872737083, 5845.298564172326, 5846.626042880005, 5808.15781711641, 5814.622049566765, 5828.184054232495, 5837.5329973601165, 5845.912311315248, 5546.278983732251, 5731.76766995098, 5792.304923409218, 5802.1296805479515, 5810.622309549865, 5883.558733094949, 5911.408859114054, 5920.0, 5935.881282374242, 5941.792037278675, 5524.309461317335, 5729.140628905531, 5746.0, 5757.157709532909, 5767.7404516796005], "value": 4406.746523990192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.9268365847076, 819.1235378145383, 882.0, 891.140049851893, 930.9759264808712, 16976.74534881976, 17586.82413175868, 17813.643727125484, 17830.375936842214, 17847.910760446197, 16297.51107466775, 16400.0, 16931.0, 17390.13049347534, 17494.737578936325, 743.2418932688654, 881.7178502879082, 891.3404080980076, 898.1826537850558, 900.4912224593116, 887.1749273175976, 892.8392889279927, 894.6063731957918, 894.9015608283083, 896.2695403246366, 939.9342046056779, 941.8587211918233, 943.7121677888241, 944.244604316544, 944.2870632672323, 766.7738017284914, 918.7840857398506, 922.6309476209502, 923.2798417234568, 924.7456949338919, 686.5811854768638, 695.9060526828864, 696.3036963036963, 696.4846013949641, 696.8083776961325, 16962.745558816627, 16997.40509082176, 17011.404600838912, 17016.914915425466, 17022.148892555324, 15237.61905952349, 15315.0, 15388.923055384652, 15569.92215038929, 15857.36570537177, 14393.496227631982, 14404.92797536016, 14412.567622971328, 14418.783718244234, 14629.0, 4668.929966050512, 4702.6473014524045, 4737.265723812815, 4803.807847686088, 4824.662273640846, 89326.55336723274, 90104.0, 90217.54891225502, 90493.0, 90948.54525727329, 12087.516499340103, 12189.207701499374, 12204.938975305244, 12236.082293827998, 12425.565105221274, 22858.771412285998, 22921.426964325692, 23074.538509230057, 23081.88459057694, 23097.422564436023, 9143.569913932892, 9158.313126515539, 9370.85943710845, 9393.483358415353, 9442.95278523603, 4933.414795277214, 4980.850574482772, 5005.799768009311, 5033.949660503348, 5134.922976155361, 3625.220577575834, 3736.178040831013, 3749.062734316421, 3758.0, 3772.9434058489146, 641.9165508483867, 642.2902692524693, 646.7380710812129, 655.947524198063, 662.0235153149163, 707.7982774909229, 719.5071376107279, 727.9017332660129, 738.9408847292203, 758.0637912178455, 10050.195984321237, 10160.390376577428, 10236.59053637861, 10347.379157250589, 10387.896121038844, 739.789160089372, 750.2534977697226, 753.7663324369463, 754.5435011817772, 766.8197973476229, 4999.800007999639, 5031.823886163967, 5036.7733451994745, 5067.1639179535705, 5071.949280507148, 128091.0, 128689.0, 128931.35534322268, 131298.0, 132497.33751331375, 67404.0, 69203.30796692069, 86370.56814716011, 88682.11317886868, 94764.52617736867, 59861.401385985584, 61062.69468652628, 63212.683936580026, 78426.0, 92149.0, 10248.897511024794, 10431.37411755297, 10555.788884222426, 10733.946330268298, 10743.194260430342, 37245.441318380785, 42919.57080429156, 51424.22863657123, 51697.7415112922, 52253.216201756244, 77120.0, 79385.20614793779, 79507.60246198732, 79724.202757974, 83014.0, 14380.56858294253, 14429.35067921967, 14558.286978886093, 14785.92607036958, 14861.108333500024, 16513.256903439134, 16722.581935451224, 16791.41230056942, 17310.394136205174, 17897.0, 14631.268436577915, 15035.699286014436, 15490.07059576429, 15670.529884103495, 16549.91725041367, 14833.406663733329, 15000.699986000438, 15375.538733838002, 16355.100469473817, 16383.836161638232, 14722.116672999655, 14731.558053257992, 15519.301631426388, 15557.76663350028, 15795.921020394824, 10755.680571349, 10952.78094438091, 11308.264962777394, 11513.136514761594, 11546.0763138947, 10734.302270352402, 11826.58606948787, 11899.524019039141, 12531.93734031324, 12705.872941270838], "value": 8147.900035270778, "unit": "score"}], "name": "dromaeo_css", "value": 7729.775734855093, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 111.0, 110.0, 109.0, 109.0, 109.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 116.0, 171.0, 116.0, 120.0, 146.0, 114.0, 151.0, 114.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 160.0, 162.0, 160.0, 160.0, 161.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 115.0, 76.0, 77.0, 73.0, 76.0, 98.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 129.0, 82.0, 82.0, 83.0, 111.0, 84.0, 83.0, 128.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 148.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 50.0, 49.0, 51.0, 51.0, 51.0, 50.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 62.0, 60.0, 61.0, 60.0, 59.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 74.0, 72.0, 72.0, 72.0, 72.0, 72.0, 74.0, 75.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 119.0, 124.0, 129.0, 116.0, 118.0, 124.0, 163.0, 127.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 152.0, 161.0, 150.0, 151.0, 153.0, 152.0, 159.0, 152.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 61.0, 61.0, 63.0, 63.0, 65.0, 62.0, 62.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1459.9, "alertThreshold": 2.0}]} 07:20:18 INFO - Return code: 0 07:20:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:18 INFO - # TBPL SUCCESS # 07:20:18 INFO - Running post-action listener: _resource_record_post_action 07:20:18 INFO - [mozharness: 2016-09-01 14:20:18.558526Z] Finished run-tests step (success) 07:20:18 INFO - Running post-run listener: _resource_record_post_run 07:20:18 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 330309632; Read time: 16; Write time: 203484 07:20:18 INFO - TinderboxPrint: CPU usage
12.9% 07:20:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 07:20:18 INFO - TinderboxPrint: I/O write bytes / time
330,309,632 / 203,484 07:20:18 INFO - TinderboxPrint: CPU idle
8,269.5 (87.1%) 07:20:18 INFO - TinderboxPrint: CPU user
1,183.8 (12.5%) 07:20:18 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77185024; Read time: 0; Write time: 74580 07:20:18 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250695680; Read time: 16; Write time: 128668 07:20:19 INFO - Running post-run listener: _upload_blobber_files 07:20:19 INFO - Blob upload gear active. 07:20:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:19 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:20:19 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:20:19 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:20:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:20:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:20 INFO - (blobuploader) - INFO - Done attempting. 07:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:21 INFO - (blobuploader) - INFO - Done attempting. 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:33 INFO - (blobuploader) - INFO - Done attempting. 07:20:33 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:33 INFO - Return code: 0 07:20:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e683d2f7d25d66ce4759cc44bd94bfd70ea5c0504c070ad55b5c5bd88b499b91de96539d724ca1ba5945087c7e9b7d759a775e476795cf04641de205df301002", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8daf85ea2446a1020d9b11044335380055a4f4aaa95a869c5cee8e82d96322396d3f0222753cb3df92a8968d18e2cb0c6cb3b7bcc0d9fef78be98ec570afe7b7"} 07:20:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:33 INFO - Contents: 07:20:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e683d2f7d25d66ce4759cc44bd94bfd70ea5c0504c070ad55b5c5bd88b499b91de96539d724ca1ba5945087c7e9b7d759a775e476795cf04641de205df301002", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8daf85ea2446a1020d9b11044335380055a4f4aaa95a869c5cee8e82d96322396d3f0222753cb3df92a8968d18e2cb0c6cb3b7bcc0d9fef78be98ec570afe7b7"} 07:20:33 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:33 INFO - Copying logs to upload dir... 07:20:33 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1685.812401 ========= master_lag: 0.76 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-01 07:20:33.498558) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:33.507156) ========= 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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 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/e683d2f7d25d66ce4759cc44bd94bfd70ea5c0504c070ad55b5c5bd88b499b91de96539d724ca1ba5945087c7e9b7d759a775e476795cf04641de205df301002", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8daf85ea2446a1020d9b11044335380055a4f4aaa95a869c5cee8e82d96322396d3f0222753cb3df92a8968d18e2cb0c6cb3b7bcc0d9fef78be98ec570afe7b7"} build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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/e683d2f7d25d66ce4759cc44bd94bfd70ea5c0504c070ad55b5c5bd88b499b91de96539d724ca1ba5945087c7e9b7d759a775e476795cf04641de205df301002", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8daf85ea2446a1020d9b11044335380055a4f4aaa95a869c5cee8e82d96322396d3f0222753cb3df92a8968d18e2cb0c6cb3b7bcc0d9fef78be98ec570afe7b7"}' ========= master_lag: 2.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:35.914029) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-09-01 07:20:35.914339) ========= 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=0333a256a5e4f052382dd180000001b1-1472734134.964078-526900705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 10.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-09-01 07:20:46.423606) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-01 07:20:46.427975) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-01 07:20:49.039183) ========= ========= Total master_lag: 13.76 =========