builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1476463849.67 results: success (0) buildid: 20161014065635 builduid: 4c388e5197cf47b4905a763d368ee8be revision: 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.668459) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.668983) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.669308) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.684184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.684494) ========= 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-1476463848.977673-633397503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-14 09:50:49-- 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% 46.6M=0s 2016-10-14 09:50:49 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128412 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.829141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.829924) ========= 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-1476463848.977673-633397503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019370 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:49.883909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 09:50:49.884784) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f --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 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f --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-1476463848.977673-633397503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-14 09:50:49,998 truncating revision to first 12 chars 2016-10-14 09:50:49,998 Setting DEBUG logging. 2016-10-14 09:50:49,998 attempt 1/10 2016-10-14 09:50:49,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4654bfb375e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 09:50:51,196 unpacking tar archive at: mozilla-beta-4654bfb375e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.503457 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 09:50:51.403292) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:51.403671) ========= script_repo_revision: 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:51.404104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:51.404405) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 09:50:51.414356) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-14 09:50:51.414725) ========= /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-1476463848.977673-633397503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:50:51 INFO - MultiFileLogger online at 20161014 09:50:51 in /builds/slave/test 09:50:51 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 09:50:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:50:51 INFO - {'append_to_log': False, 09:50:51 INFO - 'base_work_dir': '/builds/slave/test', 09:50:51 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:50:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:50:51 INFO - 'branch': 'Mozilla-Beta', 09:50:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:50:51 INFO - 'config_files': ('talos/linux_config.py',), 09:50:51 INFO - 'default_actions': ('clobber', 09:50:51 INFO - 'read-buildbot-config', 09:50:51 INFO - 'download-and-extract', 09:50:51 INFO - 'populate-webroot', 09:50:51 INFO - 'create-virtualenv', 09:50:51 INFO - 'install', 09:50:51 INFO - 'run-tests'), 09:50:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:50:51 INFO - 'download_minidump_stackwalk': True, 09:50:51 INFO - 'download_symbols': 'ondemand', 09:50:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:50:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:50:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:50:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:50:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:50:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:50:51 INFO - 'installer_path': 'installer.exe', 09:50:51 INFO - 'log_level': 'info', 09:50:51 INFO - 'log_name': 'talos', 09:50:51 INFO - 'log_to_console': True, 09:50:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:50:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:50:51 INFO - 'opt_config_files': (), 09:50:51 INFO - 'pip_index': False, 09:50:51 INFO - 'sps_profile': False, 09:50:51 INFO - 'sps_profile_interval': 0, 09:50:51 INFO - 'suite': 'dromaeojs', 09:50:51 INFO - 'system_bits': '32', 09:50:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:50:51 INFO - 'title': 'talos-linux64-ix-024', 09:50:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:50:51 INFO - 'use_talos_json': True, 09:50:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:50:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:50:51 INFO - 'work_dir': 'build'} 09:50:51 INFO - [mozharness: 2016-10-14 16:50:51.628123Z] Running clobber step. 09:50:51 INFO - Running pre-action listener: _resource_record_pre_action 09:50:51 INFO - Running main action method: clobber 09:50:51 INFO - rmtree: /builds/slave/test/build 09:50:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:50:52 INFO - Running post-action listener: _resource_record_post_action 09:50:52 INFO - [mozharness: 2016-10-14 16:50:52.086955Z] Finished clobber step (success) 09:50:52 INFO - [mozharness: 2016-10-14 16:50:52.087056Z] Running read-buildbot-config step. 09:50:52 INFO - Running pre-action listener: _resource_record_pre_action 09:50:52 INFO - Running main action method: read_buildbot_config 09:50:52 INFO - Using buildbot properties: 09:50:52 INFO - { 09:50:52 INFO - "project": "", 09:50:52 INFO - "product": "firefox", 09:50:52 INFO - "script_repo_revision": "production", 09:50:52 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:50:52 INFO - "repository": "", 09:50:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:50:52 INFO - "buildid": "20161014065635", 09:50:52 INFO - "pgo_build": "True", 09:50:52 INFO - "basedir": "/builds/slave/test", 09:50:52 INFO - "buildnumber": 422, 09:50:52 INFO - "slavename": "talos-linux64-ix-024", 09:50:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:50:52 INFO - "platform": "ubuntu64_hw", 09:50:52 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:50:52 INFO - "branch": "mozilla-beta", 09:50:52 INFO - "repo_path": "releases/mozilla-beta", 09:50:52 INFO - "revision": "4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f", 09:50:52 INFO - "stage_platform": "linux64-pgo", 09:50:52 INFO - "builduid": "4c388e5197cf47b4905a763d368ee8be", 09:50:52 INFO - "slavebuilddir": "test" 09:50:52 INFO - } 09:50:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 09:50:52 INFO - Running post-action listener: _resource_record_post_action 09:50:52 INFO - [mozharness: 2016-10-14 16:50:52.089484Z] Finished read-buildbot-config step (success) 09:50:52 INFO - [mozharness: 2016-10-14 16:50:52.089582Z] Running download-and-extract step. 09:50:52 INFO - Running pre-action listener: _resource_record_pre_action 09:50:52 INFO - Running main action method: download_and_extract 09:50:52 INFO - mkdir: /builds/slave/test/build/tests 09:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:52 INFO - https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:50:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:50:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:50:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:50:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:50:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:50:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:50:52 INFO - retry: Failed, sleeping 30 seconds before retrying 09:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:51:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:51:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:51:22 INFO - retry: Failed, sleeping 60 seconds before retrying 09:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:52:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:52:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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! 09:52:22 INFO - Caught exception: HTTP Error 404: Not Found 09:52:22 INFO - Caught exception: HTTP Error 404: Not Found 09:52:22 INFO - Caught exception: HTTP Error 404: Not Found 09:52:22 INFO - trying https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:52:22 INFO - Downloading https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:22 INFO - Downloaded 1327 bytes. 09:52:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:52:22 INFO - Using the following test package requirements: 09:52:22 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:52:22 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:52:22 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 09:52:22 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'jsshell-linux-x86_64.zip'], 09:52:22 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:52:22 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:52:22 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 09:52:22 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 09:52:22 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:52:22 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:52:22 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:52:22 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:52:22 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')]} 09:52:22 INFO - https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:52:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:52:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:52:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:52:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:52:22 INFO - retry: Failed, sleeping 30 seconds before retrying 09:52:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:52:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:52:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:52:52 INFO - retry: Failed, sleeping 60 seconds before retrying 09:53:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:53:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:53:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:53:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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! 09:53:52 INFO - Caught exception: HTTP Error 404: Not Found 09:53:52 INFO - Caught exception: HTTP Error 404: Not Found 09:53:52 INFO - Caught exception: HTTP Error 404: Not Found 09:53:52 INFO - trying https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:53:52 INFO - Downloading https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:53:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:53:53 INFO - Downloaded 21773959 bytes. 09:53:53 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'] 09:53:53 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 09:53:54 INFO - Return code: 0 09:53:54 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 09:53:54 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')]} 09:53:54 INFO - https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:53:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:53:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:53:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:53:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:53:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:53:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:53:54 INFO - retry: Failed, sleeping 30 seconds before retrying 09:54:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:54:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:54:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:54:25 INFO - retry: Failed, sleeping 60 seconds before retrying 09:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:55:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:55:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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! 09:55:25 INFO - Caught exception: HTTP Error 404: Not Found 09:55:25 INFO - Caught exception: HTTP Error 404: Not Found 09:55:25 INFO - Caught exception: HTTP Error 404: Not Found 09:55:25 INFO - trying https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:55:25 INFO - Downloading https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:25 INFO - Downloaded 21773959 bytes. 09:55:25 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'] 09:55:25 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 09:55:27 INFO - Return code: 0 09:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:27 INFO - https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:55:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:55:27 INFO - retry: Failed, sleeping 30 seconds before retrying 09:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:55:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:55:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:55:57 INFO - retry: Failed, sleeping 60 seconds before retrying 09:56:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:56:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:56:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:56:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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! 09:56:57 INFO - Caught exception: HTTP Error 404: Not Found 09:56:57 INFO - Caught exception: HTTP Error 404: Not Found 09:56:57 INFO - Caught exception: HTTP Error 404: Not Found 09:56:57 INFO - trying https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:56:57 INFO - Downloading https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:56:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:56:58 INFO - Downloaded 14413762 bytes. 09:56:58 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'] 09:56:58 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 09:56:58 INFO - Return code: 0 09:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:58 INFO - https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:56:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:56:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:56:58 INFO - retry: Failed, sleeping 30 seconds before retrying 09:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:57:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:57:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:57:28 INFO - retry: Failed, sleeping 60 seconds before retrying 09:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:58:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:58:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:58:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PdcepqZtR8mUTulnMyj8FQ/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! 09:58:28 INFO - Caught exception: HTTP Error 404: Not Found 09:58:28 INFO - Caught exception: HTTP Error 404: Not Found 09:58:28 INFO - Caught exception: HTTP Error 404: Not Found 09:58:28 INFO - trying https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:58:28 INFO - Downloading https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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 09:58:29 INFO - Downloaded 56800983 bytes. 09:58:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:58:29 INFO - mkdir: /builds/slave/test/properties 09:58:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:29 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:29 INFO - Contents: 09:58:29 INFO - build_url:https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:58:30 INFO - Running post-action listener: _resource_record_post_action 09:58:30 INFO - Running post-action listener: set_extra_try_arguments 09:58:30 INFO - [mozharness: 2016-10-14 16:58:30.326028Z] Finished download-and-extract step (success) 09:58:30 INFO - [mozharness: 2016-10-14 16:58:30.326259Z] Running populate-webroot step. 09:58:30 INFO - Running pre-action listener: _resource_record_pre_action 09:58:30 INFO - Running main action method: populate_webroot 09:58:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:30 INFO - '--tppagecycles', 09:58:30 INFO - '7'], 09:58:30 INFO - 'tests': ['tsvgm']}}, 09:58:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['damp', 'tps']}, 09:58:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'tests': ['damp', 'tps']}, 09:58:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['dromaeo_dom']}, 09:58:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['basic_compositor_video']}, 09:58:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['a11yr', 09:58:30 INFO - 'ts_paint', 09:58:30 INFO - 'tpaint', 09:58:30 INFO - 'sessionrestore', 09:58:30 INFO - 'sessionrestore_no_auto_restore', 09:58:30 INFO - 'tabpaint']}, 09:58:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['tsvgx', 09:58:30 INFO - 'tsvgr_opacity', 09:58:30 INFO - 'tart', 09:58:30 INFO - 'tscrollx', 09:58:30 INFO - 'cart']}, 09:58:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:30 INFO - 'tsvgr_opacity', 09:58:30 INFO - 'tart', 09:58:30 INFO - 'tscrollx', 09:58:30 INFO - 'cart']}, 09:58:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'talos_options': ['--disable-e10s'], 09:58:30 INFO - 'tests': ['tp5o']}, 09:58:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'tests': ['tp5o']}, 09:58:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'talos_options': ['--disable-e10s', 09:58:30 INFO - '--xperf_path', 09:58:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:30 INFO - 'tests': ['tp5n']}, 09:58:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:30 INFO - 'talos_options': ['--xperf_path', 09:58:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:30 INFO - 'tests': ['tp5n']}}, 09:58:30 INFO - 'talos.zip': {'path': '', 09:58:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:30 INFO - Running post-action listener: _resource_record_post_action 09:58:30 INFO - [mozharness: 2016-10-14 16:58:30.341933Z] Finished populate-webroot step (success) 09:58:30 INFO - [mozharness: 2016-10-14 16:58:30.342022Z] Running create-virtualenv step. 09:58:30 INFO - Running pre-action listener: _resource_record_pre_action 09:58:30 INFO - Running main action method: create_virtualenv 09:58:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:30 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 09:58:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:30 INFO - Using real prefix '/usr' 09:58:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:32 INFO - Installing pip.................done. 09:58:32 INFO - Return code: 0 09:58:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:32 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:32 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 09:58:32 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 09:58:32 INFO - Using env: {'DISPLAY': ':0', 09:58:32 INFO - 'HOME': '/home/cltbld', 09:58:32 INFO - 'LANG': 'en_US.UTF-8', 09:58:32 INFO - 'LANGUAGE': 'en_US:en', 09:58:32 INFO - 'LOGNAME': 'cltbld', 09:58:32 INFO - 'MAIL': '/var/mail/cltbld', 09:58:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:32 INFO - 'MOZ_NO_REMOTE': '1', 09:58:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:32 INFO - 'NO_EM_RESTART': '1', 09:58:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:32 INFO - 'PWD': '/builds/slave/test', 09:58:32 INFO - 'SHELL': '/bin/bash', 09:58:32 INFO - 'SHLVL': '1', 09:58:32 INFO - 'TERM': 'linux', 09:58:32 INFO - 'TMOUT': '86400', 09:58:32 INFO - 'USER': 'cltbld', 09:58:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:32 INFO - '_': '/tools/buildbot/bin/python'} 09:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:33 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 09:58:33 INFO - Cleaning up... 09:58:33 INFO - Return code: 0 09:58:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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', '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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:58: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 psutil>=3.1.1 09:58:33 INFO - Using env: {'DISPLAY': ':0', 09:58:33 INFO - 'HOME': '/home/cltbld', 09:58:33 INFO - 'LANG': 'en_US.UTF-8', 09:58:33 INFO - 'LANGUAGE': 'en_US:en', 09:58:33 INFO - 'LOGNAME': 'cltbld', 09:58:33 INFO - 'MAIL': '/var/mail/cltbld', 09:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:33 INFO - 'MOZ_NO_REMOTE': '1', 09:58:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:33 INFO - 'NO_EM_RESTART': '1', 09:58:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:33 INFO - 'PWD': '/builds/slave/test', 09:58:33 INFO - 'SHELL': '/bin/bash', 09:58:33 INFO - 'SHLVL': '1', 09:58:33 INFO - 'TERM': 'linux', 09:58:33 INFO - 'TMOUT': '86400', 09:58:33 INFO - 'USER': 'cltbld', 09:58:33 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:33 INFO - '_': '/tools/buildbot/bin/python'} 09:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:33 INFO - Downloading/unpacking psutil>=3.1.1 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:35 INFO - Installing collected packages: psutil 09:58:35 INFO - Running setup.py install for psutil 09:58:35 INFO - building 'psutil._psutil_linux' extension 09:58:35 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 09:58:35 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 09:58:35 INFO - building 'psutil._psutil_posix' extension 09:58:35 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 09:58:35 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 09:58:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:35 INFO - Successfully installed psutil 09:58:35 INFO - Cleaning up... 09:58:35 INFO - Return code: 0 09:58:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58:35 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')]} 09:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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')]} 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:35 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 09:58:35 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 09:58:35 INFO - Using env: {'DISPLAY': ':0', 09:58:35 INFO - 'HOME': '/home/cltbld', 09:58:35 INFO - 'LANG': 'en_US.UTF-8', 09:58:35 INFO - 'LANGUAGE': 'en_US:en', 09:58:35 INFO - 'LOGNAME': 'cltbld', 09:58:35 INFO - 'MAIL': '/var/mail/cltbld', 09:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:35 INFO - 'MOZ_NO_REMOTE': '1', 09:58:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:35 INFO - 'NO_EM_RESTART': '1', 09:58:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:35 INFO - 'PWD': '/builds/slave/test', 09:58:35 INFO - 'SHELL': '/bin/bash', 09:58:35 INFO - 'SHLVL': '1', 09:58:35 INFO - 'TERM': 'linux', 09:58:35 INFO - 'TMOUT': '86400', 09:58:35 INFO - 'USER': 'cltbld', 09:58:35 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:35 INFO - '_': '/tools/buildbot/bin/python'} 09:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:38 INFO - Installing collected packages: mozsystemmonitor 09:58:38 INFO - Running setup.py install for mozsystemmonitor 09:58:38 INFO - Successfully installed mozsystemmonitor 09:58:38 INFO - Cleaning up... 09:58:38 INFO - Return code: 0 09:58:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58:38 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')]} 09:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:38 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')]} 09:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:38 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:38 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 09:58:38 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 09:58:38 INFO - Using env: {'DISPLAY': ':0', 09:58:38 INFO - 'HOME': '/home/cltbld', 09:58:38 INFO - 'LANG': 'en_US.UTF-8', 09:58:38 INFO - 'LANGUAGE': 'en_US:en', 09:58:38 INFO - 'LOGNAME': 'cltbld', 09:58:38 INFO - 'MAIL': '/var/mail/cltbld', 09:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:38 INFO - 'MOZ_NO_REMOTE': '1', 09:58:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:38 INFO - 'NO_EM_RESTART': '1', 09:58:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:38 INFO - 'PWD': '/builds/slave/test', 09:58:38 INFO - 'SHELL': '/bin/bash', 09:58:38 INFO - 'SHLVL': '1', 09:58:38 INFO - 'TERM': 'linux', 09:58:38 INFO - 'TMOUT': '86400', 09:58:38 INFO - 'USER': 'cltbld', 09:58:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:38 INFO - '_': '/tools/buildbot/bin/python'} 09:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:40 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 09:58:40 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 09:58:40 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 09:58:40 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 09:58:40 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 09:58:40 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 09:58:40 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:40 INFO - warning: no files found matching '*.txt' 09:58:40 INFO - no previously-included directories found matching 'build' 09:58:40 INFO - no previously-included directories found matching 'dist' 09:58:40 INFO - no previously-included directories found matching '.git*' 09:58:40 INFO - Installing collected packages: jsonschema, functools32 09:58:40 INFO - Running setup.py install for functools32 09:58:40 INFO - warning: no files found matching '*.txt' 09:58:40 INFO - no previously-included directories found matching 'build' 09:58:40 INFO - no previously-included directories found matching 'dist' 09:58:40 INFO - no previously-included directories found matching '.git*' 09:58:40 INFO - Successfully installed jsonschema functools32 09:58:40 INFO - Cleaning up... 09:58:40 INFO - Return code: 0 09:58:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:40 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:40 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 09:58:40 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 09:58:40 INFO - Using env: {'DISPLAY': ':0', 09:58:40 INFO - 'HOME': '/home/cltbld', 09:58:40 INFO - 'LANG': 'en_US.UTF-8', 09:58:40 INFO - 'LANGUAGE': 'en_US:en', 09:58:40 INFO - 'LOGNAME': 'cltbld', 09:58:40 INFO - 'MAIL': '/var/mail/cltbld', 09:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:40 INFO - 'MOZ_NO_REMOTE': '1', 09:58:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:40 INFO - 'NO_EM_RESTART': '1', 09:58:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:40 INFO - 'PWD': '/builds/slave/test', 09:58:40 INFO - 'SHELL': '/bin/bash', 09:58:40 INFO - 'SHLVL': '1', 09:58:40 INFO - 'TERM': 'linux', 09:58:40 INFO - 'TMOUT': '86400', 09:58:40 INFO - 'USER': 'cltbld', 09:58:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:40 INFO - '_': '/tools/buildbot/bin/python'} 09:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:41 INFO - Cleaning up... 09:58:41 INFO - Return code: 0 09:58:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:41 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:41 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 09:58:41 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 09:58:41 INFO - Using env: {'DISPLAY': ':0', 09:58:41 INFO - 'HOME': '/home/cltbld', 09:58:41 INFO - 'LANG': 'en_US.UTF-8', 09:58:41 INFO - 'LANGUAGE': 'en_US:en', 09:58:41 INFO - 'LOGNAME': 'cltbld', 09:58:41 INFO - 'MAIL': '/var/mail/cltbld', 09:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:41 INFO - 'MOZ_NO_REMOTE': '1', 09:58:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:41 INFO - 'NO_EM_RESTART': '1', 09:58:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:41 INFO - 'PWD': '/builds/slave/test', 09:58:41 INFO - 'SHELL': '/bin/bash', 09:58:41 INFO - 'SHLVL': '1', 09:58:41 INFO - 'TERM': 'linux', 09:58:41 INFO - 'TMOUT': '86400', 09:58:41 INFO - 'USER': 'cltbld', 09:58:41 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:41 INFO - '_': '/tools/buildbot/bin/python'} 09:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:41 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:41 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 09:58:41 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 09:58:41 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 09:58:41 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 09:58:41 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 09:58:41 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 09:58:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:43 INFO - Downloading docopt-0.6.1.tar.gz 09:58:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:43 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:43 INFO - Running setup.py install for blobuploader 09:58:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:44 INFO - Running setup.py install for requests 09:58:44 INFO - Running setup.py install for docopt 09:58:44 INFO - Successfully installed blobuploader requests docopt 09:58:44 INFO - Cleaning up... 09:58:44 INFO - Return code: 0 09:58:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:44 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')]} 09:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 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')]} 09:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:44 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 09:58:44 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 09:58:44 INFO - Using env: {'DISPLAY': ':0', 09:58:44 INFO - 'HOME': '/home/cltbld', 09:58:44 INFO - 'LANG': 'en_US.UTF-8', 09:58:44 INFO - 'LANGUAGE': 'en_US:en', 09:58:44 INFO - 'LOGNAME': 'cltbld', 09:58:44 INFO - 'MAIL': '/var/mail/cltbld', 09:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:44 INFO - 'MOZ_NO_REMOTE': '1', 09:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:44 INFO - 'NO_EM_RESTART': '1', 09:58:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:44 INFO - 'PWD': '/builds/slave/test', 09:58:44 INFO - 'SHELL': '/bin/bash', 09:58:44 INFO - 'SHLVL': '1', 09:58:44 INFO - 'TERM': 'linux', 09:58:44 INFO - 'TMOUT': '86400', 09:58:44 INFO - 'USER': 'cltbld', 09:58:44 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:44 INFO - '_': '/tools/buildbot/bin/python'} 09:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:44 INFO - Running setup.py (path:/tmp/pip-yRuBxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:44 INFO - Running setup.py (path:/tmp/pip-0w5Sno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:44 INFO - Running setup.py (path:/tmp/pip-ubGzRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:44 INFO - Running setup.py (path:/tmp/pip-M0ku3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:45 INFO - Running setup.py (path:/tmp/pip-RVmZ9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:45 INFO - Running setup.py (path:/tmp/pip-GAUZ2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:45 INFO - Running setup.py (path:/tmp/pip-mQjImK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:45 INFO - Running setup.py (path:/tmp/pip-1NwywA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:45 INFO - Running setup.py (path:/tmp/pip-YzdLKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:45 INFO - Running setup.py (path:/tmp/pip-cJaecm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:45 INFO - Running setup.py (path:/tmp/pip-aU2L9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:45 INFO - Running setup.py (path:/tmp/pip-nnqYDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:45 INFO - Running setup.py (path:/tmp/pip-UI7UX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:45 INFO - Running setup.py (path:/tmp/pip-crP4BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:45 INFO - Running setup.py (path:/tmp/pip-0ZBBow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:45 INFO - Running setup.py (path:/tmp/pip-ERziTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:45 INFO - Running setup.py (path:/tmp/pip-ZtPwI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:46 INFO - Running setup.py install for manifestparser 09:58:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Running setup.py install for mozcrash 09:58:46 INFO - Running setup.py install for mozdebug 09:58:46 INFO - Running setup.py install for mozdevice 09:58:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Running setup.py install for mozfile 09:58:46 INFO - Running setup.py install for mozhttpd 09:58:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Running setup.py install for mozinfo 09:58:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Running setup.py install for mozInstall 09:58:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:46 INFO - Running setup.py install for mozleak 09:58:46 INFO - Running setup.py install for mozlog 09:58:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Running setup.py install for moznetwork 09:58:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Running setup.py install for mozprocess 09:58:47 INFO - Running setup.py install for mozprofile 09:58:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Running setup.py install for mozrunner 09:58:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Running setup.py install for mozscreenshot 09:58:47 INFO - Running setup.py install for moztest 09:58:47 INFO - Running setup.py install for mozversion 09:58:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:47 INFO - Cleaning up... 09:58:47 INFO - Return code: 0 09:58:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:47 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:47 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 09:58:47 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 09:58:47 INFO - Using env: {'DISPLAY': ':0', 09:58:47 INFO - 'HOME': '/home/cltbld', 09:58:47 INFO - 'LANG': 'en_US.UTF-8', 09:58:47 INFO - 'LANGUAGE': 'en_US:en', 09:58:47 INFO - 'LOGNAME': 'cltbld', 09:58:47 INFO - 'MAIL': '/var/mail/cltbld', 09:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:47 INFO - 'MOZ_NO_REMOTE': '1', 09:58:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:47 INFO - 'NO_EM_RESTART': '1', 09:58:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:47 INFO - 'PWD': '/builds/slave/test', 09:58:47 INFO - 'SHELL': '/bin/bash', 09:58:47 INFO - 'SHLVL': '1', 09:58:47 INFO - 'TERM': 'linux', 09:58:47 INFO - 'TMOUT': '86400', 09:58:47 INFO - 'USER': 'cltbld', 09:58:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:47 INFO - '_': '/tools/buildbot/bin/python'} 09:58:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:48 INFO - Running setup.py (path:/tmp/pip-7KYGFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:48 INFO - Running setup.py (path:/tmp/pip-O7liUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:48 INFO - Running setup.py (path:/tmp/pip-XyzWT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:48 INFO - Running setup.py (path:/tmp/pip-_5MryT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:48 INFO - Running setup.py (path:/tmp/pip-fofmZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:48 INFO - Running setup.py (path:/tmp/pip-DimEM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:48 INFO - Running setup.py (path:/tmp/pip-7v8QaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:48 INFO - Running setup.py (path:/tmp/pip-vE143M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:48 INFO - Running setup.py (path:/tmp/pip-8yW8jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:48 INFO - Running setup.py (path:/tmp/pip-WsKz5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:48 INFO - Running setup.py (path:/tmp/pip-4M_NBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:48 INFO - Running setup.py (path:/tmp/pip-OilGBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:48 INFO - Running setup.py (path:/tmp/pip-scA8ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:48 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)) 09:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:48 INFO - Running setup.py (path:/tmp/pip-2sATA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:49 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)) 09:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:49 INFO - Running setup.py (path:/tmp/pip-cevDxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:49 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)) 09:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:49 INFO - Running setup.py (path:/tmp/pip-FdAVeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:49 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)) 09:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:49 INFO - Running setup.py (path:/tmp/pip-oXFjdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:49 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)) 09:58:49 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)) 09:58:49 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)) 09:58:49 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)) 09:58:49 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)) 09:58:49 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)) 09:58:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:51 INFO - Downloading blessings-1.6.tar.gz 09:58:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:51 INFO - Installing collected packages: blessings 09:58:51 INFO - Running setup.py install for blessings 09:58:51 INFO - Successfully installed blessings 09:58:51 INFO - Cleaning up... 09:58:51 INFO - Return code: 0 09:58:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:51 INFO - Reading from file tmpfile_stdout 09:58:51 INFO - Current package versions: 09:58:51 INFO - argparse == 1.2.1 09:58:51 INFO - blessings == 1.6 09:58:51 INFO - blobuploader == 1.2.4 09:58:51 INFO - docopt == 0.6.1 09:58:51 INFO - functools32 == 3.2.3-2 09:58:51 INFO - jsonschema == 2.5.1 09:58:51 INFO - manifestparser == 1.1 09:58:51 INFO - mozInstall == 1.12 09:58:51 INFO - mozcrash == 0.17 09:58:51 INFO - mozdebug == 0.1 09:58:51 INFO - mozdevice == 0.48 09:58:51 INFO - mozfile == 1.2 09:58:51 INFO - mozhttpd == 0.7 09:58:51 INFO - mozinfo == 0.9 09:58:51 INFO - mozleak == 0.1 09:58:51 INFO - mozlog == 3.3 09:58:51 INFO - moznetwork == 0.27 09:58:51 INFO - mozprocess == 0.23 09:58:51 INFO - mozprofile == 0.28 09:58:51 INFO - mozrunner == 6.12 09:58:51 INFO - mozscreenshot == 0.1 09:58:51 INFO - mozsystemmonitor == 0.3 09:58:51 INFO - moztest == 0.7 09:58:51 INFO - mozversion == 1.4 09:58:51 INFO - psutil == 3.1.1 09:58:51 INFO - requests == 1.2.3 09:58:51 INFO - wsgiref == 0.1.2 09:58:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:51 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 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:51 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 09:58:51 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 09:58:51 INFO - Using env: {'DISPLAY': ':0', 09:58:51 INFO - 'HOME': '/home/cltbld', 09:58:51 INFO - 'LANG': 'en_US.UTF-8', 09:58:51 INFO - 'LANGUAGE': 'en_US:en', 09:58:51 INFO - 'LOGNAME': 'cltbld', 09:58:51 INFO - 'MAIL': '/var/mail/cltbld', 09:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:51 INFO - 'MOZ_NO_REMOTE': '1', 09:58:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:51 INFO - 'NO_EM_RESTART': '1', 09:58:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:51 INFO - 'PWD': '/builds/slave/test', 09:58:51 INFO - 'SHELL': '/bin/bash', 09:58:51 INFO - 'SHLVL': '1', 09:58:51 INFO - 'TERM': 'linux', 09:58:51 INFO - 'TMOUT': '86400', 09:58:51 INFO - 'USER': 'cltbld', 09:58:51 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:51 INFO - '_': '/tools/buildbot/bin/python'} 09:58:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 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)) 09:58:51 INFO - Cleaning up... 09:58:51 INFO - Return code: 0 09:58:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, '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-1476463848.977673-633397503', '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 09:58:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:58:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:51 INFO - Using env: {'DISPLAY': ':0', 09:58:51 INFO - 'HOME': '/home/cltbld', 09:58:51 INFO - 'LANG': 'en_US.UTF-8', 09:58:51 INFO - 'LANGUAGE': 'en_US:en', 09:58:51 INFO - 'LOGNAME': 'cltbld', 09:58:51 INFO - 'MAIL': '/var/mail/cltbld', 09:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:51 INFO - 'MOZ_NO_REMOTE': '1', 09:58:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:51 INFO - 'NO_EM_RESTART': '1', 09:58:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:51 INFO - 'PWD': '/builds/slave/test', 09:58:51 INFO - 'SHELL': '/bin/bash', 09:58:51 INFO - 'SHLVL': '1', 09:58:51 INFO - 'TERM': 'linux', 09:58:51 INFO - 'TMOUT': '86400', 09:58:51 INFO - 'USER': 'cltbld', 09:58:51 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:51 INFO - '_': '/tools/buildbot/bin/python'} 09:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:52 INFO - Cleaning up... 09:58:52 INFO - Return code: 0 09:58:52 INFO - Running post-action listener: _resource_record_post_action 09:58:52 INFO - Running post-action listener: _start_resource_monitoring 09:58:52 INFO - Starting resource monitoring. 09:58:52 INFO - [mozharness: 2016-10-14 16:58:52.090173Z] Finished create-virtualenv step (success) 09:58:52 INFO - [mozharness: 2016-10-14 16:58:52.090607Z] Running install step. 09:58:52 INFO - Running pre-action listener: _resource_record_pre_action 09:58:52 INFO - Running main action method: install 09:58:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:52 INFO - Reading from file tmpfile_stdout 09:58:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:52 INFO - Reading from file tmpfile_stdout 09:58:52 INFO - Output received: 09:58:52 INFO - Usage: mozinstall [options] installer 09:58:52 INFO - Options: 09:58:52 INFO - -h, --help show this help message and exit 09:58:52 INFO - -d DEST, --destination=DEST 09:58:52 INFO - Directory to install application into. [default: 09:58:52 INFO - "/builds/slave/test"] 09:58:52 INFO - --app=APP Application being installed. [default: firefox] 09:58:52 INFO - mkdir: /builds/slave/test/build/application 09:58:52 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'] 09:58:52 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 09:59:06 INFO - Reading from file tmpfile_stdout 09:59:06 INFO - Output received: 09:59:06 INFO - /builds/slave/test/build/application/firefox/firefox 09:59:06 INFO - Running post-action listener: _resource_record_post_action 09:59:06 INFO - [mozharness: 2016-10-14 16:59:06.312346Z] Finished install step (success) 09:59:06 INFO - [mozharness: 2016-10-14 16:59:06.312627Z] Running run-tests step. 09:59:06 INFO - Running pre-action listener: _resource_record_pre_action 09:59:06 INFO - Running main action method: run_tests 09:59:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:59:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:59:06 INFO - Python 2.7.3 09:59:06 INFO - Return code: 0 09:59:06 INFO - grabbing minidump binary from tooltool 09:59:06 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')]} 09:59:06 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 0x1bee3b0>, '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 0x1bef2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:59:06 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 09:59:06 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 09:59:06 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 09:59:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:59:06 INFO - Return code: 0 09:59:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:59:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:59:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:59:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:59:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:59:06 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/PdcepqZtR8mUTulnMyj8FQ/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 09:59:06 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/PdcepqZtR8mUTulnMyj8FQ/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 09:59:06 INFO - Using env: {'DISPLAY': ':0', 09:59:06 INFO - 'HOME': '/home/cltbld', 09:59:06 INFO - 'LANG': 'en_US.UTF-8', 09:59:06 INFO - 'LANGUAGE': 'en_US:en', 09:59:06 INFO - 'LOGNAME': 'cltbld', 09:59:06 INFO - 'MAIL': '/var/mail/cltbld', 09:59:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:59:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:06 INFO - 'MOZ_NO_REMOTE': '1', 09:59:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:59:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:06 INFO - 'NO_EM_RESTART': '1', 09:59:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:06 INFO - 'PWD': '/builds/slave/test', 09:59:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:59:06 INFO - 'SHELL': '/bin/bash', 09:59:06 INFO - 'SHLVL': '1', 09:59:06 INFO - 'TERM': 'linux', 09:59:06 INFO - 'TMOUT': '86400', 09:59:06 INFO - 'USER': 'cltbld', 09:59:06 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1476463848.977673-633397503', 09:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:06 INFO - '_': '/tools/buildbot/bin/python'} 09:59:06 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/PdcepqZtR8mUTulnMyj8FQ/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 09:59:06 INFO - mozversion application_buildid: 20161014065635 09:59:06 INFO - mozversion application_changeset: 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f 09:59:06 INFO - mozversion application_display_name: Firefox 09:59:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:59:06 INFO - mozversion application_name: Firefox 09:59:06 INFO - mozversion application_remotingname: firefox 09:59:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:59:06 INFO - mozversion application_vendor: Mozilla 09:59:06 INFO - mozversion application_version: 50.0 09:59:06 INFO - mozversion platform_buildid: 20161014065635 09:59:06 INFO - mozversion platform_changeset: 4654bfb375e4b83bf3f09a6afa797b4bb5b9fa3f 09:59:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:59:06 INFO - mozversion platform_version: 50.0 09:59:06 INFO - using testdate: 1476464346 09:59:06 INFO - actual date: 1476464346 09:59:06 INFO - starting webserver on 'localhost:58699' 09:59:06 INFO - SUITE-START | Running 2 tests 09:59:06 INFO - TEST-START | dromaeo_css 09:59:06 INFO - operating with platform_type : linux_ 09:59:06 INFO - Initialising browser for dromaeo_css test... 09:59:06 INFO - TEST-INFO | started process 4999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpttenBS/profile http://localhost:58699/getInfo.html) 09:59:10 INFO - PROCESS | 4999 | __metrics Screen width/height:1600/1200 09:59:10 INFO - PROCESS | 4999 | colorDepth:24 09:59:10 INFO - PROCESS | 4999 | Browser inner width/height: 1024/697 09:59:10 INFO - PROCESS | 4999 | __metrics 09:59:11 INFO - PROCESS | 4999 | 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" 09:59:11 INFO - PROCESS | 4999 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:12 INFO - PROCESS | 4999 | console.error: PushService: 09:59:12 INFO - PROCESS | 4999 | stateChangeProcessEnqueue: Error transitioning state 09:59:12 INFO - PROCESS | 4999 | UnknownError 09:59:12 INFO - TEST-INFO | 4999: exit 0 09:59:12 INFO - Browser initialized. 09:59:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:12 INFO - TEST-INFO | started process 5090 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpttenBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:24 INFO - PROCESS | 5090 | RSS: Main: 144486400 09:59:24 INFO - PROCESS | 5090 | 10:00:15 INFO - PROCESS | 5090 | 1476464415171 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:02:24 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58699/tests/dromaeo/cssquery-ext.html) 10:02:25 INFO - PROCESS | 5090 | RSS: Main: 262680576 10:02:25 INFO - PROCESS | 5090 | 10:05:27 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-ext.html (next: http://localhost:58699/tests/dromaeo/cssquery-jquery.html) 10:05:28 INFO - PROCESS | 5090 | RSS: Main: 232701952 10:05:28 INFO - PROCESS | 5090 | 10:08:30 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58699/tests/dromaeo/cssquery-mootools.html) 10:08:30 INFO - PROCESS | 5090 | RSS: Main: 175050752 10:08:30 INFO - PROCESS | 5090 | 10:11:32 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58699/tests/dromaeo/cssquery-prototype.html) 10:11:33 INFO - PROCESS | 5090 | RSS: Main: 201363456 10:11:33 INFO - PROCESS | 5090 | 10:14:35 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58699/tests/dromaeo/cssquery-yui.html) 10:14:36 INFO - PROCESS | 5090 | RSS: Main: 177545216 10:14:36 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | Cycle 1(1): loaded http://localhost:58699/tests/dromaeo/cssquery-yui.html (next: http://localhost:58699/tests/dromaeo/cssquery-dojo.html) 10:17:38 INFO - PROCESS | 5090 | RSS: Main: 259215360 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | __start_tp_report 10:17:38 INFO - PROCESS | 5090 | _x_x_mozilla_page_load 10:17:38 INFO - PROCESS | 5090 | _x_x_mozilla_page_load_details 10:17:38 INFO - PROCESS | 5090 | |i|pagename|runs| 10:17:38 INFO - PROCESS | 5090 | |0;dojo.html;3174.8253846038474;3175.0474857542736;3217.308278720076;3240;3255.658155893631;5622.578306626999;5639.718014099294;5682.687452190133;5691.345495268046;5700.17347484615;5660.2075709400715;5670.0927851543765;5691.317041954961;5708.514776244025;5713.285839270091;5173.922391164127;5177.094008548508;5181.4300426953005;5242.108841496935;5246.842594722164;5036.899262014757;5101.923471147936;5248.239005344223;5252.028374750665;5288.127458969266;5112.616553758465;5131.794728210867;5134.845954621367;5162.328897243353;5185.740712964337;5158.896822063556;5199.376074871002;5283.260343551906;5286.60350473716;5312.62811603184;4978.278149668314;5094.668846525001;5168.664036837594;5223.138182199932;5225.634205605609;5515;5533.750981205855;5534.75093620788;5544.0575102232715;5576.553875689935;5966.910496342558;6010.579259451796;6063.727132279058;6073.149759033695;6074.605150665193;5960.672163031031;5993.460588547008;6083.087536869461;6089.665068421235;6090.8781824363705;5613.158026296048;5650.604457687922;5653.265075540236;5662.6036177467595;5666.575006874501;6198.938010619836;6214.471769899609;6229.719662615194;6230.470410015199;6232.376762323732;5730.5129064030025;5878.559108066912;5926.081457374114;5948.078047902582;5965.492933100648;6090.348809980474;6105.84735381619;6106;6107.175531303306;6113.902014424272;6061.484773794189;6064.06007068905;6065.848353791191;6067.45392914631;6096.146539484475;5785.016547186989;5853;6033.758649653964;6087.57386982907;6099.451049405575;4585.908281834345;4589.242774942164;4596.678232523758;4600.746958917225;4681.789319480631;4132.669386449077;4133.256013917524;4141.627253547195;4161.0845613964875;4161.126163505637;5537.197106419547;5777.2200752897825;5786.566007549451;5793.942060579425;5795.88408231833;5949.25634295713;6010.459058684739;6093.6039157454625;6135.324535308531;6139.277491147187;5782.421757824184;5784.855378615568;5785.681787501728;5789.334226563915;5814.970925145347;5115.7442127893455;5286.867828304323;5289.259503669489;5291.470852914755;5294.12646913255;6211.875762484725;6242.781502647385;6245.0632405139595;6302.8424289392215;6344.6827658616885;6028.2766068072115;6135.84660383494;6140.969295153495;6141.9078713818435;6149.96925015372;6095.634261944298;6312.210973628296;6350.872982540416;6354.93645063562;6379.425851673372;5924.7037648118285;5963.075723262901;5968.045112781934;6032.396760324108;6081.179040829608;6127.662978536223;6166.907496387468;6174.753009879734;6177.2587289525545;6180.6291622502795;5769.307683078057;5980.521558275241;6012.278526576664;6013.30846952606;6014.609050411709;5665.405132461032;5788.507976822018;5821.126830975219;5917.467427931507;5925.792597259076;5792.21805056312;5822.446867547576;5834.124881267844;5840.357560668408;5842.7078646066675;5734.942650573607;5832.883342333214;5858.765649374148;5876.676782776988;5895.793647222326;5673.092305231144;5717.056685646821;5741.598088133791;5797.710114494174;5829.446202610745 10:17:38 INFO - PROCESS | 5090 | |1;ext.html;590.6279044202151;633.3318349141655;642.4667525953456;643.7328508668901;655.9475241980642;34282.657173428255;36961.44557831635;37059.07352316193;37107;37107.628923710756;45657.31514027284;46611.30083048757;46737;51143.48856511434;51153.48846511534;9065.501397423139;9235.399699019576;9236.21492173166;9237.722868313927;9250.86123708145;8376.246137847593;8576.571171441434;9096.63613455461;9188.908110918905;9195.264378849675;11004.39475828833;11304.152188585847;11315.603953861617;11662.300261984308;11680.883191168065;11099.334039957588;11195;11195.496202670818;11201.159913006475;11201.775964480705;10877.510512026978;10942.398168100752;10945.890541094566;10957.945210273978;10966.670999869937;44811.77594112041;45076.3238551422;45110.32334514952;45126;45212;3728.5898551159353;3741.0834345585504;3877.2827027000094;3892.2604705106223;3902.0635047588657;33470;33471.497927531105;33839.32321353584;35209.64790352089;35274.82362588196;4336.891577710551;4444.866654000385;4489.907663207155;4493.146302202571;4535.863924082283;362820;364768;364833;365689;366870;35557.64442355543;60948.69525652432;61039.694801526595;61228;61243.693781530805;37698.81150594229;39370.606293937264;39468.407973880414;39474.605253947666;39506;15087.849121508863;15237.314320855588;15244.847551524343;15251.23743812805;15251.771223431657;11703.76592468163;11705.590304339306;11713.1800773945;11717.414129293502;11719.882801172049;8490.575471226413;8870.290376769843;9037.231835293942;9094.317926155565;9173;3453.304963688829;3499.9475007874903;3523.612402635708;3551.7158627309755;3557.768745031565;963.6386355116691;1017.5878769098379;1043.4052590023614;1080.378782200238;1202.555054629775;32805.83597081999;33096.1725956853;33148.50277245843;33267.334653306796;33503.497447538306;1187.827764974074;1189.5836457239898;1235.5675513570359;1235.7590269897285;1258.2450529682117;16757.49727508177;17240.65518689619;17373.565660858327;17525.7371139433;17561.473155805346;650706;652866;659573;660961;666840;42500.57499425028;42745;42825.357619635106;42848.78575607102;42932;1389.3817251323064;1435.318223843666;1448.2469116059801;1466.2668665667165;1475.8597933196545;30607.2348191297;31401.214969625944;31641.36717265596;31722.36555268928;32115;52355.476445236585;53057;53284;53330.73334633302;53352;529669;530181;531756;531931;533261;26718.732812671624;29095.12714618565;29169.854150729112;29891.402171956874;30273.848630757588;50260;50699;50788.23817642812;50949.23576146435;50999;41897.16205675808;43449.3482597755;43726;44148.55851441444;44594.55405445904;42475.575244247164;42806.35790463083;43590.34614480722;43724;44537;42253.78873105615;42669.719908402796;42745;43106;43162.78418607887;36176.819115904254;37910;38321.80839095786;38347.69913805181;39990.40014399844;28081.85587540334;29166.56250156292;29234.269143271587;29426;29770 10:17:38 INFO - PROCESS | 5090 | |2;jquery.html;413.45836953590816;417.2218811915778;428.75989445910295;431.3508170203844;439.7515403796855;19491.512712182204;20450.795492045076;20928.581428371443;21455.67816482754;21705.3488395348;19828.107735151916;20493.795062049376;20586.38240852773;20928.58142837146;20962.895185524052;5787.971060144693;6037.396260373949;6039.275286965571;6042.154098426224;6060.545459090564;5818.534517238632;5991.340952495222;6052.54605904559;6055.061465472859;6076.4834989025985;6035.849103772419;6053.394660533955;6054.152418661391;6056.636601803883;6057.727402266886;5826.388229235911;5968.4329988651225;5970.1343305220935;5975.940240597625;6007.849803754919;5343.545798607123;5357.491038351351;5360.061989151922;5401.297831281948;5403.675779453245;23938.401539961465;23955.520889582283;23988.160414385413;24051.39871503209;24303.756962430325;2673.2915777319026;2678.370582913014;2739.8904043838224;2748.6014527893344;3036.1195253376495;15010.924945375313;15080.321385537678;15205.543833685007;15243.466478673194;15305;1920.3950755512108;1983.41489260667;1986.106252186522;2017.1931227508967;2043.703662968876;77895.22104778994;79736.60131699304;80125;80571.5971420145;80916.59541702371;10663.786724265627;11178.105751539857;11233.775324493465;11252.099832013419;11262.267952583219;15913.283902224197;15937.920310398375;15957.361705531881;15968.121753303447;16008;8401.915980840113;8416.537090460084;8520.446170998865;8566.443181193203;8593.398462107592;7641.503302285334;7685.579916937021;7776.766696999099;8041.557714325768;8457.873131903027;6705.966470167618;6869.969504574354;6913.5506192097355;6946.340097690711;6958.8956165657555;2031;2073.3261689950828;2088.446561661161;2089.6447603907372;2101.264557404896;866.8613021916464;1013.1438934100673;1014.7716763728102;1023.7747695506977;1129.5989923577176;18577.721334179998;20392.184312627625;20757.377278681663;20916.581668366547;21038.579228415347;1039.3296323871061;1058.5448257249302;1070.4166229404993;1073.0878753059837;1135.1316243074054;10871.728206794893;10934.4532773361;11985.34080625574;12075.939620301842;12139;620101;621297;621583;636708;646428;66197;86028;96391;97522.51238743903;97524;968.1915600473683;1037.2946396450336;1067.2315932528568;1075.575147816608;1078.5308390849848;18081.728774068135;18168.818311816714;18449.63100737951;18694;19116.713249300992;42496;46218.76890615525;47141.057178855546;47464.52535474601;48733.5126648729;54020;54522.727386362814;64761;65421.345786541526;65432.67283663551;8760.561971901507;9235.815283694423;9262.490563019099;9298.209652179641;9335.066493350882;11242;14052.156870587798;14228.928855355656;14229.928850355682;14557.12657240569;12170.33063181498;12271.877281227431;12286.324252166216;12411.379431028594;12614.180078294881;12111.757764844477;12277.75444491123;12342.938285308515;12450.377481126088;12775.361231938552;12550.937245313715;12558.811617825922;12832.807507887203;12840.293783841978;12963.092583519441;7725.381969442319;8315.293200078062;8480.194381533744;8487.660493580188;9255.583498742439;9333.953330233306;11650.76698466043;12125.393730313273;12320.507179712711;12649.114561980574 10:17:38 INFO - PROCESS | 5090 | |3;mootools.html;1537.6847746212027;1548.365170280185;1687.5106219196434;1819.3177558415603;1832.578506943402;9025.142611451909;9762.951185244081;9784.951075244613;9789.608415663373;9796.41221526709;8173.836523269531;8411.579421028955;8423.326133909288;8638.52488113155;9583.185429238523;2402.1712509184304;2477.306354220821;2477.3930387055107;2501.2996361018854;2501.9249422517355;2505.9749402506013;2525.6590360301398;2540.695116586003;2547.4905018996183;2556.335352808265;2508.5610018246734;2520.5211009908057;2521.3948652323493;2524.255344673326;2558.884850181737;2345.4370950971816;2350;2355.105060077168;2362.1496261345815;2364.231624721955;2451.0318424222332;2451.4116612013167;2453.9754602453927;2455.9631605525933;2460.237326428795;9444.622215111387;9626.08552187548;9645.71062868115;9653.17947974423;9662.13040826329;5574.944250557523;5710;5715.1713001614535;5720.141978703186;5720.570957178228;3487.4071407860724;3533.6819686228105;3679.3745063339297;3681.263747250534;3685.5761587417524;1194.8625908020601;1224.3510939202238;1228.6498347970876;1242.2981015726086;1253.1729058821134;13244.867551324556;13276.53532126371;13288.402021909038;13288.734225315537;13302.40139193729;4880.292357608127;4897.461279259279;4975.4278258000795;5064.341635587423;5084.796608135707;11182.944085279521;11196.880311968738;11203.943980280046;11216.439178041066;11223.38271395081;5757.395473475225;5759.65442073477;5761.654300741969;5763.077907534775;5772.04761214395;5206.76569554371;5272.130098533776;5273.894522109613;5290.418054014055;5311.707856067954;4220.472440944882;4240.448741663564;4241.936370954438;4318.654507639381;4328.891777705519;1339.7454483648075;1346.9191848489122;1350.3990724127668;1350.8851747601564;1352.4117009100876;1031.9122874555596;1042.7966546523355;1045.8483519889726;1055.5355643516828;1071.3893080943787;6359.682015899187;6378.84052898672;6496.415322620986;6571.211454625475;6678.23200331968;493.9357883475125;507.3075252280684;510.27031345176755;510.90548248574135;511.9923201151986;4294.892627684333;4311.07311927937;4319.222539942841;4464.665150113755;4477.597016268552;10894;11405.429728513707;11426.143039272089;11428.428578571038;11433;11115.166362522843;11302.717432064264;11330.943345283385;11344.035756960586;11691.181617286878;8469.57652117404;8644.005939316934;8667.653293868301;8673.04596494385;8680.8697869532;6468.6442245676935;6542.214934207926;6634.867302654016;6731.865362692621;6867.793966181022;10633.521491532745;11075.446227688744;11119.054880335261;11251.549938002716;11286.830697539379;10119.645812396531;11308.83036754433;11385.316880987168;11441.599544015919;11446.94276528612;8223.424360294723;8410.411271210956;8541.060483346702;8691;8709.91290087091;9311.068893110634;9321.860172097287;9384.296177786557;9420.057994200799;9437.81124377495;8386.916130838857;8486.957565212135;8512.489250644981;8693.826123477622;8723.389362744547;8139.14538973423;8249.670013199404;8333.6666533338;8338.583070846555;8548.401611887108;7256.092988376453;7364.374028207662;7371.447141464519;7371.778846634609;7374.188839227788;5516.475934786189;5610.214569960291;5624.7453011162215;5650.745716442688;5787.218725472176;5423.945760542344;5437.211604317431;5765.509931655856;5769.365369809401;5942.168096466412 10:17:38 INFO - PROCESS | 5090 | |4;prototype.html;338.06056340122615;339.30611898667223;341.9469982152767;354.82436194083925;355.3603513675384;5640.351359593648;5641.633293835897;5650.915236271454;5655.4061823508555;5688.800891968782;5270.683758974455;5416;5543.780368318963;5562.193481945115;5590.413006634296;4363.127374525092;4372.366006928985;4376.365427013081;4383.276759334722;4384.123175364924;4360.36774667673;4366.956330436703;4397.296432570789;4397.692161548693;4417.22698527758;4201.558836322188;4312.727745315124;4337.891552711191;4377.912441751163;4382.12357528494;4360.563943605645;4361.367601697768;4367.335925085696;4369.825206991716;4375.6280716138845;3868.3810590305416;3875.4380614810975;3879.603342796619;3888.5333759948703;3910.3967373377063;5165.251038599382;5403.243545903577;5440.129579267298;5440.619156659035;5464.23500709901;4951.3810773653295;4960.826371076995;4967.875803104951;4979.551840334388;4986.975065124651;5783.103618939072;5791.391903850077;5811.099279611667;5840.3575606683235;5844.1818145459665;4117.649999750025;4139.4204811326435;4163.375493675942;4215;4235.618794308527;6092.770831459334;6128.540359473012;6132.356102609251;6141;6160.599561028519;4358.411614432074;4368.4102646143;4369.060651959844;4386.583274588909;4406.669499787528;4212.789360531961;4392.275274579659;4396.626286765597;4422.601965823092;4471.418715566955;4972.65191436596;4974.676646017997;4981.23166275974;4983;5020.748962551857;5869.941300586939;5911.940880591225;5914.526837852962;5975.372585878507;6006.939930600725;1443.718474897385;1451.477468111479;1465.1794994802945;1531.5635044012404;1534.1485475561171;1013.0983424751977;1017.389566260238;1034.3897100710617;1045.6862941117633;1048.3919326790444;5640.5769567281795;5689.516391106719;5691.26013618227;5693.430656934356;5725.856853578611;5953.553483488756;5966.552508561876;5974.581779275496;5976.641401515923;6002.4897883679505;5497.285352904177;5652.98637967048;5655.830325090254;5659.773609055674;5683.403242659544;2785.874635641401;2795.4688609164195;2806.6632004159633;2831.561108028259;2848.7038397529072;5430.456954304538;5434.266374039454;5438.45615438453;5439.086129681305;5471.972640136853;5171.612129090333;5358.687121655219;5386.145342968233;5408.459154084639;5421.855323912121;5396.757145928443;5400.729963501809;5424.186372044225;5428.294321738228;5435.146548062299;5096.929644774639;5143.916713730945;5283.286756287852;5320.856015956588;5364.865878353073;5190.6107041971245;5203.479652034766;5211.296474975829;5216.634835561475;5231.973840130775;5420.701861397503;5439.238506609157;5443.618946673695;5532.529734972572;5536.972315138399;3941.349677303213;3948.5587760466647;3978.204359128128;4081.6938729594804;4373.978130109329;6075.1798507200965;6078.54410919174;6082.574219804571;6083.878322433438;6092.421219984094;5685.118806584986;5841.620294680833;5843.415658434123;5920.970395147997;5933.940660593339;5917.822465326047;5926.140709596998;5928.940710593011;5932.347441781487;5947.910781338197;5755.287839343684;5896.64620122794;5908;5916.698326368192;5938.317093534299;5925.644461332334;5948.434898684618;5948.650283138223;5966.433188847052;5967.134765459071;5689.772409103755;5794.855128621818;5818.214541036906;5833.649981001154;5851.473367396955 10:17:38 INFO - PROCESS | 5090 | |5;yui.html;788.1487992967599;815.1359558867599;817.7996390884231;824.5052968219073;831.8086840026793;14543.854561454382;15788.289526971284;15797.28912198951;15827.366905323788;15864.286107125192;13957;14011.509597164102;14028.298585070757;14031;14032.228227447486;714.1572943926178;851.6252848746532;870.6473878079388;881.2333270055029;883.3728053082287;876.5923845411883;877.6138499060424;879.7800549862534;880.2254016465502;880.9603567839463;929.0894922975488;931.9720408387759;932.4917919733728;932.5523748600676;934.6775362499926;931.6133804471136;933.304688007432;933.3186773655236;933.6918816790443;933.7012156110047;684.449018540075;685.4173952140691;685.4379408884716;685.9382655561024;686.1217641418991;13423.597292081253;13434.328283585879;13475.19148851072;13483.528076517372;13487.932560337136;13832.170069795844;14173.929130354281;14217.901001134478;14808.925955370154;16343.836561634469;14527.491537796126;14571.344289506891;14592.270386480739;14646.487372942003;14887.77668334965;4784.5933095686905;4796.3524924135145;4855;4864.927026094611;4950.504949505057;88158;88989.55505222497;90038;90414;90476.09523904743;12129.514819407124;12480;12525.93737031309;12615.621531354074;12707.872921270853;22581.774182257966;22590.435239119153;22613.095476181097;22620.0951961923;22621.43446413853;8443.274389032338;8484.27883629886;8796.604152813139;8815.074417186232;8937.553122343857;4816.253480710496;4932.186189278729;4937.901241975141;4994.950050499521;5027.1705168647495;3625.9456108158397;3767.7174211934216;3768.378217594121;3773.8301776420126;3781.640744129303;647.9384458476437;658.5686375424104;665.4277321503503;666.1506579111672;668.8227619680788;574.4800955135609;693.237438817297;699.7655785311978;702.5995182745786;720.3696765330336;9363.531823408939;9396.295277854188;9746.269029822792;9804.803903921882;9842.655507057361;595.9255093113361;653.0498125227782;669.6417416682052;673.6093066021696;704.8132244955091;4278.422412974209;4296.9570304297185;4550.112728018004;4582.587567118975;4610.193216187154;93245.53377233206;95165;125673;137197;137929;98026.50986745021;98917.50541247247;101993;102105.48947255216;103136.48431757794;87730;89936.5503172493;91369;91428;92065.53967230255;10271;10323.174146068146;10375.27373083877;10768.461576920967;10783.568657253621;51622.22566661428;51877.7406112967;52042;52157.47842521526;54998;71494;76612;78342;78955.21044789479;79289.60355198187;13918.930405347908;14196.645083872985;14211.786823197453;14617.707645847237;14646.780298295747;15926.60183495422;16023.759643605588;16092.597685057966;16097.114658693416;16951.49145525636;13869.514566990107;13949.093308934887;14008.719825603226;14835.851641483447;14885.404583816526;14795.704085918436;15426.460073897808;15671.68656626884;16008.359665613245;16442.917785410995;13848.930755346159;13856.445742170603;13929.930350348184;14342.211178385289;14802.407903683732;12281.324527151093;12554.560590379293;12707.93646031764;13071.803922940973;13157.079004469595;10903.509342079467;11105.333679979227;11712.169185733956;11866.940665296619;11880.643580692593 10:17:38 INFO - PROCESS | 5090 | __end_tp_report 10:17:38 INFO - PROCESS | 5090 | __start_cc_report 10:17:38 INFO - PROCESS | 5090 | _x_x_mozilla_cycle_collect,981 10:17:38 INFO - PROCESS | 5090 | __end_cc_report 10:17:38 INFO - PROCESS | 5090 | __startTimestamp1476465458904__endTimestamp 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | ------- Summary: start ------- 10:17:38 INFO - PROCESS | 5090 | Number of tests: 6 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#0] dojo.html Cycles:165 Average:5626.47 Median:5793.94 stddev:644.77 (11.1%) stddev-sans-first:617.40 10:17:38 INFO - PROCESS | 5090 | Values: 3174.8 3175.0 3217.3 3240.0 3255.7 5622.6 5639.7 5682.7 5691.3 5700.2 5660.2 5670.1 5691.3 5708.5 5713.3 5173.9 5177.1 5181.4 5242.1 5246.8 5036.9 5101.9 5248.2 5252.0 5288.1 5112.6 5131.8 5134.8 5162.3 5185.7 5158.9 5199.4 5283.3 5286.6 5312.6 4978.3 5094.7 5168.7 5223.1 5225.6 5515.0 5533.8 5534.8 5544.1 5576.6 5966.9 6010.6 6063.7 6073.1 6074.6 5960.7 5993.5 6083.1 6089.7 6090.9 5613.2 5650.6 5653.3 5662.6 5666.6 6198.9 6214.5 6229.7 6230.5 6232.4 5730.5 5878.6 5926.1 5948.1 5965.5 6090.3 6105.8 6106.0 6107.2 6113.9 6061.5 6064.1 6065.8 6067.5 6096.1 5785.0 5853.0 6033.8 6087.6 6099.5 4585.9 4589.2 4596.7 4600.7 4681.8 4132.7 4133.3 4141.6 4161.1 4161.1 5537.2 5777.2 5786.6 5793.9 5795.9 5949.3 6010.5 6093.6 6135.3 6139.3 5782.4 5784.9 5785.7 5789.3 5815.0 5115.7 5286.9 5289.3 5291.5 5294.1 6211.9 6242.8 6245.1 6302.8 6344.7 6028.3 6135.8 6141.0 6141.9 6150.0 6095.6 6312.2 6350.9 6354.9 6379.4 5924.7 5963.1 5968.0 6032.4 6081.2 6127.7 6166.9 6174.8 6177.3 6180.6 5769.3 5980.5 6012.3 6013.3 6014.6 5665.4 5788.5 5821.1 5917.5 5925.8 5792.2 5822.4 5834.1 5840.4 5842.7 5734.9 5832.9 5858.8 5876.7 5895.8 5673.1 5717.1 5741.6 5797.7 5829.4 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#1] ext.html Cycles:180 Average:65888.72 Median:38334.75 stddev:142168.87 (370.9%) stddev-sans-first:142483.16 10:17:38 INFO - PROCESS | 5090 | Values: 590.6 633.3 642.5 643.7 655.9 34282.7 36961.4 37059.1 37107.0 37107.6 45657.3 46611.3 46737.0 51143.5 51153.5 9065.5 9235.4 9236.2 9237.7 9250.9 8376.2 8576.6 9096.6 9188.9 9195.3 11004.4 11304.2 11315.6 11662.3 11680.9 11099.3 11195.0 11195.5 11201.2 11201.8 10877.5 10942.4 10945.9 10957.9 10966.7 44811.8 45076.3 45110.3 45126.0 45212.0 3728.6 3741.1 3877.3 3892.3 3902.1 33470.0 33471.5 33839.3 35209.6 35274.8 4336.9 4444.9 4489.9 4493.1 4535.9 362820.0 364768.0 364833.0 365689.0 366870.0 35557.6 60948.7 61039.7 61228.0 61243.7 37698.8 39370.6 39468.4 39474.6 39506.0 15087.8 15237.3 15244.8 15251.2 15251.8 11703.8 11705.6 11713.2 11717.4 11719.9 8490.6 8870.3 9037.2 9094.3 9173.0 3453.3 3499.9 3523.6 3551.7 3557.8 963.6 1017.6 1043.4 1080.4 1202.6 32805.8 33096.2 33148.5 33267.3 33503.5 1187.8 1189.6 1235.6 1235.8 1258.2 16757.5 17240.7 17373.6 17525.7 17561.5 650706.0 652866.0 659573.0 660961.0 666840.0 42500.6 42745.0 42825.4 42848.8 42932.0 1389.4 1435.3 1448.2 1466.3 1475.9 30607.2 31401.2 31641.4 31722.4 32115.0 52355.5 53057.0 53284.0 53330.7 53352.0 529669.0 530181.0 531756.0 531931.0 533261.0 26718.7 29095.1 29169.9 29891.4 30273.8 50260.0 50699.0 50788.2 50949.2 50999.0 41897.2 43449.3 43726.0 44148.6 44594.6 42475.6 42806.4 43590.3 43724.0 44537.0 42253.8 42669.7 42745.0 43106.0 43162.8 36176.8 37910.0 38321.8 38347.7 39990.4 28081.9 29166.6 29234.3 29426.0 29770.0 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#2] jquery.html Cycles:180 Average:33516.96 Median:24177.58 stddev:103044.98 (426.2%) stddev-sans-first:103304.07 10:17:38 INFO - PROCESS | 5090 | Values: 413.5 417.2 428.8 431.4 439.8 19491.5 20450.8 20928.6 21455.7 21705.3 19828.1 20493.8 20586.4 20928.6 20962.9 5788.0 6037.4 6039.3 6042.2 6060.5 5818.5 5991.3 6052.5 6055.1 6076.5 6035.8 6053.4 6054.2 6056.6 6057.7 5826.4 5968.4 5970.1 5975.9 6007.8 5343.5 5357.5 5360.1 5401.3 5403.7 23938.4 23955.5 23988.2 24051.4 24303.8 2673.3 2678.4 2739.9 2748.6 3036.1 15010.9 15080.3 15205.5 15243.5 15305.0 1920.4 1983.4 1986.1 2017.2 2043.7 77895.2 79736.6 80125.0 80571.6 80916.6 10663.8 11178.1 11233.8 11252.1 11262.3 15913.3 15937.9 15957.4 15968.1 16008.0 8401.9 8416.5 8520.4 8566.4 8593.4 7641.5 7685.6 7776.8 8041.6 8457.9 6706.0 6870.0 6913.6 6946.3 6958.9 2031.0 2073.3 2088.4 2089.6 2101.3 866.9 1013.1 1014.8 1023.8 1129.6 18577.7 20392.2 20757.4 20916.6 21038.6 1039.3 1058.5 1070.4 1073.1 1135.1 10871.7 10934.5 11985.3 12075.9 12139.0 620101.0 621297.0 621583.0 636708.0 646428.0 66197.0 86028.0 96391.0 97522.5 97524.0 968.2 1037.3 1067.2 1075.6 1078.5 18081.7 18168.8 18449.6 18694.0 19116.7 42496.0 46218.8 47141.1 47464.5 48733.5 54020.0 54522.7 64761.0 65421.3 65432.7 8760.6 9235.8 9262.5 9298.2 9335.1 11242.0 14052.2 14228.9 14229.9 14557.1 12170.3 12271.9 12286.3 12411.4 12614.2 12111.8 12277.8 12342.9 12450.4 12775.4 12550.9 12558.8 12832.8 12840.3 12963.1 7725.4 8315.3 8480.2 8487.7 9255.6 9334.0 11650.8 12125.4 12320.5 12649.1 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#3] mootools.html Cycles:180 Average:6238.26 Median:4888.88 stddev:3560.13 (72.8%) stddev-sans-first:3552.59 10:17:38 INFO - PROCESS | 5090 | Values: 1537.7 1548.4 1687.5 1819.3 1832.6 9025.1 9763.0 9785.0 9789.6 9796.4 8173.8 8411.6 8423.3 8638.5 9583.2 2402.2 2477.3 2477.4 2501.3 2501.9 2506.0 2525.7 2540.7 2547.5 2556.3 2508.6 2520.5 2521.4 2524.3 2558.9 2345.4 2350.0 2355.1 2362.1 2364.2 2451.0 2451.4 2454.0 2456.0 2460.2 9444.6 9626.1 9645.7 9653.2 9662.1 5574.9 5710.0 5715.2 5720.1 5720.6 3487.4 3533.7 3679.4 3681.3 3685.6 1194.9 1224.4 1228.6 1242.3 1253.2 13244.9 13276.5 13288.4 13288.7 13302.4 4880.3 4897.5 4975.4 5064.3 5084.8 11182.9 11196.9 11203.9 11216.4 11223.4 5757.4 5759.7 5761.7 5763.1 5772.0 5206.8 5272.1 5273.9 5290.4 5311.7 4220.5 4240.4 4241.9 4318.7 4328.9 1339.7 1346.9 1350.4 1350.9 1352.4 1031.9 1042.8 1045.8 1055.5 1071.4 6359.7 6378.8 6496.4 6571.2 6678.2 493.9 507.3 510.3 510.9 512.0 4294.9 4311.1 4319.2 4464.7 4477.6 10894.0 11405.4 11426.1 11428.4 11433.0 11115.2 11302.7 11330.9 11344.0 11691.2 8469.6 8644.0 8667.7 8673.0 8680.9 6468.6 6542.2 6634.9 6731.9 6867.8 10633.5 11075.4 11119.1 11251.5 11286.8 10119.6 11308.8 11385.3 11441.6 11446.9 8223.4 8410.4 8541.1 8691.0 8709.9 9311.1 9321.9 9384.3 9420.1 9437.8 8386.9 8487.0 8512.5 8693.8 8723.4 8139.1 8249.7 8333.7 8338.6 8548.4 7256.1 7364.4 7371.4 7371.8 7374.2 5516.5 5610.2 5624.7 5650.7 5787.2 5423.9 5437.2 5765.5 5769.4 5942.2 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#4] prototype.html Cycles:180 Average:4813.36 Median:5398.74 stddev:1397.70 (25.9%) stddev-sans-first:1360.66 10:17:38 INFO - PROCESS | 5090 | Values: 338.1 339.3 341.9 354.8 355.4 5640.4 5641.6 5650.9 5655.4 5688.8 5270.7 5416.0 5543.8 5562.2 5590.4 4363.1 4372.4 4376.4 4383.3 4384.1 4360.4 4367.0 4397.3 4397.7 4417.2 4201.6 4312.7 4337.9 4377.9 4382.1 4360.6 4361.4 4367.3 4369.8 4375.6 3868.4 3875.4 3879.6 3888.5 3910.4 5165.3 5403.2 5440.1 5440.6 5464.2 4951.4 4960.8 4967.9 4979.6 4987.0 5783.1 5791.4 5811.1 5840.4 5844.2 4117.6 4139.4 4163.4 4215.0 4235.6 6092.8 6128.5 6132.4 6141.0 6160.6 4358.4 4368.4 4369.1 4386.6 4406.7 4212.8 4392.3 4396.6 4422.6 4471.4 4972.7 4974.7 4981.2 4983.0 5020.7 5869.9 5911.9 5914.5 5975.4 6006.9 1443.7 1451.5 1465.2 1531.6 1534.1 1013.1 1017.4 1034.4 1045.7 1048.4 5640.6 5689.5 5691.3 5693.4 5725.9 5953.6 5966.6 5974.6 5976.6 6002.5 5497.3 5653.0 5655.8 5659.8 5683.4 2785.9 2795.5 2806.7 2831.6 2848.7 5430.5 5434.3 5438.5 5439.1 5472.0 5171.6 5358.7 5386.1 5408.5 5421.9 5396.8 5400.7 5424.2 5428.3 5435.1 5096.9 5143.9 5283.3 5320.9 5364.9 5190.6 5203.5 5211.3 5216.6 5232.0 5420.7 5439.2 5443.6 5532.5 5537.0 3941.3 3948.6 3978.2 4081.7 4374.0 6075.2 6078.5 6082.6 6083.9 6092.4 5685.1 5841.6 5843.4 5921.0 5933.9 5917.8 5926.1 5928.9 5932.3 5947.9 5755.3 5896.6 5908.0 5916.7 5938.3 5925.6 5948.4 5948.7 5966.4 5967.1 5689.8 5794.9 5818.2 5833.6 5851.5 10:17:38 INFO - PROCESS | 5090 | 10:17:38 INFO - PROCESS | 5090 | [#5] yui.html Cycles:180 Average:21904.58 Median:4697.39 stddev:31530.38 (671.2%) stddev-sans-first:31578.96 10:17:38 INFO - PROCESS | 5090 | Values: 788.1 815.1 817.8 824.5 831.8 14543.9 15788.3 15797.3 15827.4 15864.3 13957.0 14011.5 14028.3 14031.0 14032.2 714.2 851.6 870.6 881.2 883.4 876.6 877.6 879.8 880.2 881.0 929.1 932.0 932.5 932.6 934.7 931.6 933.3 933.3 933.7 933.7 684.4 685.4 685.4 685.9 686.1 13423.6 13434.3 13475.2 13483.5 13487.9 13832.2 14173.9 14217.9 14808.9 16343.8 14527.5 14571.3 14592.3 14646.5 14887.8 4784.6 4796.4 4855.0 4864.9 4950.5 88158.0 88989.6 90038.0 90414.0 90476.1 12129.5 12480.0 12525.9 12615.6 12707.9 22581.8 22590.4 22613.1 22620.1 22621.4 8443.3 8484.3 8796.6 8815.1 8937.6 4816.3 4932.2 4937.9 4995.0 5027.2 3625.9 3767.7 3768.4 3773.8 3781.6 647.9 658.6 665.4 666.2 668.8 574.5 693.2 699.8 702.6 720.4 9363.5 9396.3 9746.3 9804.8 9842.7 595.9 653.0 669.6 673.6 704.8 4278.4 4297.0 4550.1 4582.6 4610.2 93245.5 95165.0 125673.0 137197.0 137929.0 98026.5 98917.5 101993.0 102105.5 103136.5 87730.0 89936.6 91369.0 91428.0 92065.5 10271.0 10323.2 10375.3 10768.5 10783.6 51622.2 51877.7 52042.0 52157.5 54998.0 71494.0 76612.0 78342.0 78955.2 79289.6 13918.9 14196.6 14211.8 14617.7 14646.8 15926.6 16023.8 16092.6 16097.1 16951.5 13869.5 13949.1 14008.7 14835.9 14885.4 14795.7 15426.5 15671.7 16008.4 16442.9 13848.9 13856.4 13929.9 14342.2 14802.4 12281.3 12554.6 12707.9 13071.8 13157.1 10903.5 11105.3 11712.2 11866.9 11880.6 10:17:38 INFO - PROCESS | 5090 | -------- Summary: end -------- 10:17:38 INFO - PROCESS | 5090 | 10:17:39 INFO - TEST-INFO | 5090: exit 0 10:17:39 INFO - TEST-OK | dromaeo_css | took 1112996ms 10:17:39 INFO - TEST-START | kraken 10:17:39 INFO - operating with platform_type : linux_ 10:17:39 INFO - Initialising browser for kraken test... 10:17:39 INFO - TEST-INFO | started process 5284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDUFkgy/profile http://localhost:58699/getInfo.html) 10:17:43 INFO - PROCESS | 5284 | __metrics Screen width/height:1600/1200 10:17:43 INFO - PROCESS | 5284 | colorDepth:24 10:17:43 INFO - PROCESS | 5284 | Browser inner width/height: 1024/697 10:17:43 INFO - PROCESS | 5284 | __metrics 10:17:44 INFO - PROCESS | 5284 | 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" 10:17:44 INFO - PROCESS | 5284 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:45 INFO - TEST-INFO | 5284: exit 0 10:17:45 INFO - Browser initialized. 10:17:45 INFO - Running cycle 1/1 for kraken test... 10:17:45 INFO - TEST-INFO | started process 5375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDUFkgy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:57 INFO - PROCESS | 5375 | RSS: Main: 144023552 10:17:57 INFO - PROCESS | 5375 | 10:17:59 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58699/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:00 INFO - PROCESS | 5375 | RSS: Main: 198799360 10:18:00 INFO - PROCESS | 5375 | 10:18:03 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58699/tests/kraken/driver.html?testName=audio-dft) 10:18:03 INFO - PROCESS | 5375 | RSS: Main: 183046144 10:18:03 INFO - PROCESS | 5375 | 10:18:06 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58699/tests/kraken/driver.html?testName=audio-fft) 10:18:07 INFO - PROCESS | 5375 | RSS: Main: 275492864 10:18:07 INFO - PROCESS | 5375 | 10:18:09 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58699/tests/kraken/driver.html?testName=audio-oscillator) 10:18:09 INFO - PROCESS | 5375 | RSS: Main: 180563968 10:18:09 INFO - PROCESS | 5375 | 10:18:12 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58699/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:12 INFO - PROCESS | 5375 | RSS: Main: 179740672 10:18:12 INFO - PROCESS | 5375 | 10:18:15 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58699/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:16 INFO - PROCESS | 5375 | RSS: Main: 215396352 10:18:16 INFO - PROCESS | 5375 | 10:18:19 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58699/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:20 INFO - PROCESS | 5375 | RSS: Main: 214102016 10:18:20 INFO - PROCESS | 5375 | 10:18:22 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58699/tests/kraken/driver.html?testName=json-parse-financial) 10:18:23 INFO - PROCESS | 5375 | RSS: Main: 213647360 10:18:23 INFO - PROCESS | 5375 | 10:18:25 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58699/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:25 INFO - PROCESS | 5375 | RSS: Main: 176152576 10:18:25 INFO - PROCESS | 5375 | 10:18:27 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:28 INFO - PROCESS | 5375 | RSS: Main: 182525952 10:18:28 INFO - PROCESS | 5375 | 10:18:29 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:30 INFO - PROCESS | 5375 | RSS: Main: 189140992 10:18:30 INFO - PROCESS | 5375 | 10:18:33 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:33 INFO - PROCESS | 5375 | RSS: Main: 196898816 10:18:33 INFO - PROCESS | 5375 | 10:18:36 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:37 INFO - PROCESS | 5375 | RSS: Main: 193802240 10:18:37 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | Cycle 1(1): loaded http://localhost:58699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58699/tests/kraken/driver.html?testName=ai-astar) 10:18:38 INFO - PROCESS | 5375 | RSS: Main: 244903936 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | __start_tp_report 10:18:38 INFO - PROCESS | 5375 | _x_x_mozilla_page_load 10:18:38 INFO - PROCESS | 5375 | _x_x_mozilla_page_load_details 10:18:38 INFO - PROCESS | 5375 | |i|pagename|runs| 10:18:38 INFO - PROCESS | 5375 | |0;ai-astar;110;109;119;110;109;111;108;110;109;109 10:18:38 INFO - PROCESS | 5375 | |1;audio-beat-detection;148;116;144;180;120;138;143;116;179;116 10:18:38 INFO - PROCESS | 5375 | |2;audio-dft;161;162;163;161;160;161;161;161;161;162 10:18:38 INFO - PROCESS | 5375 | |3;audio-fft;75;75;74;167;76;76;74;75;170;74 10:18:38 INFO - PROCESS | 5375 | |4;audio-oscillator;83;83;162;83;83;83;146;84;85;141 10:18:38 INFO - PROCESS | 5375 | |5;imaging-gaussian-blur;116;116;117;117;116;117;117;117;116;116 10:18:38 INFO - PROCESS | 5375 | |6;imaging-darkroom;146;147;146;147;146;146;146;147;145;146 10:18:38 INFO - PROCESS | 5375 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 10:18:38 INFO - PROCESS | 5375 | |8;json-parse-financial;53;52;52;51;50;50;52;50;51;53 10:18:38 INFO - PROCESS | 5375 | |9;json-stringify-tinderbox;60;61;62;62;62;61;60;61;61;62 10:18:38 INFO - PROCESS | 5375 | |10;stanford-crypto-aes;79;73;72;73;77;79;72;75;73;73 10:18:38 INFO - PROCESS | 5375 | |11;stanford-crypto-ccm;131;132;138;121;121;122;119;121;162;120 10:18:38 INFO - PROCESS | 5375 | |12;stanford-crypto-pbkdf2;158;159;154;154;160;160;156;168;156;156 10:18:38 INFO - PROCESS | 5375 | |13;stanford-crypto-sha256-iterative;62;66;65;62;62;62;71;63;62;61 10:18:38 INFO - PROCESS | 5375 | __end_tp_report 10:18:38 INFO - PROCESS | 5375 | __start_cc_report 10:18:38 INFO - PROCESS | 5375 | _x_x_mozilla_cycle_collect,1665 10:18:38 INFO - PROCESS | 5375 | __end_cc_report 10:18:38 INFO - PROCESS | 5375 | __startTimestamp1476465518873__endTimestamp 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | ------- Summary: start ------- 10:18:38 INFO - PROCESS | 5375 | Number of tests: 14 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#0] ai-astar Cycles:10 Average:110.40 Median:110.00 stddev:3.13 (2.8%) stddev-sans-first:3.32 10:18:38 INFO - PROCESS | 5375 | Values: 110.0 109.0 119.0 110.0 109.0 111.0 108.0 110.0 109.0 109.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:143.50 stddev:24.36 (17.0%) stddev-sans-first:25.67 10:18:38 INFO - PROCESS | 5375 | Values: 148.0 116.0 144.0 180.0 120.0 138.0 143.0 116.0 179.0 116.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 10:18:38 INFO - PROCESS | 5375 | Values: 161.0 162.0 163.0 161.0 160.0 161.0 161.0 161.0 161.0 162.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#3] audio-fft Cycles:10 Average:93.60 Median:75.00 stddev:39.49 (52.7%) stddev-sans-first:41.31 10:18:38 INFO - PROCESS | 5375 | Values: 75.0 75.0 74.0 167.0 76.0 76.0 74.0 75.0 170.0 74.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:83.00 stddev:32.42 (39.1%) stddev-sans-first:33.54 10:18:38 INFO - PROCESS | 5375 | Values: 83.0 83.0 162.0 83.0 83.0 83.0 146.0 84.0 85.0 141.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:18:38 INFO - PROCESS | 5375 | Values: 116.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:18:38 INFO - PROCESS | 5375 | Values: 146.0 147.0 146.0 147.0 146.0 146.0 146.0 147.0 145.0 146.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:18:38 INFO - PROCESS | 5375 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:52.00 stddev:1.17 (2.3%) stddev-sans-first:1.09 10:18:38 INFO - PROCESS | 5375 | Values: 53.0 52.0 52.0 51.0 50.0 50.0 52.0 50.0 51.0 53.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.50 stddev:0.79 (1.3%) stddev-sans-first:0.71 10:18:38 INFO - PROCESS | 5375 | Values: 60.0 61.0 62.0 62.0 62.0 61.0 60.0 61.0 61.0 62.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:2.76 (3.7%) stddev-sans-first:2.42 10:18:38 INFO - PROCESS | 5375 | Values: 79.0 73.0 72.0 73.0 77.0 79.0 72.0 75.0 73.0 73.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:126.50 stddev:13.35 (10.6%) stddev-sans-first:14.13 10:18:38 INFO - PROCESS | 5375 | Values: 131.0 132.0 138.0 121.0 121.0 122.0 119.0 121.0 162.0 120.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:158.50 stddev:4.12 (2.6%) stddev-sans-first:4.37 10:18:38 INFO - PROCESS | 5375 | Values: 158.0 159.0 154.0 154.0 160.0 160.0 156.0 168.0 156.0 156.0 10:18:38 INFO - PROCESS | 5375 | 10:18:38 INFO - PROCESS | 5375 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:62.50 stddev:3.03 (4.8%) stddev-sans-first:3.15 10:18:38 INFO - PROCESS | 5375 | Values: 62.0 66.0 65.0 62.0 62.0 62.0 71.0 63.0 62.0 61.0 10:18:38 INFO - PROCESS | 5375 | -------- Summary: end -------- 10:18:38 INFO - PROCESS | 5375 | 10:18:39 INFO - TEST-INFO | 5375: exit 0 10:18:39 INFO - TEST-OK | kraken | took 60283ms 10:18:39 INFO - SUITE-END | took 1173s 10:18:40 INFO - Completed test suite (00:19:33) 10:18:40 INFO - javascript benchmark 10:18:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3174.8253846038474, 3175.0474857542736, 3217.308278720076, 3240.0, 3255.658155893631, 5622.578306626999, 5639.718014099294, 5682.687452190133, 5691.345495268046, 5700.17347484615, 5660.2075709400715, 5670.0927851543765, 5691.317041954961, 5708.514776244025, 5713.285839270091, 5173.922391164127, 5177.094008548508, 5181.4300426953005, 5242.108841496935, 5246.842594722164, 5036.899262014757, 5101.923471147936, 5248.239005344223, 5252.028374750665, 5288.127458969266, 5112.616553758465, 5131.794728210867, 5134.845954621367, 5162.328897243353, 5185.740712964337, 5158.896822063556, 5199.376074871002, 5283.260343551906, 5286.60350473716, 5312.62811603184, 4978.278149668314, 5094.668846525001, 5168.664036837594, 5223.138182199932, 5225.634205605609, 5515.0, 5533.750981205855, 5534.75093620788, 5544.0575102232715, 5576.553875689935, 5966.910496342558, 6010.579259451796, 6063.727132279058, 6073.149759033695, 6074.605150665193, 5960.672163031031, 5993.460588547008, 6083.087536869461, 6089.665068421235, 6090.8781824363705, 5613.158026296048, 5650.604457687922, 5653.265075540236, 5662.6036177467595, 5666.575006874501, 6198.938010619836, 6214.471769899609, 6229.719662615194, 6230.470410015199, 6232.376762323732, 5730.5129064030025, 5878.559108066912, 5926.081457374114, 5948.078047902582, 5965.492933100648, 6090.348809980474, 6105.84735381619, 6106.0, 6107.175531303306, 6113.902014424272, 6061.484773794189, 6064.06007068905, 6065.848353791191, 6067.45392914631, 6096.146539484475, 5785.016547186989, 5853.0, 6033.758649653964, 6087.57386982907, 6099.451049405575, 4585.908281834345, 4589.242774942164, 4596.678232523758, 4600.746958917225, 4681.789319480631, 4132.669386449077, 4133.256013917524, 4141.627253547195, 4161.0845613964875, 4161.126163505637, 5537.197106419547, 5777.2200752897825, 5786.566007549451, 5793.942060579425, 5795.88408231833, 5949.25634295713, 6010.459058684739, 6093.6039157454625, 6135.324535308531, 6139.277491147187, 5782.421757824184, 5784.855378615568, 5785.681787501728, 5789.334226563915, 5814.970925145347, 5115.7442127893455, 5286.867828304323, 5289.259503669489, 5291.470852914755, 5294.12646913255, 6211.875762484725, 6242.781502647385, 6245.0632405139595, 6302.8424289392215, 6344.6827658616885, 6028.2766068072115, 6135.84660383494, 6140.969295153495, 6141.9078713818435, 6149.96925015372, 6095.634261944298, 6312.210973628296, 6350.872982540416, 6354.93645063562, 6379.425851673372, 5924.7037648118285, 5963.075723262901, 5968.045112781934, 6032.396760324108, 6081.179040829608, 6127.662978536223, 6166.907496387468, 6174.753009879734, 6177.2587289525545, 6180.6291622502795, 5769.307683078057, 5980.521558275241, 6012.278526576664, 6013.30846952606, 6014.609050411709, 5665.405132461032, 5788.507976822018, 5821.126830975219, 5917.467427931507, 5925.792597259076, 5792.21805056312, 5822.446867547576, 5834.124881267844, 5840.357560668408, 5842.7078646066675, 5734.942650573607, 5832.883342333214, 5858.765649374148, 5876.676782776988, 5895.793647222326, 5673.092305231144, 5717.056685646821, 5741.598088133791, 5797.710114494174, 5829.446202610745], "value": 5582.200178505683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6279044202151, 633.3318349141655, 642.4667525953456, 643.7328508668901, 655.9475241980642, 34282.657173428255, 36961.44557831635, 37059.07352316193, 37107.0, 37107.628923710756, 45657.31514027284, 46611.30083048757, 46737.0, 51143.48856511434, 51153.48846511534, 9065.501397423139, 9235.399699019576, 9236.21492173166, 9237.722868313927, 9250.86123708145, 8376.246137847593, 8576.571171441434, 9096.63613455461, 9188.908110918905, 9195.264378849675, 11004.39475828833, 11304.152188585847, 11315.603953861617, 11662.300261984308, 11680.883191168065, 11099.334039957588, 11195.0, 11195.496202670818, 11201.159913006475, 11201.775964480705, 10877.510512026978, 10942.398168100752, 10945.890541094566, 10957.945210273978, 10966.670999869937, 44811.77594112041, 45076.3238551422, 45110.32334514952, 45126.0, 45212.0, 3728.5898551159353, 3741.0834345585504, 3877.2827027000094, 3892.2604705106223, 3902.0635047588657, 33470.0, 33471.497927531105, 33839.32321353584, 35209.64790352089, 35274.82362588196, 4336.891577710551, 4444.866654000385, 4489.907663207155, 4493.146302202571, 4535.863924082283, 362820.0, 364768.0, 364833.0, 365689.0, 366870.0, 35557.64442355543, 60948.69525652432, 61039.694801526595, 61228.0, 61243.693781530805, 37698.81150594229, 39370.606293937264, 39468.407973880414, 39474.605253947666, 39506.0, 15087.849121508863, 15237.314320855588, 15244.847551524343, 15251.23743812805, 15251.771223431657, 11703.76592468163, 11705.590304339306, 11713.1800773945, 11717.414129293502, 11719.882801172049, 8490.575471226413, 8870.290376769843, 9037.231835293942, 9094.317926155565, 9173.0, 3453.304963688829, 3499.9475007874903, 3523.612402635708, 3551.7158627309755, 3557.768745031565, 963.6386355116691, 1017.5878769098379, 1043.4052590023614, 1080.378782200238, 1202.555054629775, 32805.83597081999, 33096.1725956853, 33148.50277245843, 33267.334653306796, 33503.497447538306, 1187.827764974074, 1189.5836457239898, 1235.5675513570359, 1235.7590269897285, 1258.2450529682117, 16757.49727508177, 17240.65518689619, 17373.565660858327, 17525.7371139433, 17561.473155805346, 650706.0, 652866.0, 659573.0, 660961.0, 666840.0, 42500.57499425028, 42745.0, 42825.357619635106, 42848.78575607102, 42932.0, 1389.3817251323064, 1435.318223843666, 1448.2469116059801, 1466.2668665667165, 1475.8597933196545, 30607.2348191297, 31401.214969625944, 31641.36717265596, 31722.36555268928, 32115.0, 52355.476445236585, 53057.0, 53284.0, 53330.73334633302, 53352.0, 529669.0, 530181.0, 531756.0, 531931.0, 533261.0, 26718.732812671624, 29095.12714618565, 29169.854150729112, 29891.402171956874, 30273.848630757588, 50260.0, 50699.0, 50788.23817642812, 50949.23576146435, 50999.0, 41897.16205675808, 43449.3482597755, 43726.0, 44148.55851441444, 44594.55405445904, 42475.575244247164, 42806.35790463083, 43590.34614480722, 43724.0, 44537.0, 42253.78873105615, 42669.719908402796, 42745.0, 43106.0, 43162.78418607887, 36176.819115904254, 37910.0, 38321.80839095786, 38347.69913805181, 39990.40014399844, 28081.85587540334, 29166.56250156292, 29234.269143271587, 29426.0, 29770.0], "value": 19861.857135160866, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.45836953590816, 417.2218811915778, 428.75989445910295, 431.3508170203844, 439.7515403796855, 19491.512712182204, 20450.795492045076, 20928.581428371443, 21455.67816482754, 21705.3488395348, 19828.107735151916, 20493.795062049376, 20586.38240852773, 20928.58142837146, 20962.895185524052, 5787.971060144693, 6037.396260373949, 6039.275286965571, 6042.154098426224, 6060.545459090564, 5818.534517238632, 5991.340952495222, 6052.54605904559, 6055.061465472859, 6076.4834989025985, 6035.849103772419, 6053.394660533955, 6054.152418661391, 6056.636601803883, 6057.727402266886, 5826.388229235911, 5968.4329988651225, 5970.1343305220935, 5975.940240597625, 6007.849803754919, 5343.545798607123, 5357.491038351351, 5360.061989151922, 5401.297831281948, 5403.675779453245, 23938.401539961465, 23955.520889582283, 23988.160414385413, 24051.39871503209, 24303.756962430325, 2673.2915777319026, 2678.370582913014, 2739.8904043838224, 2748.6014527893344, 3036.1195253376495, 15010.924945375313, 15080.321385537678, 15205.543833685007, 15243.466478673194, 15305.0, 1920.3950755512108, 1983.41489260667, 1986.106252186522, 2017.1931227508967, 2043.703662968876, 77895.22104778994, 79736.60131699304, 80125.0, 80571.5971420145, 80916.59541702371, 10663.786724265627, 11178.105751539857, 11233.775324493465, 11252.099832013419, 11262.267952583219, 15913.283902224197, 15937.920310398375, 15957.361705531881, 15968.121753303447, 16008.0, 8401.915980840113, 8416.537090460084, 8520.446170998865, 8566.443181193203, 8593.398462107592, 7641.503302285334, 7685.579916937021, 7776.766696999099, 8041.557714325768, 8457.873131903027, 6705.966470167618, 6869.969504574354, 6913.5506192097355, 6946.340097690711, 6958.8956165657555, 2031.0, 2073.3261689950828, 2088.446561661161, 2089.6447603907372, 2101.264557404896, 866.8613021916464, 1013.1438934100673, 1014.7716763728102, 1023.7747695506977, 1129.5989923577176, 18577.721334179998, 20392.184312627625, 20757.377278681663, 20916.581668366547, 21038.579228415347, 1039.3296323871061, 1058.5448257249302, 1070.4166229404993, 1073.0878753059837, 1135.1316243074054, 10871.728206794893, 10934.4532773361, 11985.34080625574, 12075.939620301842, 12139.0, 620101.0, 621297.0, 621583.0, 636708.0, 646428.0, 66197.0, 86028.0, 96391.0, 97522.51238743903, 97524.0, 968.1915600473683, 1037.2946396450336, 1067.2315932528568, 1075.575147816608, 1078.5308390849848, 18081.728774068135, 18168.818311816714, 18449.63100737951, 18694.0, 19116.713249300992, 42496.0, 46218.76890615525, 47141.057178855546, 47464.52535474601, 48733.5126648729, 54020.0, 54522.727386362814, 64761.0, 65421.345786541526, 65432.67283663551, 8760.561971901507, 9235.815283694423, 9262.490563019099, 9298.209652179641, 9335.066493350882, 11242.0, 14052.156870587798, 14228.928855355656, 14229.928850355682, 14557.12657240569, 12170.33063181498, 12271.877281227431, 12286.324252166216, 12411.379431028594, 12614.180078294881, 12111.757764844477, 12277.75444491123, 12342.938285308515, 12450.377481126088, 12775.361231938552, 12550.937245313715, 12558.811617825922, 12832.807507887203, 12840.293783841978, 12963.092583519441, 7725.381969442319, 8315.293200078062, 8480.194381533744, 8487.660493580188, 9255.583498742439, 9333.953330233306, 11650.76698466043, 12125.393730313273, 12320.507179712711, 12649.114561980574], "value": 9883.766412836725, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.6847746212027, 1548.365170280185, 1687.5106219196434, 1819.3177558415603, 1832.578506943402, 9025.142611451909, 9762.951185244081, 9784.951075244613, 9789.608415663373, 9796.41221526709, 8173.836523269531, 8411.579421028955, 8423.326133909288, 8638.52488113155, 9583.185429238523, 2402.1712509184304, 2477.306354220821, 2477.3930387055107, 2501.2996361018854, 2501.9249422517355, 2505.9749402506013, 2525.6590360301398, 2540.695116586003, 2547.4905018996183, 2556.335352808265, 2508.5610018246734, 2520.5211009908057, 2521.3948652323493, 2524.255344673326, 2558.884850181737, 2345.4370950971816, 2350.0, 2355.105060077168, 2362.1496261345815, 2364.231624721955, 2451.0318424222332, 2451.4116612013167, 2453.9754602453927, 2455.9631605525933, 2460.237326428795, 9444.622215111387, 9626.08552187548, 9645.71062868115, 9653.17947974423, 9662.13040826329, 5574.944250557523, 5710.0, 5715.1713001614535, 5720.141978703186, 5720.570957178228, 3487.4071407860724, 3533.6819686228105, 3679.3745063339297, 3681.263747250534, 3685.5761587417524, 1194.8625908020601, 1224.3510939202238, 1228.6498347970876, 1242.2981015726086, 1253.1729058821134, 13244.867551324556, 13276.53532126371, 13288.402021909038, 13288.734225315537, 13302.40139193729, 4880.292357608127, 4897.461279259279, 4975.4278258000795, 5064.341635587423, 5084.796608135707, 11182.944085279521, 11196.880311968738, 11203.943980280046, 11216.439178041066, 11223.38271395081, 5757.395473475225, 5759.65442073477, 5761.654300741969, 5763.077907534775, 5772.04761214395, 5206.76569554371, 5272.130098533776, 5273.894522109613, 5290.418054014055, 5311.707856067954, 4220.472440944882, 4240.448741663564, 4241.936370954438, 4318.654507639381, 4328.891777705519, 1339.7454483648075, 1346.9191848489122, 1350.3990724127668, 1350.8851747601564, 1352.4117009100876, 1031.9122874555596, 1042.7966546523355, 1045.8483519889726, 1055.5355643516828, 1071.3893080943787, 6359.682015899187, 6378.84052898672, 6496.415322620986, 6571.211454625475, 6678.23200331968, 493.9357883475125, 507.3075252280684, 510.27031345176755, 510.90548248574135, 511.9923201151986, 4294.892627684333, 4311.07311927937, 4319.222539942841, 4464.665150113755, 4477.597016268552, 10894.0, 11405.429728513707, 11426.143039272089, 11428.428578571038, 11433.0, 11115.166362522843, 11302.717432064264, 11330.943345283385, 11344.035756960586, 11691.181617286878, 8469.57652117404, 8644.005939316934, 8667.653293868301, 8673.04596494385, 8680.8697869532, 6468.6442245676935, 6542.214934207926, 6634.867302654016, 6731.865362692621, 6867.793966181022, 10633.521491532745, 11075.446227688744, 11119.054880335261, 11251.549938002716, 11286.830697539379, 10119.645812396531, 11308.83036754433, 11385.316880987168, 11441.599544015919, 11446.94276528612, 8223.424360294723, 8410.411271210956, 8541.060483346702, 8691.0, 8709.91290087091, 9311.068893110634, 9321.860172097287, 9384.296177786557, 9420.057994200799, 9437.81124377495, 8386.916130838857, 8486.957565212135, 8512.489250644981, 8693.826123477622, 8723.389362744547, 8139.14538973423, 8249.670013199404, 8333.6666533338, 8338.583070846555, 8548.401611887108, 7256.092988376453, 7364.374028207662, 7371.447141464519, 7371.778846634609, 7374.188839227788, 5516.475934786189, 5610.214569960291, 5624.7453011162215, 5650.745716442688, 5787.218725472176, 5423.945760542344, 5437.211604317431, 5765.509931655856, 5769.365369809401, 5942.168096466412], "value": 4907.67535735353, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.06056340122615, 339.30611898667223, 341.9469982152767, 354.82436194083925, 355.3603513675384, 5640.351359593648, 5641.633293835897, 5650.915236271454, 5655.4061823508555, 5688.800891968782, 5270.683758974455, 5416.0, 5543.780368318963, 5562.193481945115, 5590.413006634296, 4363.127374525092, 4372.366006928985, 4376.365427013081, 4383.276759334722, 4384.123175364924, 4360.36774667673, 4366.956330436703, 4397.296432570789, 4397.692161548693, 4417.22698527758, 4201.558836322188, 4312.727745315124, 4337.891552711191, 4377.912441751163, 4382.12357528494, 4360.563943605645, 4361.367601697768, 4367.335925085696, 4369.825206991716, 4375.6280716138845, 3868.3810590305416, 3875.4380614810975, 3879.603342796619, 3888.5333759948703, 3910.3967373377063, 5165.251038599382, 5403.243545903577, 5440.129579267298, 5440.619156659035, 5464.23500709901, 4951.3810773653295, 4960.826371076995, 4967.875803104951, 4979.551840334388, 4986.975065124651, 5783.103618939072, 5791.391903850077, 5811.099279611667, 5840.3575606683235, 5844.1818145459665, 4117.649999750025, 4139.4204811326435, 4163.375493675942, 4215.0, 4235.618794308527, 6092.770831459334, 6128.540359473012, 6132.356102609251, 6141.0, 6160.599561028519, 4358.411614432074, 4368.4102646143, 4369.060651959844, 4386.583274588909, 4406.669499787528, 4212.789360531961, 4392.275274579659, 4396.626286765597, 4422.601965823092, 4471.418715566955, 4972.65191436596, 4974.676646017997, 4981.23166275974, 4983.0, 5020.748962551857, 5869.941300586939, 5911.940880591225, 5914.526837852962, 5975.372585878507, 6006.939930600725, 1443.718474897385, 1451.477468111479, 1465.1794994802945, 1531.5635044012404, 1534.1485475561171, 1013.0983424751977, 1017.389566260238, 1034.3897100710617, 1045.6862941117633, 1048.3919326790444, 5640.5769567281795, 5689.516391106719, 5691.26013618227, 5693.430656934356, 5725.856853578611, 5953.553483488756, 5966.552508561876, 5974.581779275496, 5976.641401515923, 6002.4897883679505, 5497.285352904177, 5652.98637967048, 5655.830325090254, 5659.773609055674, 5683.403242659544, 2785.874635641401, 2795.4688609164195, 2806.6632004159633, 2831.561108028259, 2848.7038397529072, 5430.456954304538, 5434.266374039454, 5438.45615438453, 5439.086129681305, 5471.972640136853, 5171.612129090333, 5358.687121655219, 5386.145342968233, 5408.459154084639, 5421.855323912121, 5396.757145928443, 5400.729963501809, 5424.186372044225, 5428.294321738228, 5435.146548062299, 5096.929644774639, 5143.916713730945, 5283.286756287852, 5320.856015956588, 5364.865878353073, 5190.6107041971245, 5203.479652034766, 5211.296474975829, 5216.634835561475, 5231.973840130775, 5420.701861397503, 5439.238506609157, 5443.618946673695, 5532.529734972572, 5536.972315138399, 3941.349677303213, 3948.5587760466647, 3978.204359128128, 4081.6938729594804, 4373.978130109329, 6075.1798507200965, 6078.54410919174, 6082.574219804571, 6083.878322433438, 6092.421219984094, 5685.118806584986, 5841.620294680833, 5843.415658434123, 5920.970395147997, 5933.940660593339, 5917.822465326047, 5926.140709596998, 5928.940710593011, 5932.347441781487, 5947.910781338197, 5755.287839343684, 5896.64620122794, 5908.0, 5916.698326368192, 5938.317093534299, 5925.644461332334, 5948.434898684618, 5948.650283138223, 5966.433188847052, 5967.134765459071, 5689.772409103755, 5794.855128621818, 5818.214541036906, 5833.649981001154, 5851.473367396955], "value": 4371.604823835918, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.1487992967599, 815.1359558867599, 817.7996390884231, 824.5052968219073, 831.8086840026793, 14543.854561454382, 15788.289526971284, 15797.28912198951, 15827.366905323788, 15864.286107125192, 13957.0, 14011.509597164102, 14028.298585070757, 14031.0, 14032.228227447486, 714.1572943926178, 851.6252848746532, 870.6473878079388, 881.2333270055029, 883.3728053082287, 876.5923845411883, 877.6138499060424, 879.7800549862534, 880.2254016465502, 880.9603567839463, 929.0894922975488, 931.9720408387759, 932.4917919733728, 932.5523748600676, 934.6775362499926, 931.6133804471136, 933.304688007432, 933.3186773655236, 933.6918816790443, 933.7012156110047, 684.449018540075, 685.4173952140691, 685.4379408884716, 685.9382655561024, 686.1217641418991, 13423.597292081253, 13434.328283585879, 13475.19148851072, 13483.528076517372, 13487.932560337136, 13832.170069795844, 14173.929130354281, 14217.901001134478, 14808.925955370154, 16343.836561634469, 14527.491537796126, 14571.344289506891, 14592.270386480739, 14646.487372942003, 14887.77668334965, 4784.5933095686905, 4796.3524924135145, 4855.0, 4864.927026094611, 4950.504949505057, 88158.0, 88989.55505222497, 90038.0, 90414.0, 90476.09523904743, 12129.514819407124, 12480.0, 12525.93737031309, 12615.621531354074, 12707.872921270853, 22581.774182257966, 22590.435239119153, 22613.095476181097, 22620.0951961923, 22621.43446413853, 8443.274389032338, 8484.27883629886, 8796.604152813139, 8815.074417186232, 8937.553122343857, 4816.253480710496, 4932.186189278729, 4937.901241975141, 4994.950050499521, 5027.1705168647495, 3625.9456108158397, 3767.7174211934216, 3768.378217594121, 3773.8301776420126, 3781.640744129303, 647.9384458476437, 658.5686375424104, 665.4277321503503, 666.1506579111672, 668.8227619680788, 574.4800955135609, 693.237438817297, 699.7655785311978, 702.5995182745786, 720.3696765330336, 9363.531823408939, 9396.295277854188, 9746.269029822792, 9804.803903921882, 9842.655507057361, 595.9255093113361, 653.0498125227782, 669.6417416682052, 673.6093066021696, 704.8132244955091, 4278.422412974209, 4296.9570304297185, 4550.112728018004, 4582.587567118975, 4610.193216187154, 93245.53377233206, 95165.0, 125673.0, 137197.0, 137929.0, 98026.50986745021, 98917.50541247247, 101993.0, 102105.48947255216, 103136.48431757794, 87730.0, 89936.5503172493, 91369.0, 91428.0, 92065.53967230255, 10271.0, 10323.174146068146, 10375.27373083877, 10768.461576920967, 10783.568657253621, 51622.22566661428, 51877.7406112967, 52042.0, 52157.47842521526, 54998.0, 71494.0, 76612.0, 78342.0, 78955.21044789479, 79289.60355198187, 13918.930405347908, 14196.645083872985, 14211.786823197453, 14617.707645847237, 14646.780298295747, 15926.60183495422, 16023.759643605588, 16092.597685057966, 16097.114658693416, 16951.49145525636, 13869.514566990107, 13949.093308934887, 14008.719825603226, 14835.851641483447, 14885.404583816526, 14795.704085918436, 15426.460073897808, 15671.68656626884, 16008.359665613245, 16442.917785410995, 13848.930755346159, 13856.445742170603, 13929.930350348184, 14342.211178385289, 14802.407903683732, 12281.324527151093, 12554.560590379293, 12707.93646031764, 13071.803922940973, 13157.079004469595, 10903.509342079467, 11105.333679979227, 11712.169185733956, 11866.940665296619, 11880.643580692593], "value": 7969.837513304381, "unit": "score"}], "name": "dromaeo_css", "value": 7564.714885323305, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 119.0, 110.0, 109.0, 111.0, 108.0, 110.0, 109.0, 109.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 144.0, 180.0, 120.0, 138.0, 143.0, 116.0, 179.0, 116.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 167.0, 76.0, 76.0, 74.0, 75.0, 170.0, 74.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 162.0, 83.0, 83.0, 83.0, 146.0, 84.0, 85.0, 141.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 51.0, 50.0, 50.0, 52.0, 50.0, 51.0, 53.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 62.0, 62.0, 61.0, 60.0, 61.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 72.0, 73.0, 77.0, 79.0, 72.0, 75.0, 73.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 138.0, 121.0, 121.0, 122.0, 119.0, 121.0, 162.0, 120.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 154.0, 154.0, 160.0, 160.0, 156.0, 168.0, 156.0, 156.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 65.0, 62.0, 62.0, 62.0, 71.0, 63.0, 62.0, 61.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1503.9999999999998, "alertThreshold": 2.0}]} 10:18:40 INFO - Return code: 0 10:18:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:40 INFO - # TBPL SUCCESS # 10:18:40 INFO - Running post-action listener: _resource_record_post_action 10:18:40 INFO - [mozharness: 2016-10-14 17:18:40.191597Z] Finished run-tests step (success) 10:18:40 INFO - Running post-run listener: _resource_record_post_run 10:18:40 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 2387968; Write bytes: 284958720; Read time: 164; Write time: 131300 10:18:40 INFO - TinderboxPrint: CPU usage
12.9% 10:18:40 INFO - TinderboxPrint: I/O read bytes / time
2,387,968 / 164 10:18:40 INFO - TinderboxPrint: I/O write bytes / time
284,958,720 / 131,300 10:18:40 INFO - TinderboxPrint: CPU idle
8,256.6 (87.1%) 10:18:40 INFO - TinderboxPrint: CPU user
1,183.2 (12.5%) 10:18:40 INFO - TinderboxPrint: Swap in / out
0 / 0 10:18:40 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70103040; Read time: 0; Write time: 30364 10:18:40 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 651264; Write bytes: 214310912; Read time: 108; Write time: 100920 10:18:40 INFO - Running post-run listener: _upload_blobber_files 10:18:40 INFO - Blob upload gear active. 10:18:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:40 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 10:18:40 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'] 10:18:40 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 10:18:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:45 INFO - (blobuploader) - INFO - Done attempting. 10:18:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:46 INFO - (blobuploader) - INFO - Done attempting. 10:18:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:47 INFO - (blobuploader) - INFO - Done attempting. 10:18:47 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:47 INFO - Return code: 0 10:18:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b67c2326b8c2e01a806b0c0392c163d3b530608c849176ba7000b74d4ba007e266fa137fcd6c838a631dec648cc71e859d5d972f53159febb7445d6dbef8045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166d0e41fbfeffae5c1401e4734fbe55e9262acb55edde28742fdcddd09feec001051d30c3883e2f04da9c3c5825db9f12c0f4c9549e447188ef8e2be184dfa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef994465427baf2b6c665ec101151f0c7e0164c344e477cf5ee084771d89a545a8ea6d777d816bae574e31fb60e0452649105f44c22f06ada5a88ec0661a95ec"} 10:18:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:47 INFO - Contents: 10:18:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b67c2326b8c2e01a806b0c0392c163d3b530608c849176ba7000b74d4ba007e266fa137fcd6c838a631dec648cc71e859d5d972f53159febb7445d6dbef8045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166d0e41fbfeffae5c1401e4734fbe55e9262acb55edde28742fdcddd09feec001051d30c3883e2f04da9c3c5825db9f12c0f4c9549e447188ef8e2be184dfa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef994465427baf2b6c665ec101151f0c7e0164c344e477cf5ee084771d89a545a8ea6d777d816bae574e31fb60e0452649105f44c22f06ada5a88ec0661a95ec"} 10:18:47 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:47 INFO - Copying logs to upload dir... 10:18:47 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.881136 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-14 10:18:47.319798) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 10:18:47.323250) ========= 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-1476463848.977673-633397503 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/1b67c2326b8c2e01a806b0c0392c163d3b530608c849176ba7000b74d4ba007e266fa137fcd6c838a631dec648cc71e859d5d972f53159febb7445d6dbef8045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166d0e41fbfeffae5c1401e4734fbe55e9262acb55edde28742fdcddd09feec001051d30c3883e2f04da9c3c5825db9f12c0f4c9549e447188ef8e2be184dfa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef994465427baf2b6c665ec101151f0c7e0164c344e477cf5ee084771d89a545a8ea6d777d816bae574e31fb60e0452649105f44c22f06ada5a88ec0661a95ec"} build_url:https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011406 build_url: 'https://queue.taskcluster.net/v1/task/PdcepqZtR8mUTulnMyj8FQ/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/1b67c2326b8c2e01a806b0c0392c163d3b530608c849176ba7000b74d4ba007e266fa137fcd6c838a631dec648cc71e859d5d972f53159febb7445d6dbef8045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/166d0e41fbfeffae5c1401e4734fbe55e9262acb55edde28742fdcddd09feec001051d30c3883e2f04da9c3c5825db9f12c0f4c9549e447188ef8e2be184dfa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef994465427baf2b6c665ec101151f0c7e0164c344e477cf5ee084771d89a545a8ea6d777d816bae574e31fb60e0452649105f44c22f06ada5a88ec0661a95ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 10:18:47.352103) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:18:47.352860) ========= 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-1476463848.977673-633397503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:18:47.433034) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 10:18:47.433399) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 10:18:47.433799) ========= ========= Total master_lag: 0.18 =========