builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-018 starttime: 1472665698.89 results: success (0) buildid: 20160831074921 builduid: 31b395d33fe84e37a77e1921c416b6d1 revision: fa475195cce705f74806ff304e31e12850715d4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:18.894294) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:18.894750) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:18.895025) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:18.909187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:18.909442) ========= 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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 10:48:19-- 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% 31.1M=0s 2016-08-31 10:48:19 (31.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135276 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.058905) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.059211) ========= 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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017388 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.108649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.109152) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa475195cce705f74806ff304e31e12850715d4a --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 fa475195cce705f74806ff304e31e12850715d4a --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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 10:48:19,859 truncating revision to first 12 chars 2016-08-31 10:48:19,860 Setting DEBUG logging. 2016-08-31 10:48:19,860 attempt 1/10 2016-08-31 10:48:19,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa475195cce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 10:48:20,211 unpacking tar archive at: mozilla-aurora-fa475195cce7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670671 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.789887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.790203) ========= script_repo_revision: fa475195cce705f74806ff304e31e12850715d4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.790609) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.790889) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 10:48:19.799735) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-31 10:48:19.800006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:48:20 INFO - MultiFileLogger online at 20160831 10:48:20 in /builds/slave/test 10:48:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:48:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:48:20 INFO - {'append_to_log': False, 10:48:20 INFO - 'base_work_dir': '/builds/slave/test', 10:48:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:48:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:48:20 INFO - 'branch': 'Mozilla-Aurora', 10:48:20 INFO - 'buildbot_json_path': 'buildprops.json', 10:48:20 INFO - 'config_files': ('talos/linux_config.py',), 10:48:20 INFO - 'default_actions': ('clobber', 10:48:20 INFO - 'read-buildbot-config', 10:48:20 INFO - 'download-and-extract', 10:48:20 INFO - 'populate-webroot', 10:48:20 INFO - 'create-virtualenv', 10:48:20 INFO - 'install', 10:48:20 INFO - 'run-tests'), 10:48:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:48:20 INFO - 'download_minidump_stackwalk': True, 10:48:20 INFO - 'download_symbols': 'ondemand', 10:48:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:48:20 INFO - 'tooltool.py': '/tools/tooltool.py', 10:48:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:48:20 INFO - '/tools/misc-python/virtualenv.py')}, 10:48:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:48:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:48:20 INFO - 'installer_path': 'installer.exe', 10:48:20 INFO - 'log_level': 'info', 10:48:20 INFO - 'log_name': 'talos', 10:48:20 INFO - 'log_to_console': True, 10:48:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:48:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:48:20 INFO - 'opt_config_files': (), 10:48:20 INFO - 'pip_index': False, 10:48:20 INFO - 'sps_profile': False, 10:48:20 INFO - 'sps_profile_interval': 0, 10:48:20 INFO - 'suite': 'dromaeojs-e10s', 10:48:20 INFO - 'system_bits': '32', 10:48:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:48:20 INFO - 'title': 'talos-linux64-ix-018', 10:48:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:48:20 INFO - 'use_talos_json': True, 10:48:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:48:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:48:20 INFO - 'work_dir': 'build'} 10:48:20 INFO - [mozharness: 2016-08-31 17:48:20.650590Z] Running clobber step. 10:48:20 INFO - Running pre-action listener: _resource_record_pre_action 10:48:20 INFO - Running main action method: clobber 10:48:20 INFO - rmtree: /builds/slave/test/build 10:48:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:21 INFO - Running post-action listener: _resource_record_post_action 10:48:21 INFO - [mozharness: 2016-08-31 17:48:21.149719Z] Finished clobber step (success) 10:48:21 INFO - [mozharness: 2016-08-31 17:48:21.149820Z] Running read-buildbot-config step. 10:48:21 INFO - Running pre-action listener: _resource_record_pre_action 10:48:21 INFO - Running main action method: read_buildbot_config 10:48:21 INFO - Using buildbot properties: 10:48:21 INFO - { 10:48:21 INFO - "project": "", 10:48:21 INFO - "product": "firefox", 10:48:21 INFO - "script_repo_revision": "production", 10:48:21 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 10:48:21 INFO - "repository": "", 10:48:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 10:48:21 INFO - "buildid": "20160831074921", 10:48:21 INFO - "pgo_build": "True", 10:48:21 INFO - "basedir": "/builds/slave/test", 10:48:21 INFO - "buildnumber": 515, 10:48:21 INFO - "slavename": "talos-linux64-ix-018", 10:48:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:48:21 INFO - "platform": "ubuntu64_hw", 10:48:21 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 10:48:21 INFO - "branch": "mozilla-aurora", 10:48:21 INFO - "repo_path": "releases/mozilla-aurora", 10:48:21 INFO - "revision": "fa475195cce705f74806ff304e31e12850715d4a", 10:48:21 INFO - "stage_platform": "linux64-pgo", 10:48:21 INFO - "builduid": "31b395d33fe84e37a77e1921c416b6d1", 10:48:21 INFO - "slavebuilddir": "test" 10:48:21 INFO - } 10:48:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 10:48:21 INFO - Running post-action listener: _resource_record_post_action 10:48:21 INFO - [mozharness: 2016-08-31 17:48:21.152267Z] Finished read-buildbot-config step (success) 10:48:21 INFO - [mozharness: 2016-08-31 17:48:21.152356Z] Running download-and-extract step. 10:48:21 INFO - Running pre-action listener: _resource_record_pre_action 10:48:21 INFO - Running main action method: download_and_extract 10:48:21 INFO - mkdir: /builds/slave/test/build/tests 10:48:21 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')]} 10:48:21 INFO - https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:48:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:48:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:48:21 INFO - retry: Failed, sleeping 30 seconds before retrying 10:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:48:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:48:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:48:51 INFO - retry: Failed, sleeping 60 seconds before retrying 10:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:49:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:49:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:49:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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! 10:49:51 INFO - Caught exception: HTTP Error 404: Not Found 10:49:51 INFO - Caught exception: HTTP Error 404: Not Found 10:49:51 INFO - Caught exception: HTTP Error 404: Not Found 10:49:51 INFO - trying https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:49:51 INFO - Downloading https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:49:51 INFO - Downloaded 1361 bytes. 10:49:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:49:51 INFO - Using the following test package requirements: 10:49:51 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:49:51 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 10:49:51 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 10:49:51 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'jsshell-linux-x86_64.zip'], 10:49:51 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 10:49:51 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:49:51 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 10:49:51 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 10:49:51 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 10:49:51 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:49:51 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 10:49:51 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:51 INFO - https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:49:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:49:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:49:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:49:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:49:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:49:51 INFO - retry: Failed, sleeping 30 seconds before retrying 10:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:50:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:50:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:50:21 INFO - retry: Failed, sleeping 60 seconds before retrying 10:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:51:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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! 10:51:22 INFO - Caught exception: HTTP Error 404: Not Found 10:51:22 INFO - Caught exception: HTTP Error 404: Not Found 10:51:22 INFO - Caught exception: HTTP Error 404: Not Found 10:51:22 INFO - trying https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:22 INFO - Downloading https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:22 INFO - Downloaded 21770241 bytes. 10:51:22 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'] 10:51:22 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 10:51:24 INFO - Return code: 0 10:51:24 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 10:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:24 INFO - https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:51:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:51:24 INFO - retry: Failed, sleeping 30 seconds before retrying 10:51:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:51:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:51:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:51:54 INFO - retry: Failed, sleeping 60 seconds before retrying 10:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:52:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:52:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:52:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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! 10:52:54 INFO - Caught exception: HTTP Error 404: Not Found 10:52:54 INFO - Caught exception: HTTP Error 404: Not Found 10:52:54 INFO - Caught exception: HTTP Error 404: Not Found 10:52:54 INFO - trying https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:52:55 INFO - Downloaded 21770241 bytes. 10:52:55 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'] 10:52:55 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 10:52:56 INFO - Return code: 0 10:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:56 INFO - https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:52:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:52:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:52:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:52:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:52:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:52:56 INFO - retry: Failed, sleeping 30 seconds before retrying 10:53:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:53:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:53:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:53:26 INFO - retry: Failed, sleeping 60 seconds before retrying 10:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:54:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:54:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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! 10:54:26 INFO - Caught exception: HTTP Error 404: Not Found 10:54:26 INFO - Caught exception: HTTP Error 404: Not Found 10:54:26 INFO - Caught exception: HTTP Error 404: Not Found 10:54:26 INFO - trying https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:54:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:27 INFO - Downloaded 14413740 bytes. 10:54:27 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'] 10:54:27 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 10:54:27 INFO - Return code: 0 10:54: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')]} 10:54:27 INFO - https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:54:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:54:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:54:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:54:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:54:27 INFO - retry: Failed, sleeping 30 seconds before retrying 10:54:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:54:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:54:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:54:57 INFO - retry: Failed, sleeping 60 seconds before retrying 10:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:55:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:55:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:55:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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! 10:55:57 INFO - Caught exception: HTTP Error 404: Not Found 10:55:57 INFO - Caught exception: HTTP Error 404: Not Found 10:55:57 INFO - Caught exception: HTTP Error 404: Not Found 10:55:57 INFO - trying https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:55:57 INFO - Downloading https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/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 10:55:58 INFO - Downloaded 63872621 bytes. 10:55:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:55:58 INFO - mkdir: /builds/slave/test/properties 10:55:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:55:58 INFO - Writing to file /builds/slave/test/properties/build_url 10:55:58 INFO - Contents: 10:55:58 INFO - build_url:https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:55:59 INFO - Running post-action listener: _resource_record_post_action 10:55:59 INFO - Running post-action listener: set_extra_try_arguments 10:55:59 INFO - [mozharness: 2016-08-31 17:55:59.483416Z] Finished download-and-extract step (success) 10:55:59 INFO - [mozharness: 2016-08-31 17:55:59.483647Z] Running populate-webroot step. 10:55:59 INFO - Running pre-action listener: _resource_record_pre_action 10:55:59 INFO - Running main action method: populate_webroot 10:55:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:55:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:55:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:55:59 INFO - '--tppagecycles', 10:55:59 INFO - '7'], 10:55:59 INFO - 'tests': ['tsvgm']}}, 10:55:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:55:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:55:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:55:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:55:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['damp', 'tps']}, 10:55:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'tests': ['damp', 'tps']}, 10:55:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['dromaeo_dom']}, 10:55:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:55:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['basic_compositor_video']}, 10:55:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:55:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'other-e10s': {'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['a11yr', 10:55:59 INFO - 'ts_paint', 10:55:59 INFO - 'tpaint', 10:55:59 INFO - 'sessionrestore', 10:55:59 INFO - 'sessionrestore_no_auto_restore', 10:55:59 INFO - 'tabpaint']}, 10:55:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['tsvgx', 10:55:59 INFO - 'tsvgr_opacity', 10:55:59 INFO - 'tart', 10:55:59 INFO - 'tscrollx', 10:55:59 INFO - 'cart']}, 10:55:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:55:59 INFO - 'tsvgr_opacity', 10:55:59 INFO - 'tart', 10:55:59 INFO - 'tscrollx', 10:55:59 INFO - 'cart']}, 10:55:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'talos_options': ['--disable-e10s'], 10:55:59 INFO - 'tests': ['tp5o']}, 10:55:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'tests': ['tp5o']}, 10:55:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'talos_options': ['--disable-e10s', 10:55:59 INFO - '--xperf_path', 10:55:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:59 INFO - 'tests': ['tp5n']}, 10:55:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:55:59 INFO - 'talos_options': ['--xperf_path', 10:55:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:59 INFO - 'tests': ['tp5n']}}, 10:55:59 INFO - 'talos.zip': {'path': '', 10:55:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:55:59 INFO - Running post-action listener: _resource_record_post_action 10:55:59 INFO - [mozharness: 2016-08-31 17:55:59.503999Z] Finished populate-webroot step (success) 10:55:59 INFO - [mozharness: 2016-08-31 17:55:59.504088Z] Running create-virtualenv step. 10:55:59 INFO - Running pre-action listener: _resource_record_pre_action 10:55:59 INFO - Running main action method: create_virtualenv 10:55:59 INFO - Creating virtualenv /builds/slave/test/build/venv 10:55:59 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 10:55:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:55:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:55:59 INFO - Using real prefix '/usr' 10:55:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:56:00 INFO - Installing distribute.............................................................................................................................................................................................done. 10:56:02 INFO - Installing pip.................done. 10:56:02 INFO - Return code: 0 10:56:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:02 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:02 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 10:56:02 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 10:56:02 INFO - Using env: {'DISPLAY': ':0', 10:56:02 INFO - 'HOME': '/home/cltbld', 10:56:02 INFO - 'LANG': 'en_US.UTF-8', 10:56:02 INFO - 'LANGUAGE': 'en_US:en', 10:56:02 INFO - 'LOGNAME': 'cltbld', 10:56:02 INFO - 'MAIL': '/var/mail/cltbld', 10:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:02 INFO - 'MOZ_NO_REMOTE': '1', 10:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:02 INFO - 'NO_EM_RESTART': '1', 10:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:02 INFO - 'PWD': '/builds/slave/test', 10:56:02 INFO - 'SHELL': '/bin/bash', 10:56:02 INFO - 'SHLVL': '1', 10:56:02 INFO - 'TERM': 'linux', 10:56:02 INFO - 'TMOUT': '86400', 10:56:02 INFO - 'USER': 'cltbld', 10:56:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:02 INFO - '_': '/tools/buildbot/bin/python'} 10:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:02 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 10:56:02 INFO - Cleaning up... 10:56:02 INFO - Return code: 0 10:56:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:02 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:02 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 10:56:02 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 10:56:02 INFO - Using env: {'DISPLAY': ':0', 10:56:02 INFO - 'HOME': '/home/cltbld', 10:56:02 INFO - 'LANG': 'en_US.UTF-8', 10:56:02 INFO - 'LANGUAGE': 'en_US:en', 10:56:02 INFO - 'LOGNAME': 'cltbld', 10:56:02 INFO - 'MAIL': '/var/mail/cltbld', 10:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:02 INFO - 'MOZ_NO_REMOTE': '1', 10:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:02 INFO - 'NO_EM_RESTART': '1', 10:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:02 INFO - 'PWD': '/builds/slave/test', 10:56:02 INFO - 'SHELL': '/bin/bash', 10:56:02 INFO - 'SHLVL': '1', 10:56:02 INFO - 'TERM': 'linux', 10:56:02 INFO - 'TMOUT': '86400', 10:56:02 INFO - 'USER': 'cltbld', 10:56:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:02 INFO - '_': '/tools/buildbot/bin/python'} 10:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:02 INFO - Downloading/unpacking psutil>=3.1.1 10:56:02 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 10:56:02 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 10:56:02 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 10:56:02 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 10:56:02 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 10:56:02 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 10:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:56:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:56:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:56:04 INFO - Installing collected packages: psutil 10:56:04 INFO - Running setup.py install for psutil 10:56:04 INFO - building 'psutil._psutil_linux' extension 10:56:04 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 10:56:04 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 10:56:04 INFO - building 'psutil._psutil_posix' extension 10:56:04 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 10:56:04 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 10:56:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:56:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:56:04 INFO - Successfully installed psutil 10:56:04 INFO - Cleaning up... 10:56:05 INFO - Return code: 0 10:56:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:56:05 INFO - Using env: {'DISPLAY': ':0', 10:56:05 INFO - 'HOME': '/home/cltbld', 10:56:05 INFO - 'LANG': 'en_US.UTF-8', 10:56:05 INFO - 'LANGUAGE': 'en_US:en', 10:56:05 INFO - 'LOGNAME': 'cltbld', 10:56:05 INFO - 'MAIL': '/var/mail/cltbld', 10:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:05 INFO - 'MOZ_NO_REMOTE': '1', 10:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:05 INFO - 'NO_EM_RESTART': '1', 10:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:05 INFO - 'PWD': '/builds/slave/test', 10:56:05 INFO - 'SHELL': '/bin/bash', 10:56:05 INFO - 'SHLVL': '1', 10:56:05 INFO - 'TERM': 'linux', 10:56:05 INFO - 'TMOUT': '86400', 10:56:05 INFO - 'USER': 'cltbld', 10:56:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:05 INFO - '_': '/tools/buildbot/bin/python'} 10:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:56:07 INFO - Installing collected packages: mozsystemmonitor 10:56:07 INFO - Running setup.py install for mozsystemmonitor 10:56:07 INFO - Successfully installed mozsystemmonitor 10:56:07 INFO - Cleaning up... 10:56:07 INFO - Return code: 0 10:56:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:56:07 INFO - Using env: {'DISPLAY': ':0', 10:56:07 INFO - 'HOME': '/home/cltbld', 10:56:07 INFO - 'LANG': 'en_US.UTF-8', 10:56:07 INFO - 'LANGUAGE': 'en_US:en', 10:56:07 INFO - 'LOGNAME': 'cltbld', 10:56:07 INFO - 'MAIL': '/var/mail/cltbld', 10:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:07 INFO - 'MOZ_NO_REMOTE': '1', 10:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:07 INFO - 'NO_EM_RESTART': '1', 10:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:07 INFO - 'PWD': '/builds/slave/test', 10:56:07 INFO - 'SHELL': '/bin/bash', 10:56:07 INFO - 'SHLVL': '1', 10:56:07 INFO - 'TERM': 'linux', 10:56:07 INFO - 'TMOUT': '86400', 10:56:07 INFO - 'USER': 'cltbld', 10:56:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:07 INFO - '_': '/tools/buildbot/bin/python'} 10:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:07 INFO - Downloading/unpacking jsonschema==2.5.1 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:07 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 10:56:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:56:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:56:09 INFO - warning: no files found matching '*.txt' 10:56:09 INFO - no previously-included directories found matching 'build' 10:56:09 INFO - no previously-included directories found matching 'dist' 10:56:09 INFO - no previously-included directories found matching '.git*' 10:56:09 INFO - Installing collected packages: jsonschema, functools32 10:56:09 INFO - Running setup.py install for functools32 10:56:09 INFO - warning: no files found matching '*.txt' 10:56:09 INFO - no previously-included directories found matching 'build' 10:56:09 INFO - no previously-included directories found matching 'dist' 10:56:09 INFO - no previously-included directories found matching '.git*' 10:56:09 INFO - Successfully installed jsonschema functools32 10:56:09 INFO - Cleaning up... 10:56:09 INFO - Return code: 0 10:56:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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', '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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:56: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 functools32==3.2.3-2 10:56:09 INFO - Using env: {'DISPLAY': ':0', 10:56:09 INFO - 'HOME': '/home/cltbld', 10:56:09 INFO - 'LANG': 'en_US.UTF-8', 10:56:09 INFO - 'LANGUAGE': 'en_US:en', 10:56:09 INFO - 'LOGNAME': 'cltbld', 10:56:09 INFO - 'MAIL': '/var/mail/cltbld', 10:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:09 INFO - 'MOZ_NO_REMOTE': '1', 10:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:09 INFO - 'NO_EM_RESTART': '1', 10:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:09 INFO - 'PWD': '/builds/slave/test', 10:56:09 INFO - 'SHELL': '/bin/bash', 10:56:09 INFO - 'SHLVL': '1', 10:56:09 INFO - 'TERM': 'linux', 10:56:09 INFO - 'TMOUT': '86400', 10:56:09 INFO - 'USER': 'cltbld', 10:56:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:09 INFO - '_': '/tools/buildbot/bin/python'} 10:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:56:10 INFO - Cleaning up... 10:56:10 INFO - Return code: 0 10:56:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:56:10 INFO - Using env: {'DISPLAY': ':0', 10:56:10 INFO - 'HOME': '/home/cltbld', 10:56:10 INFO - 'LANG': 'en_US.UTF-8', 10:56:10 INFO - 'LANGUAGE': 'en_US:en', 10:56:10 INFO - 'LOGNAME': 'cltbld', 10:56:10 INFO - 'MAIL': '/var/mail/cltbld', 10:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:10 INFO - 'MOZ_NO_REMOTE': '1', 10:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:10 INFO - 'NO_EM_RESTART': '1', 10:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:10 INFO - 'PWD': '/builds/slave/test', 10:56:10 INFO - 'SHELL': '/bin/bash', 10:56:10 INFO - 'SHLVL': '1', 10:56:10 INFO - 'TERM': 'linux', 10:56:10 INFO - 'TMOUT': '86400', 10:56:10 INFO - 'USER': 'cltbld', 10:56:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:10 INFO - '_': '/tools/buildbot/bin/python'} 10:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:10 INFO - Downloading/unpacking blobuploader==1.2.4 10:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:56:12 INFO - Downloading blobuploader-1.2.4.tar.gz 10:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:56:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:56:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56:12 INFO - Downloading docopt-0.6.1.tar.gz 10:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:56:12 INFO - Installing collected packages: blobuploader, requests, docopt 10:56:12 INFO - Running setup.py install for blobuploader 10:56:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:56:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:56:13 INFO - Running setup.py install for requests 10:56:13 INFO - Running setup.py install for docopt 10:56:13 INFO - Successfully installed blobuploader requests docopt 10:56:13 INFO - Cleaning up... 10:56:13 INFO - Return code: 0 10:56:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:13 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:13 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 10:56:13 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 10:56:13 INFO - Using env: {'DISPLAY': ':0', 10:56:13 INFO - 'HOME': '/home/cltbld', 10:56:13 INFO - 'LANG': 'en_US.UTF-8', 10:56:13 INFO - 'LANGUAGE': 'en_US:en', 10:56:13 INFO - 'LOGNAME': 'cltbld', 10:56:13 INFO - 'MAIL': '/var/mail/cltbld', 10:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:13 INFO - 'MOZ_NO_REMOTE': '1', 10:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:13 INFO - 'NO_EM_RESTART': '1', 10:56:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:13 INFO - 'PWD': '/builds/slave/test', 10:56:13 INFO - 'SHELL': '/bin/bash', 10:56:13 INFO - 'SHLVL': '1', 10:56:13 INFO - 'TERM': 'linux', 10:56:13 INFO - 'TMOUT': '86400', 10:56:13 INFO - 'USER': 'cltbld', 10:56:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:13 INFO - '_': '/tools/buildbot/bin/python'} 10:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:56:13 INFO - Running setup.py (path:/tmp/pip-4gu17C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:56:13 INFO - Running setup.py (path:/tmp/pip-MvnxDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:56:13 INFO - Running setup.py (path:/tmp/pip-Yq7Ycm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:56:13 INFO - Running setup.py (path:/tmp/pip-fD4kNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:56:14 INFO - Running setup.py (path:/tmp/pip-78Jw1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:56:14 INFO - Running setup.py (path:/tmp/pip-PH5EFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:56:14 INFO - Running setup.py (path:/tmp/pip-o5PWMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:56:14 INFO - Running setup.py (path:/tmp/pip-TpFeOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:56:14 INFO - Running setup.py (path:/tmp/pip-4sS0zD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:56:14 INFO - Running setup.py (path:/tmp/pip-_Xpd8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:56:14 INFO - Running setup.py (path:/tmp/pip-QHCeAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:56:14 INFO - Running setup.py (path:/tmp/pip-Y52Lvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:56:14 INFO - Running setup.py (path:/tmp/pip-X4UWxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:56:14 INFO - Running setup.py (path:/tmp/pip-s1wemS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:14 INFO - Running setup.py (path:/tmp/pip-59l7V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:56:14 INFO - Running setup.py (path:/tmp/pip-f9WqxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:56:14 INFO - Running setup.py (path:/tmp/pip-mXUD2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:56:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:56:14 INFO - Running setup.py install for manifestparser 10:56:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Running setup.py install for mozcrash 10:56:15 INFO - Running setup.py install for mozdebug 10:56:15 INFO - Running setup.py install for mozdevice 10:56:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Running setup.py install for mozfile 10:56:15 INFO - Running setup.py install for mozhttpd 10:56:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Running setup.py install for mozinfo 10:56:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Running setup.py install for mozInstall 10:56:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:56:15 INFO - Running setup.py install for mozleak 10:56:15 INFO - Running setup.py install for mozlog 10:56:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Running setup.py install for moznetwork 10:56:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Running setup.py install for mozprocess 10:56:16 INFO - Running setup.py install for mozprofile 10:56:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Running setup.py install for mozrunner 10:56:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Running setup.py install for mozscreenshot 10:56:16 INFO - Running setup.py install for moztest 10:56:16 INFO - Running setup.py install for mozversion 10:56:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:56:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:56:16 INFO - Cleaning up... 10:56:16 INFO - Return code: 0 10:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:16 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:16 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 10:56:16 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 10:56:16 INFO - Using env: {'DISPLAY': ':0', 10:56:16 INFO - 'HOME': '/home/cltbld', 10:56:16 INFO - 'LANG': 'en_US.UTF-8', 10:56:16 INFO - 'LANGUAGE': 'en_US:en', 10:56:16 INFO - 'LOGNAME': 'cltbld', 10:56:16 INFO - 'MAIL': '/var/mail/cltbld', 10:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:16 INFO - 'MOZ_NO_REMOTE': '1', 10:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:16 INFO - 'NO_EM_RESTART': '1', 10:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:16 INFO - 'PWD': '/builds/slave/test', 10:56:16 INFO - 'SHELL': '/bin/bash', 10:56:16 INFO - 'SHLVL': '1', 10:56:16 INFO - 'TERM': 'linux', 10:56:16 INFO - 'TMOUT': '86400', 10:56:16 INFO - 'USER': 'cltbld', 10:56:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:16 INFO - '_': '/tools/buildbot/bin/python'} 10:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:56:16 INFO - Running setup.py (path:/tmp/pip-gvF7hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:56:17 INFO - Running setup.py (path:/tmp/pip-Z9wgyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:56:17 INFO - Running setup.py (path:/tmp/pip-kstxM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:56:17 INFO - Running setup.py (path:/tmp/pip-7U1kgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:56:17 INFO - Running setup.py (path:/tmp/pip-OWAnVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:56:17 INFO - Running setup.py (path:/tmp/pip-mp8T4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:56:17 INFO - Running setup.py (path:/tmp/pip-W6TQgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:56:17 INFO - Running setup.py (path:/tmp/pip-yEOQ1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:56:17 INFO - Running setup.py (path:/tmp/pip-CHbl_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:56:17 INFO - Running setup.py (path:/tmp/pip-tGbsnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:56:17 INFO - Running setup.py (path:/tmp/pip-TF1T5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:56:17 INFO - Running setup.py (path:/tmp/pip-9nGXrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:56:17 INFO - Running setup.py (path:/tmp/pip-YHLFFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:56:17 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)) 10:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:56:17 INFO - Running setup.py (path:/tmp/pip-sFVEhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:56:18 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)) 10:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:18 INFO - Running setup.py (path:/tmp/pip-g6g4FB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:56:18 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)) 10:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:56:18 INFO - Running setup.py (path:/tmp/pip-gyGW44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:56:18 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)) 10:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:56:18 INFO - Running setup.py (path:/tmp/pip-3h6_Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:56:18 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)) 10:56:18 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)) 10:56:18 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)) 10:56:18 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)) 10:56:18 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)) 10:56:18 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)) 10:56:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:56:18 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 10:56:18 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 10:56:18 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 10:56:18 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 10:56:18 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 10:56:18 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 10:56:20 INFO - Downloading blessings-1.6.tar.gz 10:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:56:20 INFO - Installing collected packages: blessings 10:56:20 INFO - Running setup.py install for blessings 10:56:20 INFO - Successfully installed blessings 10:56:20 INFO - Cleaning up... 10:56:20 INFO - Return code: 0 10:56:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:20 INFO - Reading from file tmpfile_stdout 10:56:20 INFO - Current package versions: 10:56:20 INFO - argparse == 1.2.1 10:56:20 INFO - blessings == 1.6 10:56:20 INFO - blobuploader == 1.2.4 10:56:20 INFO - docopt == 0.6.1 10:56:20 INFO - functools32 == 3.2.3-2 10:56:20 INFO - jsonschema == 2.5.1 10:56:20 INFO - manifestparser == 1.1 10:56:20 INFO - mozInstall == 1.12 10:56:20 INFO - mozcrash == 0.17 10:56:20 INFO - mozdebug == 0.1 10:56:20 INFO - mozdevice == 0.48 10:56:20 INFO - mozfile == 1.2 10:56:20 INFO - mozhttpd == 0.7 10:56:20 INFO - mozinfo == 0.9 10:56:20 INFO - mozleak == 0.1 10:56:20 INFO - mozlog == 3.3 10:56:20 INFO - moznetwork == 0.27 10:56:20 INFO - mozprocess == 0.23 10:56:20 INFO - mozprofile == 0.28 10:56:20 INFO - mozrunner == 6.12 10:56:20 INFO - mozscreenshot == 0.1 10:56:20 INFO - mozsystemmonitor == 0.3 10:56:20 INFO - moztest == 0.7 10:56:20 INFO - mozversion == 1.4 10:56:20 INFO - psutil == 3.1.1 10:56:20 INFO - requests == 1.2.3 10:56:20 INFO - wsgiref == 0.1.2 10:56:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:20 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:20 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 10:56:20 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 10:56:20 INFO - Using env: {'DISPLAY': ':0', 10:56:20 INFO - 'HOME': '/home/cltbld', 10:56:20 INFO - 'LANG': 'en_US.UTF-8', 10:56:20 INFO - 'LANGUAGE': 'en_US:en', 10:56:20 INFO - 'LOGNAME': 'cltbld', 10:56:20 INFO - 'MAIL': '/var/mail/cltbld', 10:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:20 INFO - 'MOZ_NO_REMOTE': '1', 10:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:20 INFO - 'NO_EM_RESTART': '1', 10:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:20 INFO - 'PWD': '/builds/slave/test', 10:56:20 INFO - 'SHELL': '/bin/bash', 10:56:20 INFO - 'SHLVL': '1', 10:56:20 INFO - 'TERM': 'linux', 10:56:20 INFO - 'TMOUT': '86400', 10:56:20 INFO - 'USER': 'cltbld', 10:56:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:20 INFO - '_': '/tools/buildbot/bin/python'} 10:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 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)) 10:56:20 INFO - Cleaning up... 10:56:20 INFO - Return code: 0 10:56:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56:20 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 0x1bc0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1def400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, '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': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', '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 10:56:20 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 10:56:20 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 10:56:20 INFO - Using env: {'DISPLAY': ':0', 10:56:20 INFO - 'HOME': '/home/cltbld', 10:56:20 INFO - 'LANG': 'en_US.UTF-8', 10:56:20 INFO - 'LANGUAGE': 'en_US:en', 10:56:20 INFO - 'LOGNAME': 'cltbld', 10:56:20 INFO - 'MAIL': '/var/mail/cltbld', 10:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:20 INFO - 'MOZ_NO_REMOTE': '1', 10:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:20 INFO - 'NO_EM_RESTART': '1', 10:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:20 INFO - 'PWD': '/builds/slave/test', 10:56:20 INFO - 'SHELL': '/bin/bash', 10:56:20 INFO - 'SHLVL': '1', 10:56:20 INFO - 'TERM': 'linux', 10:56:20 INFO - 'TMOUT': '86400', 10:56:20 INFO - 'USER': 'cltbld', 10:56:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:20 INFO - '_': '/tools/buildbot/bin/python'} 10:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:56:21 INFO - Cleaning up... 10:56:21 INFO - Return code: 0 10:56:21 INFO - Running post-action listener: _resource_record_post_action 10:56:21 INFO - Running post-action listener: _start_resource_monitoring 10:56:21 INFO - Starting resource monitoring. 10:56:21 INFO - [mozharness: 2016-08-31 17:56:21.053923Z] Finished create-virtualenv step (success) 10:56:21 INFO - [mozharness: 2016-08-31 17:56:21.054357Z] Running install step. 10:56:21 INFO - Running pre-action listener: _resource_record_pre_action 10:56:21 INFO - Running main action method: install 10:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:56:21 INFO - Reading from file tmpfile_stdout 10:56:21 INFO - Detecting whether we're running mozinstall >=1.0... 10:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:56:21 INFO - Reading from file tmpfile_stdout 10:56:21 INFO - Output received: 10:56:21 INFO - Usage: mozinstall [options] installer 10:56:21 INFO - Options: 10:56:21 INFO - -h, --help show this help message and exit 10:56:21 INFO - -d DEST, --destination=DEST 10:56:21 INFO - Directory to install application into. [default: 10:56:21 INFO - "/builds/slave/test"] 10:56:21 INFO - --app=APP Application being installed. [default: firefox] 10:56:21 INFO - mkdir: /builds/slave/test/build/application 10:56:21 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'] 10:56:21 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 10:56:37 INFO - Reading from file tmpfile_stdout 10:56:37 INFO - Output received: 10:56:37 INFO - /builds/slave/test/build/application/firefox/firefox 10:56:37 INFO - Running post-action listener: _resource_record_post_action 10:56:37 INFO - [mozharness: 2016-08-31 17:56:37.367281Z] Finished install step (success) 10:56:37 INFO - [mozharness: 2016-08-31 17:56:37.367623Z] Running run-tests step. 10:56:37 INFO - Running pre-action listener: _resource_record_pre_action 10:56:37 INFO - Running main action method: run_tests 10:56:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:56:37 INFO - Python 2.7.3 10:56:37 INFO - Return code: 0 10:56:37 INFO - grabbing minidump binary from tooltool 10:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:37 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 0x1e7e570>, '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 0x1e7f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e7f900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:56:37 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 10:56:37 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 10:56:37 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 10:56:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:56:37 INFO - Return code: 0 10:56:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:56:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:56:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:56:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:56:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:56:37 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:56:37 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:56:37 INFO - Using env: {'DISPLAY': ':0', 10:56:37 INFO - 'HOME': '/home/cltbld', 10:56:37 INFO - 'LANG': 'en_US.UTF-8', 10:56:37 INFO - 'LANGUAGE': 'en_US:en', 10:56:37 INFO - 'LOGNAME': 'cltbld', 10:56:37 INFO - 'MAIL': '/var/mail/cltbld', 10:56:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:56:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:37 INFO - 'MOZ_NO_REMOTE': '1', 10:56:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:56:37 INFO - 'NO_EM_RESTART': '1', 10:56:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:37 INFO - 'PWD': '/builds/slave/test', 10:56:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:56:37 INFO - 'SHELL': '/bin/bash', 10:56:37 INFO - 'SHLVL': '1', 10:56:37 INFO - 'TERM': 'linux', 10:56:37 INFO - 'TMOUT': '86400', 10:56:37 INFO - 'USER': 'cltbld', 10:56:37 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080', 10:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:37 INFO - '_': '/tools/buildbot/bin/python'} 10:56:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:56:37 INFO - mozversion application_buildid: 20160831074921 10:56:37 INFO - mozversion application_changeset: fa475195cce705f74806ff304e31e12850715d4a 10:56:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:56:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:56:37 INFO - mozversion application_name: Firefox 10:56:37 INFO - mozversion application_remotingname: firefox-dev 10:56:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:56:37 INFO - mozversion application_vendor: Mozilla 10:56:37 INFO - mozversion application_version: 50.0a2 10:56:37 INFO - mozversion platform_buildid: 20160831074921 10:56:37 INFO - mozversion platform_changeset: fa475195cce705f74806ff304e31e12850715d4a 10:56:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:56:37 INFO - mozversion platform_version: 50.0a2 10:56:37 INFO - using testdate: 1472666197 10:56:37 INFO - actual date: 1472666197 10:56:37 INFO - starting webserver on 'localhost:50199' 10:56:37 INFO - SUITE-START | Running 2 tests 10:56:37 INFO - TEST-START | dromaeo_css 10:56:37 INFO - operating with platform_type : linux_ 10:56:37 INFO - Initialising browser for dromaeo_css test... 10:56:37 INFO - TEST-INFO | started process 12292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIzpOgM/profile http://localhost:50199/getInfo.html) 10:56:42 INFO - PROCESS | 12292 | __metrics Screen width/height:1600/1200 10:56:42 INFO - PROCESS | 12292 | colorDepth:24 10:56:42 INFO - PROCESS | 12292 | Browser inner width/height: 1024/697 10:56:42 INFO - PROCESS | 12292 | __metrics 10:56:42 ERROR - PROCESS | 12292 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 10:56:43 INFO - TEST-INFO | 12292: exit 0 10:56:43 INFO - Browser initialized. 10:56:43 INFO - Running cycle 1/1 for dromaeo_css test... 10:56:43 INFO - TEST-INFO | started process 12417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIzpOgM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:56:55 INFO - PROCESS | 12417 | RSS: Main: 152281088 10:56:55 INFO - PROCESS | 12417 | 10:57:44 INFO - PROCESS | 12417 | 1472666264924 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] 10:59:53 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50199/tests/dromaeo/cssquery-ext.html) 10:59:54 INFO - PROCESS | 12417 | RSS: Main: 158187520 10:59:54 INFO - PROCESS | 12417 | 11:02:56 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-ext.html (next: http://localhost:50199/tests/dromaeo/cssquery-jquery.html) 11:02:57 INFO - PROCESS | 12417 | RSS: Main: 153149440 11:02:57 INFO - PROCESS | 12417 | 11:05:59 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50199/tests/dromaeo/cssquery-mootools.html) 11:05:59 INFO - PROCESS | 12417 | RSS: Main: 154357760 11:05:59 INFO - PROCESS | 12417 | 11:09:01 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50199/tests/dromaeo/cssquery-prototype.html) 11:09:02 INFO - PROCESS | 12417 | RSS: Main: 152244224 11:09:02 INFO - PROCESS | 12417 | 11:12:04 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50199/tests/dromaeo/cssquery-yui.html) 11:12:05 INFO - PROCESS | 12417 | RSS: Main: 155234304 11:12:05 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | Cycle 1(1): loaded http://localhost:50199/tests/dromaeo/cssquery-yui.html (next: http://localhost:50199/tests/dromaeo/cssquery-dojo.html) 11:15:07 INFO - PROCESS | 12417 | RSS: Main: 156065792 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | __start_tp_report 11:15:07 INFO - PROCESS | 12417 | _x_x_mozilla_page_load 11:15:07 INFO - PROCESS | 12417 | _x_x_mozilla_page_load_details 11:15:07 INFO - PROCESS | 12417 | |i|pagename|runs| 11:15:07 INFO - PROCESS | 12417 | |0;dojo.html;3320.568326117605;3321.9003429897098;3346.4143774839404;3349.1627093226693;3374.3757404880116;5797.985352563301;5811.244538210027;5813.360530341659;5833.649981001143;5892.469677729004;5781.075027995522;5830.44610761978;5841.503472204857;5852.385499522553;5854.76580936762;5285.814996475124;5296.232046353276;5338.46615338465;5346.064438723227;5370.597205209625;5246.1606143017125;5256.553192978601;5265.815696450625;5290.841274761763;5320.042392369372;5368.865778355534;5379.220013098098;5380.300560927094;5441.347038355403;5441.755121019563;5386.2997810284805;5424.050791111579;5424.294841670578;5441.972790136044;5466.1527463243265;5158.3552055993005;5171.741412929338;5215.139501982168;5219.817306394258;5320.600954928396;5772.5670574707065;5798.884022319572;5812.709364531756;5849.18111464391;5879.52963762898;6224.813255602339;6248.78129265478;6259.467945224661;6283.8114856554375;6319.684015799237;6037.969810150921;6212.192414986023;6241.7191226394925;6296.748130074838;6347.238331400262;5929.199558059693;5929.881402371929;5932.881342373172;5936.762529498815;5950.9702451487465;6425.614463132227;6450.741970321228;6467;6477.514186436036;6479.643619600873;5960.1059841023325;6086.634801911811;6107.083937409424;6126.908096378558;6187.721552530147;6203.193584833943;6256.217972753406;6256.7810126645345;6262.812115636538;6315.936840631535;6162.938370616326;6209.472194863397;6220.844478887973;6243.125962365272;6260.24877014752;6095.2685677718955;6117.553376797925;6138.633639632459;6167.876642467126;6200.844978875564;4768.833090841804;4770.045990801854;4790.281457781361;4799;4800.543948324904;4220.388043733641;4220.514640816284;4248.532661407243;4251.192273468031;4271.466066741657;5852.94147058524;5902.380250073767;5905.350411454737;6006.279246490361;6062.151298818169;6434.613923164531;6461.353864613501;6471.611703297817;6479.643619600873;6483.902741458882;6065.6663883486835;6093.664848433379;6097.512199024068;6101.7559297627595;6101.816945491642;5230.581553475713;5379.515843574097;5389.487998640123;5433.212184233264;5477;6587.044878492592;6596.307387724316;6603.20761508622;6606.8348291293105;6606.86786264272;6313.747450101944;6352.745890164341;6404.519661025535;6406.519511036598;6420.101185833893;6227.283862355887;6416.422521973045;6481.546291759531;6485.254195767358;6560.737570497317;6274.686265686789;6275.215598050244;6293.150424692791;6308;6323.652199128908;6378.202724659418;6383.904241436475;6385.521085918482;6386.393292637192;6395.776147834804;5977.641341519523;6130.734471166674;6158;6174.969125154345;6250.531210159165;6125.356837531995;6228.532860035425;6273.435390814849;6275.96862015687;6294.087357333069;6124.234470691164;6126.938730612636;6150;6167.753289868355;6194.0708893666315;5927.703614819328;6084.969575152096;6089.69551522431;6130.172426722514;6241.843953901189;6016.849578760391;6017.548683848641;6019.608725432833;6039.78860739872;6048.455638992512 11:15:07 INFO - PROCESS | 12417 | |1;ext.html;586.7037146241148;623.5728525959718;651.7067319706131;662.2847324889119;693.712109474568;42365;47783;47797.52202477974;47857.76071119644;47895.80260493485;46804;47221.527784722144;47365.52634473654;47367.52632473674;47535.52464475354;9138.862917056249;9275.443473391619;9278.489683067428;9279.164875161234;9279.814403711922;9182.678606248783;9250.768730781718;9266.907330926706;9294.256459483258;9312.674056408026;9335.813283734322;9352;9391;9392.62429502819;9421.199198068172;9285.95357023214;9303.25573954082;9309.627614895397;9315.487648179347;9317.394369366004;9249.213816825579;9268.953655231748;9304.906950930472;9311.115444032874;9321.766955826091;45172.77413612911;45231;45472.090558188655;45696.543034569564;45804.54195458036;3890.0858298299877;4492.550744925481;4609.631229501631;4705.294205869112;4705.576498115186;35147.4727879082;35804.10489737751;36308.818455907814;36736;37027.62972370269;4794.4486384065585;4816.56650901417;4875.439324477695;4939.777710003059;4979.775910084055;398273;405861;407000;408666;409415;36804.07989800223;68181;68309.65845170841;68606;68694;38745.80627096847;40842.1831563373;40925.795371022956;41187;41514.584854151675;17267.568310792078;17426.651466970845;17503.03733294681;17542.91228543849;17544.473665790047;14621.634459138606;14677.55967320982;14769.33537990793;14839.851601483848;14849.554513364616;9052.592633331516;9200.07999200088;9205.263578913804;9252.7686807829;9257.166854983085;3490.2495963367996;3499.177693242086;3501.7548771585743;3563.5901871284614;3592.568891733009;1072.5816931396762;1123.9831402528969;1215.9452824622913;1240.6030070377392;1273.4778740716263;34805.303893922486;35200.82399588037;35870.28259434797;35919.4612080819;36103;1188.6018183908488;1200.273834330235;1214.8238505416841;1259.8992080633711;1309.2798960571647;18649.440516784245;18831.71752423715;18863.52841178954;19433.319833806032;20502.282420115225;610222;610777;611727;614257;614582;67609.3239067603;68187;68526.65736671285;68838.31161688319;68947.31052689509;1472.617119548925;1479.097750372272;1489.7318482673006;1514.7273490771547;1516.2418790604697;36178.296821413045;36559.45160822537;36641.81679091587;36921.44617830789;37107.257854843294;58968;59361.109583357145;59896.101558475784;59963.40036599578;59969.70015149896;558935;563697;563856;566036;566054;31093.53359699561;31730.841345793124;31806.84096579502;31880.202994925312;32041.519377208893;53548.732256338466;53809;54139.72930135324;54994;55013.449865500836;45503.31745023761;45725.31412028889;47018.76490617662;47232.52767472419;47439.762801185774;44921.55078449305;45737.31394029159;46139.30630375302;46346.768266158455;47223.52776472191;39073.413898790976;43183.13633727371;44108.779456102515;44207.77896110499;45928.31107533323;37675;38898.80550597229;40389.79805100956;41820.372694409;42282;31641;31743.206419839687;31904.84047579747;31934.201644959063;32263.838680806446 11:15:07 INFO - PROCESS | 12417 | |2;jquery.html;418.3473780901792;423.878826152271;429.22953298828605;437.86426207875553;444.0984800854267;19052.90473547632;20591.27930522432;21169.364919052434;21504.89247553764;21675.241366552174;20373.388798336073;20607.38177854663;21252.4686882828;21461.141554337846;21499.785002149973;5871.178035075093;6052.546059045567;6083.3612470690605;6093.573449858512;6115.510759139281;6038.124471951564;6038.184845045928;6074.331823499411;6089.330173680892;6111.877762444748;6030.306514750794;6033;6035.215421995156;6038.879222415571;6053.182820319266;5991.161237426763;6001.729922153513;6054.243219597551;6061.484773794233;6071.150038994567;5292.788288468457;5302.363716354023;5401.135818269058;5413.53984911283;5445.863853403657;24453.38866528333;25043.37391565207;25044.49911001788;25113.24660260177;25180.11869584574;2643.8545879976596;2693.6498255226693;2742.945141097187;2796.0772944928126;3121.469350210447;15495.457658981994;16125.758113628304;16146.75779863303;16158.919205404016;16741.07924064176;2014.8085931836451;2075.1595603780493;2088.7284652995168;2125.0118694806833;2180.7819218078225;62303.37696623021;76293;81540.59229703873;82847.58576207141;84242.57878710628;11119.388433636066;11403.543858245743;11448.713782155513;11874.821877671842;11908.285502869856;15555.688886222211;15573.143477108868;15578.688426231412;15584.688306233811;15588.92205538965;8265.710700125466;8711.956440217886;8819.647214111365;8919.777005574913;8940.597673104727;8308.252257296872;8319.667213311399;8342.833143337099;8504.65981360751;8513.787155321166;7278.563286202845;7301.123865136217;7340.5595664259245;7354.632268386559;7433.145188303414;2013.9597208055757;2129.7086370962525;2135.6796480528046;2152.386569827623;2252.9211477598205;975.1662328708993;1055.8521806947033;1069.7321678042445;1089.5267742225105;1143.4797167288864;21483.248086316904;21709.67435488469;21715.23996660141;21732.674009889863;21906.23328183538;1085.0939465546357;1090.9890901091046;1099.7140743406771;1105.535675016495;1115.8382034604938;12194.146409751233;12293.877061229274;12325.691857703629;12491.125621206429;12611.054170937216;540205;556467;588743;610481;613672;80539;82670.58664706638;83977.58011209905;84437;85357.14642853492;1052.4211683574003;1068.407034096084;1102.404701461202;1115.041064684371;1119.9776004479866;15371.615709606902;18607.906960465112;19499;19644.901775491602;19873.701894471305;37860;40426.9893252671;43246.78376608097;44255.323392013626;44404;60025;63327.0500942477;64225.357746423804;64893.67553162204;65052.34947650463;8727.170918762708;8793.692220772242;8892.15524525169;8900.020997690119;8996.96534898495;13377.866221337663;13485.662858428617;13664.24846633405;13989.23059231752;14325.426982920566;11096.5561377544;11252.099832013582;11508.482118304864;11618.767624647291;12163.148579599343;12344.074194435274;12414.379281036092;12471.563495277622;12505.624831255076;12882.806757898452;11420.82868756986;11821.40892955366;11883.108766842348;12499.437525311565;12505.874941250471;9084.77962292421;9245.02927192635;9252.907470925205;9263.629454821732;9315.39449935752;8854.778630534289;10659.4137322448;11130;11619.419029048682;12403.25580465175 11:15:07 INFO - PROCESS | 12417 | |3;mootools.html;1501.5945694662446;1561.5549568373012;1711.4951089428616;1861.4601765488;1862.9441116766502;8671.132886711326;9110.544472776368;9337.626494940203;10086.798264034724;10115.696529104129;9168.633254669821;9447.338686291961;9520.761980950463;9527.475988820612;9553.331266811327;2526.621006848969;2570.049081839722;2574.7425257474197;2577.922662320124;2580.948381032378;2464.963025554618;2550.9489810203786;2554.846709197445;2560.0271896679237;2566.0762125634756;2544.1222778141605;2557.3478762915524;2581.393372557457;2590.727973562777;2591.676040494938;2454.3005243505604;2464.162184857148;2482.6276058591175;2506.8997240110375;2508.3854455658297;2480.045182604692;2484.503099380131;2489.5394352044927;2490.1533478617357;2517.446161844391;9966.800663986753;10056.497175141214;10067.244956628208;10080.143187829044;10110.241731870066;5611.775528978836;5775.682337471438;5793.073108302693;5796.362400135981;5803.042497987827;3886.378179491296;3936.252112098692;4020.5175378954714;4021.336479480882;4077.7961101944784;1205.907457768425;1238.0652607281445;1242.2981015726177;1283.6469970758023;1289.6195622291352;13819.239941803196;13912.30438478072;13922.37349319273;13955.720885582232;13960.371783269777;5006.974965125151;5008.724520151354;5239.502247286502;5241.711705856214;5263.473652634782;11593.478293476814;11617.419129043683;11629.24409913353;11689.240199387013;11693.415329233505;5864.794732184353;5881.029630110984;5887.11693246008;5895.086261629454;5919.112133180058;4756.310335001405;5329.387120481117;5437.972810135924;5440.510354068153;5466.808661696901;4321.67587430944;4388.9122217556105;4409.581089796528;4430.689851710349;4457.375967364572;1392.352556061416;1425.4084554909596;1426.6148140002142;1437.475321507642;1438.6834896322791;1051.4795176387738;1068.823644098715;1073.4739977411011;1077.2997551591527;1123.9550417983353;6376.458001069961;6575.178102737158;6594.241662208811;6636.469082473391;6879.552829066095;460.5854730742292;466.2214102448851;468.3793972985807;480.6635355251338;481.3838286992655;4388.924713445226;4427.800748966304;4461.152381047656;4464.531224221475;4482.372467854503;11052.668419947415;11964.521419143312;12318.260904345589;12410.75178496443;12449.564265250672;11432.028277596497;11739.765204695857;11792.056635469313;11793.174477786644;11801.232919860182;9157.267418606496;9318.673846415479;9321.673741419017;9334.859977100348;9341.57962891658;6865.622390768555;6919.515633905578;6944.548604340702;7070;7125.465590080868;11174.441277935908;11357.886421135683;11457.197996140523;11487.8276825846;11550;11444.771104578029;11576.768464630492;11609.941950290195;11680.240784348991;11698;7922.920770792449;8422.5788710563;8500.14998500145;8699.391042627209;8699.652013919625;9616.663416780379;9645.27660425485;9689.27330450205;9724.124828765445;9729;8277.337812974827;8492.532910689972;8520.275776559061;8727.869081963903;8728.956355218183;8293.129221431664;8326.167383261625;8424.957875210584;8457.746267611981;8610.397272191138;7881.921180788118;7904.20957904205;7952.846837208456;7953.602319883867;7964.482308650151;5647.576431767552;5744.798932037525;5755.7409916553015;5840.123981402654;5906.970465147647;5770.711464426846;5808.36108028125;5831.766729330949;5917.260342457193;5973.372795856546 11:15:07 INFO - PROCESS | 12417 | |4;prototype.html;360.37474980908127;363.77082438064014;367.9668829805317;368.1550840820317;372.40787148434003;5825.300963884334;5912.349641539438;5919.200907877435;5925.644461332317;5929.525637948965;5517.200005999128;5804.390538993398;5825.67958762269;5832.221172542373;5869.8826023479505;4522.072975040117;4527.818887244506;4542.568455996675;4549.704269222507;4550.590446859782;4524.751138687371;4536.387587675671;4554.407926969489;4564.8174073037035;4593.19619066662;4351.8694439166875;4497.7526236057;4512.075024619945;4559.316102584606;4565.1098035883015;4390.209762242795;4450.399196108517;4450.732956022649;4456.710313829591;4481.5070342262325;3909.139989202371;3943.6253555912144;3953.9604603954167;3982.8406863725418;3992.6606238469767;5502.642328248651;5646.661200327993;5723.198752174699;5727.684977326246;5733.226014488033;5088.1350170471105;5110.6422550421485;5125.205593133071;5147.871303217412;5151.634816773558;6025.86081940909;6059.969700151515;6090.756369745205;6103.206583144163;6179.845503862394;4253.978730106361;4267.445232119836;4386.078923426085;4390.27560452525;4391;6109.175261339707;6304.905426418607;6327.715252813588;6339.96830015847;6368.745250189987;4430.867073987785;4439.533848945879;4448.688591798543;4450.221211288012;4455.287154055336;4513.88715282114;4543.954560454353;4589.472210695746;4593.909771054163;4612.95387046132;5103.642745007814;5138.067455243008;5157.329547158845;5216.747980484656;5232.895342093213;6095.573309868267;6103.328633850273;6103.816885493442;6123.755049798047;6126.08108783686;1536.531357935818;1556.1985577427633;1557.8052743407075;1561.2584022589406;1575;932.7714709895984;995.5619527407918;996.6511720897628;1002.9849552256723;1009.9646512372178;5196.570942990632;5233.555147812479;5249.212618107238;5301.257823904656;5312.442193569619;5523.309586301712;5530.253415788898;5533.557315414757;5627.606067575312;5648.830535083954;4962.454130045692;5109.20807274873;5200.895982080413;5381.520081977487;5395;2936.500794864878;2950.9409811803644;2962.8741078390503;2969.881204751829;2979.8808047678285;5500.862478438071;5691.630044047124;5705.857353566194;5715.485606295453;5726.226959360516;5546.91679624806;5560.555155587544;5587.860303492445;5614.943850561442;5669.461401166883;5669.829905102853;5731.742071606787;5747.224124743107;5770.596058275964;5778.711064446761;5319.4414586467865;5364.275822763877;5453.618246722692;5508.669479831063;5511.366192887709;5303.549198318032;5337.279467271868;5373.489518495737;5383.730813459233;5387;6109.480694141048;6268.247810262797;6499.967500162469;6507.251666058464;6558;4422.977885110683;4428.490723566702;4429.800658970418;4441.844535441244;4457.643388528845;6240.188775459162;6244.906326405198;6318.526110541819;6409.519286053472;6418.871622567429;6149.569530133027;6153.907691384543;6155.72299246526;6162.537809664203;6165.568410211242;5912.61567998096;6140.324564298013;6169.84575385619;6173.9008737027825;6220.595661282002;6039.788607398896;6061.454469097803;6123.530352715406;6145;6162.428580712069;6248.812535623939;6253.5622506424115;6274.968625156845;6281.560290779602;6313.936860631335;5762.962666719863;6005.939940600538;6049.516038716804;6052.546059045677;6124.479419249414 11:15:07 INFO - PROCESS | 12417 | |5;yui.html;743.9814004649879;793.8293266947605;818.5988865455928;834.7328854766474;845.9365547583928;16824.747628785564;18172.90913545432;18173.18220680069;18252;18275.08624568771;16150.515484535483;16697.332106715716;16698.58253543662;16709;16720.83279167208;731.5098883747875;870.4516154822453;887.835750386179;891.0510306523531;902.1159263921328;889.3285569395098;898.4833720610642;903.1284810158196;903.1781079217913;904.5432056811301;950.1543626172712;950.4819873169139;956.5217391304348;956.617353058775;956.9712908612753;944.9480278584675;949.0225068179786;953.2421724728822;954.6992697300328;956.0964888180637;698.7833771530791;705.1538154214911;705.1819888928818;705.5413980912448;706.2161001288565;16102.597435064217;16389.918050409673;16482;16646.16769161537;16707.081110538915;16195.43315983935;17152.399666011628;17175.828241717547;17388.391406300718;17555.473335799947;13149.539766108235;13290.800637990438;13304.73390532184;13385.19688818674;13437;4209.894752631209;4241.130568233505;4263.829446822124;4275.5724427557;4340.934885976713;64816;88976.55511722465;89153.10846891513;89381.10618893793;90667.54666226692;12833.935830320976;12855.871441285655;12915.547955821501;12971.156874803108;12990.805137922938;22951.885240574025;23114.1910033148;23163.189288375164;23172.420689482897;23338.299851004496;8017.639206235734;8169.632366543401;8366.288865446506;8462.407631465861;9823.312368134162;5002.249662550574;5026.346574945233;5032.119379108642;5142.61430392722;5189.636725429259;3899.1421887184774;4051.2707712611423;4052.209819085309;4053.8783836484954;4055.1889622075705;680.9863802723918;683.1938312790855;683.7094234950225;694.7533625562953;706.5442789400807;654.0516251435438;661.6030381770995;729.4456213277947;749.79380670315;877.2324216310728;10262;10653.520591573399;11028.779424411627;11448.828267575993;11739;820.8358328334357;824.7154731617583;831.2020460358003;832.1678321678322;838.5932822581043;5398.379186393615;5697.914531282034;5699.344575373885;5757.625754325955;5767.625104368202;73269;73705.6314718423;98360;124318;140652.2967385177;108002.45998769956;111050.44474777736;111070.44464777624;111779;116509;93899.53050234841;96361;96492.51753741327;97830;100021.49989250007;10031.147352475122;10131.544080516247;10236.744081398025;10237.48812559384;10352.792944141225;56478.152827708436;56593.71703141458;57750.7112464435;58902.116468253866;59064.11403829032;81379.59310203648;86676;87238.56380718309;87684.56157719171;91721;12403.131780775258;13081.934590326988;13279.800802988157;13353.399097040463;13583.93208033993;15016.249187540361;15188.088714677155;15204.543863684106;15273.236338182824;15318.387264509294;14060.929695351459;14426;14603.780943286072;14638.414463421344;14714.264286785405;14133;14221.64445888861;14226.928865355607;14272.643183920485;14289.571312860631;13912.304384780518;14068.296585170905;14501.492447764278;14502.419903203754;14628.414863405345;11517.136214783757;11809.940950295537;11842.881571184522;12110.333931633493;12453.128281020241;12243;12349.876501234872;12376.75246495083;12380.81428778551;12482.937585312015 11:15:07 INFO - PROCESS | 12417 | __end_tp_report 11:15:07 INFO - PROCESS | 12417 | __start_cc_report 11:15:07 INFO - PROCESS | 12417 | _x_x_mozilla_cycle_collect,320 11:15:07 INFO - PROCESS | 12417 | __end_cc_report 11:15:07 INFO - PROCESS | 12417 | __startTimestamp1472667307670__endTimestamp 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | ------- Summary: start ------- 11:15:07 INFO - PROCESS | 12417 | Number of tests: 6 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#0] dojo.html Cycles:165 Average:5840.83 Median:6089.70 stddev:684.50 (11.2%) stddev-sans-first:657.43 11:15:07 INFO - PROCESS | 12417 | Values: 3320.6 3321.9 3346.4 3349.2 3374.4 5798.0 5811.2 5813.4 5833.6 5892.5 5781.1 5830.4 5841.5 5852.4 5854.8 5285.8 5296.2 5338.5 5346.1 5370.6 5246.2 5256.6 5265.8 5290.8 5320.0 5368.9 5379.2 5380.3 5441.3 5441.8 5386.3 5424.1 5424.3 5442.0 5466.2 5158.4 5171.7 5215.1 5219.8 5320.6 5772.6 5798.9 5812.7 5849.2 5879.5 6224.8 6248.8 6259.5 6283.8 6319.7 6038.0 6212.2 6241.7 6296.7 6347.2 5929.2 5929.9 5932.9 5936.8 5951.0 6425.6 6450.7 6467.0 6477.5 6479.6 5960.1 6086.6 6107.1 6126.9 6187.7 6203.2 6256.2 6256.8 6262.8 6315.9 6162.9 6209.5 6220.8 6243.1 6260.2 6095.3 6117.6 6138.6 6167.9 6200.8 4768.8 4770.0 4790.3 4799.0 4800.5 4220.4 4220.5 4248.5 4251.2 4271.5 5852.9 5902.4 5905.4 6006.3 6062.2 6434.6 6461.4 6471.6 6479.6 6483.9 6065.7 6093.7 6097.5 6101.8 6101.8 5230.6 5379.5 5389.5 5433.2 5477.0 6587.0 6596.3 6603.2 6606.8 6606.9 6313.7 6352.7 6404.5 6406.5 6420.1 6227.3 6416.4 6481.5 6485.3 6560.7 6274.7 6275.2 6293.2 6308.0 6323.7 6378.2 6383.9 6385.5 6386.4 6395.8 5977.6 6130.7 6158.0 6175.0 6250.5 6125.4 6228.5 6273.4 6276.0 6294.1 6124.2 6126.9 6150.0 6167.8 6194.1 5927.7 6085.0 6089.7 6130.2 6241.8 6016.8 6017.5 6019.6 6039.8 6048.5 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#1] ext.html Cycles:180 Average:68869.52 Median:45770.93 stddev:142332.04 (311.0%) stddev-sans-first:142638.98 11:15:07 INFO - PROCESS | 12417 | Values: 586.7 623.6 651.7 662.3 693.7 42365.0 47783.0 47797.5 47857.8 47895.8 46804.0 47221.5 47365.5 47367.5 47535.5 9138.9 9275.4 9278.5 9279.2 9279.8 9182.7 9250.8 9266.9 9294.3 9312.7 9335.8 9352.0 9391.0 9392.6 9421.2 9286.0 9303.3 9309.6 9315.5 9317.4 9249.2 9269.0 9304.9 9311.1 9321.8 45172.8 45231.0 45472.1 45696.5 45804.5 3890.1 4492.6 4609.6 4705.3 4705.6 35147.5 35804.1 36308.8 36736.0 37027.6 4794.4 4816.6 4875.4 4939.8 4979.8 398273.0 405861.0 407000.0 408666.0 409415.0 36804.1 68181.0 68309.7 68606.0 68694.0 38745.8 40842.2 40925.8 41187.0 41514.6 17267.6 17426.7 17503.0 17542.9 17544.5 14621.6 14677.6 14769.3 14839.9 14849.6 9052.6 9200.1 9205.3 9252.8 9257.2 3490.2 3499.2 3501.8 3563.6 3592.6 1072.6 1124.0 1215.9 1240.6 1273.5 34805.3 35200.8 35870.3 35919.5 36103.0 1188.6 1200.3 1214.8 1259.9 1309.3 18649.4 18831.7 18863.5 19433.3 20502.3 610222.0 610777.0 611727.0 614257.0 614582.0 67609.3 68187.0 68526.7 68838.3 68947.3 1472.6 1479.1 1489.7 1514.7 1516.2 36178.3 36559.5 36641.8 36921.4 37107.3 58968.0 59361.1 59896.1 59963.4 59969.7 558935.0 563697.0 563856.0 566036.0 566054.0 31093.5 31730.8 31806.8 31880.2 32041.5 53548.7 53809.0 54139.7 54994.0 55013.4 45503.3 45725.3 47018.8 47232.5 47439.8 44921.6 45737.3 46139.3 46346.8 47223.5 39073.4 43183.1 44108.8 44207.8 45928.3 37675.0 38898.8 40389.8 41820.4 42282.0 31641.0 31743.2 31904.8 31934.2 32263.8 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#2] jquery.html Cycles:180 Average:32074.49 Median:25078.87 stddev:95379.95 (380.3%) stddev-sans-first:95617.90 11:15:07 INFO - PROCESS | 12417 | Values: 418.3 423.9 429.2 437.9 444.1 19052.9 20591.3 21169.4 21504.9 21675.2 20373.4 20607.4 21252.5 21461.1 21499.8 5871.2 6052.5 6083.4 6093.6 6115.5 6038.1 6038.2 6074.3 6089.3 6111.9 6030.3 6033.0 6035.2 6038.9 6053.2 5991.2 6001.7 6054.2 6061.5 6071.2 5292.8 5302.4 5401.1 5413.5 5445.9 24453.4 25043.4 25044.5 25113.2 25180.1 2643.9 2693.6 2742.9 2796.1 3121.5 15495.5 16125.8 16146.8 16158.9 16741.1 2014.8 2075.2 2088.7 2125.0 2180.8 62303.4 76293.0 81540.6 82847.6 84242.6 11119.4 11403.5 11448.7 11874.8 11908.3 15555.7 15573.1 15578.7 15584.7 15588.9 8265.7 8712.0 8819.6 8919.8 8940.6 8308.3 8319.7 8342.8 8504.7 8513.8 7278.6 7301.1 7340.6 7354.6 7433.1 2014.0 2129.7 2135.7 2152.4 2252.9 975.2 1055.9 1069.7 1089.5 1143.5 21483.2 21709.7 21715.2 21732.7 21906.2 1085.1 1091.0 1099.7 1105.5 1115.8 12194.1 12293.9 12325.7 12491.1 12611.1 540205.0 556467.0 588743.0 610481.0 613672.0 80539.0 82670.6 83977.6 84437.0 85357.1 1052.4 1068.4 1102.4 1115.0 1120.0 15371.6 18607.9 19499.0 19644.9 19873.7 37860.0 40427.0 43246.8 44255.3 44404.0 60025.0 63327.1 64225.4 64893.7 65052.3 8727.2 8793.7 8892.2 8900.0 8997.0 13377.9 13485.7 13664.2 13989.2 14325.4 11096.6 11252.1 11508.5 11618.8 12163.1 12344.1 12414.4 12471.6 12505.6 12882.8 11420.8 11821.4 11883.1 12499.4 12505.9 9084.8 9245.0 9252.9 9263.6 9315.4 8854.8 10659.4 11130.0 11619.4 12403.3 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#3] mootools.html Cycles:180 Average:6454.02 Median:4429.25 stddev:3711.74 (83.8%) stddev-sans-first:3703.49 11:15:07 INFO - PROCESS | 12417 | Values: 1501.6 1561.6 1711.5 1861.5 1862.9 8671.1 9110.5 9337.6 10086.8 10115.7 9168.6 9447.3 9520.8 9527.5 9553.3 2526.6 2570.0 2574.7 2577.9 2580.9 2465.0 2550.9 2554.8 2560.0 2566.1 2544.1 2557.3 2581.4 2590.7 2591.7 2454.3 2464.2 2482.6 2506.9 2508.4 2480.0 2484.5 2489.5 2490.2 2517.4 9966.8 10056.5 10067.2 10080.1 10110.2 5611.8 5775.7 5793.1 5796.4 5803.0 3886.4 3936.3 4020.5 4021.3 4077.8 1205.9 1238.1 1242.3 1283.6 1289.6 13819.2 13912.3 13922.4 13955.7 13960.4 5007.0 5008.7 5239.5 5241.7 5263.5 11593.5 11617.4 11629.2 11689.2 11693.4 5864.8 5881.0 5887.1 5895.1 5919.1 4756.3 5329.4 5438.0 5440.5 5466.8 4321.7 4388.9 4409.6 4430.7 4457.4 1392.4 1425.4 1426.6 1437.5 1438.7 1051.5 1068.8 1073.5 1077.3 1124.0 6376.5 6575.2 6594.2 6636.5 6879.6 460.6 466.2 468.4 480.7 481.4 4388.9 4427.8 4461.2 4464.5 4482.4 11052.7 11964.5 12318.3 12410.8 12449.6 11432.0 11739.8 11792.1 11793.2 11801.2 9157.3 9318.7 9321.7 9334.9 9341.6 6865.6 6919.5 6944.5 7070.0 7125.5 11174.4 11357.9 11457.2 11487.8 11550.0 11444.8 11576.8 11609.9 11680.2 11698.0 7922.9 8422.6 8500.1 8699.4 8699.7 9616.7 9645.3 9689.3 9724.1 9729.0 8277.3 8492.5 8520.3 8727.9 8729.0 8293.1 8326.2 8425.0 8457.7 8610.4 7881.9 7904.2 7952.8 7953.6 7964.5 5647.6 5744.8 5755.7 5840.1 5907.0 5770.7 5808.4 5831.8 5917.3 5973.4 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#4] prototype.html Cycles:180 Average:4971.92 Median:5501.75 stddev:1449.86 (26.4%) stddev-sans-first:1412.01 11:15:07 INFO - PROCESS | 12417 | Values: 360.4 363.8 368.0 368.2 372.4 5825.3 5912.3 5919.2 5925.6 5929.5 5517.2 5804.4 5825.7 5832.2 5869.9 4522.1 4527.8 4542.6 4549.7 4550.6 4524.8 4536.4 4554.4 4564.8 4593.2 4351.9 4497.8 4512.1 4559.3 4565.1 4390.2 4450.4 4450.7 4456.7 4481.5 3909.1 3943.6 3954.0 3982.8 3992.7 5502.6 5646.7 5723.2 5727.7 5733.2 5088.1 5110.6 5125.2 5147.9 5151.6 6025.9 6060.0 6090.8 6103.2 6179.8 4254.0 4267.4 4386.1 4390.3 4391.0 6109.2 6304.9 6327.7 6340.0 6368.7 4430.9 4439.5 4448.7 4450.2 4455.3 4513.9 4544.0 4589.5 4593.9 4613.0 5103.6 5138.1 5157.3 5216.7 5232.9 6095.6 6103.3 6103.8 6123.8 6126.1 1536.5 1556.2 1557.8 1561.3 1575.0 932.8 995.6 996.7 1003.0 1010.0 5196.6 5233.6 5249.2 5301.3 5312.4 5523.3 5530.3 5533.6 5627.6 5648.8 4962.5 5109.2 5200.9 5381.5 5395.0 2936.5 2950.9 2962.9 2969.9 2979.9 5500.9 5691.6 5705.9 5715.5 5726.2 5546.9 5560.6 5587.9 5614.9 5669.5 5669.8 5731.7 5747.2 5770.6 5778.7 5319.4 5364.3 5453.6 5508.7 5511.4 5303.5 5337.3 5373.5 5383.7 5387.0 6109.5 6268.2 6500.0 6507.3 6558.0 4423.0 4428.5 4429.8 4441.8 4457.6 6240.2 6244.9 6318.5 6409.5 6418.9 6149.6 6153.9 6155.7 6162.5 6165.6 5912.6 6140.3 6169.8 6173.9 6220.6 6039.8 6061.5 6123.5 6145.0 6162.4 6248.8 6253.6 6275.0 6281.6 6313.9 5763.0 6005.9 6049.5 6052.5 6124.5 11:15:07 INFO - PROCESS | 12417 | 11:15:07 INFO - PROCESS | 12417 | [#5] yui.html Cycles:180 Average:22482.63 Median:4053.04 stddev:32036.25 (790.4%) stddev-sans-first:32084.53 11:15:07 INFO - PROCESS | 12417 | Values: 744.0 793.8 818.6 834.7 845.9 16824.7 18172.9 18173.2 18252.0 18275.1 16150.5 16697.3 16698.6 16709.0 16720.8 731.5 870.5 887.8 891.1 902.1 889.3 898.5 903.1 903.2 904.5 950.2 950.5 956.5 956.6 957.0 944.9 949.0 953.2 954.7 956.1 698.8 705.2 705.2 705.5 706.2 16102.6 16389.9 16482.0 16646.2 16707.1 16195.4 17152.4 17175.8 17388.4 17555.5 13149.5 13290.8 13304.7 13385.2 13437.0 4209.9 4241.1 4263.8 4275.6 4340.9 64816.0 88976.6 89153.1 89381.1 90667.5 12833.9 12855.9 12915.5 12971.2 12990.8 22951.9 23114.2 23163.2 23172.4 23338.3 8017.6 8169.6 8366.3 8462.4 9823.3 5002.2 5026.3 5032.1 5142.6 5189.6 3899.1 4051.3 4052.2 4053.9 4055.2 681.0 683.2 683.7 694.8 706.5 654.1 661.6 729.4 749.8 877.2 10262.0 10653.5 11028.8 11448.8 11739.0 820.8 824.7 831.2 832.2 838.6 5398.4 5697.9 5699.3 5757.6 5767.6 73269.0 73705.6 98360.0 124318.0 140652.3 108002.5 111050.4 111070.4 111779.0 116509.0 93899.5 96361.0 96492.5 97830.0 100021.5 10031.1 10131.5 10236.7 10237.5 10352.8 56478.2 56593.7 57750.7 58902.1 59064.1 81379.6 86676.0 87238.6 87684.6 91721.0 12403.1 13081.9 13279.8 13353.4 13583.9 15016.2 15188.1 15204.5 15273.2 15318.4 14060.9 14426.0 14603.8 14638.4 14714.3 14133.0 14221.6 14226.9 14272.6 14289.6 13912.3 14068.3 14501.5 14502.4 14628.4 11517.1 11809.9 11842.9 12110.3 12453.1 12243.0 12349.9 12376.8 12380.8 12482.9 11:15:07 INFO - PROCESS | 12417 | -------- Summary: end -------- 11:15:07 INFO - PROCESS | 12417 | 11:15:08 INFO - TEST-INFO | 12417: exit 0 11:15:08 INFO - TEST-OK | dromaeo_css | took 1110790ms 11:15:08 INFO - TEST-START | kraken 11:15:08 INFO - operating with platform_type : linux_ 11:15:08 INFO - Initialising browser for kraken test... 11:15:08 INFO - TEST-INFO | started process 12680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcEISKE/profile http://localhost:50199/getInfo.html) 11:15:12 INFO - PROCESS | 12680 | __metrics Screen width/height:1600/1200 11:15:12 INFO - PROCESS | 12680 | colorDepth:24 11:15:12 INFO - PROCESS | 12680 | Browser inner width/height: 1024/697 11:15:12 INFO - PROCESS | 12680 | __metrics 11:15:13 ERROR - PROCESS | 12680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 11:15:14 INFO - TEST-INFO | 12680: exit 0 11:15:14 INFO - Browser initialized. 11:15:14 INFO - Running cycle 1/1 for kraken test... 11:15:14 INFO - TEST-INFO | started process 12812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcEISKE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:15:25 INFO - PROCESS | 12812 | RSS: Main: 152240128 11:15:25 INFO - PROCESS | 12812 | 11:15:28 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50199/tests/kraken/driver.html?testName=audio-beat-detection) 11:15:29 INFO - PROCESS | 12812 | RSS: Main: 157892608 11:15:29 INFO - PROCESS | 12812 | 11:15:31 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50199/tests/kraken/driver.html?testName=audio-dft) 11:15:32 INFO - PROCESS | 12812 | RSS: Main: 156925952 11:15:32 INFO - PROCESS | 12812 | 11:15:35 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50199/tests/kraken/driver.html?testName=audio-fft) 11:15:36 INFO - PROCESS | 12812 | RSS: Main: 156930048 11:15:36 INFO - PROCESS | 12812 | 11:15:38 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50199/tests/kraken/driver.html?testName=audio-oscillator) 11:15:38 INFO - PROCESS | 12812 | RSS: Main: 156442624 11:15:38 INFO - PROCESS | 12812 | 11:15:40 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50199/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:15:41 INFO - PROCESS | 12812 | RSS: Main: 156823552 11:15:41 INFO - PROCESS | 12812 | 11:15:44 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50199/tests/kraken/driver.html?testName=imaging-darkroom) 11:15:45 INFO - PROCESS | 12812 | RSS: Main: 156237824 11:15:45 INFO - PROCESS | 12812 | 11:15:48 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50199/tests/kraken/driver.html?testName=imaging-desaturate) 11:15:49 INFO - PROCESS | 12812 | RSS: Main: 157011968 11:15:49 INFO - PROCESS | 12812 | 11:15:52 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50199/tests/kraken/driver.html?testName=json-parse-financial) 11:15:52 INFO - PROCESS | 12812 | RSS: Main: 157224960 11:15:52 INFO - PROCESS | 12812 | 11:15:54 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50199/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:15:54 INFO - PROCESS | 12812 | RSS: Main: 156598272 11:15:54 INFO - PROCESS | 12812 | 11:15:56 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:15:57 INFO - PROCESS | 12812 | RSS: Main: 157212672 11:15:57 INFO - PROCESS | 12812 | 11:15:59 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:16:00 INFO - PROCESS | 12812 | RSS: Main: 157274112 11:16:00 INFO - PROCESS | 12812 | 11:16:02 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:16:03 INFO - PROCESS | 12812 | RSS: Main: 157544448 11:16:03 INFO - PROCESS | 12812 | 11:16:05 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:16:06 INFO - PROCESS | 12812 | RSS: Main: 158195712 11:16:06 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | Cycle 1(1): loaded http://localhost:50199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50199/tests/kraken/driver.html?testName=ai-astar) 11:16:08 INFO - PROCESS | 12812 | RSS: Main: 158552064 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | __start_tp_report 11:16:08 INFO - PROCESS | 12812 | _x_x_mozilla_page_load 11:16:08 INFO - PROCESS | 12812 | _x_x_mozilla_page_load_details 11:16:08 INFO - PROCESS | 12812 | |i|pagename|runs| 11:16:08 INFO - PROCESS | 12812 | |0;ai-astar;102;113;105;110;110;115;112;113;114;111 11:16:08 INFO - PROCESS | 12812 | |1;audio-beat-detection;152;117;152;169;116;150;143;120;179;115 11:16:08 INFO - PROCESS | 12812 | |2;audio-dft;170;163;163;162;164;161;173;164;161;161 11:16:08 INFO - PROCESS | 12812 | |3;audio-fft;74;75;76;172;77;76;89;75;163;76 11:16:08 INFO - PROCESS | 12812 | |4;audio-oscillator;85;87;157;87;83;83;135;83;84;140 11:16:08 INFO - PROCESS | 12812 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 11:16:08 INFO - PROCESS | 12812 | |6;imaging-darkroom;147;147;146;147;147;147;147;148;147;146 11:16:08 INFO - PROCESS | 12812 | |7;imaging-desaturate;95;95;95;96;96;95;96;96;95;95 11:16:08 INFO - PROCESS | 12812 | |8;json-parse-financial;51;55;56;51;51;51;51;52;55;50 11:16:08 INFO - PROCESS | 12812 | |9;json-stringify-tinderbox;60;60;59;60;60;60;60;60;60;62 11:16:08 INFO - PROCESS | 12812 | |10;stanford-crypto-aes;75;73;73;72;73;72;73;74;72;81 11:16:08 INFO - PROCESS | 12812 | |11;stanford-crypto-ccm;128;120;121;120;117;122;126;125;160;124 11:16:08 INFO - PROCESS | 12812 | |12;stanford-crypto-pbkdf2;160;152;152;153;157;153;162;156;154;151 11:16:08 INFO - PROCESS | 12812 | |13;stanford-crypto-sha256-iterative;63;62;64;61;62;67;61;61;65;65 11:16:08 INFO - PROCESS | 12812 | __end_tp_report 11:16:08 INFO - PROCESS | 12812 | __start_cc_report 11:16:08 INFO - PROCESS | 12812 | _x_x_mozilla_cycle_collect,864 11:16:08 INFO - PROCESS | 12812 | __end_cc_report 11:16:08 INFO - PROCESS | 12812 | __startTimestamp1472667368389__endTimestamp 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | ------- Summary: start ------- 11:16:08 INFO - PROCESS | 12812 | Number of tests: 14 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.50 stddev:4.09 (3.6%) stddev-sans-first:2.96 11:16:08 INFO - PROCESS | 12812 | Values: 102.0 113.0 105.0 110.0 110.0 115.0 112.0 113.0 114.0 111.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:151.00 stddev:23.27 (15.4%) stddev-sans-first:24.35 11:16:08 INFO - PROCESS | 12812 | Values: 152.0 117.0 152.0 169.0 116.0 150.0 143.0 120.0 179.0 115.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#2] audio-dft Cycles:10 Average:164.20 Median:163.50 stddev:4.08 (2.5%) stddev-sans-first:3.75 11:16:08 INFO - PROCESS | 12812 | Values: 170.0 163.0 163.0 162.0 164.0 161.0 173.0 164.0 161.0 161.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:38.35 (50.5%) stddev-sans-first:39.89 11:16:08 INFO - PROCESS | 12812 | Values: 74.0 75.0 76.0 172.0 77.0 76.0 89.0 75.0 163.0 76.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:83.50 stddev:29.25 (35.0%) stddev-sans-first:30.34 11:16:08 INFO - PROCESS | 12812 | Values: 85.0 87.0 157.0 87.0 83.0 83.0 135.0 83.0 84.0 140.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 11:16:08 INFO - PROCESS | 12812 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 11:16:08 INFO - PROCESS | 12812 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 147.0 148.0 147.0 146.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 11:16:08 INFO - PROCESS | 12812 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:51.50 stddev:2.16 (4.2%) stddev-sans-first:2.24 11:16:08 INFO - PROCESS | 12812 | Values: 51.0 55.0 56.0 51.0 51.0 51.0 51.0 52.0 55.0 50.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 11:16:08 INFO - PROCESS | 12812 | Values: 60.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 62.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:2.70 (3.7%) stddev-sans-first:2.83 11:16:08 INFO - PROCESS | 12812 | Values: 75.0 73.0 73.0 72.0 73.0 72.0 73.0 74.0 72.0 81.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:124.50 stddev:12.28 (9.9%) stddev-sans-first:13.01 11:16:08 INFO - PROCESS | 12812 | Values: 128.0 120.0 121.0 120.0 117.0 122.0 126.0 125.0 160.0 124.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:155.00 stddev:3.68 (2.4%) stddev-sans-first:3.43 11:16:08 INFO - PROCESS | 12812 | Values: 160.0 152.0 152.0 153.0 157.0 153.0 162.0 156.0 154.0 151.0 11:16:08 INFO - PROCESS | 12812 | 11:16:08 INFO - PROCESS | 12812 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.50 stddev:2.08 (3.3%) stddev-sans-first:2.20 11:16:08 INFO - PROCESS | 12812 | Values: 63.0 62.0 64.0 61.0 62.0 67.0 61.0 61.0 65.0 65.0 11:16:08 INFO - PROCESS | 12812 | -------- Summary: end -------- 11:16:08 INFO - PROCESS | 12812 | 11:16:09 INFO - TEST-INFO | 12812: exit 0 11:16:09 INFO - TEST-OK | kraken | took 60946ms 11:16:09 INFO - SUITE-END | took 1171s 11:16:09 INFO - Completed test suite (00:19:31) 11:16:09 INFO - javascript benchmark 11:16:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.055884180091, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.568326117605, 3321.9003429897098, 3346.4143774839404, 3349.1627093226693, 3374.3757404880116, 5797.985352563301, 5811.244538210027, 5813.360530341659, 5833.649981001143, 5892.469677729004, 5781.075027995522, 5830.44610761978, 5841.503472204857, 5852.385499522553, 5854.76580936762, 5285.814996475124, 5296.232046353276, 5338.46615338465, 5346.064438723227, 5370.597205209625, 5246.1606143017125, 5256.553192978601, 5265.815696450625, 5290.841274761763, 5320.042392369372, 5368.865778355534, 5379.220013098098, 5380.300560927094, 5441.347038355403, 5441.755121019563, 5386.2997810284805, 5424.050791111579, 5424.294841670578, 5441.972790136044, 5466.1527463243265, 5158.3552055993005, 5171.741412929338, 5215.139501982168, 5219.817306394258, 5320.600954928396, 5772.5670574707065, 5798.884022319572, 5812.709364531756, 5849.18111464391, 5879.52963762898, 6224.813255602339, 6248.78129265478, 6259.467945224661, 6283.8114856554375, 6319.684015799237, 6037.969810150921, 6212.192414986023, 6241.7191226394925, 6296.748130074838, 6347.238331400262, 5929.199558059693, 5929.881402371929, 5932.881342373172, 5936.762529498815, 5950.9702451487465, 6425.614463132227, 6450.741970321228, 6467.0, 6477.514186436036, 6479.643619600873, 5960.1059841023325, 6086.634801911811, 6107.083937409424, 6126.908096378558, 6187.721552530147, 6203.193584833943, 6256.217972753406, 6256.7810126645345, 6262.812115636538, 6315.936840631535, 6162.938370616326, 6209.472194863397, 6220.844478887973, 6243.125962365272, 6260.24877014752, 6095.2685677718955, 6117.553376797925, 6138.633639632459, 6167.876642467126, 6200.844978875564, 4768.833090841804, 4770.045990801854, 4790.281457781361, 4799.0, 4800.543948324904, 4220.388043733641, 4220.514640816284, 4248.532661407243, 4251.192273468031, 4271.466066741657, 5852.94147058524, 5902.380250073767, 5905.350411454737, 6006.279246490361, 6062.151298818169, 6434.613923164531, 6461.353864613501, 6471.611703297817, 6479.643619600873, 6483.902741458882, 6065.6663883486835, 6093.664848433379, 6097.512199024068, 6101.7559297627595, 6101.816945491642, 5230.581553475713, 5379.515843574097, 5389.487998640123, 5433.212184233264, 5477.0, 6587.044878492592, 6596.307387724316, 6603.20761508622, 6606.8348291293105, 6606.86786264272, 6313.747450101944, 6352.745890164341, 6404.519661025535, 6406.519511036598, 6420.101185833893, 6227.283862355887, 6416.422521973045, 6481.546291759531, 6485.254195767358, 6560.737570497317, 6274.686265686789, 6275.215598050244, 6293.150424692791, 6308.0, 6323.652199128908, 6378.202724659418, 6383.904241436475, 6385.521085918482, 6386.393292637192, 6395.776147834804, 5977.641341519523, 6130.734471166674, 6158.0, 6174.969125154345, 6250.531210159165, 6125.356837531995, 6228.532860035425, 6273.435390814849, 6275.96862015687, 6294.087357333069, 6124.234470691164, 6126.938730612636, 6150.0, 6167.753289868355, 6194.0708893666315, 5927.703614819328, 6084.969575152096, 6089.69551522431, 6130.172426722514, 6241.843953901189, 6016.849578760391, 6017.548683848641, 6019.608725432833, 6039.78860739872, 6048.455638992512], "value": 5792.865500853628, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.7037146241148, 623.5728525959718, 651.7067319706131, 662.2847324889119, 693.712109474568, 42365.0, 47783.0, 47797.52202477974, 47857.76071119644, 47895.80260493485, 46804.0, 47221.527784722144, 47365.52634473654, 47367.52632473674, 47535.52464475354, 9138.862917056249, 9275.443473391619, 9278.489683067428, 9279.164875161234, 9279.814403711922, 9182.678606248783, 9250.768730781718, 9266.907330926706, 9294.256459483258, 9312.674056408026, 9335.813283734322, 9352.0, 9391.0, 9392.62429502819, 9421.199198068172, 9285.95357023214, 9303.25573954082, 9309.627614895397, 9315.487648179347, 9317.394369366004, 9249.213816825579, 9268.953655231748, 9304.906950930472, 9311.115444032874, 9321.766955826091, 45172.77413612911, 45231.0, 45472.090558188655, 45696.543034569564, 45804.54195458036, 3890.0858298299877, 4492.550744925481, 4609.631229501631, 4705.294205869112, 4705.576498115186, 35147.4727879082, 35804.10489737751, 36308.818455907814, 36736.0, 37027.62972370269, 4794.4486384065585, 4816.56650901417, 4875.439324477695, 4939.777710003059, 4979.775910084055, 398273.0, 405861.0, 407000.0, 408666.0, 409415.0, 36804.07989800223, 68181.0, 68309.65845170841, 68606.0, 68694.0, 38745.80627096847, 40842.1831563373, 40925.795371022956, 41187.0, 41514.584854151675, 17267.568310792078, 17426.651466970845, 17503.03733294681, 17542.91228543849, 17544.473665790047, 14621.634459138606, 14677.55967320982, 14769.33537990793, 14839.851601483848, 14849.554513364616, 9052.592633331516, 9200.07999200088, 9205.263578913804, 9252.7686807829, 9257.166854983085, 3490.2495963367996, 3499.177693242086, 3501.7548771585743, 3563.5901871284614, 3592.568891733009, 1072.5816931396762, 1123.9831402528969, 1215.9452824622913, 1240.6030070377392, 1273.4778740716263, 34805.303893922486, 35200.82399588037, 35870.28259434797, 35919.4612080819, 36103.0, 1188.6018183908488, 1200.273834330235, 1214.8238505416841, 1259.8992080633711, 1309.2798960571647, 18649.440516784245, 18831.71752423715, 18863.52841178954, 19433.319833806032, 20502.282420115225, 610222.0, 610777.0, 611727.0, 614257.0, 614582.0, 67609.3239067603, 68187.0, 68526.65736671285, 68838.31161688319, 68947.31052689509, 1472.617119548925, 1479.097750372272, 1489.7318482673006, 1514.7273490771547, 1516.2418790604697, 36178.296821413045, 36559.45160822537, 36641.81679091587, 36921.44617830789, 37107.257854843294, 58968.0, 59361.109583357145, 59896.101558475784, 59963.40036599578, 59969.70015149896, 558935.0, 563697.0, 563856.0, 566036.0, 566054.0, 31093.53359699561, 31730.841345793124, 31806.84096579502, 31880.202994925312, 32041.519377208893, 53548.732256338466, 53809.0, 54139.72930135324, 54994.0, 55013.449865500836, 45503.31745023761, 45725.31412028889, 47018.76490617662, 47232.52767472419, 47439.762801185774, 44921.55078449305, 45737.31394029159, 46139.30630375302, 46346.768266158455, 47223.52776472191, 39073.413898790976, 43183.13633727371, 44108.779456102515, 44207.77896110499, 45928.31107533323, 37675.0, 38898.80550597229, 40389.79805100956, 41820.372694409, 42282.0, 31641.0, 31743.206419839687, 31904.84047579747, 31934.201644959063, 32263.838680806446], "value": 21116.57958691642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.3473780901792, 423.878826152271, 429.22953298828605, 437.86426207875553, 444.0984800854267, 19052.90473547632, 20591.27930522432, 21169.364919052434, 21504.89247553764, 21675.241366552174, 20373.388798336073, 20607.38177854663, 21252.4686882828, 21461.141554337846, 21499.785002149973, 5871.178035075093, 6052.546059045567, 6083.3612470690605, 6093.573449858512, 6115.510759139281, 6038.124471951564, 6038.184845045928, 6074.331823499411, 6089.330173680892, 6111.877762444748, 6030.306514750794, 6033.0, 6035.215421995156, 6038.879222415571, 6053.182820319266, 5991.161237426763, 6001.729922153513, 6054.243219597551, 6061.484773794233, 6071.150038994567, 5292.788288468457, 5302.363716354023, 5401.135818269058, 5413.53984911283, 5445.863853403657, 24453.38866528333, 25043.37391565207, 25044.49911001788, 25113.24660260177, 25180.11869584574, 2643.8545879976596, 2693.6498255226693, 2742.945141097187, 2796.0772944928126, 3121.469350210447, 15495.457658981994, 16125.758113628304, 16146.75779863303, 16158.919205404016, 16741.07924064176, 2014.8085931836451, 2075.1595603780493, 2088.7284652995168, 2125.0118694806833, 2180.7819218078225, 62303.37696623021, 76293.0, 81540.59229703873, 82847.58576207141, 84242.57878710628, 11119.388433636066, 11403.543858245743, 11448.713782155513, 11874.821877671842, 11908.285502869856, 15555.688886222211, 15573.143477108868, 15578.688426231412, 15584.688306233811, 15588.92205538965, 8265.710700125466, 8711.956440217886, 8819.647214111365, 8919.777005574913, 8940.597673104727, 8308.252257296872, 8319.667213311399, 8342.833143337099, 8504.65981360751, 8513.787155321166, 7278.563286202845, 7301.123865136217, 7340.5595664259245, 7354.632268386559, 7433.145188303414, 2013.9597208055757, 2129.7086370962525, 2135.6796480528046, 2152.386569827623, 2252.9211477598205, 975.1662328708993, 1055.8521806947033, 1069.7321678042445, 1089.5267742225105, 1143.4797167288864, 21483.248086316904, 21709.67435488469, 21715.23996660141, 21732.674009889863, 21906.23328183538, 1085.0939465546357, 1090.9890901091046, 1099.7140743406771, 1105.535675016495, 1115.8382034604938, 12194.146409751233, 12293.877061229274, 12325.691857703629, 12491.125621206429, 12611.054170937216, 540205.0, 556467.0, 588743.0, 610481.0, 613672.0, 80539.0, 82670.58664706638, 83977.58011209905, 84437.0, 85357.14642853492, 1052.4211683574003, 1068.407034096084, 1102.404701461202, 1115.041064684371, 1119.9776004479866, 15371.615709606902, 18607.906960465112, 19499.0, 19644.901775491602, 19873.701894471305, 37860.0, 40426.9893252671, 43246.78376608097, 44255.323392013626, 44404.0, 60025.0, 63327.0500942477, 64225.357746423804, 64893.67553162204, 65052.34947650463, 8727.170918762708, 8793.692220772242, 8892.15524525169, 8900.020997690119, 8996.96534898495, 13377.866221337663, 13485.662858428617, 13664.24846633405, 13989.23059231752, 14325.426982920566, 11096.5561377544, 11252.099832013582, 11508.482118304864, 11618.767624647291, 12163.148579599343, 12344.074194435274, 12414.379281036092, 12471.563495277622, 12505.624831255076, 12882.806757898452, 11420.82868756986, 11821.40892955366, 11883.108766842348, 12499.437525311565, 12505.874941250471, 9084.77962292421, 9245.02927192635, 9252.907470925205, 9263.629454821732, 9315.39449935752, 8854.778630534289, 10659.4137322448, 11130.0, 11619.419029048682, 12403.25580465175], "value": 9975.22203671497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.5945694662446, 1561.5549568373012, 1711.4951089428616, 1861.4601765488, 1862.9441116766502, 8671.132886711326, 9110.544472776368, 9337.626494940203, 10086.798264034724, 10115.696529104129, 9168.633254669821, 9447.338686291961, 9520.761980950463, 9527.475988820612, 9553.331266811327, 2526.621006848969, 2570.049081839722, 2574.7425257474197, 2577.922662320124, 2580.948381032378, 2464.963025554618, 2550.9489810203786, 2554.846709197445, 2560.0271896679237, 2566.0762125634756, 2544.1222778141605, 2557.3478762915524, 2581.393372557457, 2590.727973562777, 2591.676040494938, 2454.3005243505604, 2464.162184857148, 2482.6276058591175, 2506.8997240110375, 2508.3854455658297, 2480.045182604692, 2484.503099380131, 2489.5394352044927, 2490.1533478617357, 2517.446161844391, 9966.800663986753, 10056.497175141214, 10067.244956628208, 10080.143187829044, 10110.241731870066, 5611.775528978836, 5775.682337471438, 5793.073108302693, 5796.362400135981, 5803.042497987827, 3886.378179491296, 3936.252112098692, 4020.5175378954714, 4021.336479480882, 4077.7961101944784, 1205.907457768425, 1238.0652607281445, 1242.2981015726177, 1283.6469970758023, 1289.6195622291352, 13819.239941803196, 13912.30438478072, 13922.37349319273, 13955.720885582232, 13960.371783269777, 5006.974965125151, 5008.724520151354, 5239.502247286502, 5241.711705856214, 5263.473652634782, 11593.478293476814, 11617.419129043683, 11629.24409913353, 11689.240199387013, 11693.415329233505, 5864.794732184353, 5881.029630110984, 5887.11693246008, 5895.086261629454, 5919.112133180058, 4756.310335001405, 5329.387120481117, 5437.972810135924, 5440.510354068153, 5466.808661696901, 4321.67587430944, 4388.9122217556105, 4409.581089796528, 4430.689851710349, 4457.375967364572, 1392.352556061416, 1425.4084554909596, 1426.6148140002142, 1437.475321507642, 1438.6834896322791, 1051.4795176387738, 1068.823644098715, 1073.4739977411011, 1077.2997551591527, 1123.9550417983353, 6376.458001069961, 6575.178102737158, 6594.241662208811, 6636.469082473391, 6879.552829066095, 460.5854730742292, 466.2214102448851, 468.3793972985807, 480.6635355251338, 481.3838286992655, 4388.924713445226, 4427.800748966304, 4461.152381047656, 4464.531224221475, 4482.372467854503, 11052.668419947415, 11964.521419143312, 12318.260904345589, 12410.75178496443, 12449.564265250672, 11432.028277596497, 11739.765204695857, 11792.056635469313, 11793.174477786644, 11801.232919860182, 9157.267418606496, 9318.673846415479, 9321.673741419017, 9334.859977100348, 9341.57962891658, 6865.622390768555, 6919.515633905578, 6944.548604340702, 7070.0, 7125.465590080868, 11174.441277935908, 11357.886421135683, 11457.197996140523, 11487.8276825846, 11550.0, 11444.771104578029, 11576.768464630492, 11609.941950290195, 11680.240784348991, 11698.0, 7922.920770792449, 8422.5788710563, 8500.14998500145, 8699.391042627209, 8699.652013919625, 9616.663416780379, 9645.27660425485, 9689.27330450205, 9724.124828765445, 9729.0, 8277.337812974827, 8492.532910689972, 8520.275776559061, 8727.869081963903, 8728.956355218183, 8293.129221431664, 8326.167383261625, 8424.957875210584, 8457.746267611981, 8610.397272191138, 7881.921180788118, 7904.20957904205, 7952.846837208456, 7953.602319883867, 7964.482308650151, 5647.576431767552, 5744.798932037525, 5755.7409916553015, 5840.123981402654, 5906.970465147647, 5770.711464426846, 5808.36108028125, 5831.766729330949, 5917.260342457193, 5973.372795856546], "value": 5053.4835554617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.37474980908127, 363.77082438064014, 367.9668829805317, 368.1550840820317, 372.40787148434003, 5825.300963884334, 5912.349641539438, 5919.200907877435, 5925.644461332317, 5929.525637948965, 5517.200005999128, 5804.390538993398, 5825.67958762269, 5832.221172542373, 5869.8826023479505, 4522.072975040117, 4527.818887244506, 4542.568455996675, 4549.704269222507, 4550.590446859782, 4524.751138687371, 4536.387587675671, 4554.407926969489, 4564.8174073037035, 4593.19619066662, 4351.8694439166875, 4497.7526236057, 4512.075024619945, 4559.316102584606, 4565.1098035883015, 4390.209762242795, 4450.399196108517, 4450.732956022649, 4456.710313829591, 4481.5070342262325, 3909.139989202371, 3943.6253555912144, 3953.9604603954167, 3982.8406863725418, 3992.6606238469767, 5502.642328248651, 5646.661200327993, 5723.198752174699, 5727.684977326246, 5733.226014488033, 5088.1350170471105, 5110.6422550421485, 5125.205593133071, 5147.871303217412, 5151.634816773558, 6025.86081940909, 6059.969700151515, 6090.756369745205, 6103.206583144163, 6179.845503862394, 4253.978730106361, 4267.445232119836, 4386.078923426085, 4390.27560452525, 4391.0, 6109.175261339707, 6304.905426418607, 6327.715252813588, 6339.96830015847, 6368.745250189987, 4430.867073987785, 4439.533848945879, 4448.688591798543, 4450.221211288012, 4455.287154055336, 4513.88715282114, 4543.954560454353, 4589.472210695746, 4593.909771054163, 4612.95387046132, 5103.642745007814, 5138.067455243008, 5157.329547158845, 5216.747980484656, 5232.895342093213, 6095.573309868267, 6103.328633850273, 6103.816885493442, 6123.755049798047, 6126.08108783686, 1536.531357935818, 1556.1985577427633, 1557.8052743407075, 1561.2584022589406, 1575.0, 932.7714709895984, 995.5619527407918, 996.6511720897628, 1002.9849552256723, 1009.9646512372178, 5196.570942990632, 5233.555147812479, 5249.212618107238, 5301.257823904656, 5312.442193569619, 5523.309586301712, 5530.253415788898, 5533.557315414757, 5627.606067575312, 5648.830535083954, 4962.454130045692, 5109.20807274873, 5200.895982080413, 5381.520081977487, 5395.0, 2936.500794864878, 2950.9409811803644, 2962.8741078390503, 2969.881204751829, 2979.8808047678285, 5500.862478438071, 5691.630044047124, 5705.857353566194, 5715.485606295453, 5726.226959360516, 5546.91679624806, 5560.555155587544, 5587.860303492445, 5614.943850561442, 5669.461401166883, 5669.829905102853, 5731.742071606787, 5747.224124743107, 5770.596058275964, 5778.711064446761, 5319.4414586467865, 5364.275822763877, 5453.618246722692, 5508.669479831063, 5511.366192887709, 5303.549198318032, 5337.279467271868, 5373.489518495737, 5383.730813459233, 5387.0, 6109.480694141048, 6268.247810262797, 6499.967500162469, 6507.251666058464, 6558.0, 4422.977885110683, 4428.490723566702, 4429.800658970418, 4441.844535441244, 4457.643388528845, 6240.188775459162, 6244.906326405198, 6318.526110541819, 6409.519286053472, 6418.871622567429, 6149.569530133027, 6153.907691384543, 6155.72299246526, 6162.537809664203, 6165.568410211242, 5912.61567998096, 6140.324564298013, 6169.84575385619, 6173.9008737027825, 6220.595661282002, 6039.788607398896, 6061.454469097803, 6123.530352715406, 6145.0, 6162.428580712069, 6248.812535623939, 6253.5622506424115, 6274.968625156845, 6281.560290779602, 6313.936860631335, 5762.962666719863, 6005.939940600538, 6049.516038716804, 6052.546059045677, 6124.479419249414], "value": 4512.681850012569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9814004649879, 793.8293266947605, 818.5988865455928, 834.7328854766474, 845.9365547583928, 16824.747628785564, 18172.90913545432, 18173.18220680069, 18252.0, 18275.08624568771, 16150.515484535483, 16697.332106715716, 16698.58253543662, 16709.0, 16720.83279167208, 731.5098883747875, 870.4516154822453, 887.835750386179, 891.0510306523531, 902.1159263921328, 889.3285569395098, 898.4833720610642, 903.1284810158196, 903.1781079217913, 904.5432056811301, 950.1543626172712, 950.4819873169139, 956.5217391304348, 956.617353058775, 956.9712908612753, 944.9480278584675, 949.0225068179786, 953.2421724728822, 954.6992697300328, 956.0964888180637, 698.7833771530791, 705.1538154214911, 705.1819888928818, 705.5413980912448, 706.2161001288565, 16102.597435064217, 16389.918050409673, 16482.0, 16646.16769161537, 16707.081110538915, 16195.43315983935, 17152.399666011628, 17175.828241717547, 17388.391406300718, 17555.473335799947, 13149.539766108235, 13290.800637990438, 13304.73390532184, 13385.19688818674, 13437.0, 4209.894752631209, 4241.130568233505, 4263.829446822124, 4275.5724427557, 4340.934885976713, 64816.0, 88976.55511722465, 89153.10846891513, 89381.10618893793, 90667.54666226692, 12833.935830320976, 12855.871441285655, 12915.547955821501, 12971.156874803108, 12990.805137922938, 22951.885240574025, 23114.1910033148, 23163.189288375164, 23172.420689482897, 23338.299851004496, 8017.639206235734, 8169.632366543401, 8366.288865446506, 8462.407631465861, 9823.312368134162, 5002.249662550574, 5026.346574945233, 5032.119379108642, 5142.61430392722, 5189.636725429259, 3899.1421887184774, 4051.2707712611423, 4052.209819085309, 4053.8783836484954, 4055.1889622075705, 680.9863802723918, 683.1938312790855, 683.7094234950225, 694.7533625562953, 706.5442789400807, 654.0516251435438, 661.6030381770995, 729.4456213277947, 749.79380670315, 877.2324216310728, 10262.0, 10653.520591573399, 11028.779424411627, 11448.828267575993, 11739.0, 820.8358328334357, 824.7154731617583, 831.2020460358003, 832.1678321678322, 838.5932822581043, 5398.379186393615, 5697.914531282034, 5699.344575373885, 5757.625754325955, 5767.625104368202, 73269.0, 73705.6314718423, 98360.0, 124318.0, 140652.2967385177, 108002.45998769956, 111050.44474777736, 111070.44464777624, 111779.0, 116509.0, 93899.53050234841, 96361.0, 96492.51753741327, 97830.0, 100021.49989250007, 10031.147352475122, 10131.544080516247, 10236.744081398025, 10237.48812559384, 10352.792944141225, 56478.152827708436, 56593.71703141458, 57750.7112464435, 58902.116468253866, 59064.11403829032, 81379.59310203648, 86676.0, 87238.56380718309, 87684.56157719171, 91721.0, 12403.131780775258, 13081.934590326988, 13279.800802988157, 13353.399097040463, 13583.93208033993, 15016.249187540361, 15188.088714677155, 15204.543863684106, 15273.236338182824, 15318.387264509294, 14060.929695351459, 14426.0, 14603.780943286072, 14638.414463421344, 14714.264286785405, 14133.0, 14221.64445888861, 14226.928865355607, 14272.643183920485, 14289.571312860631, 13912.304384780518, 14068.296585170905, 14501.492447764278, 14502.419903203754, 14628.414863405345, 11517.136214783757, 11809.940950295537, 11842.881571184522, 12110.333931633493, 12453.128281020241, 12243.0, 12349.876501234872, 12376.75246495083, 12380.81428778551, 12482.937585312015], "value": 8274.662723497702, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 105.0, 110.0, 110.0, 115.0, 112.0, 113.0, 114.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 152.0, 169.0, 116.0, 150.0, 143.0, 120.0, 179.0, 115.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 163.0, 162.0, 164.0, 161.0, 173.0, 164.0, 161.0, 161.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 172.0, 77.0, 76.0, 89.0, 75.0, 163.0, 76.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 157.0, 87.0, 83.0, 83.0, 135.0, 83.0, 84.0, 140.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 55.0, 56.0, 51.0, 51.0, 51.0, 51.0, 52.0, 55.0, 50.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 62.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 73.0, 72.0, 73.0, 72.0, 73.0, 74.0, 72.0, 81.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 121.0, 120.0, 117.0, 122.0, 126.0, 125.0, 160.0, 124.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 152.0, 153.0, 157.0, 153.0, 162.0, 156.0, 154.0, 151.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 61.0, 62.0, 67.0, 61.0, 61.0, 65.0, 65.0], "value": 63.1, "unit": "score"}]}]} 11:16:09 INFO - Return code: 0 11:16:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:16:09 ERROR - # TBPL SUCCESS # 11:16:09 INFO - Running post-action listener: _resource_record_post_action 11:16:09 INFO - [mozharness: 2016-08-31 18:16:09.668598Z] Finished run-tests step (success) 11:16:09 INFO - Running post-run listener: _resource_record_post_run 11:16:09 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 329023488; Read time: 0; Write time: 204104 11:16:09 INFO - TinderboxPrint: CPU usage
13.3% 11:16:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:16:09 INFO - TinderboxPrint: I/O write bytes / time
329,023,488 / 204,104 11:16:09 INFO - TinderboxPrint: CPU idle
8,216.6 (86.7%) 11:16:09 INFO - TinderboxPrint: CPU user
1,205.6 (12.7%) 11:16:09 INFO - TinderboxPrint: Swap in / out
0 / 0 11:16:09 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77160448; Read time: 0; Write time: 36912 11:16:09 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 251338752; Read time: 0; Write time: 167132 11:16:10 INFO - Running post-run listener: _upload_blobber_files 11:16:10 INFO - Blob upload gear active. 11:16:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:16:10 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 11:16:10 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'] 11:16:10 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 11:16:10 INFO - (blobuploader) - INFO - Open directory for files ... 11:16:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:16:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:11 INFO - (blobuploader) - INFO - Done attempting. 11:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:16:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:12 INFO - (blobuploader) - INFO - Done attempting. 11:16:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:16:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:16:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:16:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:16:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:16:16 INFO - (blobuploader) - INFO - Done attempting. 11:16:16 INFO - (blobuploader) - INFO - Iteration through files over. 11:16:16 INFO - Return code: 0 11:16:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:16:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:16:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fcea371ef100eb13caf05e162e537519487cf1a8fdc790bf877dccb8050586690ec7bc8c2b8fed6302ee56901b53d0248ccc4ed3980b05092f519f2e67cbb9fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d02efd0cae735d5d78fe278e18400cad51cb06caafcbc23767bcfa8d906fecefabe4a197404cfcc4ce8a609ff87fa7d913f1f5533a0c4525c03a20f49b32788d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc90948182784a45963fdf8ea172a70ce0c15c2ffa1d588bd0504e927531fc895965d972595763514fe826cbc6df440fb5a74e157e9c7571fa84fb24b6aa7082"} 11:16:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:16:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:16:16 INFO - Contents: 11:16:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fcea371ef100eb13caf05e162e537519487cf1a8fdc790bf877dccb8050586690ec7bc8c2b8fed6302ee56901b53d0248ccc4ed3980b05092f519f2e67cbb9fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d02efd0cae735d5d78fe278e18400cad51cb06caafcbc23767bcfa8d906fecefabe4a197404cfcc4ce8a609ff87fa7d913f1f5533a0c4525c03a20f49b32788d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc90948182784a45963fdf8ea172a70ce0c15c2ffa1d588bd0504e927531fc895965d972595763514fe826cbc6df440fb5a74e157e9c7571fa84fb24b6aa7082"} 11:16:16 INFO - Running post-run listener: copy_logs_to_upload_dir 11:16:16 INFO - Copying logs to upload dir... 11:16:16 INFO - mkdir: /builds/slave/test/build/upload/logs 11:16:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.333176 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-31 11:16:16.150265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 11:16:16.154103) ========= 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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fcea371ef100eb13caf05e162e537519487cf1a8fdc790bf877dccb8050586690ec7bc8c2b8fed6302ee56901b53d0248ccc4ed3980b05092f519f2e67cbb9fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d02efd0cae735d5d78fe278e18400cad51cb06caafcbc23767bcfa8d906fecefabe4a197404cfcc4ce8a609ff87fa7d913f1f5533a0c4525c03a20f49b32788d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc90948182784a45963fdf8ea172a70ce0c15c2ffa1d588bd0504e927531fc895965d972595763514fe826cbc6df440fb5a74e157e9c7571fa84fb24b6aa7082"} build_url:https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/VryS3wMBRt6APZ3rjmRWHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fcea371ef100eb13caf05e162e537519487cf1a8fdc790bf877dccb8050586690ec7bc8c2b8fed6302ee56901b53d0248ccc4ed3980b05092f519f2e67cbb9fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d02efd0cae735d5d78fe278e18400cad51cb06caafcbc23767bcfa8d906fecefabe4a197404cfcc4ce8a609ff87fa7d913f1f5533a0c4525c03a20f49b32788d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc90948182784a45963fdf8ea172a70ce0c15c2ffa1d588bd0504e927531fc895965d972595763514fe826cbc6df440fb5a74e157e9c7571fa84fb24b6aa7082"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 11:16:16.179816) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 11:16:16.180169) ========= 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=62c568580acbfdaac3eec7be000001dc-1472665698.874427-1998013080 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 11:16:16.264813) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 11:16:16.265461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 11:16:16.265871) ========= ========= Total master_lag: 0.17 =========