builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1477918003.55 results: success (0) buildid: 20161031030621 builduid: c4bdedaff90841f5a3fe2f0cff4bee5d revision: 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.546002) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.546501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.547128) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.586364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.586693) ========= 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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-31 05:46:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.3M=0s 2016-10-31 05:46:43 (49.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118482 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.719174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.719472) ========= 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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021944 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.770228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:43.770585) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa --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-beta --rev 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa --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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-31 05:46:44,107 truncating revision to first 12 chars 2016-10-31 05:46:44,108 Setting DEBUG logging. 2016-10-31 05:46:44,108 attempt 1/10 2016-10-31 05:46:44,108 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1be8e06fcf3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 05:46:44,619 unpacking tar archive at: mozilla-beta-1be8e06fcf3c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.894634 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:44.678720) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:44.679019) ========= script_repo_revision: 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:44.679812) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:44.680082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 05:46:44.692752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-31 05:46:44.693084) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:45 INFO - MultiFileLogger online at 20161031 05:46:45 in /builds/slave/test 05:46:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:46:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:45 INFO - {'append_to_log': False, 05:46:45 INFO - 'base_work_dir': '/builds/slave/test', 05:46:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:46:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:45 INFO - 'branch': 'Mozilla-Beta', 05:46:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:45 INFO - 'config_files': ('talos/linux_config.py',), 05:46:45 INFO - 'default_actions': ('clobber', 05:46:45 INFO - 'read-buildbot-config', 05:46:45 INFO - 'download-and-extract', 05:46:45 INFO - 'populate-webroot', 05:46:45 INFO - 'create-virtualenv', 05:46:45 INFO - 'install', 05:46:45 INFO - 'run-tests'), 05:46:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:45 INFO - 'download_minidump_stackwalk': True, 05:46:45 INFO - 'download_symbols': 'ondemand', 05:46:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:45 INFO - 'installer_path': 'installer.exe', 05:46:45 INFO - 'log_level': 'info', 05:46:45 INFO - 'log_name': 'talos', 05:46:45 INFO - 'log_to_console': True, 05:46:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:45 INFO - 'opt_config_files': (), 05:46:45 INFO - 'pip_index': False, 05:46:45 INFO - 'sps_profile': False, 05:46:45 INFO - 'sps_profile_interval': 0, 05:46:45 INFO - 'suite': 'dromaeojs', 05:46:45 INFO - 'system_bits': '32', 05:46:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:45 INFO - 'title': 'talos-linux64-ix-024', 05:46:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:45 INFO - 'use_talos_json': True, 05:46:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:45 INFO - 'work_dir': 'build'} 05:46:45 INFO - [mozharness: 2016-10-31 12:46:45.114566Z] Running clobber step. 05:46:45 INFO - Running pre-action listener: _resource_record_pre_action 05:46:45 INFO - Running main action method: clobber 05:46:45 INFO - rmtree: /builds/slave/test/build 05:46:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:45 INFO - Running post-action listener: _resource_record_post_action 05:46:45 INFO - [mozharness: 2016-10-31 12:46:45.573767Z] Finished clobber step (success) 05:46:45 INFO - [mozharness: 2016-10-31 12:46:45.573868Z] Running read-buildbot-config step. 05:46:45 INFO - Running pre-action listener: _resource_record_pre_action 05:46:45 INFO - Running main action method: read_buildbot_config 05:46:45 INFO - Using buildbot properties: 05:46:45 INFO - { 05:46:45 INFO - "project": "", 05:46:45 INFO - "product": "firefox", 05:46:45 INFO - "script_repo_revision": "production", 05:46:45 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:46:45 INFO - "repository": "", 05:46:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:46:45 INFO - "buildid": "20161031030621", 05:46:45 INFO - "pgo_build": "True", 05:46:45 INFO - "basedir": "/builds/slave/test", 05:46:45 INFO - "buildnumber": 458, 05:46:45 INFO - "slavename": "talos-linux64-ix-024", 05:46:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:46:45 INFO - "platform": "ubuntu64_hw", 05:46:45 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:46:45 INFO - "branch": "mozilla-beta", 05:46:45 INFO - "repo_path": "releases/mozilla-beta", 05:46:45 INFO - "revision": "1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa", 05:46:45 INFO - "stage_platform": "linux64-pgo", 05:46:45 INFO - "builduid": "c4bdedaff90841f5a3fe2f0cff4bee5d", 05:46:45 INFO - "slavebuilddir": "test" 05:46:45 INFO - } 05:46:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 05:46:45 INFO - Running post-action listener: _resource_record_post_action 05:46:45 INFO - [mozharness: 2016-10-31 12:46:45.576310Z] Finished read-buildbot-config step (success) 05:46:45 INFO - [mozharness: 2016-10-31 12:46:45.576400Z] Running download-and-extract step. 05:46:45 INFO - Running pre-action listener: _resource_record_pre_action 05:46:45 INFO - Running main action method: download_and_extract 05:46:45 INFO - mkdir: /builds/slave/test/build/tests 05:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:45 INFO - https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:46:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:46:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:46:45 INFO - retry: Failed, sleeping 30 seconds before retrying 05:47:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 05:47:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:47:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:47:15 INFO - retry: Failed, sleeping 60 seconds before retrying 05:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 05:48:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:48:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 05:48:15 INFO - Caught exception: HTTP Error 404: Not Found 05:48:15 INFO - Caught exception: HTTP Error 404: Not Found 05:48:15 INFO - Caught exception: HTTP Error 404: Not Found 05:48:15 INFO - trying https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:15 INFO - Downloading https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:48:16 INFO - Downloaded 1327 bytes. 05:48:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:48:16 INFO - Using the following test package requirements: 05:48:16 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:48:16 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:48:16 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 05:48:16 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'jsshell-linux-x86_64.zip'], 05:48:16 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:48:16 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:48:16 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 05:48:16 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 05:48:16 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:48:16 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:48:16 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:48:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:48: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')]} 05:48:16 INFO - https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:48:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:48:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:48:16 INFO - retry: Failed, sleeping 30 seconds before retrying 05:48:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 05:48:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:48:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:48:46 INFO - retry: Failed, sleeping 60 seconds before retrying 05:49:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 05:49:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:49:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 05:49:46 INFO - Caught exception: HTTP Error 404: Not Found 05:49:46 INFO - Caught exception: HTTP Error 404: Not Found 05:49:46 INFO - Caught exception: HTTP Error 404: Not Found 05:49:46 INFO - trying https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:49:48 INFO - Downloaded 21787681 bytes. 05:49:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:49:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:49:49 INFO - Return code: 0 05:49:49 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:49 INFO - https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:49:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:49:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:49:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:49:49 INFO - retry: Failed, sleeping 30 seconds before retrying 05:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 05:50:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:50:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:50:19 INFO - retry: Failed, sleeping 60 seconds before retrying 05:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 05:51:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:51:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:51:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 05:51:19 INFO - Caught exception: HTTP Error 404: Not Found 05:51:19 INFO - Caught exception: HTTP Error 404: Not Found 05:51:19 INFO - Caught exception: HTTP Error 404: Not Found 05:51:19 INFO - trying https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:51:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:51:20 INFO - Downloaded 21787681 bytes. 05:51:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:51:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:51:21 INFO - Return code: 0 05:51: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')]} 05:51:21 INFO - https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:51:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:51:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:51:21 INFO - retry: Failed, sleeping 30 seconds before retrying 05:51:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 05:51:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:51:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:51:51 INFO - retry: Failed, sleeping 60 seconds before retrying 05:52:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 05:52:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:52:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:52:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 05:52:51 INFO - Caught exception: HTTP Error 404: Not Found 05:52:51 INFO - Caught exception: HTTP Error 404: Not Found 05:52:51 INFO - Caught exception: HTTP Error 404: Not Found 05:52:51 INFO - trying https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:52:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:52:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:52:53 INFO - Downloaded 14413770 bytes. 05:52:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:52:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:52:53 INFO - Return code: 0 05:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:52:53 INFO - https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:52:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:52:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:52:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:52:53 INFO - retry: Failed, sleeping 30 seconds before retrying 05:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 05:53:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:53:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:53:23 INFO - retry: Failed, sleeping 60 seconds before retrying 05:54:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 05:54:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:54:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 05:54:23 INFO - Caught exception: HTTP Error 404: Not Found 05:54:23 INFO - Caught exception: HTTP Error 404: Not Found 05:54:23 INFO - Caught exception: HTTP Error 404: Not Found 05:54:23 INFO - trying https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:54:25 INFO - Downloaded 56784782 bytes. 05:54:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:25 INFO - mkdir: /builds/slave/test/properties 05:54:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:25 INFO - Contents: 05:54:25 INFO - build_url:https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:54:25 INFO - Running post-action listener: _resource_record_post_action 05:54:25 INFO - Running post-action listener: set_extra_try_arguments 05:54:25 INFO - [mozharness: 2016-10-31 12:54:25.970495Z] Finished download-and-extract step (success) 05:54:25 INFO - [mozharness: 2016-10-31 12:54:25.970726Z] Running populate-webroot step. 05:54:25 INFO - Running pre-action listener: _resource_record_pre_action 05:54:25 INFO - Running main action method: populate_webroot 05:54:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:25 INFO - '--tppagecycles', 05:54:25 INFO - '7'], 05:54:25 INFO - 'tests': ['tsvgm']}}, 05:54:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['damp', 'tps']}, 05:54:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'tests': ['damp', 'tps']}, 05:54:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['dromaeo_dom']}, 05:54:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['basic_compositor_video']}, 05:54:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:54:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['a11yr', 05:54:25 INFO - 'ts_paint', 05:54:25 INFO - 'tpaint', 05:54:25 INFO - 'sessionrestore', 05:54:25 INFO - 'sessionrestore_no_auto_restore', 05:54:25 INFO - 'tabpaint']}, 05:54:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['tsvgx', 05:54:25 INFO - 'tsvgr_opacity', 05:54:25 INFO - 'tart', 05:54:25 INFO - 'tscrollx', 05:54:25 INFO - 'cart']}, 05:54:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:25 INFO - 'tsvgr_opacity', 05:54:25 INFO - 'tart', 05:54:25 INFO - 'tscrollx', 05:54:25 INFO - 'cart']}, 05:54:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'talos_options': ['--disable-e10s'], 05:54:25 INFO - 'tests': ['tp5o']}, 05:54:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'tests': ['tp5o']}, 05:54:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'talos_options': ['--disable-e10s', 05:54:25 INFO - '--xperf_path', 05:54:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:25 INFO - 'tests': ['tp5n']}, 05:54:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:25 INFO - 'talos_options': ['--xperf_path', 05:54:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:25 INFO - 'tests': ['tp5n']}}, 05:54:25 INFO - 'talos.zip': {'path': '', 05:54:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:25 INFO - Running post-action listener: _resource_record_post_action 05:54:25 INFO - [mozharness: 2016-10-31 12:54:25.993192Z] Finished populate-webroot step (success) 05:54:25 INFO - [mozharness: 2016-10-31 12:54:25.993284Z] Running create-virtualenv step. 05:54:25 INFO - Running pre-action listener: _resource_record_pre_action 05:54:25 INFO - Running main action method: create_virtualenv 05:54:25 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:25 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 05:54:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:26 INFO - Using real prefix '/usr' 05:54:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:26 INFO - Installing distribute.............................................................................................................................................................................................done. 05:54:28 INFO - Installing pip.................done. 05:54:28 INFO - Return code: 0 05:54:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:28 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:28 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 05:54:28 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 05:54:28 INFO - Using env: {'DISPLAY': ':0', 05:54:28 INFO - 'HOME': '/home/cltbld', 05:54:28 INFO - 'LANG': 'en_US.UTF-8', 05:54:28 INFO - 'LANGUAGE': 'en_US:en', 05:54:28 INFO - 'LOGNAME': 'cltbld', 05:54:28 INFO - 'MAIL': '/var/mail/cltbld', 05:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:28 INFO - 'MOZ_NO_REMOTE': '1', 05:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:28 INFO - 'NO_EM_RESTART': '1', 05:54:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:28 INFO - 'PWD': '/builds/slave/test', 05:54:28 INFO - 'SHELL': '/bin/bash', 05:54:28 INFO - 'SHLVL': '1', 05:54:28 INFO - 'TERM': 'linux', 05:54:28 INFO - 'TMOUT': '86400', 05:54:28 INFO - 'USER': 'cltbld', 05:54:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:28 INFO - '_': '/tools/buildbot/bin/python'} 05:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:28 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 05:54:28 INFO - Cleaning up... 05:54:28 INFO - Return code: 0 05:54:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:28 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:28 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 05:54:28 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 05:54:28 INFO - Using env: {'DISPLAY': ':0', 05:54:28 INFO - 'HOME': '/home/cltbld', 05:54:28 INFO - 'LANG': 'en_US.UTF-8', 05:54:28 INFO - 'LANGUAGE': 'en_US:en', 05:54:28 INFO - 'LOGNAME': 'cltbld', 05:54:28 INFO - 'MAIL': '/var/mail/cltbld', 05:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:28 INFO - 'MOZ_NO_REMOTE': '1', 05:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:28 INFO - 'NO_EM_RESTART': '1', 05:54:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:28 INFO - 'PWD': '/builds/slave/test', 05:54:28 INFO - 'SHELL': '/bin/bash', 05:54:28 INFO - 'SHLVL': '1', 05:54:28 INFO - 'TERM': 'linux', 05:54:28 INFO - 'TMOUT': '86400', 05:54:28 INFO - 'USER': 'cltbld', 05:54:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:28 INFO - '_': '/tools/buildbot/bin/python'} 05:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:28 INFO - Downloading/unpacking psutil>=3.1.1 05:54:28 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 05:54:28 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 05:54:28 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 05:54:28 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 05:54:28 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 05:54:28 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 05:54:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:54:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:31 INFO - Installing collected packages: psutil 05:54:31 INFO - Running setup.py install for psutil 05:54:31 INFO - building 'psutil._psutil_linux' extension 05:54:31 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 05:54:31 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 05:54:31 INFO - building 'psutil._psutil_posix' extension 05:54:31 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 05:54:31 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 05:54:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:31 INFO - Successfully installed psutil 05:54:31 INFO - Cleaning up... 05:54:31 INFO - Return code: 0 05:54:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:54:31 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')]} 05:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 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')]} 05:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:31 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:31 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 05:54:31 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 05:54:31 INFO - Using env: {'DISPLAY': ':0', 05:54:31 INFO - 'HOME': '/home/cltbld', 05:54:31 INFO - 'LANG': 'en_US.UTF-8', 05:54:31 INFO - 'LANGUAGE': 'en_US:en', 05:54:31 INFO - 'LOGNAME': 'cltbld', 05:54:31 INFO - 'MAIL': '/var/mail/cltbld', 05:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:31 INFO - 'MOZ_NO_REMOTE': '1', 05:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:31 INFO - 'NO_EM_RESTART': '1', 05:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:31 INFO - 'PWD': '/builds/slave/test', 05:54:31 INFO - 'SHELL': '/bin/bash', 05:54:31 INFO - 'SHLVL': '1', 05:54:31 INFO - 'TERM': 'linux', 05:54:31 INFO - 'TMOUT': '86400', 05:54:31 INFO - 'USER': 'cltbld', 05:54:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:31 INFO - '_': '/tools/buildbot/bin/python'} 05:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:31 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 05:54:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:54:33 INFO - Installing collected packages: mozsystemmonitor 05:54:33 INFO - Running setup.py install for mozsystemmonitor 05:54:33 INFO - Successfully installed mozsystemmonitor 05:54:33 INFO - Cleaning up... 05:54:33 INFO - Return code: 0 05:54:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:54:33 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')]} 05:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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')]} 05:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:33 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:33 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 05:54:33 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 05:54:33 INFO - Using env: {'DISPLAY': ':0', 05:54:33 INFO - 'HOME': '/home/cltbld', 05:54:33 INFO - 'LANG': 'en_US.UTF-8', 05:54:33 INFO - 'LANGUAGE': 'en_US:en', 05:54:33 INFO - 'LOGNAME': 'cltbld', 05:54:33 INFO - 'MAIL': '/var/mail/cltbld', 05:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:33 INFO - 'MOZ_NO_REMOTE': '1', 05:54:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:33 INFO - 'NO_EM_RESTART': '1', 05:54:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:33 INFO - 'PWD': '/builds/slave/test', 05:54:33 INFO - 'SHELL': '/bin/bash', 05:54:33 INFO - 'SHLVL': '1', 05:54:33 INFO - 'TERM': 'linux', 05:54:33 INFO - 'TMOUT': '86400', 05:54:33 INFO - 'USER': 'cltbld', 05:54:33 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:33 INFO - '_': '/tools/buildbot/bin/python'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:54:34 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 05:54:34 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 05:54:34 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 05:54:34 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 05:54:34 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 05:54:34 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 05:54:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:36 INFO - warning: no files found matching '*.txt' 05:54:36 INFO - no previously-included directories found matching 'build' 05:54:36 INFO - no previously-included directories found matching 'dist' 05:54:36 INFO - no previously-included directories found matching '.git*' 05:54:36 INFO - Installing collected packages: jsonschema, functools32 05:54:36 INFO - Running setup.py install for functools32 05:54:36 INFO - warning: no files found matching '*.txt' 05:54:36 INFO - no previously-included directories found matching 'build' 05:54:36 INFO - no previously-included directories found matching 'dist' 05:54:36 INFO - no previously-included directories found matching '.git*' 05:54:36 INFO - Successfully installed jsonschema functools32 05:54:36 INFO - Cleaning up... 05:54:36 INFO - Return code: 0 05:54:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:54:36 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')]} 05:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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')]} 05:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:36 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 05:54:36 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 05:54:36 INFO - Using env: {'DISPLAY': ':0', 05:54:36 INFO - 'HOME': '/home/cltbld', 05:54:36 INFO - 'LANG': 'en_US.UTF-8', 05:54:36 INFO - 'LANGUAGE': 'en_US:en', 05:54:36 INFO - 'LOGNAME': 'cltbld', 05:54:36 INFO - 'MAIL': '/var/mail/cltbld', 05:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:36 INFO - 'MOZ_NO_REMOTE': '1', 05:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:36 INFO - 'NO_EM_RESTART': '1', 05:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:36 INFO - 'PWD': '/builds/slave/test', 05:54:36 INFO - 'SHELL': '/bin/bash', 05:54:36 INFO - 'SHLVL': '1', 05:54:36 INFO - 'TERM': 'linux', 05:54:36 INFO - 'TMOUT': '86400', 05:54:36 INFO - 'USER': 'cltbld', 05:54:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:36 INFO - '_': '/tools/buildbot/bin/python'} 05:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:54:36 INFO - Cleaning up... 05:54:36 INFO - Return code: 0 05:54:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:54:36 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')]} 05:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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')]} 05:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:36 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:36 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 05:54:36 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 05:54:36 INFO - Using env: {'DISPLAY': ':0', 05:54:36 INFO - 'HOME': '/home/cltbld', 05:54:36 INFO - 'LANG': 'en_US.UTF-8', 05:54:36 INFO - 'LANGUAGE': 'en_US:en', 05:54:36 INFO - 'LOGNAME': 'cltbld', 05:54:36 INFO - 'MAIL': '/var/mail/cltbld', 05:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:36 INFO - 'MOZ_NO_REMOTE': '1', 05:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:36 INFO - 'NO_EM_RESTART': '1', 05:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:36 INFO - 'PWD': '/builds/slave/test', 05:54:36 INFO - 'SHELL': '/bin/bash', 05:54:36 INFO - 'SHLVL': '1', 05:54:36 INFO - 'TERM': 'linux', 05:54:36 INFO - 'TMOUT': '86400', 05:54:36 INFO - 'USER': 'cltbld', 05:54:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:36 INFO - '_': '/tools/buildbot/bin/python'} 05:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:36 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 05:54:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:54:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:38 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 05:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:54:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 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 05:54:39 INFO - Downloading docopt-0.6.1.tar.gz 05:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:54:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:54:39 INFO - Running setup.py install for blobuploader 05:54:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:54:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:54:39 INFO - Running setup.py install for requests 05:54:40 INFO - Running setup.py install for docopt 05:54:40 INFO - Successfully installed blobuploader requests docopt 05:54:40 INFO - Cleaning up... 05:54:40 INFO - Return code: 0 05:54:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:40 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')]} 05:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:40 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')]} 05:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:40 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:40 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 05:54:40 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 05:54:40 INFO - Using env: {'DISPLAY': ':0', 05:54:40 INFO - 'HOME': '/home/cltbld', 05:54:40 INFO - 'LANG': 'en_US.UTF-8', 05:54:40 INFO - 'LANGUAGE': 'en_US:en', 05:54:40 INFO - 'LOGNAME': 'cltbld', 05:54:40 INFO - 'MAIL': '/var/mail/cltbld', 05:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:40 INFO - 'MOZ_NO_REMOTE': '1', 05:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:40 INFO - 'NO_EM_RESTART': '1', 05:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:40 INFO - 'PWD': '/builds/slave/test', 05:54:40 INFO - 'SHELL': '/bin/bash', 05:54:40 INFO - 'SHLVL': '1', 05:54:40 INFO - 'TERM': 'linux', 05:54:40 INFO - 'TMOUT': '86400', 05:54:40 INFO - 'USER': 'cltbld', 05:54:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:40 INFO - '_': '/tools/buildbot/bin/python'} 05:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:40 INFO - Running setup.py (path:/tmp/pip-w5rn1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:40 INFO - Running setup.py (path:/tmp/pip-gCVuXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:40 INFO - Running setup.py (path:/tmp/pip-ClwBYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:40 INFO - Running setup.py (path:/tmp/pip-t5zr3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:40 INFO - Running setup.py (path:/tmp/pip-2Cf7il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:40 INFO - Running setup.py (path:/tmp/pip-zVg4Qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:40 INFO - Running setup.py (path:/tmp/pip-_xnA9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:41 INFO - Running setup.py (path:/tmp/pip-KBxp3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:41 INFO - Running setup.py (path:/tmp/pip-v4w0kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:41 INFO - Running setup.py (path:/tmp/pip-NV82aY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:41 INFO - Running setup.py (path:/tmp/pip-l0KIDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:41 INFO - Running setup.py (path:/tmp/pip-wgJsc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:41 INFO - Running setup.py (path:/tmp/pip-s93K9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:41 INFO - Running setup.py (path:/tmp/pip-l1eN3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:41 INFO - Running setup.py (path:/tmp/pip-iYboQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:41 INFO - Running setup.py (path:/tmp/pip-sPXn9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:41 INFO - Running setup.py (path:/tmp/pip-DPaiXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:54:41 INFO - Running setup.py install for manifestparser 05:54:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:54:41 INFO - Running setup.py install for mozcrash 05:54:41 INFO - Running setup.py install for mozdebug 05:54:42 INFO - Running setup.py install for mozdevice 05:54:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozfile 05:54:42 INFO - Running setup.py install for mozhttpd 05:54:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozinfo 05:54:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozInstall 05:54:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozleak 05:54:42 INFO - Running setup.py install for mozlog 05:54:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for moznetwork 05:54:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:54:42 INFO - Running setup.py install for mozprocess 05:54:42 INFO - Running setup.py install for mozprofile 05:54:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Running setup.py install for mozrunner 05:54:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Running setup.py install for mozscreenshot 05:54:43 INFO - Running setup.py install for moztest 05:54:43 INFO - Running setup.py install for mozversion 05:54:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:43 INFO - Cleaning up... 05:54:43 INFO - Return code: 0 05:54:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:43 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:43 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 05:54:43 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 05:54:43 INFO - Using env: {'DISPLAY': ':0', 05:54:43 INFO - 'HOME': '/home/cltbld', 05:54:43 INFO - 'LANG': 'en_US.UTF-8', 05:54:43 INFO - 'LANGUAGE': 'en_US:en', 05:54:43 INFO - 'LOGNAME': 'cltbld', 05:54:43 INFO - 'MAIL': '/var/mail/cltbld', 05:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:43 INFO - 'MOZ_NO_REMOTE': '1', 05:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:43 INFO - 'NO_EM_RESTART': '1', 05:54:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:43 INFO - 'PWD': '/builds/slave/test', 05:54:43 INFO - 'SHELL': '/bin/bash', 05:54:43 INFO - 'SHLVL': '1', 05:54:43 INFO - 'TERM': 'linux', 05:54:43 INFO - 'TMOUT': '86400', 05:54:43 INFO - 'USER': 'cltbld', 05:54:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:43 INFO - '_': '/tools/buildbot/bin/python'} 05:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 INFO - Running setup.py (path:/tmp/pip-esIgOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:43 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)) 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 INFO - Running setup.py (path:/tmp/pip-sYjD5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:43 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)) 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:43 INFO - Running setup.py (path:/tmp/pip-2dB7_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:43 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)) 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:43 INFO - Running setup.py (path:/tmp/pip-31Gh1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:44 INFO - Running setup.py (path:/tmp/pip-5DNhTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:44 INFO - Running setup.py (path:/tmp/pip-F5NrgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:44 INFO - Running setup.py (path:/tmp/pip-Ghjt8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:44 INFO - Running setup.py (path:/tmp/pip-uLo5wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:44 INFO - Running setup.py (path:/tmp/pip-rHGhcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:44 INFO - Running setup.py (path:/tmp/pip-5t2Pyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 INFO - Running setup.py (path:/tmp/pip-dCYYYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:44 INFO - Running setup.py (path:/tmp/pip-kU04v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:44 INFO - Running setup.py (path:/tmp/pip-WgI6Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:44 INFO - Running setup.py (path:/tmp/pip-NAjUng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:44 INFO - Running setup.py (path:/tmp/pip-qbFM9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:44 INFO - Running setup.py (path:/tmp/pip-XI4DJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:44 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)) 05:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:44 INFO - Running setup.py (path:/tmp/pip-ChkzW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:44 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)) 05:54:44 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)) 05:54:44 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)) 05:54:44 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)) 05:54:44 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)) 05:54:44 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)) 05:54:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:44 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 05:54:44 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 05:54:44 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 05:54:44 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 05:54:44 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 05:54:44 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 05:54:46 INFO - Downloading blessings-1.6.tar.gz 05:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:46 INFO - Installing collected packages: blessings 05:54:46 INFO - Running setup.py install for blessings 05:54:47 INFO - Successfully installed blessings 05:54:47 INFO - Cleaning up... 05:54:47 INFO - Return code: 0 05:54:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:47 INFO - Reading from file tmpfile_stdout 05:54:47 INFO - Current package versions: 05:54:47 INFO - argparse == 1.2.1 05:54:47 INFO - blessings == 1.6 05:54:47 INFO - blobuploader == 1.2.4 05:54:47 INFO - docopt == 0.6.1 05:54:47 INFO - functools32 == 3.2.3-2 05:54:47 INFO - jsonschema == 2.5.1 05:54:47 INFO - manifestparser == 1.1 05:54:47 INFO - mozInstall == 1.12 05:54:47 INFO - mozcrash == 0.17 05:54:47 INFO - mozdebug == 0.1 05:54:47 INFO - mozdevice == 0.48 05:54:47 INFO - mozfile == 1.2 05:54:47 INFO - mozhttpd == 0.7 05:54:47 INFO - mozinfo == 0.9 05:54:47 INFO - mozleak == 0.1 05:54:47 INFO - mozlog == 3.3 05:54:47 INFO - moznetwork == 0.27 05:54:47 INFO - mozprocess == 0.23 05:54:47 INFO - mozprofile == 0.28 05:54:47 INFO - mozrunner == 6.12 05:54:47 INFO - mozscreenshot == 0.1 05:54:47 INFO - mozsystemmonitor == 0.3 05:54:47 INFO - moztest == 0.7 05:54:47 INFO - mozversion == 1.4 05:54:47 INFO - psutil == 3.1.1 05:54:47 INFO - requests == 1.2.3 05:54:47 INFO - wsgiref == 0.1.2 05:54:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:47 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 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:47 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 05:54:47 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 05:54:47 INFO - Using env: {'DISPLAY': ':0', 05:54:47 INFO - 'HOME': '/home/cltbld', 05:54:47 INFO - 'LANG': 'en_US.UTF-8', 05:54:47 INFO - 'LANGUAGE': 'en_US:en', 05:54:47 INFO - 'LOGNAME': 'cltbld', 05:54:47 INFO - 'MAIL': '/var/mail/cltbld', 05:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:47 INFO - 'MOZ_NO_REMOTE': '1', 05:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:47 INFO - 'NO_EM_RESTART': '1', 05:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:47 INFO - 'PWD': '/builds/slave/test', 05:54:47 INFO - 'SHELL': '/bin/bash', 05:54:47 INFO - 'SHLVL': '1', 05:54:47 INFO - 'TERM': 'linux', 05:54:47 INFO - 'TMOUT': '86400', 05:54:47 INFO - 'USER': 'cltbld', 05:54:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:47 INFO - '_': '/tools/buildbot/bin/python'} 05:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 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)) 05:54:47 INFO - Cleaning up... 05:54:47 INFO - Return code: 0 05:54:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2609030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, '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': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', '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 05:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:47 INFO - Using env: {'DISPLAY': ':0', 05:54:47 INFO - 'HOME': '/home/cltbld', 05:54:47 INFO - 'LANG': 'en_US.UTF-8', 05:54:47 INFO - 'LANGUAGE': 'en_US:en', 05:54:47 INFO - 'LOGNAME': 'cltbld', 05:54:47 INFO - 'MAIL': '/var/mail/cltbld', 05:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:47 INFO - 'MOZ_NO_REMOTE': '1', 05:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:47 INFO - 'NO_EM_RESTART': '1', 05:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:47 INFO - 'PWD': '/builds/slave/test', 05:54:47 INFO - 'SHELL': '/bin/bash', 05:54:47 INFO - 'SHLVL': '1', 05:54:47 INFO - 'TERM': 'linux', 05:54:47 INFO - 'TMOUT': '86400', 05:54:47 INFO - 'USER': 'cltbld', 05:54:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:47 INFO - '_': '/tools/buildbot/bin/python'} 05:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:54:47 INFO - Cleaning up... 05:54:47 INFO - Return code: 0 05:54:47 INFO - Running post-action listener: _resource_record_post_action 05:54:47 INFO - Running post-action listener: _start_resource_monitoring 05:54:47 INFO - Starting resource monitoring. 05:54:47 INFO - [mozharness: 2016-10-31 12:54:47.881396Z] Finished create-virtualenv step (success) 05:54:47 INFO - [mozharness: 2016-10-31 12:54:47.881881Z] Running install step. 05:54:47 INFO - Running pre-action listener: _resource_record_pre_action 05:54:47 INFO - Running main action method: install 05:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:48 INFO - Reading from file tmpfile_stdout 05:54:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:48 INFO - Reading from file tmpfile_stdout 05:54:48 INFO - Output received: 05:54:48 INFO - Usage: mozinstall [options] installer 05:54:48 INFO - Options: 05:54:48 INFO - -h, --help show this help message and exit 05:54:48 INFO - -d DEST, --destination=DEST 05:54:48 INFO - Directory to install application into. [default: 05:54:48 INFO - "/builds/slave/test"] 05:54:48 INFO - --app=APP Application being installed. [default: firefox] 05:54:48 INFO - mkdir: /builds/slave/test/build/application 05:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:55:02 INFO - Reading from file tmpfile_stdout 05:55:02 INFO - Output received: 05:55:02 INFO - /builds/slave/test/build/application/firefox/firefox 05:55:02 INFO - Running post-action listener: _resource_record_post_action 05:55:02 INFO - [mozharness: 2016-10-31 12:55:02.193913Z] Finished install step (success) 05:55:02 INFO - [mozharness: 2016-10-31 12:55:02.194190Z] Running run-tests step. 05:55:02 INFO - Running pre-action listener: _resource_record_pre_action 05:55:02 INFO - Running main action method: run_tests 05:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:02 INFO - Python 2.7.3 05:55:02 INFO - Return code: 0 05:55:02 INFO - grabbing minidump binary from tooltool 05:55: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')]} 05:55:02 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 0x28c83b0>, '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 0x28c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:02 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 05:55:02 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 05:55:02 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 05:55:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:02 INFO - Return code: 0 05:55:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:55:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:55:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:55:02 INFO - Using env: {'DISPLAY': ':0', 05:55:02 INFO - 'HOME': '/home/cltbld', 05:55:02 INFO - 'LANG': 'en_US.UTF-8', 05:55:02 INFO - 'LANGUAGE': 'en_US:en', 05:55:02 INFO - 'LOGNAME': 'cltbld', 05:55:02 INFO - 'MAIL': '/var/mail/cltbld', 05:55:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:02 INFO - 'MOZ_NO_REMOTE': '1', 05:55:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:02 INFO - 'NO_EM_RESTART': '1', 05:55:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:02 INFO - 'PWD': '/builds/slave/test', 05:55:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:02 INFO - 'SHELL': '/bin/bash', 05:55:02 INFO - 'SHLVL': '1', 05:55:02 INFO - 'TERM': 'linux', 05:55:02 INFO - 'TMOUT': '86400', 05:55:02 INFO - 'USER': 'cltbld', 05:55:02 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867', 05:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:02 INFO - '_': '/tools/buildbot/bin/python'} 05:55:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:55:02 INFO - mozversion application_buildid: 20161031030621 05:55:02 INFO - mozversion application_changeset: 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa 05:55:02 INFO - mozversion application_display_name: Firefox 05:55:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:02 INFO - mozversion application_name: Firefox 05:55:02 INFO - mozversion application_remotingname: firefox 05:55:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:55:02 INFO - mozversion application_vendor: Mozilla 05:55:02 INFO - mozversion application_version: 50.0 05:55:02 INFO - mozversion platform_buildid: 20161031030621 05:55:02 INFO - mozversion platform_changeset: 1be8e06fcf3c63bdb1c8854aadd4785ebb04f1aa 05:55:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:55:02 INFO - mozversion platform_version: 50.0 05:55:02 INFO - using testdate: 1477918502 05:55:02 INFO - actual date: 1477918502 05:55:02 INFO - starting webserver on 'localhost:52113' 05:55:02 INFO - SUITE-START | Running 2 tests 05:55:02 INFO - TEST-START | dromaeo_css 05:55:02 INFO - operating with platform_type : linux_ 05:55:02 INFO - Initialising browser for dromaeo_css test... 05:55:02 INFO - TEST-INFO | started process 21648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpud8uo3/profile http://localhost:52113/getInfo.html) 05:55:06 INFO - PROCESS | 21648 | __metrics Screen width/height:1600/1200 05:55:06 INFO - PROCESS | 21648 | colorDepth:24 05:55:06 INFO - PROCESS | 21648 | Browser inner width/height: 1024/697 05:55:06 INFO - PROCESS | 21648 | __metrics 05:55:07 INFO - PROCESS | 21648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:55:07 INFO - PROCESS | 21648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:55:07 INFO - PROCESS | 21648 | console.error: PushService: 05:55:07 INFO - PROCESS | 21648 | stateChangeProcessEnqueue: Error transitioning state 05:55:07 INFO - PROCESS | 21648 | UnknownError 05:55:08 INFO - TEST-INFO | 21648: exit 0 05:55:08 INFO - Browser initialized. 05:55:08 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:08 INFO - TEST-INFO | started process 21740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpud8uo3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:19 INFO - PROCESS | 21740 | RSS: Main: 144920576 05:55:19 INFO - PROCESS | 21740 | 05:56:10 INFO - PROCESS | 21740 | 1477918570639 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] 05:58:19 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52113/tests/dromaeo/cssquery-ext.html) 05:58:20 INFO - PROCESS | 21740 | RSS: Main: 233418752 05:58:20 INFO - PROCESS | 21740 | 06:01:22 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-ext.html (next: http://localhost:52113/tests/dromaeo/cssquery-jquery.html) 06:01:23 INFO - PROCESS | 21740 | RSS: Main: 240787456 06:01:23 INFO - PROCESS | 21740 | 06:04:25 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52113/tests/dromaeo/cssquery-mootools.html) 06:04:25 INFO - PROCESS | 21740 | RSS: Main: 219226112 06:04:25 INFO - PROCESS | 21740 | 06:07:27 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52113/tests/dromaeo/cssquery-prototype.html) 06:07:28 INFO - PROCESS | 21740 | RSS: Main: 181612544 06:07:28 INFO - PROCESS | 21740 | 06:10:30 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52113/tests/dromaeo/cssquery-yui.html) 06:10:31 INFO - PROCESS | 21740 | RSS: Main: 183386112 06:10:31 INFO - PROCESS | 21740 | 06:13:33 INFO - PROCESS | 21740 | Cycle 1(1): loaded http://localhost:52113/tests/dromaeo/cssquery-yui.html (next: http://localhost:52113/tests/dromaeo/cssquery-dojo.html) 06:13:34 INFO - PROCESS | 21740 | RSS: Main: 266588160 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | __start_tp_report 06:13:34 INFO - PROCESS | 21740 | _x_x_mozilla_page_load 06:13:34 INFO - PROCESS | 21740 | _x_x_mozilla_page_load_details 06:13:34 INFO - PROCESS | 21740 | |i|pagename|runs| 06:13:34 INFO - PROCESS | 21740 | |0;dojo.html;3238.174265562282;3246;3257.902262932111;3290.177455636091;3300.521424393463;5636.18275350074;5687.289088863892;5740.311162660487;5748.770049198038;5762.884742305151;5681.204631351614;5742.79900203493;5749.3388260350075;5755.4244575542425;5762.740676669549;5201.297824793643;5209.739513024343;5218.530332270114;5256.737163141846;5275.551578115865;5128.179491281396;5281.63028587999;5292.947070529284;5296.25852380667;5328.600354973392;5097.668651537638;5116.283720279164;5140.203268493389;5168.48315168482;5230.686158830464;5132.948670513303;5149.665271757323;5269.789208431658;5272.868178295535;5318.867028324323;5034.370703662043;5152.381714194284;5229.764660590283;5284.4187139414635;5305.8938821223355;5572.832815015556;5607.495325420732;5656.519195868356;5672.9432705672825;5711.600187986843;6099.969500152471;6184.288806787232;6212.937870621281;6216.937830621681;6221.657808820513;5791.536677065867;6092.82742469918;6225.077316255802;6248.187735594389;6267.341929097424;5747.712614369265;5777.393373695785;5789.971050144764;5791.681457519792;5803.390643982406;6371.39471750183;6381.617102973836;6384.585001974857;6387.936120638827;6388.616682999035;5763.625364351304;5849.766009359578;5994.880102398015;6119.663418512025;6128.571000029955;6155.261368635703;6197.318294987548;6197.566170368121;6200.286966998763;6203.286622038523;6015.218021657157;6015.939840601625;6058.515318774487;6066.666333351708;6075.362086980892;5859.707014649251;5952.10718392236;5958.285005799332;6001.789937352171;6055.757769689163;4601.746903920316;4626.421697287839;4626.91267552125;4627.838025669085;4627.95870929045;4070.1656160487028;4141.171765646883;4156;4176.83292668296;4177.415161877339;5870.354261031283;5874;5882.911756323659;5886.499647529922;5920.733566989496;6139.815805525842;6173.660448675366;6185.969070154621;6188.81433556994;6194.628322300587;5608.831735047955;5694.5159661428415;5762;5856.853578660568;5871.911921321183;5268.736563171826;5270.446603106619;5272.340957380327;5295.682259064469;5297.947020529822;6302.149209856702;6340.778072767431;6345.96827015862;6357.268914074941;6358.078078678565;5906.557008224487;5985.401459854154;6042.909356359746;6092.573519853568;6108.236470441195;6310.905336420049;6370.968145159244;6385.840353991188;6396.904046439214;6397.520185985976;6023.066424704178;6024.457798798129;6026.668533230714;6026.819195424145;6040.033594624839;5952.910706339495;6009.369016253405;6129.448349648553;6138.14066030765;6140.5394595406415;5882.441168089024;5945.702714864153;5998.970005149947;6020.54845886569;6027.186329845415;5882.382349853204;5964.5526585505395;5978.611390259619;5989.700514974146;5992.790252341147;5780.421957804354;5859.150423188699;5923.526117910471;5937.376575459687;5943.583949123693;5996.640201587918;6076.483498902643;6081.695915204311;6083.178770865877;6087.9391206087375;5924.170616113662;5924.200232968667;5926.051831706906;5928.851778705394;5929.0513517836935 06:13:34 INFO - PROCESS | 21740 | |1;ext.html;625.1497962770632;661.4576047640935;672.7443571442855;678.6945874356541;681.5229339462378;33755;46057.30914036288;46805.76597117018;46896.531034689644;50227.74886125564;48439.27341089878;48856.51143488564;48969.75515122419;48998.75500622501;49001;8600.612972416226;9333.346665733385;9464.242860571172;9471.905280947205;9476.099770521789;10906.182036347265;11154.330740155616;11167.72080697985;11508.482118304697;11511;11370.60202892899;11469;11482.138839587022;11488.597899073535;11488.827667584992;11176.329420234772;11238.719032024183;11259.605913792979;11259.718507037349;11264.943675281653;11156.665300041012;11228.382438965853;11234.494447749788;11241.044511216556;11242.213045086848;45058.54941450609;45218.547814521764;45256.77371613154;45284.773576131905;45359.09281814345;3669.1744357519347;3852.325842977468;3964.940525892114;4019.894529004518;4210.45264115663;33736;33790.32419351624;34035.14912127217;34456.48315275273;35055.12362190965;4430.734155950653;4446.3108218226225;4489.21438748221;4541.568550987651;4542.636589072867;368165;370921;371842;373191;373660;60959.69520152371;61263.69368153131;61397;61499;61679.6916015426;38599.614003860166;39521.40717889234;64121.35878641247;64449.355506445274;64503.67748161323;18246.817531824778;18424.17091230898;18451.16969736338;18465.169067392;18472.630547388977;13516.324183790772;14536.345864436125;14697.92651036738;14733.67396934281;14920.925395373171;20472.69290960637;20496.282630107875;20551.794482055288;20552.38342849717;20669.58660826775;7487.625618719042;7502.549847009197;7592.8481430372185;7681.539107653559;7740.26467485588;973.8539219117189;1071.539238127597;1106.397013627575;1142.3431526871916;1145.73075327298;33994.32011359759;34489.31021379609;34667.306653866784;34897.002443336205;35154.824225879216;1165.341582006164;1198.3528894396927;1212.5998420521207;1260.2060701757835;1309.9148555344063;17099.572510687332;18179.272829086953;18345.08274586265;18397;18429.078546072644;643622;649771;651280;651573;656062;64124;64512.99999999906;64530.354696453374;64597.354026459136;64638;1352.1751731443812;1408.401429392504;1432.2051261549732;1452.244832686997;1455.3741890986978;33783.32433351369;35483.822580887965;35769;35857.64142358543;36124;54015;54744.98902560238;54836.451635483136;54890.45109548853;55100.72449637726;449417;452686;453758;454029;454507;27160.72839271582;27405;30056;30085.849570752005;30710.692893070784;51909.740451297504;52283.47716522786;52374;52934.73532632312;53106;43473;44087;44487.77756111199;45431.318530222736;46219.3067103987;43368.78315608402;43370;44068.55931440773;44550.55449445464;44793.552064478936;43893;43993.560064398946;44776.77611611921;44984.1003179928;46840;37958.8102059499;39919;40368.596314037655;40447.191056178126;40580.39129413002;29824.850875745484;29888;30761.538576921812;30869.845650771604;30898.53652195174 06:13:34 INFO - PROCESS | 21740 | |2;jquery.html;404.4297540467939;410.5258426517373;429.50177793759246;429.6219326992247;430.27928220231126;19104.522386940334;20189.596208075847;20766.06552705128;21494;21707.891460542716;20182.69725954108;20401.693974590355;20474.385768426935;20476.795232047676;20684.06921688524;5759.65442073477;6018.6689732064715;6022.096685497188;6027.5479339049525;6033.276006879159;5788.739506722208;5792.565557583198;5826.747249341391;5842.503387212092;5941.0494320908665;5802.912956305659;5809.941900580982;5812.680302583356;5812.941870581282;5826.563007774413;5746.53567121987;5820.156077368779;5894.528437724993;5944.851378715523;5953.553483488756;5308.681479111266;5309.761060752276;5345.1714984177515;5357.464253574651;5366.70483123428;24106.156284529956;24368.634470482957;24374.512509749704;24620.507589848283;24683;2678.8644160493172;2758.517259479603;2839.886404543816;2952.778541609388;3012.412579546989;15052;15736.842631573652;15808.841911580852;15826.446074387457;16200.43298484559;2009.156154415149;2029.320177740444;2070.554830711389;2079.719237902879;2104.926327578543;58083.709581451825;73607.63196184038;73643;83851;85000;11291.54833806655;11487.655370338736;11657.941710291394;11658.358790266617;12666.36668166588;16053.598660033358;16371.590710232102;16381.426650067191;16397.098159601337;16401.671966560603;9085.318601104944;9098.818023639491;9174.816503669888;9233.676821311223;9439.386439881386;9135.223506002065;9139.862902056475;9211.493367864698;9344.065593440602;9422;6798.660066996729;6883.27725588816;6986.056882320923;7062.293770622897;7103.89344159838;1837.7731218198091;1926.8843869367602;2031.1570698160099;2043.2235750414748;2148.130007347027;1036.6268143468346;1053.7681710023783;1068.4817863336275;1107.9667609971714;1129.6102844518791;19434.611307773765;20125.194992200442;20966.685499717518;21375.251866184608;21451.24920627802;1051.0855555666467;1057;1070.5931745936493;1103.194667892443;1105.2318638546294;11945.88054119465;11974.521019159136;12048.87951120495;12127;12266.141370104186;572642;577570;579039;594745.2101915962;596861;80648.19351806407;81865.59067204744;83508.58245708732;83692.58153709193;84084;1047.7223535763028;1052.2108418685987;1080.5353697909975;1080.8324709670014;1089.2484185911853;17972.910135449238;18440.631187376446;18688.81311186925;18774.15516301797;18935.526611834815;41396;41469.37795933003;41495.58504415038;41607.16785664209;42028.3695744558;53793.46206538041;54474.18288725593;55458.44541554533;55518.16722749081;56268.15597766119;7458.769303064933;8273.669053237803;9102.317326200693;9468.715938521855;9477.620895164391;13556.11885227457;13623.318834058457;13821.585352439442;13958.441662333798;14273.429922708287;11960.58137965167;12099.455524501242;12292.692682682648;12466.43901024438;12604.117711760455;12107.33409662477;12130.575429859913;12219.389030548615;12274.324912129918;12673.683157921127;12021;12085.335306558223;12417.62747117588;12533.435995380047;12603.432845522157;8950;8988.57342447992;9119.772005699646;9231.907680923105;9485.810283794148;12296.815547766611;12312.938435307766;12320.876791231973;12594.591103095074;12964.870351296366 06:13:34 INFO - PROCESS | 21740 | |3;mootools.html;1540.176005836877;1543.1435553267936;1699.1164594410907;1747.100243374663;1811.3207547169811;8904.821903561924;9585.904140958588;9587.04129587041;9587.856182157264;9597.136257736802;8224.547649879254;8458.661653533867;8463.830723385528;8632.09363016882;9416.52917354133;2368.644703294511;2453.374389530676;2454.7667971542674;2466.2231397109854;2467.383154211447;2495.214007587604;2509.14689005738;2509.4353770401603;2524.7727704506683;2527.4565968316806;2478.615814548748;2491.1530079772874;2509.7992160627105;2517.987410062947;2518.0934863449143;2369.3958040699686;2377.38188071101;2379.6787433696404;2382.4282172278704;2438.7805097541304;2354.3607028180536;2391.3065211088765;2395.47299594089;2398.1126983016397;2400.9039638414615;9294.163525282689;9376.296777741696;9376.624935002592;9379.76550586234;9458.290628202913;5367.704776237305;5380.650257733235;5479.068558345091;5491;5494.642848214853;3193.744500439959;3257.5765150530515;3260.494623333387;3280.803151810875;3323.102762254178;1169.830374595679;1175.3418085871854;1191.023360844108;1194.9641510754604;1201.7296108375633;12540.623781286577;12589.559365422167;12615.873841261562;12623.116381853273;12631.684207894785;4561.543845615412;4737.810487580458;4844.15227335222;4847.733374664427;4871.269309603588;10569.735756605993;10669.626563070255;10676.46617669129;10696.144308455305;10699.411532365795;5485.040117979338;5494;5494.972525137348;5499.1476321170285;5511.366192887789;3973.662238709684;4453.844115455943;5027.622928280393;5043.167877300278;5066.594672426197;4232.666709986344;4263.062126332202;4354.542773008852;4389.495208051051;4393.51671316155;1319.7228581997697;1342.0806747377883;1344.8117263583106;1348.6830594810212;1349.3956663639271;1031.7317497450567;1050.8528805967171;1057.3074636113358;1069.6202847989007;1077.235163034239;6413.967930160319;6561.212654481397;6628.1383420156035;6711.664416779142;6813.454923606099;461.47852926193184;469.99295010574866;1081.5241293830848;1106.2919731371928;1133.7449073958294;4179.916401672011;4319.719218250866;4324.71889327199;4439.4228750262255;4522.163399771053;11221.887781122085;11579;11605.825912611317;11631.59289424866;11633.127515436376;11435.59975400857;11494.252873563191;11526.654200374001;11537.711557211136;11546.480408381643;8865.64537418509;8888.111188881061;9007.414518056305;9009.954950225207;9012.594433250519;6596;6674;6679.398854103154;6686.398224159849;6764.594124352555;10884.401357925073;10989;11097.833532496858;11153.72115697114;11251.099912006855;9703.12671859536;11207.775844483227;11221.326720396803;11289.32264064126;11363.54545818158;7287.599182045038;7965.362770978424;8068.87896681562;8257.876131857907;8273.710420135267;9103.180713735796;9103.49930753815;9124.270058395445;9178;9206.585703643219;7920.841583168189;7933.484323518953;7979;8038.39712021589;8144.941157649468;7411.666475008746;7612.467127301035;7723.165043453234;7766.456348055808;7783.961080194563;7601.923980760343;7636.350910172698;7664;7670.386369090348;7706.07527096752;5324.866878328073;5470.124780035176;5503.7798488060025;5662.7734890603915;5759.769609215585;5533.6403133796175;5564.721763911707;5565.443455654402;5733.9965506037115;5805.651660900359 06:13:34 INFO - PROCESS | 21740 | |4;prototype.html;357.37281071719127;358.84031605935354;362.0622587498379;362.8802495176592;370.2871971454949;5543.556515478762;5548.611597188188;5573.495156307795;5577.386487486378;5584.134459158832;5471.781128754855;5540.529055030317;5544.364412498313;5546.500814926656;5556.499915007648;4157.542670306271;4299.849505267316;4320.546342634026;4364.890877728067;4367.650587952972;4278.37963495293;4312.784360781964;4320.4599425071865;4327.264365057932;4333.848315308965;4297.570242975708;4298.398224248608;4304.74171549708;4308.97845510772;4322.351647252905;4158.2307273154565;4296.742195468282;4300.548442413533;4316.892077698051;4335.804888779997;3730.3285408626434;3743.1390780120723;3760.3983362662116;3766.1714422826935;3767.9811600942094;5328.20076988451;5332.733363331818;5335.919961200585;5392;5404.7838086476495;4760.976195119002;4928.7535623219055;4961.727105009223;4982.302477653096;5015.774290156952;5697.430256974311;5703.31560212773;5730.828075157752;5764.769409223626;5778.5377169826315;4195.748255104673;4205.789710514493;4229.851955181553;4237.618614324695;4238.597333253336;5892.440218179309;5894;5968.373320801296;5994.670293133832;5995.100734889757;4279.572042795758;4283.008401093506;4286.935695964563;4288.914221715549;4309.332053531708;4324.935125973113;4363.694541382073;4367.934480982788;4369.956300437018;4389.626881715026;4798.832040878552;4917.213245880642;4966.503349665005;4988.825391111293;4992.925106123411;5760.164776107441;5760.251167348218;5776.191333213354;5779.24869766936;5840.737166827503;1471.2423102102425;1488.1666266890359;1489.4116823854517;1498.8234236124808;1500.8574185452364;902.490093097411;962.1821451766083;964.8890377606665;975.4196253229317;977.0180968126987;4900.779464924087;4916.680415773035;4978.158081509864;5002.949380630036;5052.3222691973315;5204.323437953118;5217.50433708797;5257.579393648499;5279.357243501971;5301.496357845998;5027.748612569357;5044.621653376012;5056.26684130799;5284.471552844761;5740.1963725077685;3150.6534281229046;3155.826429546351;3165.7467402607735;3209.534617480452;3214.630317513516;6096.725647345791;6261.373862613793;6272.153259309935;6286.214223222098;6305.590136641195;5817;6008.879822403527;6022.969885150546;6051.667158306247;6080.14877917092;5963.522918166536;6096.207493025879;6105.389461053948;6109.609166912317;6114.6636934969;5960.433758792908;5961.195238642727;5999.740054588586;6000.369961154191;6005.759769609342;5796.62321949072;5808.128780682762;5811.709414529172;5815.680137592473;5853.209816674695;6122.561198118349;6171.457135716071;6171.9691401542705;6174.38256174379;6179.598326108789;4087.2438598859308;4259.190753756776;4276.550962149054;4294.3558466230315;4305.9354109689;5856.4143585641095;5863.912041319299;5873.236479257669;5891.1457838612305;5901.786829938773;5787.797427090031;5792.420757924174;5809.825705228681;5842.941570584239;5868.67722275279;5679;5761;5786.33457152416;5803.506701930383;5875.236219291465;5699.601027928171;5730.971345143247;5743.253377060955;5756.769729210952;5764.135379692912;5646.955313267059;5775.191473193672;5838.4453476919625;5857.1799948008165;5871.1486834409625;5639.266895303748;5639.6334238274385;5666.121751128417;5678.261825962599;5688.857778555569 06:13:34 INFO - PROCESS | 21740 | |5;yui.html;692.729835364208;764.9617519124042;775.2325198053926;775.9224077592245;831.6922738586721;15270.618234544128;16459.506214813544;16491.09298988555;16525.999999999985;16575.585610359733;14927.32827022784;14993.775093373608;15000.549983500485;15042.84957150428;15196.620084497894;712.8360477090272;819.5410570080744;832.014063334947;833.3166803221361;840.4369072721277;850.974470765878;851.6125163050797;852.6589364254286;857.9613917373701;860.4794099569764;903.0202230579829;912.968046118386;913.7532866126141;914.963401463944;915.1946287267195;905.4386280506095;912.3066469483174;913.137085454246;914.8124634449955;915.1351972386097;670.6780745242262;671.9832004199885;672.1665135232325;672.3377473188905;673.0308355967392;14413.711725765426;14581.416743330254;14584.635384115376;14605.6348591285;14619.122852628876;15530.767038494432;15613.297401616954;15614.687706245812;15770;15826.05043697382;11453.484593193327;11466.165413533794;11589.826152607717;11683.649490515298;12117.636470905887;4108.568600296956;4120.958790412087;4142.150859073913;4269.786510674454;4280.4221430106845;75140.62429687871;75330.62334688347;76838.23161768366;77356.99999999943;78218.60890695568;11793;12090.455929483194;12114;12122.818157727641;12285.324307163191;22348.888255558617;22354.21760238384;22354.776452235594;22411.77588224097;22807.657885131735;8332.125126861714;8412.411131220879;8423.831523369498;8620.698275560326;8638.784030399165;4780.95219047812;4954.653174277766;4998.225275082368;5056.115179843513;5141.897162056738;3515.1387909962223;3646.726495512847;3648.5804132524568;3656.1590834108306;3658.5243918290453;601.4977493792732;607.507918585938;608.2123649873424;608.7747533412665;644.5101722690695;611.4344231585854;626.8338890194102;635.7838334966134;693.3655705029942;710.6091649592703;8606.397552171413;8665.653373865101;8699.826003479895;8931.285497160212;9002.279817614575;648.6108334998963;659.2682122843639;716.1477841368859;716.6739133694157;754.8188434775615;4221.97889010553;4342;4405.255671928309;4407.779611019436;4475.7985890635;69253.65373173203;70587.2941270591;119774;122037;130304.348478257;94528;95099.0490095104;95610.52194739121;108331;109946.45026774815;57420.71289643609;62923.37076629267;74137.62931185309;87034;91462.54268728614;8080.797980050545;8097.574625001145;8371.790705232417;8382.58087095655;8516.493034074929;51449.228261576856;51693;52615.73692131668;53086.73456632692;53153.202701958726;75695;76362.61818690872;81204.18795811966;82464;82649.58675206585;10853.511591978538;11070.391128487689;11372.545098196311;11375.886241137483;11455.369954652575;13704.588862334145;14127;14508.1295122293;14532;15166.165860877758;12706;13303;14082;14781.704365912836;14794.334254958769;13812.672695614101;13973.92573633799;14064.296785160497;14161.645958850699;14421.351039203471;12239.816402754144;12446.688832779253;12625.621231363073;12803.935980320039;12880.484780608671;10657.20070994663;10891.945540272249;11554.306741595532;11586.396756287766;11611.187216894736;11179.832302515631;11338.09295256361;11355;11420.257683250562;11562 06:13:34 INFO - PROCESS | 21740 | __end_tp_report 06:13:34 INFO - PROCESS | 21740 | __start_cc_report 06:13:34 INFO - PROCESS | 21740 | _x_x_mozilla_cycle_collect,908 06:13:34 INFO - PROCESS | 21740 | __end_cc_report 06:13:34 INFO - PROCESS | 21740 | __startTimestamp1477919614025__endTimestamp 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | ------- Summary: start ------- 06:13:34 INFO - PROCESS | 21740 | Number of tests: 6 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#0] dojo.html Cycles:165 Average:5685.56 Median:5906.56 stddev:658.54 (11.1%) stddev-sans-first:631.95 06:13:34 INFO - PROCESS | 21740 | Values: 3238.2 3246.0 3257.9 3290.2 3300.5 5636.2 5687.3 5740.3 5748.8 5762.9 5681.2 5742.8 5749.3 5755.4 5762.7 5201.3 5209.7 5218.5 5256.7 5275.6 5128.2 5281.6 5292.9 5296.3 5328.6 5097.7 5116.3 5140.2 5168.5 5230.7 5132.9 5149.7 5269.8 5272.9 5318.9 5034.4 5152.4 5229.8 5284.4 5305.9 5572.8 5607.5 5656.5 5672.9 5711.6 6100.0 6184.3 6212.9 6216.9 6221.7 5791.5 6092.8 6225.1 6248.2 6267.3 5747.7 5777.4 5790.0 5791.7 5803.4 6371.4 6381.6 6384.6 6387.9 6388.6 5763.6 5849.8 5994.9 6119.7 6128.6 6155.3 6197.3 6197.6 6200.3 6203.3 6015.2 6015.9 6058.5 6066.7 6075.4 5859.7 5952.1 5958.3 6001.8 6055.8 4601.7 4626.4 4626.9 4627.8 4628.0 4070.2 4141.2 4156.0 4176.8 4177.4 5870.4 5874.0 5882.9 5886.5 5920.7 6139.8 6173.7 6186.0 6188.8 6194.6 5608.8 5694.5 5762.0 5856.9 5871.9 5268.7 5270.4 5272.3 5295.7 5297.9 6302.1 6340.8 6346.0 6357.3 6358.1 5906.6 5985.4 6042.9 6092.6 6108.2 6310.9 6371.0 6385.8 6396.9 6397.5 6023.1 6024.5 6026.7 6026.8 6040.0 5952.9 6009.4 6129.4 6138.1 6140.5 5882.4 5945.7 5999.0 6020.5 6027.2 5882.4 5964.6 5978.6 5989.7 5992.8 5780.4 5859.2 5923.5 5937.4 5943.6 5996.6 6076.5 6081.7 6083.2 6087.9 5924.2 5924.2 5926.1 5928.9 5929.1 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#1] ext.html Cycles:180 Average:66243.66 Median:21941.97 stddev:134380.63 (612.4%) stddev-sans-first:134667.29 06:13:34 INFO - PROCESS | 21740 | Values: 625.1 661.5 672.7 678.7 681.5 33755.0 46057.3 46805.8 46896.5 50227.7 48439.3 48856.5 48969.8 48998.8 49001.0 8600.6 9333.3 9464.2 9471.9 9476.1 10906.2 11154.3 11167.7 11508.5 11511.0 11370.6 11469.0 11482.1 11488.6 11488.8 11176.3 11238.7 11259.6 11259.7 11264.9 11156.7 11228.4 11234.5 11241.0 11242.2 45058.5 45218.5 45256.8 45284.8 45359.1 3669.2 3852.3 3964.9 4019.9 4210.5 33736.0 33790.3 34035.1 34456.5 35055.1 4430.7 4446.3 4489.2 4541.6 4542.6 368165.0 370921.0 371842.0 373191.0 373660.0 60959.7 61263.7 61397.0 61499.0 61679.7 38599.6 39521.4 64121.4 64449.4 64503.7 18246.8 18424.2 18451.2 18465.2 18472.6 13516.3 14536.3 14697.9 14733.7 14920.9 20472.7 20496.3 20551.8 20552.4 20669.6 7487.6 7502.5 7592.8 7681.5 7740.3 973.9 1071.5 1106.4 1142.3 1145.7 33994.3 34489.3 34667.3 34897.0 35154.8 1165.3 1198.4 1212.6 1260.2 1309.9 17099.6 18179.3 18345.1 18397.0 18429.1 643622.0 649771.0 651280.0 651573.0 656062.0 64124.0 64513.0 64530.4 64597.4 64638.0 1352.2 1408.4 1432.2 1452.2 1455.4 33783.3 35483.8 35769.0 35857.6 36124.0 54015.0 54745.0 54836.5 54890.5 55100.7 449417.0 452686.0 453758.0 454029.0 454507.0 27160.7 27405.0 30056.0 30085.8 30710.7 51909.7 52283.5 52374.0 52934.7 53106.0 43473.0 44087.0 44487.8 45431.3 46219.3 43368.8 43370.0 44068.6 44550.6 44793.6 43893.0 43993.6 44776.8 44984.1 46840.0 37958.8 39919.0 40368.6 40447.2 40580.4 29824.9 29888.0 30761.5 30869.8 30898.5 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#2] jquery.html Cycles:180 Average:31807.33 Median:24237.40 stddev:95517.42 (394.1%) stddev-sans-first:95756.26 06:13:34 INFO - PROCESS | 21740 | Values: 404.4 410.5 429.5 429.6 430.3 19104.5 20189.6 20766.1 21494.0 21707.9 20182.7 20401.7 20474.4 20476.8 20684.1 5759.7 6018.7 6022.1 6027.5 6033.3 5788.7 5792.6 5826.7 5842.5 5941.0 5802.9 5809.9 5812.7 5812.9 5826.6 5746.5 5820.2 5894.5 5944.9 5953.6 5308.7 5309.8 5345.2 5357.5 5366.7 24106.2 24368.6 24374.5 24620.5 24683.0 2678.9 2758.5 2839.9 2952.8 3012.4 15052.0 15736.8 15808.8 15826.4 16200.4 2009.2 2029.3 2070.6 2079.7 2104.9 58083.7 73607.6 73643.0 83851.0 85000.0 11291.5 11487.7 11657.9 11658.4 12666.4 16053.6 16371.6 16381.4 16397.1 16401.7 9085.3 9098.8 9174.8 9233.7 9439.4 9135.2 9139.9 9211.5 9344.1 9422.0 6798.7 6883.3 6986.1 7062.3 7103.9 1837.8 1926.9 2031.2 2043.2 2148.1 1036.6 1053.8 1068.5 1108.0 1129.6 19434.6 20125.2 20966.7 21375.3 21451.2 1051.1 1057.0 1070.6 1103.2 1105.2 11945.9 11974.5 12048.9 12127.0 12266.1 572642.0 577570.0 579039.0 594745.2 596861.0 80648.2 81865.6 83508.6 83692.6 84084.0 1047.7 1052.2 1080.5 1080.8 1089.2 17972.9 18440.6 18688.8 18774.2 18935.5 41396.0 41469.4 41495.6 41607.2 42028.4 53793.5 54474.2 55458.4 55518.2 56268.2 7458.8 8273.7 9102.3 9468.7 9477.6 13556.1 13623.3 13821.6 13958.4 14273.4 11960.6 12099.5 12292.7 12466.4 12604.1 12107.3 12130.6 12219.4 12274.3 12673.7 12021.0 12085.3 12417.6 12533.4 12603.4 8950.0 8988.6 9119.8 9231.9 9485.8 12296.8 12312.9 12320.9 12594.6 12964.9 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#3] mootools.html Cycles:180 Average:6122.15 Median:4416.47 stddev:3486.73 (78.9%) stddev-sans-first:3479.51 06:13:34 INFO - PROCESS | 21740 | Values: 1540.2 1543.1 1699.1 1747.1 1811.3 8904.8 9585.9 9587.0 9587.9 9597.1 8224.5 8458.7 8463.8 8632.1 9416.5 2368.6 2453.4 2454.8 2466.2 2467.4 2495.2 2509.1 2509.4 2524.8 2527.5 2478.6 2491.2 2509.8 2518.0 2518.1 2369.4 2377.4 2379.7 2382.4 2438.8 2354.4 2391.3 2395.5 2398.1 2400.9 9294.2 9376.3 9376.6 9379.8 9458.3 5367.7 5380.7 5479.1 5491.0 5494.6 3193.7 3257.6 3260.5 3280.8 3323.1 1169.8 1175.3 1191.0 1195.0 1201.7 12540.6 12589.6 12615.9 12623.1 12631.7 4561.5 4737.8 4844.2 4847.7 4871.3 10569.7 10669.6 10676.5 10696.1 10699.4 5485.0 5494.0 5495.0 5499.1 5511.4 3973.7 4453.8 5027.6 5043.2 5066.6 4232.7 4263.1 4354.5 4389.5 4393.5 1319.7 1342.1 1344.8 1348.7 1349.4 1031.7 1050.9 1057.3 1069.6 1077.2 6414.0 6561.2 6628.1 6711.7 6813.5 461.5 470.0 1081.5 1106.3 1133.7 4179.9 4319.7 4324.7 4439.4 4522.2 11221.9 11579.0 11605.8 11631.6 11633.1 11435.6 11494.3 11526.7 11537.7 11546.5 8865.6 8888.1 9007.4 9010.0 9012.6 6596.0 6674.0 6679.4 6686.4 6764.6 10884.4 10989.0 11097.8 11153.7 11251.1 9703.1 11207.8 11221.3 11289.3 11363.5 7287.6 7965.4 8068.9 8257.9 8273.7 9103.2 9103.5 9124.3 9178.0 9206.6 7920.8 7933.5 7979.0 8038.4 8144.9 7411.7 7612.5 7723.2 7766.5 7784.0 7601.9 7636.4 7664.0 7670.4 7706.1 5324.9 5470.1 5503.8 5662.8 5759.8 5533.6 5564.7 5565.4 5734.0 5805.7 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#4] prototype.html Cycles:180 Average:4840.74 Median:5542.04 stddev:1414.31 (25.5%) stddev-sans-first:1377.66 06:13:34 INFO - PROCESS | 21740 | Values: 357.4 358.8 362.1 362.9 370.3 5543.6 5548.6 5573.5 5577.4 5584.1 5471.8 5540.5 5544.4 5546.5 5556.5 4157.5 4299.8 4320.5 4364.9 4367.7 4278.4 4312.8 4320.5 4327.3 4333.8 4297.6 4298.4 4304.7 4309.0 4322.4 4158.2 4296.7 4300.5 4316.9 4335.8 3730.3 3743.1 3760.4 3766.2 3768.0 5328.2 5332.7 5335.9 5392.0 5404.8 4761.0 4928.8 4961.7 4982.3 5015.8 5697.4 5703.3 5730.8 5764.8 5778.5 4195.7 4205.8 4229.9 4237.6 4238.6 5892.4 5894.0 5968.4 5994.7 5995.1 4279.6 4283.0 4286.9 4288.9 4309.3 4324.9 4363.7 4367.9 4370.0 4389.6 4798.8 4917.2 4966.5 4988.8 4992.9 5760.2 5760.3 5776.2 5779.2 5840.7 1471.2 1488.2 1489.4 1498.8 1500.9 902.5 962.2 964.9 975.4 977.0 4900.8 4916.7 4978.2 5002.9 5052.3 5204.3 5217.5 5257.6 5279.4 5301.5 5027.7 5044.6 5056.3 5284.5 5740.2 3150.7 3155.8 3165.7 3209.5 3214.6 6096.7 6261.4 6272.2 6286.2 6305.6 5817.0 6008.9 6023.0 6051.7 6080.1 5963.5 6096.2 6105.4 6109.6 6114.7 5960.4 5961.2 5999.7 6000.4 6005.8 5796.6 5808.1 5811.7 5815.7 5853.2 6122.6 6171.5 6172.0 6174.4 6179.6 4087.2 4259.2 4276.6 4294.4 4305.9 5856.4 5863.9 5873.2 5891.1 5901.8 5787.8 5792.4 5809.8 5842.9 5868.7 5679.0 5761.0 5786.3 5803.5 5875.2 5699.6 5731.0 5743.3 5756.8 5764.1 5647.0 5775.2 5838.4 5857.2 5871.1 5639.3 5639.6 5666.1 5678.3 5688.9 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - PROCESS | 21740 | [#5] yui.html Cycles:180 Average:20349.20 Median:4867.80 stddev:29122.87 (598.3%) stddev-sans-first:29167.16 06:13:34 INFO - PROCESS | 21740 | Values: 692.7 765.0 775.2 775.9 831.7 15270.6 16459.5 16491.1 16526.0 16575.6 14927.3 14993.8 15000.5 15042.8 15196.6 712.8 819.5 832.0 833.3 840.4 851.0 851.6 852.7 858.0 860.5 903.0 913.0 913.8 915.0 915.2 905.4 912.3 913.1 914.8 915.1 670.7 672.0 672.2 672.3 673.0 14413.7 14581.4 14584.6 14605.6 14619.1 15530.8 15613.3 15614.7 15770.0 15826.1 11453.5 11466.2 11589.8 11683.6 12117.6 4108.6 4121.0 4142.2 4269.8 4280.4 75140.6 75330.6 76838.2 77357.0 78218.6 11793.0 12090.5 12114.0 12122.8 12285.3 22348.9 22354.2 22354.8 22411.8 22807.7 8332.1 8412.4 8423.8 8620.7 8638.8 4781.0 4954.7 4998.2 5056.1 5141.9 3515.1 3646.7 3648.6 3656.2 3658.5 601.5 607.5 608.2 608.8 644.5 611.4 626.8 635.8 693.4 710.6 8606.4 8665.7 8699.8 8931.3 9002.3 648.6 659.3 716.1 716.7 754.8 4222.0 4342.0 4405.3 4407.8 4475.8 69253.7 70587.3 119774.0 122037.0 130304.3 94528.0 95099.0 95610.5 108331.0 109946.5 57420.7 62923.4 74137.6 87034.0 91462.5 8080.8 8097.6 8371.8 8382.6 8516.5 51449.2 51693.0 52615.7 53086.7 53153.2 75695.0 76362.6 81204.2 82464.0 82649.6 10853.5 11070.4 11372.5 11375.9 11455.4 13704.6 14127.0 14508.1 14532.0 15166.2 12706.0 13303.0 14082.0 14781.7 14794.3 13812.7 13973.9 14064.3 14161.6 14421.4 12239.8 12446.7 12625.6 12803.9 12880.5 10657.2 10891.9 11554.3 11586.4 11611.2 11179.8 11338.1 11355.0 11420.3 11562.0 06:13:34 INFO - PROCESS | 21740 | -------- Summary: end -------- 06:13:34 INFO - PROCESS | 21740 | 06:13:34 INFO - TEST-INFO | 21740: exit 0 06:13:34 INFO - TEST-OK | dromaeo_css | took 1112232ms 06:13:34 INFO - TEST-START | kraken 06:13:34 INFO - operating with platform_type : linux_ 06:13:34 INFO - Initialising browser for kraken test... 06:13:34 INFO - TEST-INFO | started process 21931 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK1PS1x/profile http://localhost:52113/getInfo.html) 06:13:38 INFO - PROCESS | 21931 | __metrics Screen width/height:1600/1200 06:13:38 INFO - PROCESS | 21931 | colorDepth:24 06:13:38 INFO - PROCESS | 21931 | Browser inner width/height: 1024/697 06:13:38 INFO - PROCESS | 21931 | __metrics 06:13:39 INFO - PROCESS | 21931 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:13:39 INFO - PROCESS | 21931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:13:39 INFO - PROCESS | 21931 | console.error: PushService: 06:13:40 INFO - PROCESS | 21931 | stateChangeProcessEnqueue: Error transitioning state 06:13:40 INFO - PROCESS | 21931 | UnknownError 06:13:40 INFO - TEST-INFO | 21931: exit 0 06:13:40 INFO - Browser initialized. 06:13:40 INFO - Running cycle 1/1 for kraken test... 06:13:40 INFO - TEST-INFO | started process 22020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK1PS1x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:13:52 INFO - PROCESS | 22020 | RSS: Main: 144470016 06:13:52 INFO - PROCESS | 22020 | 06:13:54 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52113/tests/kraken/driver.html?testName=audio-beat-detection) 06:13:55 INFO - PROCESS | 22020 | RSS: Main: 199221248 06:13:55 INFO - PROCESS | 22020 | 06:13:58 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52113/tests/kraken/driver.html?testName=audio-dft) 06:13:58 INFO - PROCESS | 22020 | RSS: Main: 183308288 06:13:58 INFO - PROCESS | 22020 | 06:14:01 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52113/tests/kraken/driver.html?testName=audio-fft) 06:14:02 INFO - PROCESS | 22020 | RSS: Main: 275046400 06:14:02 INFO - PROCESS | 22020 | 06:14:04 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52113/tests/kraken/driver.html?testName=audio-oscillator) 06:14:04 INFO - PROCESS | 22020 | RSS: Main: 180191232 06:14:04 INFO - PROCESS | 22020 | 06:14:06 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52113/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:07 INFO - PROCESS | 22020 | RSS: Main: 180064256 06:14:07 INFO - PROCESS | 22020 | 06:14:10 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52113/tests/kraken/driver.html?testName=imaging-darkroom) 06:14:11 INFO - PROCESS | 22020 | RSS: Main: 215339008 06:14:11 INFO - PROCESS | 22020 | 06:14:14 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52113/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:15 INFO - PROCESS | 22020 | RSS: Main: 214441984 06:14:15 INFO - PROCESS | 22020 | 06:14:17 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52113/tests/kraken/driver.html?testName=json-parse-financial) 06:14:18 INFO - PROCESS | 22020 | RSS: Main: 214069248 06:14:18 INFO - PROCESS | 22020 | 06:14:19 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52113/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:20 INFO - PROCESS | 22020 | RSS: Main: 176562176 06:14:20 INFO - PROCESS | 22020 | 06:14:22 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:22 INFO - PROCESS | 22020 | RSS: Main: 184287232 06:14:22 INFO - PROCESS | 22020 | 06:14:24 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:25 INFO - PROCESS | 22020 | RSS: Main: 189976576 06:14:25 INFO - PROCESS | 22020 | 06:14:27 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:28 INFO - PROCESS | 22020 | RSS: Main: 192724992 06:14:28 INFO - PROCESS | 22020 | 06:14:31 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:14:31 INFO - PROCESS | 22020 | RSS: Main: 194084864 06:14:31 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | Cycle 1(1): loaded http://localhost:52113/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52113/tests/kraken/driver.html?testName=ai-astar) 06:14:33 INFO - PROCESS | 22020 | RSS: Main: 243978240 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | __start_tp_report 06:14:33 INFO - PROCESS | 22020 | _x_x_mozilla_page_load 06:14:33 INFO - PROCESS | 22020 | _x_x_mozilla_page_load_details 06:14:33 INFO - PROCESS | 22020 | |i|pagename|runs| 06:14:33 INFO - PROCESS | 22020 | |0;ai-astar;111;111;120;110;111;111;112;112;112;111 06:14:33 INFO - PROCESS | 22020 | |1;audio-beat-detection;143;122;151;193;115;145;146;116;175;116 06:14:33 INFO - PROCESS | 22020 | |2;audio-dft;161;162;162;160;160;159;160;162;160;159 06:14:33 INFO - PROCESS | 22020 | |3;audio-fft;74;73;74;172;76;75;74;75;164;75 06:14:33 INFO - PROCESS | 22020 | |4;audio-oscillator;83;83;149;84;85;83;142;84;83;153 06:14:33 INFO - PROCESS | 22020 | |5;imaging-gaussian-blur;116;117;117;117;116;117;117;116;117;116 06:14:33 INFO - PROCESS | 22020 | |6;imaging-darkroom;145;146;145;147;147;145;145;145;147;146 06:14:33 INFO - PROCESS | 22020 | |7;imaging-desaturate;95;95;95;95;95;96;94;94;95;94 06:14:33 INFO - PROCESS | 22020 | |8;json-parse-financial;50;49;51;49;51;60;50;50;50;51 06:14:33 INFO - PROCESS | 22020 | |9;json-stringify-tinderbox;59;60;60;59;60;59;59;61;64;59 06:14:33 INFO - PROCESS | 22020 | |10;stanford-crypto-aes;75;73;72;72;77;72;72;72;73;72 06:14:33 INFO - PROCESS | 22020 | |11;stanford-crypto-ccm;118;126;117;121;133;118;123;120;178;125 06:14:33 INFO - PROCESS | 22020 | |12;stanford-crypto-pbkdf2;153;154;152;154;152;152;152;152;149;155 06:14:33 INFO - PROCESS | 22020 | |13;stanford-crypto-sha256-iterative;64;66;66;62;61;65;61;65;61;61 06:14:33 INFO - PROCESS | 22020 | __end_tp_report 06:14:33 INFO - PROCESS | 22020 | __start_cc_report 06:14:33 INFO - PROCESS | 22020 | _x_x_mozilla_cycle_collect,1616 06:14:33 INFO - PROCESS | 22020 | __end_cc_report 06:14:33 INFO - PROCESS | 22020 | __startTimestamp1477919673645__endTimestamp 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | ------- Summary: start ------- 06:14:33 INFO - PROCESS | 22020 | Number of tests: 14 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#0] ai-astar Cycles:10 Average:112.10 Median:111.50 stddev:2.85 (2.6%) stddev-sans-first:2.99 06:14:33 INFO - PROCESS | 22020 | Values: 111.0 111.0 120.0 110.0 111.0 111.0 112.0 112.0 112.0 111.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:145.50 stddev:26.41 (18.2%) stddev-sans-first:28.01 06:14:33 INFO - PROCESS | 22020 | Values: 143.0 122.0 151.0 193.0 115.0 145.0 146.0 116.0 175.0 116.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.18 (0.7%) stddev-sans-first:1.24 06:14:33 INFO - PROCESS | 22020 | Values: 161.0 162.0 162.0 160.0 160.0 159.0 160.0 162.0 160.0 159.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.50 stddev:39.48 (53.0%) stddev-sans-first:41.26 06:14:33 INFO - PROCESS | 22020 | Values: 74.0 73.0 74.0 172.0 76.0 75.0 74.0 75.0 164.0 75.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:83.00 stddev:31.24 (37.6%) stddev-sans-first:32.29 06:14:33 INFO - PROCESS | 22020 | Values: 83.0 83.0 149.0 84.0 85.0 83.0 142.0 84.0 83.0 153.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 06:14:33 INFO - PROCESS | 22020 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 06:14:33 INFO - PROCESS | 22020 | Values: 145.0 146.0 145.0 147.0 147.0 145.0 145.0 145.0 147.0 146.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 06:14:33 INFO - PROCESS | 22020 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 94.0 94.0 95.0 94.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:50.50 stddev:3.21 (6.4%) stddev-sans-first:3.38 06:14:33 INFO - PROCESS | 22020 | Values: 50.0 49.0 51.0 49.0 51.0 60.0 50.0 50.0 50.0 51.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.56 (2.6%) stddev-sans-first:1.62 06:14:33 INFO - PROCESS | 22020 | Values: 59.0 60.0 60.0 59.0 60.0 59.0 59.0 61.0 64.0 59.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:72.50 stddev:1.70 (2.3%) stddev-sans-first:1.64 06:14:33 INFO - PROCESS | 22020 | Values: 75.0 73.0 72.0 72.0 77.0 72.0 72.0 72.0 73.0 72.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:124.00 stddev:18.25 (14.7%) stddev-sans-first:19.00 06:14:33 INFO - PROCESS | 22020 | Values: 118.0 126.0 117.0 121.0 133.0 118.0 123.0 120.0 178.0 125.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:152.50 stddev:1.65 (1.1%) stddev-sans-first:1.74 06:14:33 INFO - PROCESS | 22020 | Values: 153.0 154.0 152.0 154.0 152.0 152.0 152.0 152.0 149.0 155.0 06:14:33 INFO - PROCESS | 22020 | 06:14:33 INFO - PROCESS | 22020 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:64.50 stddev:2.20 (3.4%) stddev-sans-first:2.32 06:14:33 INFO - PROCESS | 22020 | Values: 64.0 66.0 66.0 62.0 61.0 65.0 61.0 65.0 61.0 61.0 06:14:33 INFO - PROCESS | 22020 | -------- Summary: end -------- 06:14:33 INFO - PROCESS | 22020 | 06:14:34 INFO - TEST-INFO | 22020: exit 0 06:14:34 INFO - TEST-OK | kraken | took 59809ms 06:14:34 INFO - SUITE-END | took 1172s 06:14:34 INFO - Completed test suite (00:19:32) 06:14:34 INFO - javascript benchmark 06:14:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3238.174265562282, 3246.0, 3257.902262932111, 3290.177455636091, 3300.521424393463, 5636.18275350074, 5687.289088863892, 5740.311162660487, 5748.770049198038, 5762.884742305151, 5681.204631351614, 5742.79900203493, 5749.3388260350075, 5755.4244575542425, 5762.740676669549, 5201.297824793643, 5209.739513024343, 5218.530332270114, 5256.737163141846, 5275.551578115865, 5128.179491281396, 5281.63028587999, 5292.947070529284, 5296.25852380667, 5328.600354973392, 5097.668651537638, 5116.283720279164, 5140.203268493389, 5168.48315168482, 5230.686158830464, 5132.948670513303, 5149.665271757323, 5269.789208431658, 5272.868178295535, 5318.867028324323, 5034.370703662043, 5152.381714194284, 5229.764660590283, 5284.4187139414635, 5305.8938821223355, 5572.832815015556, 5607.495325420732, 5656.519195868356, 5672.9432705672825, 5711.600187986843, 6099.969500152471, 6184.288806787232, 6212.937870621281, 6216.937830621681, 6221.657808820513, 5791.536677065867, 6092.82742469918, 6225.077316255802, 6248.187735594389, 6267.341929097424, 5747.712614369265, 5777.393373695785, 5789.971050144764, 5791.681457519792, 5803.390643982406, 6371.39471750183, 6381.617102973836, 6384.585001974857, 6387.936120638827, 6388.616682999035, 5763.625364351304, 5849.766009359578, 5994.880102398015, 6119.663418512025, 6128.571000029955, 6155.261368635703, 6197.318294987548, 6197.566170368121, 6200.286966998763, 6203.286622038523, 6015.218021657157, 6015.939840601625, 6058.515318774487, 6066.666333351708, 6075.362086980892, 5859.707014649251, 5952.10718392236, 5958.285005799332, 6001.789937352171, 6055.757769689163, 4601.746903920316, 4626.421697287839, 4626.91267552125, 4627.838025669085, 4627.95870929045, 4070.1656160487028, 4141.171765646883, 4156.0, 4176.83292668296, 4177.415161877339, 5870.354261031283, 5874.0, 5882.911756323659, 5886.499647529922, 5920.733566989496, 6139.815805525842, 6173.660448675366, 6185.969070154621, 6188.81433556994, 6194.628322300587, 5608.831735047955, 5694.5159661428415, 5762.0, 5856.853578660568, 5871.911921321183, 5268.736563171826, 5270.446603106619, 5272.340957380327, 5295.682259064469, 5297.947020529822, 6302.149209856702, 6340.778072767431, 6345.96827015862, 6357.268914074941, 6358.078078678565, 5906.557008224487, 5985.401459854154, 6042.909356359746, 6092.573519853568, 6108.236470441195, 6310.905336420049, 6370.968145159244, 6385.840353991188, 6396.904046439214, 6397.520185985976, 6023.066424704178, 6024.457798798129, 6026.668533230714, 6026.819195424145, 6040.033594624839, 5952.910706339495, 6009.369016253405, 6129.448349648553, 6138.14066030765, 6140.5394595406415, 5882.441168089024, 5945.702714864153, 5998.970005149947, 6020.54845886569, 6027.186329845415, 5882.382349853204, 5964.5526585505395, 5978.611390259619, 5989.700514974146, 5992.790252341147, 5780.421957804354, 5859.150423188699, 5923.526117910471, 5937.376575459687, 5943.583949123693, 5996.640201587918, 6076.483498902643, 6081.695915204311, 6083.178770865877, 6087.9391206087375, 5924.170616113662, 5924.200232968667, 5926.051831706906, 5928.851778705394, 5929.0513517836935], "value": 5640.02912723241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.1497962770632, 661.4576047640935, 672.7443571442855, 678.6945874356541, 681.5229339462378, 33755.0, 46057.30914036288, 46805.76597117018, 46896.531034689644, 50227.74886125564, 48439.27341089878, 48856.51143488564, 48969.75515122419, 48998.75500622501, 49001.0, 8600.612972416226, 9333.346665733385, 9464.242860571172, 9471.905280947205, 9476.099770521789, 10906.182036347265, 11154.330740155616, 11167.72080697985, 11508.482118304697, 11511.0, 11370.60202892899, 11469.0, 11482.138839587022, 11488.597899073535, 11488.827667584992, 11176.329420234772, 11238.719032024183, 11259.605913792979, 11259.718507037349, 11264.943675281653, 11156.665300041012, 11228.382438965853, 11234.494447749788, 11241.044511216556, 11242.213045086848, 45058.54941450609, 45218.547814521764, 45256.77371613154, 45284.773576131905, 45359.09281814345, 3669.1744357519347, 3852.325842977468, 3964.940525892114, 4019.894529004518, 4210.45264115663, 33736.0, 33790.32419351624, 34035.14912127217, 34456.48315275273, 35055.12362190965, 4430.734155950653, 4446.3108218226225, 4489.21438748221, 4541.568550987651, 4542.636589072867, 368165.0, 370921.0, 371842.0, 373191.0, 373660.0, 60959.69520152371, 61263.69368153131, 61397.0, 61499.0, 61679.6916015426, 38599.614003860166, 39521.40717889234, 64121.35878641247, 64449.355506445274, 64503.67748161323, 18246.817531824778, 18424.17091230898, 18451.16969736338, 18465.169067392, 18472.630547388977, 13516.324183790772, 14536.345864436125, 14697.92651036738, 14733.67396934281, 14920.925395373171, 20472.69290960637, 20496.282630107875, 20551.794482055288, 20552.38342849717, 20669.58660826775, 7487.625618719042, 7502.549847009197, 7592.8481430372185, 7681.539107653559, 7740.26467485588, 973.8539219117189, 1071.539238127597, 1106.397013627575, 1142.3431526871916, 1145.73075327298, 33994.32011359759, 34489.31021379609, 34667.306653866784, 34897.002443336205, 35154.824225879216, 1165.341582006164, 1198.3528894396927, 1212.5998420521207, 1260.2060701757835, 1309.9148555344063, 17099.572510687332, 18179.272829086953, 18345.08274586265, 18397.0, 18429.078546072644, 643622.0, 649771.0, 651280.0, 651573.0, 656062.0, 64124.0, 64512.99999999906, 64530.354696453374, 64597.354026459136, 64638.0, 1352.1751731443812, 1408.401429392504, 1432.2051261549732, 1452.244832686997, 1455.3741890986978, 33783.32433351369, 35483.822580887965, 35769.0, 35857.64142358543, 36124.0, 54015.0, 54744.98902560238, 54836.451635483136, 54890.45109548853, 55100.72449637726, 449417.0, 452686.0, 453758.0, 454029.0, 454507.0, 27160.72839271582, 27405.0, 30056.0, 30085.849570752005, 30710.692893070784, 51909.740451297504, 52283.47716522786, 52374.0, 52934.73532632312, 53106.0, 43473.0, 44087.0, 44487.77756111199, 45431.318530222736, 46219.3067103987, 43368.78315608402, 43370.0, 44068.55931440773, 44550.55449445464, 44793.552064478936, 43893.0, 43993.560064398946, 44776.77611611921, 44984.1003179928, 46840.0, 37958.8102059499, 39919.0, 40368.596314037655, 40447.191056178126, 40580.39129413002, 29824.850875745484, 29888.0, 30761.538576921812, 30869.845650771604, 30898.53652195174], "value": 22028.3523303643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.4297540467939, 410.5258426517373, 429.50177793759246, 429.6219326992247, 430.27928220231126, 19104.522386940334, 20189.596208075847, 20766.06552705128, 21494.0, 21707.891460542716, 20182.69725954108, 20401.693974590355, 20474.385768426935, 20476.795232047676, 20684.06921688524, 5759.65442073477, 6018.6689732064715, 6022.096685497188, 6027.5479339049525, 6033.276006879159, 5788.739506722208, 5792.565557583198, 5826.747249341391, 5842.503387212092, 5941.0494320908665, 5802.912956305659, 5809.941900580982, 5812.680302583356, 5812.941870581282, 5826.563007774413, 5746.53567121987, 5820.156077368779, 5894.528437724993, 5944.851378715523, 5953.553483488756, 5308.681479111266, 5309.761060752276, 5345.1714984177515, 5357.464253574651, 5366.70483123428, 24106.156284529956, 24368.634470482957, 24374.512509749704, 24620.507589848283, 24683.0, 2678.8644160493172, 2758.517259479603, 2839.886404543816, 2952.778541609388, 3012.412579546989, 15052.0, 15736.842631573652, 15808.841911580852, 15826.446074387457, 16200.43298484559, 2009.156154415149, 2029.320177740444, 2070.554830711389, 2079.719237902879, 2104.926327578543, 58083.709581451825, 73607.63196184038, 73643.0, 83851.0, 85000.0, 11291.54833806655, 11487.655370338736, 11657.941710291394, 11658.358790266617, 12666.36668166588, 16053.598660033358, 16371.590710232102, 16381.426650067191, 16397.098159601337, 16401.671966560603, 9085.318601104944, 9098.818023639491, 9174.816503669888, 9233.676821311223, 9439.386439881386, 9135.223506002065, 9139.862902056475, 9211.493367864698, 9344.065593440602, 9422.0, 6798.660066996729, 6883.27725588816, 6986.056882320923, 7062.293770622897, 7103.89344159838, 1837.7731218198091, 1926.8843869367602, 2031.1570698160099, 2043.2235750414748, 2148.130007347027, 1036.6268143468346, 1053.7681710023783, 1068.4817863336275, 1107.9667609971714, 1129.6102844518791, 19434.611307773765, 20125.194992200442, 20966.685499717518, 21375.251866184608, 21451.24920627802, 1051.0855555666467, 1057.0, 1070.5931745936493, 1103.194667892443, 1105.2318638546294, 11945.88054119465, 11974.521019159136, 12048.87951120495, 12127.0, 12266.141370104186, 572642.0, 577570.0, 579039.0, 594745.2101915962, 596861.0, 80648.19351806407, 81865.59067204744, 83508.58245708732, 83692.58153709193, 84084.0, 1047.7223535763028, 1052.2108418685987, 1080.5353697909975, 1080.8324709670014, 1089.2484185911853, 17972.910135449238, 18440.631187376446, 18688.81311186925, 18774.15516301797, 18935.526611834815, 41396.0, 41469.37795933003, 41495.58504415038, 41607.16785664209, 42028.3695744558, 53793.46206538041, 54474.18288725593, 55458.44541554533, 55518.16722749081, 56268.15597766119, 7458.769303064933, 8273.669053237803, 9102.317326200693, 9468.715938521855, 9477.620895164391, 13556.11885227457, 13623.318834058457, 13821.585352439442, 13958.441662333798, 14273.429922708287, 11960.58137965167, 12099.455524501242, 12292.692682682648, 12466.43901024438, 12604.117711760455, 12107.33409662477, 12130.575429859913, 12219.389030548615, 12274.324912129918, 12673.683157921127, 12021.0, 12085.335306558223, 12417.62747117588, 12533.435995380047, 12603.432845522157, 8950.0, 8988.57342447992, 9119.772005699646, 9231.907680923105, 9485.810283794148, 12296.815547766611, 12312.938435307766, 12320.876791231973, 12594.591103095074, 12964.870351296366], "value": 9908.063690837851, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.176005836877, 1543.1435553267936, 1699.1164594410907, 1747.100243374663, 1811.3207547169811, 8904.821903561924, 9585.904140958588, 9587.04129587041, 9587.856182157264, 9597.136257736802, 8224.547649879254, 8458.661653533867, 8463.830723385528, 8632.09363016882, 9416.52917354133, 2368.644703294511, 2453.374389530676, 2454.7667971542674, 2466.2231397109854, 2467.383154211447, 2495.214007587604, 2509.14689005738, 2509.4353770401603, 2524.7727704506683, 2527.4565968316806, 2478.615814548748, 2491.1530079772874, 2509.7992160627105, 2517.987410062947, 2518.0934863449143, 2369.3958040699686, 2377.38188071101, 2379.6787433696404, 2382.4282172278704, 2438.7805097541304, 2354.3607028180536, 2391.3065211088765, 2395.47299594089, 2398.1126983016397, 2400.9039638414615, 9294.163525282689, 9376.296777741696, 9376.624935002592, 9379.76550586234, 9458.290628202913, 5367.704776237305, 5380.650257733235, 5479.068558345091, 5491.0, 5494.642848214853, 3193.744500439959, 3257.5765150530515, 3260.494623333387, 3280.803151810875, 3323.102762254178, 1169.830374595679, 1175.3418085871854, 1191.023360844108, 1194.9641510754604, 1201.7296108375633, 12540.623781286577, 12589.559365422167, 12615.873841261562, 12623.116381853273, 12631.684207894785, 4561.543845615412, 4737.810487580458, 4844.15227335222, 4847.733374664427, 4871.269309603588, 10569.735756605993, 10669.626563070255, 10676.46617669129, 10696.144308455305, 10699.411532365795, 5485.040117979338, 5494.0, 5494.972525137348, 5499.1476321170285, 5511.366192887789, 3973.662238709684, 4453.844115455943, 5027.622928280393, 5043.167877300278, 5066.594672426197, 4232.666709986344, 4263.062126332202, 4354.542773008852, 4389.495208051051, 4393.51671316155, 1319.7228581997697, 1342.0806747377883, 1344.8117263583106, 1348.6830594810212, 1349.3956663639271, 1031.7317497450567, 1050.8528805967171, 1057.3074636113358, 1069.6202847989007, 1077.235163034239, 6413.967930160319, 6561.212654481397, 6628.1383420156035, 6711.664416779142, 6813.454923606099, 461.47852926193184, 469.99295010574866, 1081.5241293830848, 1106.2919731371928, 1133.7449073958294, 4179.916401672011, 4319.719218250866, 4324.71889327199, 4439.4228750262255, 4522.163399771053, 11221.887781122085, 11579.0, 11605.825912611317, 11631.59289424866, 11633.127515436376, 11435.59975400857, 11494.252873563191, 11526.654200374001, 11537.711557211136, 11546.480408381643, 8865.64537418509, 8888.111188881061, 9007.414518056305, 9009.954950225207, 9012.594433250519, 6596.0, 6674.0, 6679.398854103154, 6686.398224159849, 6764.594124352555, 10884.401357925073, 10989.0, 11097.833532496858, 11153.72115697114, 11251.099912006855, 9703.12671859536, 11207.775844483227, 11221.326720396803, 11289.32264064126, 11363.54545818158, 7287.599182045038, 7965.362770978424, 8068.87896681562, 8257.876131857907, 8273.710420135267, 9103.180713735796, 9103.49930753815, 9124.270058395445, 9178.0, 9206.585703643219, 7920.841583168189, 7933.484323518953, 7979.0, 8038.39712021589, 8144.941157649468, 7411.666475008746, 7612.467127301035, 7723.165043453234, 7766.456348055808, 7783.961080194563, 7601.923980760343, 7636.350910172698, 7664.0, 7670.386369090348, 7706.07527096752, 5324.866878328073, 5470.124780035176, 5503.7798488060025, 5662.7734890603915, 5759.769609215585, 5533.6403133796175, 5564.721763911707, 5565.443455654402, 5733.9965506037115, 5805.651660900359], "value": 4880.784403478213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.37281071719127, 358.84031605935354, 362.0622587498379, 362.8802495176592, 370.2871971454949, 5543.556515478762, 5548.611597188188, 5573.495156307795, 5577.386487486378, 5584.134459158832, 5471.781128754855, 5540.529055030317, 5544.364412498313, 5546.500814926656, 5556.499915007648, 4157.542670306271, 4299.849505267316, 4320.546342634026, 4364.890877728067, 4367.650587952972, 4278.37963495293, 4312.784360781964, 4320.4599425071865, 4327.264365057932, 4333.848315308965, 4297.570242975708, 4298.398224248608, 4304.74171549708, 4308.97845510772, 4322.351647252905, 4158.2307273154565, 4296.742195468282, 4300.548442413533, 4316.892077698051, 4335.804888779997, 3730.3285408626434, 3743.1390780120723, 3760.3983362662116, 3766.1714422826935, 3767.9811600942094, 5328.20076988451, 5332.733363331818, 5335.919961200585, 5392.0, 5404.7838086476495, 4760.976195119002, 4928.7535623219055, 4961.727105009223, 4982.302477653096, 5015.774290156952, 5697.430256974311, 5703.31560212773, 5730.828075157752, 5764.769409223626, 5778.5377169826315, 4195.748255104673, 4205.789710514493, 4229.851955181553, 4237.618614324695, 4238.597333253336, 5892.440218179309, 5894.0, 5968.373320801296, 5994.670293133832, 5995.100734889757, 4279.572042795758, 4283.008401093506, 4286.935695964563, 4288.914221715549, 4309.332053531708, 4324.935125973113, 4363.694541382073, 4367.934480982788, 4369.956300437018, 4389.626881715026, 4798.832040878552, 4917.213245880642, 4966.503349665005, 4988.825391111293, 4992.925106123411, 5760.164776107441, 5760.251167348218, 5776.191333213354, 5779.24869766936, 5840.737166827503, 1471.2423102102425, 1488.1666266890359, 1489.4116823854517, 1498.8234236124808, 1500.8574185452364, 902.490093097411, 962.1821451766083, 964.8890377606665, 975.4196253229317, 977.0180968126987, 4900.779464924087, 4916.680415773035, 4978.158081509864, 5002.949380630036, 5052.3222691973315, 5204.323437953118, 5217.50433708797, 5257.579393648499, 5279.357243501971, 5301.496357845998, 5027.748612569357, 5044.621653376012, 5056.26684130799, 5284.471552844761, 5740.1963725077685, 3150.6534281229046, 3155.826429546351, 3165.7467402607735, 3209.534617480452, 3214.630317513516, 6096.725647345791, 6261.373862613793, 6272.153259309935, 6286.214223222098, 6305.590136641195, 5817.0, 6008.879822403527, 6022.969885150546, 6051.667158306247, 6080.14877917092, 5963.522918166536, 6096.207493025879, 6105.389461053948, 6109.609166912317, 6114.6636934969, 5960.433758792908, 5961.195238642727, 5999.740054588586, 6000.369961154191, 6005.759769609342, 5796.62321949072, 5808.128780682762, 5811.709414529172, 5815.680137592473, 5853.209816674695, 6122.561198118349, 6171.457135716071, 6171.9691401542705, 6174.38256174379, 6179.598326108789, 4087.2438598859308, 4259.190753756776, 4276.550962149054, 4294.3558466230315, 4305.9354109689, 5856.4143585641095, 5863.912041319299, 5873.236479257669, 5891.1457838612305, 5901.786829938773, 5787.797427090031, 5792.420757924174, 5809.825705228681, 5842.941570584239, 5868.67722275279, 5679.0, 5761.0, 5786.33457152416, 5803.506701930383, 5875.236219291465, 5699.601027928171, 5730.971345143247, 5743.253377060955, 5756.769729210952, 5764.135379692912, 5646.955313267059, 5775.191473193672, 5838.4453476919625, 5857.1799948008165, 5871.1486834409625, 5639.266895303748, 5639.6334238274385, 5666.121751128417, 5678.261825962599, 5688.857778555569], "value": 4394.418235122043, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.729835364208, 764.9617519124042, 775.2325198053926, 775.9224077592245, 831.6922738586721, 15270.618234544128, 16459.506214813544, 16491.09298988555, 16525.999999999985, 16575.585610359733, 14927.32827022784, 14993.775093373608, 15000.549983500485, 15042.84957150428, 15196.620084497894, 712.8360477090272, 819.5410570080744, 832.014063334947, 833.3166803221361, 840.4369072721277, 850.974470765878, 851.6125163050797, 852.6589364254286, 857.9613917373701, 860.4794099569764, 903.0202230579829, 912.968046118386, 913.7532866126141, 914.963401463944, 915.1946287267195, 905.4386280506095, 912.3066469483174, 913.137085454246, 914.8124634449955, 915.1351972386097, 670.6780745242262, 671.9832004199885, 672.1665135232325, 672.3377473188905, 673.0308355967392, 14413.711725765426, 14581.416743330254, 14584.635384115376, 14605.6348591285, 14619.122852628876, 15530.767038494432, 15613.297401616954, 15614.687706245812, 15770.0, 15826.05043697382, 11453.484593193327, 11466.165413533794, 11589.826152607717, 11683.649490515298, 12117.636470905887, 4108.568600296956, 4120.958790412087, 4142.150859073913, 4269.786510674454, 4280.4221430106845, 75140.62429687871, 75330.62334688347, 76838.23161768366, 77356.99999999943, 78218.60890695568, 11793.0, 12090.455929483194, 12114.0, 12122.818157727641, 12285.324307163191, 22348.888255558617, 22354.21760238384, 22354.776452235594, 22411.77588224097, 22807.657885131735, 8332.125126861714, 8412.411131220879, 8423.831523369498, 8620.698275560326, 8638.784030399165, 4780.95219047812, 4954.653174277766, 4998.225275082368, 5056.115179843513, 5141.897162056738, 3515.1387909962223, 3646.726495512847, 3648.5804132524568, 3656.1590834108306, 3658.5243918290453, 601.4977493792732, 607.507918585938, 608.2123649873424, 608.7747533412665, 644.5101722690695, 611.4344231585854, 626.8338890194102, 635.7838334966134, 693.3655705029942, 710.6091649592703, 8606.397552171413, 8665.653373865101, 8699.826003479895, 8931.285497160212, 9002.279817614575, 648.6108334998963, 659.2682122843639, 716.1477841368859, 716.6739133694157, 754.8188434775615, 4221.97889010553, 4342.0, 4405.255671928309, 4407.779611019436, 4475.7985890635, 69253.65373173203, 70587.2941270591, 119774.0, 122037.0, 130304.348478257, 94528.0, 95099.0490095104, 95610.52194739121, 108331.0, 109946.45026774815, 57420.71289643609, 62923.37076629267, 74137.62931185309, 87034.0, 91462.54268728614, 8080.797980050545, 8097.574625001145, 8371.790705232417, 8382.58087095655, 8516.493034074929, 51449.228261576856, 51693.0, 52615.73692131668, 53086.73456632692, 53153.202701958726, 75695.0, 76362.61818690872, 81204.18795811966, 82464.0, 82649.58675206585, 10853.511591978538, 11070.391128487689, 11372.545098196311, 11375.886241137483, 11455.369954652575, 13704.588862334145, 14127.0, 14508.1295122293, 14532.0, 15166.165860877758, 12706.0, 13303.0, 14082.0, 14781.704365912836, 14794.334254958769, 13812.672695614101, 13973.92573633799, 14064.296785160497, 14161.645958850699, 14421.351039203471, 12239.816402754144, 12446.688832779253, 12625.621231363073, 12803.935980320039, 12880.484780608671, 10657.20070994663, 10891.945540272249, 11554.306741595532, 11586.396756287766, 11611.187216894736, 11179.832302515631, 11338.09295256361, 11355.0, 11420.257683250562, 11562.0], "value": 7538.586497854041, "unit": "score"}], "name": "dromaeo_css", "value": 7641.2252954728265, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 120.0, 110.0, 111.0, 111.0, 112.0, 112.0, 112.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 122.0, 151.0, 193.0, 115.0, 145.0, 146.0, 116.0, 175.0, 116.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 160.0, 160.0, 159.0, 160.0, 162.0, 160.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 172.0, 76.0, 75.0, 74.0, 75.0, 164.0, 75.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 149.0, 84.0, 85.0, 83.0, 142.0, 84.0, 83.0, 153.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 147.0, 147.0, 145.0, 145.0, 145.0, 147.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 49.0, 51.0, 60.0, 50.0, 50.0, 50.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 61.0, 64.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 72.0, 72.0, 77.0, 72.0, 72.0, 72.0, 73.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 117.0, 121.0, 133.0, 118.0, 123.0, 120.0, 178.0, 125.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 152.0, 154.0, 152.0, 152.0, 152.0, 152.0, 149.0, 155.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 62.0, 61.0, 65.0, 61.0, 65.0, 61.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1495.8, "alertThreshold": 2.0}]} 06:14:34 INFO - Return code: 0 06:14:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:14:34 INFO - # TBPL SUCCESS # 06:14:34 INFO - Running post-action listener: _resource_record_post_action 06:14:34 INFO - [mozharness: 2016-10-31 13:14:34.928603Z] Finished run-tests step (success) 06:14:34 INFO - Running post-run listener: _resource_record_post_run 06:14:35 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 289021952; Read time: 48; Write time: 174736 06:14:35 INFO - TinderboxPrint: CPU usage
12.9% 06:14:35 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 48 06:14:35 INFO - TinderboxPrint: I/O write bytes / time
289,021,952 / 174,736 06:14:35 INFO - TinderboxPrint: CPU idle
8,244.5 (87.1%) 06:14:35 INFO - TinderboxPrint: CPU user
1,181.1 (12.5%) 06:14:35 INFO - TinderboxPrint: Swap in / out
0 / 0 06:14:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71299072; Read time: 0; Write time: 74720 06:14:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 217722880; Read time: 0; Write time: 100016 06:14:35 INFO - Running post-run listener: _upload_blobber_files 06:14:35 INFO - Blob upload gear active. 06:14:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:35 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 06:14:35 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:14:35 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:14:35 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:14:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:14:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:40 INFO - (blobuploader) - INFO - Done attempting. 06:14:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:14:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:14:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:41 INFO - (blobuploader) - INFO - Done attempting. 06:14:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:14:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:42 INFO - (blobuploader) - INFO - Done attempting. 06:14:42 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:42 INFO - Return code: 0 06:14:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb22930499af49a61ef986fc3061382f71cde473ef25d764c47bc9265c56b6eec63230c97820dc4199f1201bbe9b5c0fca82f2bd1356a3bcc68a6fc0ba65abdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f89e4a912472334a00a77368f380c4f86403cb98d342222f2095c0219f47551cd24cb3cac566623228b0f657531254a9d829970e8a21d39ab129becd1324b3b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/482ead592b6f2372c88dfea2f27d66946a6a78524ebdd86f42a179d626dc3e626d92243657531a60d720248737fc3308cc43da7aecd0ff8111f4684a16cfe608"} 06:14:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:42 INFO - Contents: 06:14:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb22930499af49a61ef986fc3061382f71cde473ef25d764c47bc9265c56b6eec63230c97820dc4199f1201bbe9b5c0fca82f2bd1356a3bcc68a6fc0ba65abdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f89e4a912472334a00a77368f380c4f86403cb98d342222f2095c0219f47551cd24cb3cac566623228b0f657531254a9d829970e8a21d39ab129becd1324b3b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/482ead592b6f2372c88dfea2f27d66946a6a78524ebdd86f42a179d626dc3e626d92243657531a60d720248737fc3308cc43da7aecd0ff8111f4684a16cfe608"} 06:14:42 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:42 INFO - Copying logs to upload dir... 06:14:42 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.393384 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-31 06:14:42.109635) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 06:14:42.112679) ========= 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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb22930499af49a61ef986fc3061382f71cde473ef25d764c47bc9265c56b6eec63230c97820dc4199f1201bbe9b5c0fca82f2bd1356a3bcc68a6fc0ba65abdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f89e4a912472334a00a77368f380c4f86403cb98d342222f2095c0219f47551cd24cb3cac566623228b0f657531254a9d829970e8a21d39ab129becd1324b3b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/482ead592b6f2372c88dfea2f27d66946a6a78524ebdd86f42a179d626dc3e626d92243657531a60d720248737fc3308cc43da7aecd0ff8111f4684a16cfe608"} build_url:https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/ABjYqLisSMOmPPg7vqy1JA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb22930499af49a61ef986fc3061382f71cde473ef25d764c47bc9265c56b6eec63230c97820dc4199f1201bbe9b5c0fca82f2bd1356a3bcc68a6fc0ba65abdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f89e4a912472334a00a77368f380c4f86403cb98d342222f2095c0219f47551cd24cb3cac566623228b0f657531254a9d829970e8a21d39ab129becd1324b3b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/482ead592b6f2372c88dfea2f27d66946a6a78524ebdd86f42a179d626dc3e626d92243657531a60d720248737fc3308cc43da7aecd0ff8111f4684a16cfe608"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 06:14:42.137067) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 06:14:42.137361) ========= 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=41a7e32711b8e08598a63237000001d6-1477911648.473426-73119867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 06:14:42.222827) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 06:14:42.223130) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 06:14:42.223395) ========= ========= Total master_lag: 0.17 =========