builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1476913724.11 results: success (0) buildid: 20161019120023 builduid: 1e2814078e4e497eae85f50656771125 revision: 0c46713f5b17e943e39866326bd040f639364b8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.109468) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.109974) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.110322) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.125160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.125513) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-19 14:48:44-- 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.7M=0s 2016-10-19 14:48:44 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075277 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.215308) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.215672) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018785 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.265569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:44.265973) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0c46713f5b17e943e39866326bd040f639364b8e --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 0c46713f5b17e943e39866326bd040f639364b8e --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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-19 14:48:44,599 truncating revision to first 12 chars 2016-10-19 14:48:44,599 Setting DEBUG logging. 2016-10-19 14:48:44,599 attempt 1/10 2016-10-19 14:48:44,599 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0c46713f5b17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 14:48:44,919 unpacking tar archive at: mozilla-beta-0c46713f5b17/testing/mozharness/ program finished with exit code 0 elapsedTime=0.759547 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:45.040030) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:45.040496) ========= script_repo_revision: 0c46713f5b17e943e39866326bd040f639364b8e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:45.041022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:45.041407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 14:48:45.051484) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-19 14:48:45.051981) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:48:45 INFO - MultiFileLogger online at 20161019 14:48:45 in /builds/slave/test 14:48:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:48:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:48:45 INFO - {'append_to_log': False, 14:48:45 INFO - 'base_work_dir': '/builds/slave/test', 14:48:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:48:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:48:45 INFO - 'branch': 'Mozilla-Beta', 14:48:45 INFO - 'buildbot_json_path': 'buildprops.json', 14:48:45 INFO - 'config_files': ('talos/linux_config.py',), 14:48:45 INFO - 'default_actions': ('clobber', 14:48:45 INFO - 'read-buildbot-config', 14:48:45 INFO - 'download-and-extract', 14:48:45 INFO - 'populate-webroot', 14:48:45 INFO - 'create-virtualenv', 14:48:45 INFO - 'install', 14:48:45 INFO - 'run-tests'), 14:48:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:48:45 INFO - 'download_minidump_stackwalk': True, 14:48:45 INFO - 'download_symbols': 'ondemand', 14:48:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:48:45 INFO - 'tooltool.py': '/tools/tooltool.py', 14:48:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:48:45 INFO - '/tools/misc-python/virtualenv.py')}, 14:48:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:48:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:48:45 INFO - 'installer_path': 'installer.exe', 14:48:45 INFO - 'log_level': 'info', 14:48:45 INFO - 'log_name': 'talos', 14:48:45 INFO - 'log_to_console': True, 14:48:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:48:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:48:45 INFO - 'opt_config_files': (), 14:48:45 INFO - 'pip_index': False, 14:48:45 INFO - 'sps_profile': False, 14:48:45 INFO - 'sps_profile_interval': 0, 14:48:45 INFO - 'suite': 'dromaeojs', 14:48:45 INFO - 'system_bits': '32', 14:48:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:48:45 INFO - 'title': 'talos-linux64-ix-040', 14:48:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:48:45 INFO - 'use_talos_json': True, 14:48:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:48:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:48:45 INFO - 'work_dir': 'build'} 14:48:45 INFO - [mozharness: 2016-10-19 21:48:45.480693Z] Running clobber step. 14:48:45 INFO - Running pre-action listener: _resource_record_pre_action 14:48:45 INFO - Running main action method: clobber 14:48:45 INFO - rmtree: /builds/slave/test/build 14:48:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:48:45 INFO - Running post-action listener: _resource_record_post_action 14:48:45 INFO - [mozharness: 2016-10-19 21:48:45.953692Z] Finished clobber step (success) 14:48:45 INFO - [mozharness: 2016-10-19 21:48:45.953794Z] Running read-buildbot-config step. 14:48:45 INFO - Running pre-action listener: _resource_record_pre_action 14:48:45 INFO - Running main action method: read_buildbot_config 14:48:45 INFO - Using buildbot properties: 14:48:45 INFO - { 14:48:45 INFO - "project": "", 14:48:45 INFO - "product": "firefox", 14:48:45 INFO - "script_repo_revision": "production", 14:48:45 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:48:45 INFO - "repository": "", 14:48:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:48:45 INFO - "buildid": "20161019120023", 14:48:45 INFO - "pgo_build": "True", 14:48:45 INFO - "basedir": "/builds/slave/test", 14:48:45 INFO - "buildnumber": 197, 14:48:45 INFO - "slavename": "talos-linux64-ix-040", 14:48:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:48:45 INFO - "platform": "ubuntu64_hw", 14:48:45 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:48:45 INFO - "branch": "mozilla-beta", 14:48:45 INFO - "repo_path": "releases/mozilla-beta", 14:48:45 INFO - "revision": "0c46713f5b17e943e39866326bd040f639364b8e", 14:48:45 INFO - "stage_platform": "linux64-pgo", 14:48:45 INFO - "builduid": "1e2814078e4e497eae85f50656771125", 14:48:45 INFO - "slavebuilddir": "test" 14:48:45 INFO - } 14:48:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 14:48:45 INFO - Running post-action listener: _resource_record_post_action 14:48:45 INFO - [mozharness: 2016-10-19 21:48:45.956232Z] Finished read-buildbot-config step (success) 14:48:45 INFO - [mozharness: 2016-10-19 21:48:45.956320Z] Running download-and-extract step. 14:48:45 INFO - Running pre-action listener: _resource_record_pre_action 14:48:45 INFO - Running main action method: download_and_extract 14:48:45 INFO - mkdir: /builds/slave/test/build/tests 14:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:45 INFO - https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:48:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:48:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:48:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:48:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:48:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:48:45 INFO - retry: Failed, sleeping 30 seconds before retrying 14:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:49:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:49:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:49:15 INFO - retry: Failed, sleeping 60 seconds before retrying 14:50:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:50:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:50:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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! 14:50:16 INFO - Caught exception: HTTP Error 404: Not Found 14:50:16 INFO - Caught exception: HTTP Error 404: Not Found 14:50:16 INFO - Caught exception: HTTP Error 404: Not Found 14:50:16 INFO - trying https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:50:16 INFO - Downloading https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:16 INFO - Downloaded 1327 bytes. 14:50:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:50:16 INFO - Using the following test package requirements: 14:50:16 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:50:16 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:50:16 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:50:16 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'jsshell-linux-x86_64.zip'], 14:50:16 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:50:16 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:50:16 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:50:16 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:50:16 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:50:16 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:50:16 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:50:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:50:16 INFO - https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:50:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:50:16 INFO - retry: Failed, sleeping 30 seconds before retrying 14:50:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:50:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:50:46 INFO - retry: Failed, sleeping 60 seconds before retrying 14:51:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:51:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:51:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:51:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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! 14:51:46 INFO - Caught exception: HTTP Error 404: Not Found 14:51:46 INFO - Caught exception: HTTP Error 404: Not Found 14:51:46 INFO - Caught exception: HTTP Error 404: Not Found 14:51:46 INFO - trying https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:51:46 INFO - Downloading https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:51:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:51:47 INFO - Downloaded 21774985 bytes. 14:51:47 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'] 14:51:47 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 14:51:48 INFO - Return code: 0 14:51:48 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 14:51:48 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')]} 14:51:48 INFO - https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:51:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:51:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:51:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:51:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:51:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:51:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:51:48 INFO - retry: Failed, sleeping 30 seconds before retrying 14:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:52:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:52:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:52:18 INFO - retry: Failed, sleeping 60 seconds before retrying 14:53:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:53:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:53:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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! 14:53:18 INFO - Caught exception: HTTP Error 404: Not Found 14:53:18 INFO - Caught exception: HTTP Error 404: Not Found 14:53:18 INFO - Caught exception: HTTP Error 404: Not Found 14:53:18 INFO - trying https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:53:18 INFO - Downloading https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:20 INFO - Downloaded 21774985 bytes. 14:53:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:53:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:53:21 INFO - Return code: 0 14:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:21 INFO - https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:53:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:53:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:53:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:53:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:53:21 INFO - retry: Failed, sleeping 30 seconds before retrying 14:53:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:53:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:53:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:53:51 INFO - retry: Failed, sleeping 60 seconds before retrying 14:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:54:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:54:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:54:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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! 14:54:51 INFO - Caught exception: HTTP Error 404: Not Found 14:54:51 INFO - Caught exception: HTTP Error 404: Not Found 14:54:51 INFO - Caught exception: HTTP Error 404: Not Found 14:54:51 INFO - trying https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:54:51 INFO - Downloading https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:54:52 INFO - Downloaded 14413762 bytes. 14:54:52 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'] 14:54:52 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 14:54:52 INFO - Return code: 0 14:54: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')]} 14:54:52 INFO - https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:54:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:54:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:54:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:54:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:54:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:54:52 INFO - retry: Failed, sleeping 30 seconds before retrying 14:55:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:55:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:55:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:55:22 INFO - retry: Failed, sleeping 60 seconds before retrying 14:56:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:56:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:56:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:56:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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! 14:56:22 INFO - Caught exception: HTTP Error 404: Not Found 14:56:22 INFO - Caught exception: HTTP Error 404: Not Found 14:56:22 INFO - Caught exception: HTTP Error 404: Not Found 14:56:22 INFO - trying https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:56:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:56:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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 14:56:23 INFO - Downloaded 56802534 bytes. 14:56:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:56:23 INFO - mkdir: /builds/slave/test/properties 14:56:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:56:23 INFO - Writing to file /builds/slave/test/properties/build_url 14:56:23 INFO - Contents: 14:56:23 INFO - build_url:https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:56:24 INFO - Running post-action listener: _resource_record_post_action 14:56:24 INFO - Running post-action listener: set_extra_try_arguments 14:56:24 INFO - [mozharness: 2016-10-19 21:56:24.539758Z] Finished download-and-extract step (success) 14:56:24 INFO - [mozharness: 2016-10-19 21:56:24.539990Z] Running populate-webroot step. 14:56:24 INFO - Running pre-action listener: _resource_record_pre_action 14:56:24 INFO - Running main action method: populate_webroot 14:56:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:56:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:56:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:56:24 INFO - '--tppagecycles', 14:56:24 INFO - '7'], 14:56:24 INFO - 'tests': ['tsvgm']}}, 14:56:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:56:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:56:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:56:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:56:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:56:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:56:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['damp', 'tps']}, 14:56:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'tests': ['damp', 'tps']}, 14:56:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['dromaeo_dom']}, 14:56:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:56:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['basic_compositor_video']}, 14:56:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:56:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'other-e10s': {'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['a11yr', 14:56:24 INFO - 'ts_paint', 14:56:24 INFO - 'tpaint', 14:56:24 INFO - 'sessionrestore', 14:56:24 INFO - 'sessionrestore_no_auto_restore', 14:56:24 INFO - 'tabpaint']}, 14:56:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['tsvgx', 14:56:24 INFO - 'tsvgr_opacity', 14:56:24 INFO - 'tart', 14:56:24 INFO - 'tscrollx', 14:56:24 INFO - 'cart']}, 14:56:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:56:24 INFO - 'tsvgr_opacity', 14:56:24 INFO - 'tart', 14:56:24 INFO - 'tscrollx', 14:56:24 INFO - 'cart']}, 14:56:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'talos_options': ['--disable-e10s'], 14:56:24 INFO - 'tests': ['tp5o']}, 14:56:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'tests': ['tp5o']}, 14:56:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'talos_options': ['--disable-e10s', 14:56:24 INFO - '--xperf_path', 14:56:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:56:24 INFO - 'tests': ['tp5n']}, 14:56:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:56:24 INFO - 'talos_options': ['--xperf_path', 14:56:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:56:24 INFO - 'tests': ['tp5n']}}, 14:56:24 INFO - 'talos.zip': {'path': '', 14:56:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:56:24 INFO - Running post-action listener: _resource_record_post_action 14:56:24 INFO - [mozharness: 2016-10-19 21:56:24.561873Z] Finished populate-webroot step (success) 14:56:24 INFO - [mozharness: 2016-10-19 21:56:24.561962Z] Running create-virtualenv step. 14:56:24 INFO - Running pre-action listener: _resource_record_pre_action 14:56:24 INFO - Running main action method: create_virtualenv 14:56:24 INFO - Creating virtualenv /builds/slave/test/build/venv 14:56:24 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 14:56:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:56:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:56:24 INFO - Using real prefix '/usr' 14:56:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:56:25 INFO - Installing distribute.............................................................................................................................................................................................done. 14:56:27 INFO - Installing pip.................done. 14:56:27 INFO - Return code: 0 14:56:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:56:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:56:27 INFO - Using env: {'DISPLAY': ':0', 14:56:27 INFO - 'HOME': '/home/cltbld', 14:56:27 INFO - 'LANG': 'en_US.UTF-8', 14:56:27 INFO - 'LANGUAGE': 'en_US:en', 14:56:27 INFO - 'LOGNAME': 'cltbld', 14:56:27 INFO - 'MAIL': '/var/mail/cltbld', 14:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:27 INFO - 'MOZ_NO_REMOTE': '1', 14:56:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:27 INFO - 'NO_EM_RESTART': '1', 14:56:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:27 INFO - 'PWD': '/builds/slave/test', 14:56:27 INFO - 'SHELL': '/bin/bash', 14:56:27 INFO - 'SHLVL': '1', 14:56:27 INFO - 'TERM': 'linux', 14:56:27 INFO - 'TMOUT': '86400', 14:56:27 INFO - 'USER': 'cltbld', 14:56:27 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:27 INFO - '_': '/tools/buildbot/bin/python'} 14:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:27 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 14:56:27 INFO - Cleaning up... 14:56:27 INFO - Return code: 0 14:56:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:56:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:56:27 INFO - Using env: {'DISPLAY': ':0', 14:56:27 INFO - 'HOME': '/home/cltbld', 14:56:27 INFO - 'LANG': 'en_US.UTF-8', 14:56:27 INFO - 'LANGUAGE': 'en_US:en', 14:56:27 INFO - 'LOGNAME': 'cltbld', 14:56:27 INFO - 'MAIL': '/var/mail/cltbld', 14:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:27 INFO - 'MOZ_NO_REMOTE': '1', 14:56:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:27 INFO - 'NO_EM_RESTART': '1', 14:56:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:27 INFO - 'PWD': '/builds/slave/test', 14:56:27 INFO - 'SHELL': '/bin/bash', 14:56:27 INFO - 'SHLVL': '1', 14:56:27 INFO - 'TERM': 'linux', 14:56:27 INFO - 'TMOUT': '86400', 14:56:27 INFO - 'USER': 'cltbld', 14:56:27 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:27 INFO - '_': '/tools/buildbot/bin/python'} 14:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:27 INFO - Downloading/unpacking psutil>=3.1.1 14:56:27 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 14:56:27 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 14:56:27 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 14:56:27 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 14:56:27 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 14:56:27 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 14:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:56:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:56:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:56:29 INFO - Installing collected packages: psutil 14:56:29 INFO - Running setup.py install for psutil 14:56:29 INFO - building 'psutil._psutil_linux' extension 14:56:29 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 14:56:29 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 14:56:29 INFO - building 'psutil._psutil_posix' extension 14:56:29 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 14:56:29 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 14:56:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:56:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:56:30 INFO - Successfully installed psutil 14:56:30 INFO - Cleaning up... 14:56:30 INFO - Return code: 0 14:56:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:30 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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:30 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 14:56:30 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 14:56:30 INFO - Using env: {'DISPLAY': ':0', 14:56:30 INFO - 'HOME': '/home/cltbld', 14:56:30 INFO - 'LANG': 'en_US.UTF-8', 14:56:30 INFO - 'LANGUAGE': 'en_US:en', 14:56:30 INFO - 'LOGNAME': 'cltbld', 14:56:30 INFO - 'MAIL': '/var/mail/cltbld', 14:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:30 INFO - 'MOZ_NO_REMOTE': '1', 14:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:30 INFO - 'NO_EM_RESTART': '1', 14:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:30 INFO - 'PWD': '/builds/slave/test', 14:56:30 INFO - 'SHELL': '/bin/bash', 14:56:30 INFO - 'SHLVL': '1', 14:56:30 INFO - 'TERM': 'linux', 14:56:30 INFO - 'TMOUT': '86400', 14:56:30 INFO - 'USER': 'cltbld', 14:56:30 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:30 INFO - '_': '/tools/buildbot/bin/python'} 14:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:56:30 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 14:56:30 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 14:56:30 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 14:56:30 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 14:56:30 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 14:56:30 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 14:56:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:56:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:56:32 INFO - Installing collected packages: mozsystemmonitor 14:56:32 INFO - Running setup.py install for mozsystemmonitor 14:56:32 INFO - Successfully installed mozsystemmonitor 14:56:32 INFO - Cleaning up... 14:56:32 INFO - Return code: 0 14:56:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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', '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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:56: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 jsonschema==2.5.1 14:56:32 INFO - Using env: {'DISPLAY': ':0', 14:56:32 INFO - 'HOME': '/home/cltbld', 14:56:32 INFO - 'LANG': 'en_US.UTF-8', 14:56:32 INFO - 'LANGUAGE': 'en_US:en', 14:56:32 INFO - 'LOGNAME': 'cltbld', 14:56:32 INFO - 'MAIL': '/var/mail/cltbld', 14:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:32 INFO - 'MOZ_NO_REMOTE': '1', 14:56:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:32 INFO - 'NO_EM_RESTART': '1', 14:56:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:32 INFO - 'PWD': '/builds/slave/test', 14:56:32 INFO - 'SHELL': '/bin/bash', 14:56:32 INFO - 'SHLVL': '1', 14:56:32 INFO - 'TERM': 'linux', 14:56:32 INFO - 'TMOUT': '86400', 14:56:32 INFO - 'USER': 'cltbld', 14:56:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:32 INFO - '_': '/tools/buildbot/bin/python'} 14:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:32 INFO - Downloading/unpacking jsonschema==2.5.1 14:56:32 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 14:56:32 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 14:56:32 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 14:56:32 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 14:56:32 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 14:56:32 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 14:56:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:56:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:56:34 INFO - Downloading functools32-3.2.3-2.tar.gz 14:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:56:34 INFO - warning: no files found matching '*.txt' 14:56:34 INFO - no previously-included directories found matching 'build' 14:56:34 INFO - no previously-included directories found matching 'dist' 14:56:34 INFO - no previously-included directories found matching '.git*' 14:56:34 INFO - Installing collected packages: jsonschema, functools32 14:56:34 INFO - Running setup.py install for functools32 14:56:35 INFO - warning: no files found matching '*.txt' 14:56:35 INFO - no previously-included directories found matching 'build' 14:56:35 INFO - no previously-included directories found matching 'dist' 14:56:35 INFO - no previously-included directories found matching '.git*' 14:56:35 INFO - Successfully installed jsonschema functools32 14:56:35 INFO - Cleaning up... 14:56:35 INFO - Return code: 0 14:56:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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', '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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:56: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 functools32==3.2.3-2 14:56:35 INFO - Using env: {'DISPLAY': ':0', 14:56:35 INFO - 'HOME': '/home/cltbld', 14:56:35 INFO - 'LANG': 'en_US.UTF-8', 14:56:35 INFO - 'LANGUAGE': 'en_US:en', 14:56:35 INFO - 'LOGNAME': 'cltbld', 14:56:35 INFO - 'MAIL': '/var/mail/cltbld', 14:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:35 INFO - 'MOZ_NO_REMOTE': '1', 14:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:35 INFO - 'NO_EM_RESTART': '1', 14:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:35 INFO - 'PWD': '/builds/slave/test', 14:56:35 INFO - 'SHELL': '/bin/bash', 14:56:35 INFO - 'SHLVL': '1', 14:56:35 INFO - 'TERM': 'linux', 14:56:35 INFO - 'TMOUT': '86400', 14:56:35 INFO - 'USER': 'cltbld', 14:56:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:35 INFO - '_': '/tools/buildbot/bin/python'} 14:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:56:35 INFO - Cleaning up... 14:56:35 INFO - Return code: 0 14:56:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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', '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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:56: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 blobuploader==1.2.4 14:56:35 INFO - Using env: {'DISPLAY': ':0', 14:56:35 INFO - 'HOME': '/home/cltbld', 14:56:35 INFO - 'LANG': 'en_US.UTF-8', 14:56:35 INFO - 'LANGUAGE': 'en_US:en', 14:56:35 INFO - 'LOGNAME': 'cltbld', 14:56:35 INFO - 'MAIL': '/var/mail/cltbld', 14:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:35 INFO - 'MOZ_NO_REMOTE': '1', 14:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:35 INFO - 'NO_EM_RESTART': '1', 14:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:35 INFO - 'PWD': '/builds/slave/test', 14:56:35 INFO - 'SHELL': '/bin/bash', 14:56:35 INFO - 'SHLVL': '1', 14:56:35 INFO - 'TERM': 'linux', 14:56:35 INFO - 'TMOUT': '86400', 14:56:35 INFO - 'USER': 'cltbld', 14:56:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:35 INFO - '_': '/tools/buildbot/bin/python'} 14:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:35 INFO - Downloading/unpacking blobuploader==1.2.4 14:56:35 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 14:56:35 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 14:56:35 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 14:56:35 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 14:56:35 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 14:56:35 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 14:56:37 INFO - Downloading blobuploader-1.2.4.tar.gz 14:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:56:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:56:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:37 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 14:56:38 INFO - Downloading docopt-0.6.1.tar.gz 14:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:56:38 INFO - Installing collected packages: blobuploader, requests, docopt 14:56:38 INFO - Running setup.py install for blobuploader 14:56:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:56:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:56:38 INFO - Running setup.py install for requests 14:56:38 INFO - Running setup.py install for docopt 14:56:38 INFO - Successfully installed blobuploader requests docopt 14:56:38 INFO - Cleaning up... 14:56:38 INFO - Return code: 0 14:56:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:38 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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:38 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 14:56:38 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 14:56:38 INFO - Using env: {'DISPLAY': ':0', 14:56:38 INFO - 'HOME': '/home/cltbld', 14:56:38 INFO - 'LANG': 'en_US.UTF-8', 14:56:38 INFO - 'LANGUAGE': 'en_US:en', 14:56:38 INFO - 'LOGNAME': 'cltbld', 14:56:38 INFO - 'MAIL': '/var/mail/cltbld', 14:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:38 INFO - 'MOZ_NO_REMOTE': '1', 14:56:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:38 INFO - 'NO_EM_RESTART': '1', 14:56:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:38 INFO - 'PWD': '/builds/slave/test', 14:56:38 INFO - 'SHELL': '/bin/bash', 14:56:38 INFO - 'SHLVL': '1', 14:56:38 INFO - 'TERM': 'linux', 14:56:38 INFO - 'TMOUT': '86400', 14:56:38 INFO - 'USER': 'cltbld', 14:56:38 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:38 INFO - '_': '/tools/buildbot/bin/python'} 14:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:56:39 INFO - Running setup.py (path:/tmp/pip-Yx8o2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:56:39 INFO - Running setup.py (path:/tmp/pip-TtjS6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:56:39 INFO - Running setup.py (path:/tmp/pip-RzZ6de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:56:39 INFO - Running setup.py (path:/tmp/pip-vyYEY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:56:39 INFO - Running setup.py (path:/tmp/pip-WK91Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:56:39 INFO - Running setup.py (path:/tmp/pip-rxrIIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:56:39 INFO - Running setup.py (path:/tmp/pip-vAMXvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:56:39 INFO - Running setup.py (path:/tmp/pip-0MSqEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:56:39 INFO - Running setup.py (path:/tmp/pip-dbLqER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:56:39 INFO - Running setup.py (path:/tmp/pip-uAotR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:56:39 INFO - Running setup.py (path:/tmp/pip-ihapF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:56:39 INFO - Running setup.py (path:/tmp/pip-fMyNa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:56:39 INFO - Running setup.py (path:/tmp/pip-reVUUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:56:40 INFO - Running setup.py (path:/tmp/pip-6D1Sam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:56:40 INFO - Running setup.py (path:/tmp/pip-GpzYI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:56:40 INFO - Running setup.py (path:/tmp/pip-lAW_xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:56:40 INFO - Running setup.py (path:/tmp/pip-xsxBWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:56:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:56:40 INFO - Running setup.py install for manifestparser 14:56:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:56:40 INFO - Running setup.py install for mozcrash 14:56:40 INFO - Running setup.py install for mozdebug 14:56:40 INFO - Running setup.py install for mozdevice 14:56:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:56:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:56:40 INFO - Running setup.py install for mozfile 14:56:40 INFO - Running setup.py install for mozhttpd 14:56:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:56:40 INFO - Running setup.py install for mozinfo 14:56:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:56:40 INFO - Running setup.py install for mozInstall 14:56:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Running setup.py install for mozleak 14:56:41 INFO - Running setup.py install for mozlog 14:56:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Running setup.py install for moznetwork 14:56:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Running setup.py install for mozprocess 14:56:41 INFO - Running setup.py install for mozprofile 14:56:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Running setup.py install for mozrunner 14:56:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:56:41 INFO - Running setup.py install for mozscreenshot 14:56:41 INFO - Running setup.py install for moztest 14:56:41 INFO - Running setup.py install for mozversion 14:56:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:56:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:56:42 INFO - Cleaning up... 14:56:42 INFO - Return code: 0 14:56:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:56:42 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')]} 14:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:42 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')]} 14:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:42 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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:42 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 14:56:42 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 14:56:42 INFO - Using env: {'DISPLAY': ':0', 14:56:42 INFO - 'HOME': '/home/cltbld', 14:56:42 INFO - 'LANG': 'en_US.UTF-8', 14:56:42 INFO - 'LANGUAGE': 'en_US:en', 14:56:42 INFO - 'LOGNAME': 'cltbld', 14:56:42 INFO - 'MAIL': '/var/mail/cltbld', 14:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:42 INFO - 'MOZ_NO_REMOTE': '1', 14:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:42 INFO - 'NO_EM_RESTART': '1', 14:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:42 INFO - 'PWD': '/builds/slave/test', 14:56:42 INFO - 'SHELL': '/bin/bash', 14:56:42 INFO - 'SHLVL': '1', 14:56:42 INFO - 'TERM': 'linux', 14:56:42 INFO - 'TMOUT': '86400', 14:56:42 INFO - 'USER': 'cltbld', 14:56:42 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:42 INFO - '_': '/tools/buildbot/bin/python'} 14:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:56:42 INFO - Running setup.py (path:/tmp/pip-GF5dil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:56:42 INFO - Running setup.py (path:/tmp/pip-YqZfl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:56:42 INFO - Running setup.py (path:/tmp/pip-S8onvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:56:42 INFO - Running setup.py (path:/tmp/pip-QSydHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:56:42 INFO - Running setup.py (path:/tmp/pip-1YpOmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:56:42 INFO - Running setup.py (path:/tmp/pip-3T6eCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:56:42 INFO - Running setup.py (path:/tmp/pip-n43McX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:56:42 INFO - Running setup.py (path:/tmp/pip-_hwFS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:56:42 INFO - Running setup.py (path:/tmp/pip-aOE0Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:56:42 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)) 14:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:56:42 INFO - Running setup.py (path:/tmp/pip-VMMnoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:56:43 INFO - Running setup.py (path:/tmp/pip-zSLqlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:56:43 INFO - Running setup.py (path:/tmp/pip-ryxlaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:56:43 INFO - Running setup.py (path:/tmp/pip-AmuJLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:56:43 INFO - Running setup.py (path:/tmp/pip-H2s052-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:56:43 INFO - Running setup.py (path:/tmp/pip-zyGVJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:56:43 INFO - Running setup.py (path:/tmp/pip-_G4teP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:56:43 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)) 14:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:56:43 INFO - Running setup.py (path:/tmp/pip-5JCz2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:56:43 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)) 14:56:43 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)) 14:56:43 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)) 14:56:43 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)) 14:56:43 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)) 14:56:43 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)) 14:56:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:56: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 14:56: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 14:56: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 14:56: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 14:56: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 14:56: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 14:56:45 INFO - Downloading blessings-1.6.tar.gz 14:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:56:45 INFO - Installing collected packages: blessings 14:56:45 INFO - Running setup.py install for blessings 14:56:45 INFO - Successfully installed blessings 14:56:45 INFO - Cleaning up... 14:56:45 INFO - Return code: 0 14:56:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:56:45 INFO - Reading from file tmpfile_stdout 14:56:45 INFO - Current package versions: 14:56:45 INFO - argparse == 1.2.1 14:56:45 INFO - blessings == 1.6 14:56:45 INFO - blobuploader == 1.2.4 14:56:45 INFO - docopt == 0.6.1 14:56:45 INFO - functools32 == 3.2.3-2 14:56:45 INFO - jsonschema == 2.5.1 14:56:45 INFO - manifestparser == 1.1 14:56:45 INFO - mozInstall == 1.12 14:56:45 INFO - mozcrash == 0.17 14:56:45 INFO - mozdebug == 0.1 14:56:45 INFO - mozdevice == 0.48 14:56:45 INFO - mozfile == 1.2 14:56:45 INFO - mozhttpd == 0.7 14:56:45 INFO - mozinfo == 0.9 14:56:45 INFO - mozleak == 0.1 14:56:45 INFO - mozlog == 3.3 14:56:45 INFO - moznetwork == 0.27 14:56:45 INFO - mozprocess == 0.23 14:56:45 INFO - mozprofile == 0.28 14:56:45 INFO - mozrunner == 6.12 14:56:45 INFO - mozscreenshot == 0.1 14:56:45 INFO - mozsystemmonitor == 0.3 14:56:45 INFO - moztest == 0.7 14:56:45 INFO - mozversion == 1.4 14:56:45 INFO - psutil == 3.1.1 14:56:45 INFO - requests == 1.2.3 14:56:45 INFO - wsgiref == 0.1.2 14:56:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:45 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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:45 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 14:56:45 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 14:56:45 INFO - Using env: {'DISPLAY': ':0', 14:56:45 INFO - 'HOME': '/home/cltbld', 14:56:45 INFO - 'LANG': 'en_US.UTF-8', 14:56:45 INFO - 'LANGUAGE': 'en_US:en', 14:56:45 INFO - 'LOGNAME': 'cltbld', 14:56:45 INFO - 'MAIL': '/var/mail/cltbld', 14:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:45 INFO - 'MOZ_NO_REMOTE': '1', 14:56:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:45 INFO - 'NO_EM_RESTART': '1', 14:56:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:45 INFO - 'PWD': '/builds/slave/test', 14:56:45 INFO - 'SHELL': '/bin/bash', 14:56:45 INFO - 'SHLVL': '1', 14:56:45 INFO - 'TERM': 'linux', 14:56:45 INFO - 'TMOUT': '86400', 14:56:45 INFO - 'USER': 'cltbld', 14:56:45 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:45 INFO - '_': '/tools/buildbot/bin/python'} 14:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 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)) 14:56:46 INFO - Cleaning up... 14:56:46 INFO - Return code: 0 14:56:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:56:46 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')]} 14:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:46 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')]} 14:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:46 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 0x17ab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', '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 14:56:46 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 14:56:46 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 14:56:46 INFO - Using env: {'DISPLAY': ':0', 14:56:46 INFO - 'HOME': '/home/cltbld', 14:56:46 INFO - 'LANG': 'en_US.UTF-8', 14:56:46 INFO - 'LANGUAGE': 'en_US:en', 14:56:46 INFO - 'LOGNAME': 'cltbld', 14:56:46 INFO - 'MAIL': '/var/mail/cltbld', 14:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:46 INFO - 'MOZ_NO_REMOTE': '1', 14:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:46 INFO - 'NO_EM_RESTART': '1', 14:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:46 INFO - 'PWD': '/builds/slave/test', 14:56:46 INFO - 'SHELL': '/bin/bash', 14:56:46 INFO - 'SHLVL': '1', 14:56:46 INFO - 'TERM': 'linux', 14:56:46 INFO - 'TMOUT': '86400', 14:56:46 INFO - 'USER': 'cltbld', 14:56:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:46 INFO - '_': '/tools/buildbot/bin/python'} 14:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:56:46 INFO - Cleaning up... 14:56:46 INFO - Return code: 0 14:56:46 INFO - Running post-action listener: _resource_record_post_action 14:56:46 INFO - Running post-action listener: _start_resource_monitoring 14:56:46 INFO - Starting resource monitoring. 14:56:46 INFO - [mozharness: 2016-10-19 21:56:46.422110Z] Finished create-virtualenv step (success) 14:56:46 INFO - [mozharness: 2016-10-19 21:56:46.422540Z] Running install step. 14:56:46 INFO - Running pre-action listener: _resource_record_pre_action 14:56:46 INFO - Running main action method: install 14:56:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:56:46 INFO - Reading from file tmpfile_stdout 14:56:46 INFO - Detecting whether we're running mozinstall >=1.0... 14:56:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:56:46 INFO - Reading from file tmpfile_stdout 14:56:46 INFO - Output received: 14:56:46 INFO - Usage: mozinstall [options] installer 14:56:46 INFO - Options: 14:56:46 INFO - -h, --help show this help message and exit 14:56:46 INFO - -d DEST, --destination=DEST 14:56:46 INFO - Directory to install application into. [default: 14:56:46 INFO - "/builds/slave/test"] 14:56:46 INFO - --app=APP Application being installed. [default: firefox] 14:56:46 INFO - mkdir: /builds/slave/test/build/application 14:56:46 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'] 14:56:46 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 14:57:00 INFO - Reading from file tmpfile_stdout 14:57:00 INFO - Output received: 14:57:00 INFO - /builds/slave/test/build/application/firefox/firefox 14:57:00 INFO - Running post-action listener: _resource_record_post_action 14:57:00 INFO - [mozharness: 2016-10-19 21:57:00.276825Z] Finished install step (success) 14:57:00 INFO - [mozharness: 2016-10-19 21:57:00.277104Z] Running run-tests step. 14:57:00 INFO - Running pre-action listener: _resource_record_pre_action 14:57:00 INFO - Running main action method: run_tests 14:57:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:57:00 INFO - Python 2.7.3 14:57:00 INFO - Return code: 0 14:57:00 INFO - grabbing minidump binary from tooltool 14:57:00 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')]} 14:57:00 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 0x1a6a3b0>, '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 0x1a6b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:57:00 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 14:57:00 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 14:57:00 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 14:57:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:57:00 INFO - Return code: 0 14:57:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:57:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:57:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:57:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:57:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:57:00 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/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:57:00 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/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 14:57:00 INFO - Using env: {'DISPLAY': ':0', 14:57:00 INFO - 'HOME': '/home/cltbld', 14:57:00 INFO - 'LANG': 'en_US.UTF-8', 14:57:00 INFO - 'LANGUAGE': 'en_US:en', 14:57:00 INFO - 'LOGNAME': 'cltbld', 14:57:00 INFO - 'MAIL': '/var/mail/cltbld', 14:57:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:57:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:00 INFO - 'MOZ_NO_REMOTE': '1', 14:57:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:00 INFO - 'NO_EM_RESTART': '1', 14:57:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:00 INFO - 'PWD': '/builds/slave/test', 14:57:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:57:00 INFO - 'SHELL': '/bin/bash', 14:57:00 INFO - 'SHLVL': '1', 14:57:00 INFO - 'TERM': 'linux', 14:57:00 INFO - 'TMOUT': '86400', 14:57:00 INFO - 'USER': 'cltbld', 14:57:00 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222', 14:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:00 INFO - '_': '/tools/buildbot/bin/python'} 14:57:00 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/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:57:00 INFO - mozversion application_buildid: 20161019120023 14:57:00 INFO - mozversion application_changeset: 0c46713f5b17e943e39866326bd040f639364b8e 14:57:00 INFO - mozversion application_display_name: Firefox 14:57:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:57:00 INFO - mozversion application_name: Firefox 14:57:00 INFO - mozversion application_remotingname: firefox 14:57:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:57:00 INFO - mozversion application_vendor: Mozilla 14:57:00 INFO - mozversion application_version: 50.0 14:57:00 INFO - mozversion platform_buildid: 20161019120023 14:57:00 INFO - mozversion platform_changeset: 0c46713f5b17e943e39866326bd040f639364b8e 14:57:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:57:00 INFO - mozversion platform_version: 50.0 14:57:00 INFO - using testdate: 1476914220 14:57:00 INFO - actual date: 1476914220 14:57:00 INFO - starting webserver on 'localhost:48886' 14:57:00 INFO - SUITE-START | Running 2 tests 14:57:00 INFO - TEST-START | dromaeo_css 14:57:00 INFO - operating with platform_type : linux_ 14:57:00 INFO - Initialising browser for dromaeo_css test... 14:57:00 INFO - TEST-INFO | started process 5560 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT1W8Qg/profile http://localhost:48886/getInfo.html) 14:57:04 INFO - PROCESS | 5560 | __metrics Screen width/height:1600/1200 14:57:04 INFO - PROCESS | 5560 | colorDepth:24 14:57:04 INFO - PROCESS | 5560 | Browser inner width/height: 1024/697 14:57:04 INFO - PROCESS | 5560 | __metrics 14:57:05 INFO - PROCESS | 5560 | 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" 14:57:05 INFO - PROCESS | 5560 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:57:06 INFO - TEST-INFO | 5560: exit 0 14:57:06 INFO - Browser initialized. 14:57:06 INFO - Running cycle 1/1 for dromaeo_css test... 14:57:06 INFO - TEST-INFO | started process 5645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT1W8Qg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:57:17 INFO - PROCESS | 5645 | RSS: Main: 143728640 14:57:17 INFO - PROCESS | 5645 | 14:58:09 INFO - PROCESS | 5645 | 1476914289516 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] 15:00:17 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48886/tests/dromaeo/cssquery-ext.html) 15:00:18 INFO - PROCESS | 5645 | RSS: Main: 269144064 15:00:18 INFO - PROCESS | 5645 | 15:03:20 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-ext.html (next: http://localhost:48886/tests/dromaeo/cssquery-jquery.html) 15:03:21 INFO - PROCESS | 5645 | RSS: Main: 234557440 15:03:21 INFO - PROCESS | 5645 | 15:06:23 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48886/tests/dromaeo/cssquery-mootools.html) 15:06:23 INFO - PROCESS | 5645 | RSS: Main: 178257920 15:06:23 INFO - PROCESS | 5645 | 15:09:25 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48886/tests/dromaeo/cssquery-prototype.html) 15:09:26 INFO - PROCESS | 5645 | RSS: Main: 174546944 15:09:26 INFO - PROCESS | 5645 | 15:12:28 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48886/tests/dromaeo/cssquery-yui.html) 15:12:29 INFO - PROCESS | 5645 | RSS: Main: 175878144 15:12:29 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:48886/tests/dromaeo/cssquery-yui.html (next: http://localhost:48886/tests/dromaeo/cssquery-dojo.html) 15:15:31 INFO - PROCESS | 5645 | RSS: Main: 263602176 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | __start_tp_report 15:15:31 INFO - PROCESS | 5645 | _x_x_mozilla_page_load 15:15:31 INFO - PROCESS | 5645 | _x_x_mozilla_page_load_details 15:15:31 INFO - PROCESS | 5645 | |i|pagename|runs| 15:15:31 INFO - PROCESS | 5645 | |0;dojo.html;3185.2673885006448;3189.856456459459;3196.872125114995;3206.615206175259;3249.6262929763084;5485.259489968853;5543.473370029851;5563.610547261688;5578.079616863212;5587.888242235158;5506.366767821702;5600.1599760036015;5604.439556044394;5608.46719561641;5627.943720562804;5031.220160875061;5057.671251368649;5084.186530155176;5140.665856719302;5159.303494028296;4961.479044700309;4962.801487940478;5112.769925353368;5164.561012313958;5167.250748641445;4991.500849915015;5003.124453220672;5010.398752149729;5030.220315851049;5047.772850221749;5035.144025515671;5077.873053173682;5126.487351264881;5129.512696293846;5130.897382052338;4864.0515099555605;5021.146405111103;5059.266406371093;5082.771275292584;5113.488651134857;5274.129768588178;5380.838574842761;5396.568274538066;5421.810236641738;5457.454254574551;5744.856378590527;5791.855203619901;5841.591088623798;5847.736851841635;5847.970760146214;5680.772769089232;5807.041838096709;5890.793822216245;5905.586608937376;5910.320313163998;5504.91742623861;5547.5007249347345;5549.694766787825;5555.30558680165;5566.332040155126;6047.6000796654225;6056.424639659225;6058.617672790834;6080.331163572004;6083.330833608299;5578.860528486739;5809.8547536311935;5823.359430462646;5840.737166827503;5858.443447872445;6052.455279024821;6056.606320589148;6058.303295121029;6081.726322315506;6093.177421048191;6023.849403764853;6024.638521688713;6024.759009639653;6027.939720602825;6032.426919442646;5701.173329867146;5836.212111364996;5867.530597552186;6007.849803754853;6026.608270462406;4594.425696787902;4602.493725690171;4608.769561521911;4624.1676498230645;4632.536746325341;4041.2725709372007;4052.473178486837;4096.774677392772;4097.733647312915;4115.42384066225;5494.587905907073;5598.10430331145;5648.74580643872;5668.121441176624;5682.6306290091015;5969.552283578749;6035.39646035393;6059.60612560191;6079.878402432015;6092.238470191226;5676.8864622707315;5688.288963879515;5688.943110568925;5690.317161940594;5693.17448969897;5027.421846487628;5067.417247016567;5180.870478237999;5219.4780521947505;5221.530062294336;6135.44780969706;6214.5960512566535;6215.875682486235;6218.844528886814;6221.3156552779155;5918.822435326947;5919.526437885045;5938.079597662369;5948.672822994777;5997.6701281428195;5898.35118136996;6098.878022439615;6203.16257305258;6204.193454850841;6209.223847019122;5774.711264436677;5890.175375447355;5893.852653683521;5918.082697181943;5930.495907847881;5973.880522389615;5980.073088671263;5982.641041537521;5983.34183239852;5985.6408615483215;5630.85922851932;5808.912866307093;5849.532037436909;5858.794942177004;5860.648361098348;5839.562032847638;5841.474267315962;5843.824685259449;5879.911801323069;5884.205632239593;5776.9133462998925;5796.188533605383;5818.127280907728;5834.562407819346;5839.912401314069;5669.518090962314;5725.227094342377;5809.796657116981;5872.412758724094;5880.235569375954;5626.549876009991;5654.83035508919;5667.4615911488345;5674;5703.344115426779 15:15:31 INFO - PROCESS | 5645 | |1;ext.html;582.798934367643;637.7512770019694;662.3078882567717;668.9732410703572;678.6911955060448;36187.819060904694;47755.76122119389;48392;48402.515974840244;48436.51563484364;49621.50378496214;50080;50094;50250.49749502504;50265;8677.305815534774;9421.481818499997;9588.23294136471;9588.952055239713;11287.435628218598;11014.284071535325;11482.827757583644;11485.368304743235;11492.540298388054;11495.712607184803;11548;11670.241434306785;11677.240979336317;11678.8248176277;11688.532458701642;11533.942330288379;11555;11570.190086693934;11595.53617855289;11607.24552904067;11250.212485126045;11397.088232941345;11406.258593191416;11412.20114591979;11413.144014198968;46538;46946.53053469456;47153;47268.29097563539;47346.289805652945;3896.8636097736444;3958.841646334143;4070.3080476319096;4349.760763158025;4685.578297953167;35187.4721879172;35443;35581;35686.703821655945;36286.09284767875;4602.194615942197;4696.319033740123;4717.103750287434;4724.574788269072;4727.149113159629;389103;391713;391978;392530;392844;66494.33505664881;66719;66782;66830.66584667143;66980.33019669839;40037.39943900844;40363.798181008904;40500.59499405027;42726.78636606859;69328;18816.905915470335;18944.62110757805;18995.43013709591;19011.04944752757;19018.80981190198;12133.878661213452;12310.876891231152;12311;13017.739645207042;13482.25847578373;20220.69668954967;20797.272095476586;20899;20980.580388392453;20998.685019724715;7317.780466586011;7436.256374362521;7462.179160292363;7479.783693011948;7760.767176984699;968.4431451915177;1042.926995110335;1078.0567003871613;1110.9111271098293;1118.2116607791488;34414.65585344165;34552.13619659529;34903.65096349004;35045.64954350424;35169.52100748484;1132.416805345248;1154.5266440759417;1155.6359746679768;1200.8498937632796;1222.248317284881;18331;18556.350527731465;18576.349827755963;18715.438536843918;18795.15421806022;603667;610663;611404;611655;613874;65740;66263.33736662669;66312.6684366575;66326.33673663298;66399.66800165968;1321.557278311757;1396.6229118138046;1416.5126617052053;1431.2843578210895;1450.6590951126477;34282.48576271408;34996.300073998886;35444.64555354517;35591.64408355884;35661.28677426489;56230.15654765264;56886;56960.43039569551;57068.714656428114;57081;334167;334806;336791;337027;337261;28209.576856347583;30833.845830770704;31569.368612628077;31597.368052639278;31789.364212715154;51933.740331298104;52520;52651.73674131605;52720;52823.47176528339;44580.33129503125;45233.77383113063;45921.77039114783;45982.54017459782;47331.763341183076;44048.339274910264;44798.328025079;45637.54362456333;46207.878500236766;46380.536194638975;43880;44541.44104956311;45616;45701.77149114366;45709.31436028529;37087.258254835295;39659.2068158641;39888.60111398849;40205.79897100496;40641.39037914375;29487.579408109337;29745;29763.70236297696;29765.255868603457;30026.699733003265 15:15:31 INFO - PROCESS | 5645 | |2;jquery.html;407.77979890858956;409.8729393887897;424.537254392712;430.2170050508075;439.59557207369215;19194.328198513038;20732.378028659146;20790.480237994037;21008.579828403424;21853.562928741416;19327;19733.90133049333;19853.205871765113;20046.59906801863;20252.189912403523;5415.702136382497;5607.57943154263;5618.606697531174;5619;5619.466150715675;5548.611597188178;5589.496945274925;5756.971215143918;6003.519718422515;6045.879082418349;5913.9112913306335;6049.395060493959;6062.151298818169;6063.2724073111285;6063.727132279058;5846.883062338772;5993.940060599403;6022.066579680157;6047.3650266722025;6056.848578785522;5462.153366228241;5465.234867118606;5466.1527463243265;5471.781128754845;5472.097103977839;22501.887490562607;23467.413314667097;23608.17371391993;24880;25294.494110117696;2422.2248880358115;2506.5112303100905;2599.57107077332;2695.905643302475;2927.487689654302;10548.419836908966;10661.680149595524;10731.463426828705;10785.946070269676;10980.176486763525;1971.2115153938396;1971.9605607887906;1991.7410736604293;2019.2730616977876;2040.5306779440812;85401.57299213464;88467.55766221127;89663.10336896613;94251;94355.05644943532;11610.477528511237;11780.234284771463;12016.579419720269;12125.636230913087;12162.939185304016;17401.91299043497;17420.47738567845;17425.041622710618;17440.12799360027;17450.650986980443;7651.770446886602;8020.35837133028;8088.150744171778;8096.595170241464;8716.564171791386;8164.795880102926;8237.382196335298;8485.151484851465;8795.604197810986;8801.07588703177;4451.5325890781005;4477.507474177838;4535.183666939916;4535.818567257273;4624.260118381043;1906.8665193436605;2023.170500094954;2075.885826279539;2096.7798381170064;2122.161746110278;931.8229536388064;1000.3947611694965;1011.4689787861284;1052.1950707708456;1103.5751235774285;20090.698639520127;21034.78965210359;21052.578948420945;21083;21213.257535986166;1045.8901815309434;1070.6145787516489;1072.8176210044312;1084.728817795551;1092.6995076354067;9846.655367062118;11619.302841829518;11659.18385712992;11665.125115616363;11676.824847627293;499333;505108;522768;527803;528406;91406.54296728606;93115;94218.52890735502;96330;97817.02182978079;1009.6516701737888;1010.5957616953277;1028.3161697471098;1032.1381646325403;1046.9005444482762;17874.37439689657;17930.372436964644;18822.6235475287;18863.90568047151;18902.905485472485;45600;45620.54379456163;46036;46728.29907551457;47280;60893.39106608877;66179.33820661924;66599;67714.3228567708;68479.65760171367;8683.565821708762;8769.079246679263;8898.4660920345;8950.955245223733;9071.501067441353;12020.158588898694;12970.870291297344;13177.47290108385;13517.121387109806;13890.722185556435;11133.332000080021;11181.664550063511;11263.155263355446;11431.314121152758;11651;11256.437178141225;11489.310641361544;11675.35785531804;12248;12363.381830908238;12254.632361029184;12505.312207828292;12528.937355313165;12889.09776315649;13007.333496633357;7949.8410031800195;8869.64521419136;8922.68770593026;9179.586918588546;9238.630454782002;11361.886381136414;11502.079833613123;12021.879781202426;12169.939150304546;12563.246205227715 15:15:31 INFO - PROCESS | 5645 | |3;mootools.html;1366.52854765106;1499.4676889704156;1527.870131038862;1679.5297316751307;1845.2987864611455;8705.695300664478;9061.818763624724;9104.817903641931;9394;9405.106514881076;9265.166135047844;9393.62425502979;9408.15326620604;9409;9412.576434060467;2496.9250922472265;2520.558902192109;2522.2433270019014;2524.204875464223;2524.6465494830736;2450.2894160693468;2495.7753802157804;2499.5750722377147;2503.2615378463397;2508.8745562721883;2473.258022593224;2496.313513783715;2504.6743923289946;2516.5218608464425;2519.533886231044;2433.5254625348066;2451.950960980779;2458.1273647854932;2459.286806826018;2483.7143728471096;2355.3522781235124;2369.7748713872156;2378.179528062833;2380.6071998120287;2456.8034557235555;9292.256619470494;9487.620495180185;9515.90484095157;9604.423734575948;9632.518374081268;5527.474889885454;5664.461876121762;5672.716364181774;5686.54507639392;5688.175214593902;3553.786772793615;3587.3901436755814;3622.2393297407575;3637.7089832813313;3678.282734866702;1184.413715210976;1199.874013228616;1208.9335086570236;1223.7858374784466;1249.9500019999189;13528.59414217575;13657.248851313174;13666.453341866314;13669.658258543517;13708.17750934937;4767.713937163781;4785.186518291829;4798.328234047236;4907.828226012072;4943.579795717333;10653.147748180281;10683;10686.038256556947;10690.251682382313;10696;5571.804986825442;5586.16207568868;5586.91619625706;5593.272874526367;5599.580031497572;4255.87232383029;4596.149712303168;4653.441587009581;5121.795128194905;5195.532402083831;3941.0935484838647;3954.347532657137;4046.959530404717;4050;4066.6949978751713;1212.0606529939191;1216.9756604867853;1233.463443402123;1240.1257113734796;1242.5402601037674;1007.6171054999054;1025.2310766924807;1030.9329893556894;1039.7920415916847;1048.8898665640152;6334.3032266450655;6389.52078594098;6453.645049522227;6463.321351258144;6569.868602627921;494.29809670268304;496.6523433596497;502.41719605257725;503.49147361164756;510.50736039721846;4401.801918913592;4448.55514448559;4464.285714285698;4623.791929363187;4644.489106198315;11220.214584978972;11768.235064720766;11770.234934729215;11771.352575608253;11791.823122653168;11302.208845380908;11681.649550513499;11713.941430292794;11717.296962182296;11763.058955283557;8551.144885511523;8742.86885696715;8745.431546949429;8748.693795717247;8758.737237882873;6298.370162983665;6329.430351268408;6441;6496.090547323284;6536.182977127859;10562.630307939186;10652.09457196116;10778.353298802096;10836.620718274822;10842.945785271024;9797.412155270707;10604.469776511298;10780.838287425851;11239.438028098399;11246.943765281449;8318.916810832055;8570.442921210102;8711.782205444915;8882.600282987152;8890.022097569392;9107.362484626012;9304.209142222717;9363.531823408666;9367.344285900193;9375.390599611004;8351.498910065415;8389.370797190113;8467.36494762883;8502.74491765248;8518.574071296287;8309.335253179852;8335.624896879775;8368.995720513578;8510.063892971892;8519.06290308078;7486.737964171228;7519.248075192437;7543.057117860268;7602.7339043133225;7644.388448923962;5507.201455788968;5578.107502799533;5609.80365687199;5618.803341883015;5637.238972738792;5660;5665.915011274917;5698.971505142447;5780.653160810365;5912.231409916683 15:15:31 INFO - PROCESS | 5645 | |4;prototype.html;326.86925229908036;336.66501830678476;338.2018436489885;340.27691156292883;345.0717569737406;5413;5452.236686863832;5457.999999999995;5466.633341664584;5514.117741161415;5271.657342272759;5281.9207711884255;5374.898145880095;5394.514493695567;5412.269343638608;4158.91682166358;4186.534712850494;4248.553901840309;4252.404663347126;4264.125854199881;4217.8312867485265;4223.303154979425;4228.809703563332;4240.681948853833;4243.32106862902;4193.22425351311;4194.370844373338;4194.769287689176;4216.789160541976;4221.24017676818;3996.5412624392034;4076.9388459173138;4195.391668208108;4211.389348544474;4213.810378532943;3746.6253374662588;3750.718696097804;3758.229562939591;3763.7741735495956;3798.4682144499793;5177.8187763428095;5193.740312984373;5197.194434862602;5231.895362092737;5272.367315922075;4731.929021064687;4773.76131193439;4800.3279540864305;4803.231482962709;4845.63657725672;5504.706393997448;5537.280153580009;5548.195511650828;5550.195221692872;5552.944470555283;4146.460960075201;4151.3150330195485;4162.5421203667875;4169.332906734938;4179.832806687759;5638.4925356718095;5743.54051675865;5787.479126878601;5793.217915581385;5845.298564172284;4194.748315101104;4215.241256573846;4229.963658903527;4241.384999175164;4251.404803327537;4303.526612072669;4305.547917468683;4314.223439780807;4319.265724826787;4322.351647252937;4578.496365399803;4790.131848579057;4804.135255653946;4828.493008234085;4856.902861942742;5562.527185189304;5585.425327790217;5590.468905453975;5601.271834661468;5610.775568977195;1439.6688761584894;1454.4981981216463;1464.5679524540287;1466.6040169154483;1471.146734893759;901.0097689953996;933.453929451265;935.9438433694;937.2970272295778;945.2815859946353;4857.757112144449;4865.521152375997;4906.31311616374;4908.2392229203815;4926.679765815282;5134.691918484903;5139.177731563007;5161.4322424533275;5168.767405466763;5219.608529360313;4553;4821.879453013703;4945.19500382365;4947.950520494749;4958.504149585085;2682.3428260076407;2687.2667129701235;2709.8916043358045;2712.6880408753245;2735.753782159615;5286.524212820787;5332.346972438557;5350.678959262456;5364.463553644682;5403.595734687122;5233.502817232356;5248.343957005374;5459.454054594588;5883.499707574541;5949.464548190684;6009.2188015557695;6026.337102918763;6031.819045428644;6043.848903777441;6052.727627256784;5634.267545219259;5807.825765226881;5841.299044114733;5846.298444186725;5867.73595188209;5662.2639056922335;5743.770249190153;5760.683162425939;5763.279590051244;5773.538116950718;6055.576109672281;6060.454559089703;6061.212042434456;6100.603460775035;6111.511079113571;4038.1721747041197;4108.8356465741035;4151.46031015966;4191.203671302443;4241.257779888569;5703.914441283467;5709.65742055478;5744.047024011856;5755.02164632022;5763.51010164124;5652.378238393856;5665.943340566541;5667.914981275202;5670.943290567042;5674.467893668604;5450.886229811981;5565.693886836263;5567.8886422272135;5607.80372686954;5632.577556683347;5567.610267281251;5589.860253493695;5590.664560126405;5591.27313449249;5601.355844077983;5445.210444485607;5534.557235421237;5590.86022849432;5596.608237423341;5672.914906276492;5530.253415788817;5533.833984980457;5536.0865457200725;5538.6399884007415;5550.364511245141 15:15:31 INFO - PROCESS | 5645 | |5;yui.html;823.7034667519691;854.1159899504015;877.2411863737867;896.3770179725092;906.4606559097335;16157.596060098489;17389.73915391267;17568.64862702745;17572.560685982826;17575.47273581793;15878.603034924105;15958.7606185907;16045.91977040116;16489.917550412232;16716.164191790424;862.8101817600134;879.5162660536711;880.7886107334258;881.0704706534603;882.355880207449;726.0851327327579;858.5621333120091;876.4522173641474;878.0999475537593;889.524104604037;900.8423525882977;904.525124309736;909.7179874239007;909.963601455941;913.0458670689146;908.0692290402325;910.9407888487293;913.2146354135439;914.1270087066869;916.1983264643437;670.7887015590073;675.7938828657258;676.4993904510676;676.9153855768029;678.8845896197658;16254.431094911739;16265.593360165973;16275.104869232187;16292.348306067743;16294.755578666329;16757.41349052777;17006.3197472101;17069.317227310894;17355.21901514435;17787.377441789475;12995.285259310735;13127.803082953764;13132.86867131326;13192.340382980909;13413.39639716215;4171.395147703596;4204.07510347723;4268.295731204347;4304.053108316165;4334.566543345672;88695;88972.55513722455;89109.55445222797;90395;92732;13009.739805203842;13074.215547067017;13255.867441325656;13267.800982985264;13328.266945317906;24414.877925610614;24557.263282101565;24593;25149.874250628996;25799.613005804928;8379.952505936757;8641.049484556694;8917.55412229386;9079.092090790868;9151.63393464254;4965.255211718271;5121;5213.165893457029;5254.159334506537;5289.100852855024;4042.15114825884;4103.466549348565;4140.0270936329935;4157.334826427757;4175.83296668136;675.3757213900577;685.3318014935398;691.439933653732;703.8310805406768;704.5314865614412;771.5139462138928;808.2604416958417;818.8485130250923;831.2934006094891;847.720252316734;9719.9028009719;10718.35689858611;10739;11237.269577477438;11396.430178491208;670.9843916722557;795.5226863881625;811.5860910935465;817.7873752824308;823.6746485138457;5048.823291184791;5128.333316668809;5246.134387825966;5257.08001099806;5350.892982140335;74126;75340.62329688316;75744.62127689326;75828;124908;111659;112762;113689;115508;117274.41362793131;98079;99350;99931.50034249782;101431.49284253531;101890;9539.09378609031;9626.807463850824;9924.503774811086;9940;9945;57795.71102144462;58362.708186458796;59546.70226648839;59700.40299597122;61353.38646613655;81297;87841.12158878586;88839;89660.5516972411;92428.53785731028;13124.475020999053;13154;13615.591532254048;13786.172829630254;14021.719565608835;14641.707165856837;14933.850661493247;14985.550433487013;15162.014469059475;15809.367625294859;12516.561920332744;12643.304618246084;13652.385642645906;13689;13708.86291137076;13119.606411807661;13551.59345219645;13618.114822536503;13739.313034348042;13951.372188251757;13452.46190152422;13484.393202305724;13537.323133843072;13658.931705341409;13772.93113534466;10926.071283940953;10966.06788422993;11314.151438642299;11401.885981140082;11415;11080.833787493033;11475.196736228718;11607.941960290482;11817.586384476503;11848.526058957545 15:15:31 INFO - PROCESS | 5645 | __end_tp_report 15:15:31 INFO - PROCESS | 5645 | __start_cc_report 15:15:31 INFO - PROCESS | 5645 | _x_x_mozilla_cycle_collect,1059 15:15:31 INFO - PROCESS | 5645 | __end_cc_report 15:15:31 INFO - PROCESS | 5645 | __startTimestamp1476915331958__endTimestamp 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | ------- Summary: start ------- 15:15:31 INFO - PROCESS | 5645 | Number of tests: 6 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#0] dojo.html Cycles:165 Average:5525.01 Median:5693.17 stddev:626.39 (11.0%) stddev-sans-first:600.82 15:15:31 INFO - PROCESS | 5645 | Values: 3185.3 3189.9 3196.9 3206.6 3249.6 5485.3 5543.5 5563.6 5578.1 5587.9 5506.4 5600.2 5604.4 5608.5 5627.9 5031.2 5057.7 5084.2 5140.7 5159.3 4961.5 4962.8 5112.8 5164.6 5167.3 4991.5 5003.1 5010.4 5030.2 5047.8 5035.1 5077.9 5126.5 5129.5 5130.9 4864.1 5021.1 5059.3 5082.8 5113.5 5274.1 5380.8 5396.6 5421.8 5457.5 5744.9 5791.9 5841.6 5847.7 5848.0 5680.8 5807.0 5890.8 5905.6 5910.3 5504.9 5547.5 5549.7 5555.3 5566.3 6047.6 6056.4 6058.6 6080.3 6083.3 5578.9 5809.9 5823.4 5840.7 5858.4 6052.5 6056.6 6058.3 6081.7 6093.2 6023.8 6024.6 6024.8 6027.9 6032.4 5701.2 5836.2 5867.5 6007.8 6026.6 4594.4 4602.5 4608.8 4624.2 4632.5 4041.3 4052.5 4096.8 4097.7 4115.4 5494.6 5598.1 5648.7 5668.1 5682.6 5969.6 6035.4 6059.6 6079.9 6092.2 5676.9 5688.3 5688.9 5690.3 5693.2 5027.4 5067.4 5180.9 5219.5 5221.5 6135.4 6214.6 6215.9 6218.8 6221.3 5918.8 5919.5 5938.1 5948.7 5997.7 5898.4 6098.9 6203.2 6204.2 6209.2 5774.7 5890.2 5893.9 5918.1 5930.5 5973.9 5980.1 5982.6 5983.3 5985.6 5630.9 5808.9 5849.5 5858.8 5860.6 5839.6 5841.5 5843.8 5879.9 5884.2 5776.9 5796.2 5818.1 5834.6 5839.9 5669.5 5725.2 5809.8 5872.4 5880.2 5626.5 5654.8 5667.5 5674.0 5703.3 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#1] ext.html Cycles:180 Average:62754.20 Median:392254.00 stddev:122388.52 (31.2%) stddev-sans-first:122642.83 15:15:31 INFO - PROCESS | 5645 | Values: 582.8 637.8 662.3 669.0 678.7 36187.8 47755.8 48392.0 48402.5 48436.5 49621.5 50080.0 50094.0 50250.5 50265.0 8677.3 9421.5 9588.2 9589.0 11287.4 11014.3 11482.8 11485.4 11492.5 11495.7 11548.0 11670.2 11677.2 11678.8 11688.5 11533.9 11555.0 11570.2 11595.5 11607.2 11250.2 11397.1 11406.3 11412.2 11413.1 46538.0 46946.5 47153.0 47268.3 47346.3 3896.9 3958.8 4070.3 4349.8 4685.6 35187.5 35443.0 35581.0 35686.7 36286.1 4602.2 4696.3 4717.1 4724.6 4727.1 389103.0 391713.0 391978.0 392530.0 392844.0 66494.3 66719.0 66782.0 66830.7 66980.3 40037.4 40363.8 40500.6 42726.8 69328.0 18816.9 18944.6 18995.4 19011.0 19018.8 12133.9 12310.9 12311.0 13017.7 13482.3 20220.7 20797.3 20899.0 20980.6 20998.7 7317.8 7436.3 7462.2 7479.8 7760.8 968.4 1042.9 1078.1 1110.9 1118.2 34414.7 34552.1 34903.7 35045.6 35169.5 1132.4 1154.5 1155.6 1200.8 1222.2 18331.0 18556.4 18576.3 18715.4 18795.2 603667.0 610663.0 611404.0 611655.0 613874.0 65740.0 66263.3 66312.7 66326.3 66399.7 1321.6 1396.6 1416.5 1431.3 1450.7 34282.5 34996.3 35444.6 35591.6 35661.3 56230.2 56886.0 56960.4 57068.7 57081.0 334167.0 334806.0 336791.0 337027.0 337261.0 28209.6 30833.8 31569.4 31597.4 31789.4 51933.7 52520.0 52651.7 52720.0 52823.5 44580.3 45233.8 45921.8 45982.5 47331.8 44048.3 44798.3 45637.5 46207.9 46380.5 43880.0 44541.4 45616.0 45701.8 45709.3 37087.3 39659.2 39888.6 40205.8 40641.4 29487.6 29745.0 29763.7 29765.3 30026.7 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#2] jquery.html Cycles:180 Average:30775.80 Median:13015.20 stddev:85345.17 (655.7%) stddev-sans-first:85554.12 15:15:31 INFO - PROCESS | 5645 | Values: 407.8 409.9 424.5 430.2 439.6 19194.3 20732.4 20790.5 21008.6 21853.6 19327.0 19733.9 19853.2 20046.6 20252.2 5415.7 5607.6 5618.6 5619.0 5619.5 5548.6 5589.5 5757.0 6003.5 6045.9 5913.9 6049.4 6062.2 6063.3 6063.7 5846.9 5993.9 6022.1 6047.4 6056.8 5462.2 5465.2 5466.2 5471.8 5472.1 22501.9 23467.4 23608.2 24880.0 25294.5 2422.2 2506.5 2599.6 2695.9 2927.5 10548.4 10661.7 10731.5 10785.9 10980.2 1971.2 1972.0 1991.7 2019.3 2040.5 85401.6 88467.6 89663.1 94251.0 94355.1 11610.5 11780.2 12016.6 12125.6 12162.9 17401.9 17420.5 17425.0 17440.1 17450.7 7651.8 8020.4 8088.2 8096.6 8716.6 8164.8 8237.4 8485.2 8795.6 8801.1 4451.5 4477.5 4535.2 4535.8 4624.3 1906.9 2023.2 2075.9 2096.8 2122.2 931.8 1000.4 1011.5 1052.2 1103.6 20090.7 21034.8 21052.6 21083.0 21213.3 1045.9 1070.6 1072.8 1084.7 1092.7 9846.7 11619.3 11659.2 11665.1 11676.8 499333.0 505108.0 522768.0 527803.0 528406.0 91406.5 93115.0 94218.5 96330.0 97817.0 1009.7 1010.6 1028.3 1032.1 1046.9 17874.4 17930.4 18822.6 18863.9 18902.9 45600.0 45620.5 46036.0 46728.3 47280.0 60893.4 66179.3 66599.0 67714.3 68479.7 8683.6 8769.1 8898.5 8951.0 9071.5 12020.2 12970.9 13177.5 13517.1 13890.7 11133.3 11181.7 11263.2 11431.3 11651.0 11256.4 11489.3 11675.4 12248.0 12363.4 12254.6 12505.3 12528.9 12889.1 13007.3 7949.8 8869.6 8922.7 9179.6 9238.6 11361.9 11502.1 12021.9 12169.9 12563.2 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#3] mootools.html Cycles:180 Average:6203.11 Median:4648.97 stddev:3582.39 (77.1%) stddev-sans-first:3574.00 15:15:31 INFO - PROCESS | 5645 | Values: 1366.5 1499.5 1527.9 1679.5 1845.3 8705.7 9061.8 9104.8 9394.0 9405.1 9265.2 9393.6 9408.2 9409.0 9412.6 2496.9 2520.6 2522.2 2524.2 2524.6 2450.3 2495.8 2499.6 2503.3 2508.9 2473.3 2496.3 2504.7 2516.5 2519.5 2433.5 2452.0 2458.1 2459.3 2483.7 2355.4 2369.8 2378.2 2380.6 2456.8 9292.3 9487.6 9515.9 9604.4 9632.5 5527.5 5664.5 5672.7 5686.5 5688.2 3553.8 3587.4 3622.2 3637.7 3678.3 1184.4 1199.9 1208.9 1223.8 1250.0 13528.6 13657.2 13666.5 13669.7 13708.2 4767.7 4785.2 4798.3 4907.8 4943.6 10653.1 10683.0 10686.0 10690.3 10696.0 5571.8 5586.2 5586.9 5593.3 5599.6 4255.9 4596.1 4653.4 5121.8 5195.5 3941.1 3954.3 4047.0 4050.0 4066.7 1212.1 1217.0 1233.5 1240.1 1242.5 1007.6 1025.2 1030.9 1039.8 1048.9 6334.3 6389.5 6453.6 6463.3 6569.9 494.3 496.7 502.4 503.5 510.5 4401.8 4448.6 4464.3 4623.8 4644.5 11220.2 11768.2 11770.2 11771.4 11791.8 11302.2 11681.6 11713.9 11717.3 11763.1 8551.1 8742.9 8745.4 8748.7 8758.7 6298.4 6329.4 6441.0 6496.1 6536.2 10562.6 10652.1 10778.4 10836.6 10842.9 9797.4 10604.5 10780.8 11239.4 11246.9 8318.9 8570.4 8711.8 8882.6 8890.0 9107.4 9304.2 9363.5 9367.3 9375.4 8351.5 8389.4 8467.4 8502.7 8518.6 8309.3 8335.6 8369.0 8510.1 8519.1 7486.7 7519.2 7543.1 7602.7 7644.4 5507.2 5578.1 5609.8 5618.8 5637.2 5660.0 5665.9 5699.0 5780.7 5912.2 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#4] prototype.html Cycles:180 Average:4678.67 Median:5260.00 stddev:1368.31 (26.0%) stddev-sans-first:1332.59 15:15:31 INFO - PROCESS | 5645 | Values: 326.9 336.7 338.2 340.3 345.1 5413.0 5452.2 5458.0 5466.6 5514.1 5271.7 5281.9 5374.9 5394.5 5412.3 4158.9 4186.5 4248.6 4252.4 4264.1 4217.8 4223.3 4228.8 4240.7 4243.3 4193.2 4194.4 4194.8 4216.8 4221.2 3996.5 4076.9 4195.4 4211.4 4213.8 3746.6 3750.7 3758.2 3763.8 3798.5 5177.8 5193.7 5197.2 5231.9 5272.4 4731.9 4773.8 4800.3 4803.2 4845.6 5504.7 5537.3 5548.2 5550.2 5552.9 4146.5 4151.3 4162.5 4169.3 4179.8 5638.5 5743.5 5787.5 5793.2 5845.3 4194.7 4215.2 4230.0 4241.4 4251.4 4303.5 4305.5 4314.2 4319.3 4322.4 4578.5 4790.1 4804.1 4828.5 4856.9 5562.5 5585.4 5590.5 5601.3 5610.8 1439.7 1454.5 1464.6 1466.6 1471.1 901.0 933.5 935.9 937.3 945.3 4857.8 4865.5 4906.3 4908.2 4926.7 5134.7 5139.2 5161.4 5168.8 5219.6 4553.0 4821.9 4945.2 4948.0 4958.5 2682.3 2687.3 2709.9 2712.7 2735.8 5286.5 5332.3 5350.7 5364.5 5403.6 5233.5 5248.3 5459.5 5883.5 5949.5 6009.2 6026.3 6031.8 6043.8 6052.7 5634.3 5807.8 5841.3 5846.3 5867.7 5662.3 5743.8 5760.7 5763.3 5773.5 6055.6 6060.5 6061.2 6100.6 6111.5 4038.2 4108.8 4151.5 4191.2 4241.3 5703.9 5709.7 5744.0 5755.0 5763.5 5652.4 5665.9 5667.9 5670.9 5674.5 5450.9 5565.7 5567.9 5607.8 5632.6 5567.6 5589.9 5590.7 5591.3 5601.4 5445.2 5534.6 5590.9 5596.6 5672.9 5530.3 5533.8 5536.1 5538.6 5550.4 15:15:31 INFO - PROCESS | 5645 | 15:15:31 INFO - PROCESS | 5645 | [#5] yui.html Cycles:180 Average:22289.44 Median:4319.31 stddev:31671.61 (733.3%) stddev-sans-first:31719.44 15:15:31 INFO - PROCESS | 5645 | Values: 823.7 854.1 877.2 896.4 906.5 16157.6 17389.7 17568.6 17572.6 17575.5 15878.6 15958.8 16045.9 16489.9 16716.2 862.8 879.5 880.8 881.1 882.4 726.1 858.6 876.5 878.1 889.5 900.8 904.5 909.7 910.0 913.0 908.1 910.9 913.2 914.1 916.2 670.8 675.8 676.5 676.9 678.9 16254.4 16265.6 16275.1 16292.3 16294.8 16757.4 17006.3 17069.3 17355.2 17787.4 12995.3 13127.8 13132.9 13192.3 13413.4 4171.4 4204.1 4268.3 4304.1 4334.6 88695.0 88972.6 89109.6 90395.0 92732.0 13009.7 13074.2 13255.9 13267.8 13328.3 24414.9 24557.3 24593.0 25149.9 25799.6 8380.0 8641.0 8917.6 9079.1 9151.6 4965.3 5121.0 5213.2 5254.2 5289.1 4042.2 4103.5 4140.0 4157.3 4175.8 675.4 685.3 691.4 703.8 704.5 771.5 808.3 818.8 831.3 847.7 9719.9 10718.4 10739.0 11237.3 11396.4 671.0 795.5 811.6 817.8 823.7 5048.8 5128.3 5246.1 5257.1 5350.9 74126.0 75340.6 75744.6 75828.0 124908.0 111659.0 112762.0 113689.0 115508.0 117274.4 98079.0 99350.0 99931.5 101431.5 101890.0 9539.1 9626.8 9924.5 9940.0 9945.0 57795.7 58362.7 59546.7 59700.4 61353.4 81297.0 87841.1 88839.0 89660.6 92428.5 13124.5 13154.0 13615.6 13786.2 14021.7 14641.7 14933.9 14985.6 15162.0 15809.4 12516.6 12643.3 13652.4 13689.0 13708.9 13119.6 13551.6 13618.1 13739.3 13951.4 13452.5 13484.4 13537.3 13658.9 13772.9 10926.1 10966.1 11314.2 11401.9 11415.0 11080.8 11475.2 11607.9 11817.6 11848.5 15:15:31 INFO - PROCESS | 5645 | -------- Summary: end -------- 15:15:31 INFO - PROCESS | 5645 | 15:15:32 INFO - TEST-INFO | 5645: exit 0 15:15:32 INFO - TEST-OK | dromaeo_css | took 1112132ms 15:15:32 INFO - TEST-START | kraken 15:15:32 INFO - operating with platform_type : linux_ 15:15:32 INFO - Initialising browser for kraken test... 15:15:32 INFO - TEST-INFO | started process 5835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbpZ3pU/profile http://localhost:48886/getInfo.html) 15:15:36 INFO - PROCESS | 5835 | __metrics Screen width/height:1600/1200 15:15:36 INFO - PROCESS | 5835 | colorDepth:24 15:15:36 INFO - PROCESS | 5835 | Browser inner width/height: 1024/697 15:15:36 INFO - PROCESS | 5835 | __metrics 15:15:37 INFO - PROCESS | 5835 | 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" 15:15:37 INFO - PROCESS | 5835 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:15:37 INFO - PROCESS | 5835 | console.error: PushService: 15:15:37 INFO - PROCESS | 5835 | stateChangeProcessEnqueue: Error transitioning state 15:15:37 INFO - PROCESS | 5835 | UnknownError 15:15:38 INFO - TEST-INFO | 5835: exit 0 15:15:38 INFO - Browser initialized. 15:15:38 INFO - Running cycle 1/1 for kraken test... 15:15:38 INFO - TEST-INFO | started process 5924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbpZ3pU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:50 INFO - PROCESS | 5924 | RSS: Main: 143712256 15:15:50 INFO - PROCESS | 5924 | 15:15:52 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48886/tests/kraken/driver.html?testName=audio-beat-detection) 15:15:53 INFO - PROCESS | 5924 | RSS: Main: 198803456 15:15:53 INFO - PROCESS | 5924 | 15:15:56 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48886/tests/kraken/driver.html?testName=audio-dft) 15:15:56 INFO - PROCESS | 5924 | RSS: Main: 183582720 15:15:56 INFO - PROCESS | 5924 | 15:15:59 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48886/tests/kraken/driver.html?testName=audio-fft) 15:16:00 INFO - PROCESS | 5924 | RSS: Main: 275243008 15:16:00 INFO - PROCESS | 5924 | 15:16:02 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48886/tests/kraken/driver.html?testName=audio-oscillator) 15:16:02 INFO - PROCESS | 5924 | RSS: Main: 180383744 15:16:02 INFO - PROCESS | 5924 | 15:16:04 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48886/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:16:05 INFO - PROCESS | 5924 | RSS: Main: 178999296 15:16:05 INFO - PROCESS | 5924 | 15:16:08 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48886/tests/kraken/driver.html?testName=imaging-darkroom) 15:16:09 INFO - PROCESS | 5924 | RSS: Main: 214937600 15:16:09 INFO - PROCESS | 5924 | 15:16:12 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48886/tests/kraken/driver.html?testName=imaging-desaturate) 15:16:12 INFO - PROCESS | 5924 | RSS: Main: 213987328 15:16:12 INFO - PROCESS | 5924 | 15:16:15 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48886/tests/kraken/driver.html?testName=json-parse-financial) 15:16:16 INFO - PROCESS | 5924 | RSS: Main: 214298624 15:16:16 INFO - PROCESS | 5924 | 15:16:17 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48886/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:16:18 INFO - PROCESS | 5924 | RSS: Main: 176340992 15:16:18 INFO - PROCESS | 5924 | 15:16:20 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:16:20 INFO - PROCESS | 5924 | RSS: Main: 183750656 15:16:20 INFO - PROCESS | 5924 | 15:16:22 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:16:23 INFO - PROCESS | 5924 | RSS: Main: 189509632 15:16:23 INFO - PROCESS | 5924 | 15:16:25 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:16:26 INFO - PROCESS | 5924 | RSS: Main: 193089536 15:16:26 INFO - PROCESS | 5924 | 15:16:29 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:16:29 INFO - PROCESS | 5924 | RSS: Main: 193773568 15:16:29 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | Cycle 1(1): loaded http://localhost:48886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48886/tests/kraken/driver.html?testName=ai-astar) 15:16:31 INFO - PROCESS | 5924 | RSS: Main: 244121600 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | __start_tp_report 15:16:31 INFO - PROCESS | 5924 | _x_x_mozilla_page_load 15:16:31 INFO - PROCESS | 5924 | _x_x_mozilla_page_load_details 15:16:31 INFO - PROCESS | 5924 | |i|pagename|runs| 15:16:31 INFO - PROCESS | 5924 | |0;ai-astar;111;110;122;111;110;111;110;109;111;110 15:16:31 INFO - PROCESS | 5924 | |1;audio-beat-detection;156;115;139;175;125;143;144;117;177;116 15:16:31 INFO - PROCESS | 5924 | |2;audio-dft;162;159;160;161;160;162;160;159;161;161 15:16:31 INFO - PROCESS | 5924 | |3;audio-fft;76;73;76;172;75;77;75;75;160;74 15:16:31 INFO - PROCESS | 5924 | |4;audio-oscillator;85;84;140;83;83;84;144;84;83;140 15:16:31 INFO - PROCESS | 5924 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;116;118;116 15:16:31 INFO - PROCESS | 5924 | |6;imaging-darkroom;145;146;145;145;146;146;146;146;145;146 15:16:31 INFO - PROCESS | 5924 | |7;imaging-desaturate;94;94;94;95;94;94;95;94;95;94 15:16:31 INFO - PROCESS | 5924 | |8;json-parse-financial;50;50;49;50;50;51;51;50;48;49 15:16:31 INFO - PROCESS | 5924 | |9;json-stringify-tinderbox;61;61;60;60;61;59;60;60;60;60 15:16:31 INFO - PROCESS | 5924 | |10;stanford-crypto-aes;73;78;73;74;78;83;72;73;74;75 15:16:31 INFO - PROCESS | 5924 | |11;stanford-crypto-ccm;128;127;131;128;125;125;117;116;176;121 15:16:31 INFO - PROCESS | 5924 | |12;stanford-crypto-pbkdf2;155;155;153;157;152;154;151;152;152;154 15:16:31 INFO - PROCESS | 5924 | |13;stanford-crypto-sha256-iterative;62;65;64;60;61;61;60;61;66;61 15:16:31 INFO - PROCESS | 5924 | __end_tp_report 15:16:31 INFO - PROCESS | 5924 | __start_cc_report 15:16:31 INFO - PROCESS | 5924 | _x_x_mozilla_cycle_collect,1696 15:16:31 INFO - PROCESS | 5924 | __end_cc_report 15:16:31 INFO - PROCESS | 5924 | __startTimestamp1476915391565__endTimestamp 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | ------- Summary: start ------- 15:16:31 INFO - PROCESS | 5924 | Number of tests: 14 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:3.75 (3.4%) stddev-sans-first:3.97 15:16:31 INFO - PROCESS | 5924 | Values: 111.0 110.0 122.0 111.0 110.0 111.0 110.0 109.0 111.0 110.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:143.50 stddev:23.16 (16.1%) stddev-sans-first:23.89 15:16:31 INFO - PROCESS | 5924 | Values: 156.0 115.0 139.0 175.0 125.0 143.0 144.0 117.0 177.0 116.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 15:16:31 INFO - PROCESS | 5924 | Values: 162.0 159.0 160.0 161.0 160.0 162.0 160.0 159.0 161.0 161.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.00 stddev:38.44 (51.2%) stddev-sans-first:40.25 15:16:31 INFO - PROCESS | 5924 | Values: 76.0 73.0 76.0 172.0 75.0 77.0 75.0 75.0 160.0 74.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:83.50 stddev:27.86 (33.4%) stddev-sans-first:28.94 15:16:31 INFO - PROCESS | 5924 | Values: 85.0 84.0 140.0 83.0 83.0 84.0 144.0 84.0 83.0 140.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 15:16:31 INFO - PROCESS | 5924 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 118.0 116.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 15:16:31 INFO - PROCESS | 5924 | Values: 145.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 145.0 146.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:16:31 INFO - PROCESS | 5924 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 15:16:31 INFO - PROCESS | 5924 | Values: 50.0 50.0 49.0 50.0 50.0 51.0 51.0 50.0 48.0 49.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 15:16:31 INFO - PROCESS | 5924 | Values: 61.0 61.0 60.0 60.0 61.0 59.0 60.0 60.0 60.0 60.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:74.50 stddev:3.40 (4.6%) stddev-sans-first:3.50 15:16:31 INFO - PROCESS | 5924 | Values: 73.0 78.0 73.0 74.0 78.0 83.0 72.0 73.0 74.0 75.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:127.50 stddev:17.08 (13.4%) stddev-sans-first:18.11 15:16:31 INFO - PROCESS | 5924 | Values: 128.0 127.0 131.0 128.0 125.0 125.0 117.0 116.0 176.0 121.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.00 stddev:1.84 (1.2%) stddev-sans-first:1.87 15:16:31 INFO - PROCESS | 5924 | Values: 155.0 155.0 153.0 157.0 152.0 154.0 151.0 152.0 152.0 154.0 15:16:31 INFO - PROCESS | 5924 | 15:16:31 INFO - PROCESS | 5924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.50 stddev:2.13 (3.5%) stddev-sans-first:2.26 15:16:31 INFO - PROCESS | 5924 | Values: 62.0 65.0 64.0 60.0 61.0 61.0 60.0 61.0 66.0 61.0 15:16:31 INFO - PROCESS | 5924 | -------- Summary: end -------- 15:16:31 INFO - PROCESS | 5924 | 15:16:32 INFO - TEST-INFO | 5924: exit 0 15:16:32 INFO - TEST-OK | kraken | took 59879ms 15:16:32 INFO - SUITE-END | took 1172s 15:16:32 INFO - Completed test suite (00:19:32) 15:16:32 INFO - javascript benchmark 15:16:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3185.2673885006448, 3189.856456459459, 3196.872125114995, 3206.615206175259, 3249.6262929763084, 5485.259489968853, 5543.473370029851, 5563.610547261688, 5578.079616863212, 5587.888242235158, 5506.366767821702, 5600.1599760036015, 5604.439556044394, 5608.46719561641, 5627.943720562804, 5031.220160875061, 5057.671251368649, 5084.186530155176, 5140.665856719302, 5159.303494028296, 4961.479044700309, 4962.801487940478, 5112.769925353368, 5164.561012313958, 5167.250748641445, 4991.500849915015, 5003.124453220672, 5010.398752149729, 5030.220315851049, 5047.772850221749, 5035.144025515671, 5077.873053173682, 5126.487351264881, 5129.512696293846, 5130.897382052338, 4864.0515099555605, 5021.146405111103, 5059.266406371093, 5082.771275292584, 5113.488651134857, 5274.129768588178, 5380.838574842761, 5396.568274538066, 5421.810236641738, 5457.454254574551, 5744.856378590527, 5791.855203619901, 5841.591088623798, 5847.736851841635, 5847.970760146214, 5680.772769089232, 5807.041838096709, 5890.793822216245, 5905.586608937376, 5910.320313163998, 5504.91742623861, 5547.5007249347345, 5549.694766787825, 5555.30558680165, 5566.332040155126, 6047.6000796654225, 6056.424639659225, 6058.617672790834, 6080.331163572004, 6083.330833608299, 5578.860528486739, 5809.8547536311935, 5823.359430462646, 5840.737166827503, 5858.443447872445, 6052.455279024821, 6056.606320589148, 6058.303295121029, 6081.726322315506, 6093.177421048191, 6023.849403764853, 6024.638521688713, 6024.759009639653, 6027.939720602825, 6032.426919442646, 5701.173329867146, 5836.212111364996, 5867.530597552186, 6007.849803754853, 6026.608270462406, 4594.425696787902, 4602.493725690171, 4608.769561521911, 4624.1676498230645, 4632.536746325341, 4041.2725709372007, 4052.473178486837, 4096.774677392772, 4097.733647312915, 4115.42384066225, 5494.587905907073, 5598.10430331145, 5648.74580643872, 5668.121441176624, 5682.6306290091015, 5969.552283578749, 6035.39646035393, 6059.60612560191, 6079.878402432015, 6092.238470191226, 5676.8864622707315, 5688.288963879515, 5688.943110568925, 5690.317161940594, 5693.17448969897, 5027.421846487628, 5067.417247016567, 5180.870478237999, 5219.4780521947505, 5221.530062294336, 6135.44780969706, 6214.5960512566535, 6215.875682486235, 6218.844528886814, 6221.3156552779155, 5918.822435326947, 5919.526437885045, 5938.079597662369, 5948.672822994777, 5997.6701281428195, 5898.35118136996, 6098.878022439615, 6203.16257305258, 6204.193454850841, 6209.223847019122, 5774.711264436677, 5890.175375447355, 5893.852653683521, 5918.082697181943, 5930.495907847881, 5973.880522389615, 5980.073088671263, 5982.641041537521, 5983.34183239852, 5985.6408615483215, 5630.85922851932, 5808.912866307093, 5849.532037436909, 5858.794942177004, 5860.648361098348, 5839.562032847638, 5841.474267315962, 5843.824685259449, 5879.911801323069, 5884.205632239593, 5776.9133462998925, 5796.188533605383, 5818.127280907728, 5834.562407819346, 5839.912401314069, 5669.518090962314, 5725.227094342377, 5809.796657116981, 5872.412758724094, 5880.235569375954, 5626.549876009991, 5654.83035508919, 5667.4615911488345, 5674.0, 5703.344115426779], "value": 5482.844348205424, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.798934367643, 637.7512770019694, 662.3078882567717, 668.9732410703572, 678.6911955060448, 36187.819060904694, 47755.76122119389, 48392.0, 48402.515974840244, 48436.51563484364, 49621.50378496214, 50080.0, 50094.0, 50250.49749502504, 50265.0, 8677.305815534774, 9421.481818499997, 9588.23294136471, 9588.952055239713, 11287.435628218598, 11014.284071535325, 11482.827757583644, 11485.368304743235, 11492.540298388054, 11495.712607184803, 11548.0, 11670.241434306785, 11677.240979336317, 11678.8248176277, 11688.532458701642, 11533.942330288379, 11555.0, 11570.190086693934, 11595.53617855289, 11607.24552904067, 11250.212485126045, 11397.088232941345, 11406.258593191416, 11412.20114591979, 11413.144014198968, 46538.0, 46946.53053469456, 47153.0, 47268.29097563539, 47346.289805652945, 3896.8636097736444, 3958.841646334143, 4070.3080476319096, 4349.760763158025, 4685.578297953167, 35187.4721879172, 35443.0, 35581.0, 35686.703821655945, 36286.09284767875, 4602.194615942197, 4696.319033740123, 4717.103750287434, 4724.574788269072, 4727.149113159629, 389103.0, 391713.0, 391978.0, 392530.0, 392844.0, 66494.33505664881, 66719.0, 66782.0, 66830.66584667143, 66980.33019669839, 40037.39943900844, 40363.798181008904, 40500.59499405027, 42726.78636606859, 69328.0, 18816.905915470335, 18944.62110757805, 18995.43013709591, 19011.04944752757, 19018.80981190198, 12133.878661213452, 12310.876891231152, 12311.0, 13017.739645207042, 13482.25847578373, 20220.69668954967, 20797.272095476586, 20899.0, 20980.580388392453, 20998.685019724715, 7317.780466586011, 7436.256374362521, 7462.179160292363, 7479.783693011948, 7760.767176984699, 968.4431451915177, 1042.926995110335, 1078.0567003871613, 1110.9111271098293, 1118.2116607791488, 34414.65585344165, 34552.13619659529, 34903.65096349004, 35045.64954350424, 35169.52100748484, 1132.416805345248, 1154.5266440759417, 1155.6359746679768, 1200.8498937632796, 1222.248317284881, 18331.0, 18556.350527731465, 18576.349827755963, 18715.438536843918, 18795.15421806022, 603667.0, 610663.0, 611404.0, 611655.0, 613874.0, 65740.0, 66263.33736662669, 66312.6684366575, 66326.33673663298, 66399.66800165968, 1321.557278311757, 1396.6229118138046, 1416.5126617052053, 1431.2843578210895, 1450.6590951126477, 34282.48576271408, 34996.300073998886, 35444.64555354517, 35591.64408355884, 35661.28677426489, 56230.15654765264, 56886.0, 56960.43039569551, 57068.714656428114, 57081.0, 334167.0, 334806.0, 336791.0, 337027.0, 337261.0, 28209.576856347583, 30833.845830770704, 31569.368612628077, 31597.368052639278, 31789.364212715154, 51933.740331298104, 52520.0, 52651.73674131605, 52720.0, 52823.47176528339, 44580.33129503125, 45233.77383113063, 45921.77039114783, 45982.54017459782, 47331.763341183076, 44048.339274910264, 44798.328025079, 45637.54362456333, 46207.878500236766, 46380.536194638975, 43880.0, 44541.44104956311, 45616.0, 45701.77149114366, 45709.31436028529, 37087.258254835295, 39659.2068158641, 39888.60111398849, 40205.79897100496, 40641.39037914375, 29487.579408109337, 29745.0, 29763.70236297696, 29765.255868603457, 30026.699733003265], "value": 21987.959923389702, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.77979890858956, 409.8729393887897, 424.537254392712, 430.2170050508075, 439.59557207369215, 19194.328198513038, 20732.378028659146, 20790.480237994037, 21008.579828403424, 21853.562928741416, 19327.0, 19733.90133049333, 19853.205871765113, 20046.59906801863, 20252.189912403523, 5415.702136382497, 5607.57943154263, 5618.606697531174, 5619.0, 5619.466150715675, 5548.611597188178, 5589.496945274925, 5756.971215143918, 6003.519718422515, 6045.879082418349, 5913.9112913306335, 6049.395060493959, 6062.151298818169, 6063.2724073111285, 6063.727132279058, 5846.883062338772, 5993.940060599403, 6022.066579680157, 6047.3650266722025, 6056.848578785522, 5462.153366228241, 5465.234867118606, 5466.1527463243265, 5471.781128754845, 5472.097103977839, 22501.887490562607, 23467.413314667097, 23608.17371391993, 24880.0, 25294.494110117696, 2422.2248880358115, 2506.5112303100905, 2599.57107077332, 2695.905643302475, 2927.487689654302, 10548.419836908966, 10661.680149595524, 10731.463426828705, 10785.946070269676, 10980.176486763525, 1971.2115153938396, 1971.9605607887906, 1991.7410736604293, 2019.2730616977876, 2040.5306779440812, 85401.57299213464, 88467.55766221127, 89663.10336896613, 94251.0, 94355.05644943532, 11610.477528511237, 11780.234284771463, 12016.579419720269, 12125.636230913087, 12162.939185304016, 17401.91299043497, 17420.47738567845, 17425.041622710618, 17440.12799360027, 17450.650986980443, 7651.770446886602, 8020.35837133028, 8088.150744171778, 8096.595170241464, 8716.564171791386, 8164.795880102926, 8237.382196335298, 8485.151484851465, 8795.604197810986, 8801.07588703177, 4451.5325890781005, 4477.507474177838, 4535.183666939916, 4535.818567257273, 4624.260118381043, 1906.8665193436605, 2023.170500094954, 2075.885826279539, 2096.7798381170064, 2122.161746110278, 931.8229536388064, 1000.3947611694965, 1011.4689787861284, 1052.1950707708456, 1103.5751235774285, 20090.698639520127, 21034.78965210359, 21052.578948420945, 21083.0, 21213.257535986166, 1045.8901815309434, 1070.6145787516489, 1072.8176210044312, 1084.728817795551, 1092.6995076354067, 9846.655367062118, 11619.302841829518, 11659.18385712992, 11665.125115616363, 11676.824847627293, 499333.0, 505108.0, 522768.0, 527803.0, 528406.0, 91406.54296728606, 93115.0, 94218.52890735502, 96330.0, 97817.02182978079, 1009.6516701737888, 1010.5957616953277, 1028.3161697471098, 1032.1381646325403, 1046.9005444482762, 17874.37439689657, 17930.372436964644, 18822.6235475287, 18863.90568047151, 18902.905485472485, 45600.0, 45620.54379456163, 46036.0, 46728.29907551457, 47280.0, 60893.39106608877, 66179.33820661924, 66599.0, 67714.3228567708, 68479.65760171367, 8683.565821708762, 8769.079246679263, 8898.4660920345, 8950.955245223733, 9071.501067441353, 12020.158588898694, 12970.870291297344, 13177.47290108385, 13517.121387109806, 13890.722185556435, 11133.332000080021, 11181.664550063511, 11263.155263355446, 11431.314121152758, 11651.0, 11256.437178141225, 11489.310641361544, 11675.35785531804, 12248.0, 12363.381830908238, 12254.632361029184, 12505.312207828292, 12528.937355313165, 12889.09776315649, 13007.333496633357, 7949.8410031800195, 8869.64521419136, 8922.68770593026, 9179.586918588546, 9238.630454782002, 11361.886381136414, 11502.079833613123, 12021.879781202426, 12169.939150304546, 12563.246205227715], "value": 9646.34199743359, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.52854765106, 1499.4676889704156, 1527.870131038862, 1679.5297316751307, 1845.2987864611455, 8705.695300664478, 9061.818763624724, 9104.817903641931, 9394.0, 9405.106514881076, 9265.166135047844, 9393.62425502979, 9408.15326620604, 9409.0, 9412.576434060467, 2496.9250922472265, 2520.558902192109, 2522.2433270019014, 2524.204875464223, 2524.6465494830736, 2450.2894160693468, 2495.7753802157804, 2499.5750722377147, 2503.2615378463397, 2508.8745562721883, 2473.258022593224, 2496.313513783715, 2504.6743923289946, 2516.5218608464425, 2519.533886231044, 2433.5254625348066, 2451.950960980779, 2458.1273647854932, 2459.286806826018, 2483.7143728471096, 2355.3522781235124, 2369.7748713872156, 2378.179528062833, 2380.6071998120287, 2456.8034557235555, 9292.256619470494, 9487.620495180185, 9515.90484095157, 9604.423734575948, 9632.518374081268, 5527.474889885454, 5664.461876121762, 5672.716364181774, 5686.54507639392, 5688.175214593902, 3553.786772793615, 3587.3901436755814, 3622.2393297407575, 3637.7089832813313, 3678.282734866702, 1184.413715210976, 1199.874013228616, 1208.9335086570236, 1223.7858374784466, 1249.9500019999189, 13528.59414217575, 13657.248851313174, 13666.453341866314, 13669.658258543517, 13708.17750934937, 4767.713937163781, 4785.186518291829, 4798.328234047236, 4907.828226012072, 4943.579795717333, 10653.147748180281, 10683.0, 10686.038256556947, 10690.251682382313, 10696.0, 5571.804986825442, 5586.16207568868, 5586.91619625706, 5593.272874526367, 5599.580031497572, 4255.87232383029, 4596.149712303168, 4653.441587009581, 5121.795128194905, 5195.532402083831, 3941.0935484838647, 3954.347532657137, 4046.959530404717, 4050.0, 4066.6949978751713, 1212.0606529939191, 1216.9756604867853, 1233.463443402123, 1240.1257113734796, 1242.5402601037674, 1007.6171054999054, 1025.2310766924807, 1030.9329893556894, 1039.7920415916847, 1048.8898665640152, 6334.3032266450655, 6389.52078594098, 6453.645049522227, 6463.321351258144, 6569.868602627921, 494.29809670268304, 496.6523433596497, 502.41719605257725, 503.49147361164756, 510.50736039721846, 4401.801918913592, 4448.55514448559, 4464.285714285698, 4623.791929363187, 4644.489106198315, 11220.214584978972, 11768.235064720766, 11770.234934729215, 11771.352575608253, 11791.823122653168, 11302.208845380908, 11681.649550513499, 11713.941430292794, 11717.296962182296, 11763.058955283557, 8551.144885511523, 8742.86885696715, 8745.431546949429, 8748.693795717247, 8758.737237882873, 6298.370162983665, 6329.430351268408, 6441.0, 6496.090547323284, 6536.182977127859, 10562.630307939186, 10652.09457196116, 10778.353298802096, 10836.620718274822, 10842.945785271024, 9797.412155270707, 10604.469776511298, 10780.838287425851, 11239.438028098399, 11246.943765281449, 8318.916810832055, 8570.442921210102, 8711.782205444915, 8882.600282987152, 8890.022097569392, 9107.362484626012, 9304.209142222717, 9363.531823408666, 9367.344285900193, 9375.390599611004, 8351.498910065415, 8389.370797190113, 8467.36494762883, 8502.74491765248, 8518.574071296287, 8309.335253179852, 8335.624896879775, 8368.995720513578, 8510.063892971892, 8519.06290308078, 7486.737964171228, 7519.248075192437, 7543.057117860268, 7602.7339043133225, 7644.388448923962, 5507.201455788968, 5578.107502799533, 5609.80365687199, 5618.803341883015, 5637.238972738792, 5660.0, 5665.915011274917, 5698.971505142447, 5780.653160810365, 5912.231409916683], "value": 4853.921378060618, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.86925229908036, 336.66501830678476, 338.2018436489885, 340.27691156292883, 345.0717569737406, 5413.0, 5452.236686863832, 5457.999999999995, 5466.633341664584, 5514.117741161415, 5271.657342272759, 5281.9207711884255, 5374.898145880095, 5394.514493695567, 5412.269343638608, 4158.91682166358, 4186.534712850494, 4248.553901840309, 4252.404663347126, 4264.125854199881, 4217.8312867485265, 4223.303154979425, 4228.809703563332, 4240.681948853833, 4243.32106862902, 4193.22425351311, 4194.370844373338, 4194.769287689176, 4216.789160541976, 4221.24017676818, 3996.5412624392034, 4076.9388459173138, 4195.391668208108, 4211.389348544474, 4213.810378532943, 3746.6253374662588, 3750.718696097804, 3758.229562939591, 3763.7741735495956, 3798.4682144499793, 5177.8187763428095, 5193.740312984373, 5197.194434862602, 5231.895362092737, 5272.367315922075, 4731.929021064687, 4773.76131193439, 4800.3279540864305, 4803.231482962709, 4845.63657725672, 5504.706393997448, 5537.280153580009, 5548.195511650828, 5550.195221692872, 5552.944470555283, 4146.460960075201, 4151.3150330195485, 4162.5421203667875, 4169.332906734938, 4179.832806687759, 5638.4925356718095, 5743.54051675865, 5787.479126878601, 5793.217915581385, 5845.298564172284, 4194.748315101104, 4215.241256573846, 4229.963658903527, 4241.384999175164, 4251.404803327537, 4303.526612072669, 4305.547917468683, 4314.223439780807, 4319.265724826787, 4322.351647252937, 4578.496365399803, 4790.131848579057, 4804.135255653946, 4828.493008234085, 4856.902861942742, 5562.527185189304, 5585.425327790217, 5590.468905453975, 5601.271834661468, 5610.775568977195, 1439.6688761584894, 1454.4981981216463, 1464.5679524540287, 1466.6040169154483, 1471.146734893759, 901.0097689953996, 933.453929451265, 935.9438433694, 937.2970272295778, 945.2815859946353, 4857.757112144449, 4865.521152375997, 4906.31311616374, 4908.2392229203815, 4926.679765815282, 5134.691918484903, 5139.177731563007, 5161.4322424533275, 5168.767405466763, 5219.608529360313, 4553.0, 4821.879453013703, 4945.19500382365, 4947.950520494749, 4958.504149585085, 2682.3428260076407, 2687.2667129701235, 2709.8916043358045, 2712.6880408753245, 2735.753782159615, 5286.524212820787, 5332.346972438557, 5350.678959262456, 5364.463553644682, 5403.595734687122, 5233.502817232356, 5248.343957005374, 5459.454054594588, 5883.499707574541, 5949.464548190684, 6009.2188015557695, 6026.337102918763, 6031.819045428644, 6043.848903777441, 6052.727627256784, 5634.267545219259, 5807.825765226881, 5841.299044114733, 5846.298444186725, 5867.73595188209, 5662.2639056922335, 5743.770249190153, 5760.683162425939, 5763.279590051244, 5773.538116950718, 6055.576109672281, 6060.454559089703, 6061.212042434456, 6100.603460775035, 6111.511079113571, 4038.1721747041197, 4108.8356465741035, 4151.46031015966, 4191.203671302443, 4241.257779888569, 5703.914441283467, 5709.65742055478, 5744.047024011856, 5755.02164632022, 5763.51010164124, 5652.378238393856, 5665.943340566541, 5667.914981275202, 5670.943290567042, 5674.467893668604, 5450.886229811981, 5565.693886836263, 5567.8886422272135, 5607.80372686954, 5632.577556683347, 5567.610267281251, 5589.860253493695, 5590.664560126405, 5591.27313449249, 5601.355844077983, 5445.210444485607, 5534.557235421237, 5590.86022849432, 5596.608237423341, 5672.914906276492, 5530.253415788817, 5533.833984980457, 5536.0865457200725, 5538.6399884007415, 5550.364511245141], "value": 4242.530705975119, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.7034667519691, 854.1159899504015, 877.2411863737867, 896.3770179725092, 906.4606559097335, 16157.596060098489, 17389.73915391267, 17568.64862702745, 17572.560685982826, 17575.47273581793, 15878.603034924105, 15958.7606185907, 16045.91977040116, 16489.917550412232, 16716.164191790424, 862.8101817600134, 879.5162660536711, 880.7886107334258, 881.0704706534603, 882.355880207449, 726.0851327327579, 858.5621333120091, 876.4522173641474, 878.0999475537593, 889.524104604037, 900.8423525882977, 904.525124309736, 909.7179874239007, 909.963601455941, 913.0458670689146, 908.0692290402325, 910.9407888487293, 913.2146354135439, 914.1270087066869, 916.1983264643437, 670.7887015590073, 675.7938828657258, 676.4993904510676, 676.9153855768029, 678.8845896197658, 16254.431094911739, 16265.593360165973, 16275.104869232187, 16292.348306067743, 16294.755578666329, 16757.41349052777, 17006.3197472101, 17069.317227310894, 17355.21901514435, 17787.377441789475, 12995.285259310735, 13127.803082953764, 13132.86867131326, 13192.340382980909, 13413.39639716215, 4171.395147703596, 4204.07510347723, 4268.295731204347, 4304.053108316165, 4334.566543345672, 88695.0, 88972.55513722455, 89109.55445222797, 90395.0, 92732.0, 13009.739805203842, 13074.215547067017, 13255.867441325656, 13267.800982985264, 13328.266945317906, 24414.877925610614, 24557.263282101565, 24593.0, 25149.874250628996, 25799.613005804928, 8379.952505936757, 8641.049484556694, 8917.55412229386, 9079.092090790868, 9151.63393464254, 4965.255211718271, 5121.0, 5213.165893457029, 5254.159334506537, 5289.100852855024, 4042.15114825884, 4103.466549348565, 4140.0270936329935, 4157.334826427757, 4175.83296668136, 675.3757213900577, 685.3318014935398, 691.439933653732, 703.8310805406768, 704.5314865614412, 771.5139462138928, 808.2604416958417, 818.8485130250923, 831.2934006094891, 847.720252316734, 9719.9028009719, 10718.35689858611, 10739.0, 11237.269577477438, 11396.430178491208, 670.9843916722557, 795.5226863881625, 811.5860910935465, 817.7873752824308, 823.6746485138457, 5048.823291184791, 5128.333316668809, 5246.134387825966, 5257.08001099806, 5350.892982140335, 74126.0, 75340.62329688316, 75744.62127689326, 75828.0, 124908.0, 111659.0, 112762.0, 113689.0, 115508.0, 117274.41362793131, 98079.0, 99350.0, 99931.50034249782, 101431.49284253531, 101890.0, 9539.09378609031, 9626.807463850824, 9924.503774811086, 9940.0, 9945.0, 57795.71102144462, 58362.708186458796, 59546.70226648839, 59700.40299597122, 61353.38646613655, 81297.0, 87841.12158878586, 88839.0, 89660.5516972411, 92428.53785731028, 13124.475020999053, 13154.0, 13615.591532254048, 13786.172829630254, 14021.719565608835, 14641.707165856837, 14933.850661493247, 14985.550433487013, 15162.014469059475, 15809.367625294859, 12516.561920332744, 12643.304618246084, 13652.385642645906, 13689.0, 13708.86291137076, 13119.606411807661, 13551.59345219645, 13618.114822536503, 13739.313034348042, 13951.372188251757, 13452.46190152422, 13484.393202305724, 13537.323133843072, 13658.931705341409, 13772.93113534466, 10926.071283940953, 10966.06788422993, 11314.151438642299, 11401.885981140082, 11415.0, 11080.833787493033, 11475.196736228718, 11607.941960290482, 11817.586384476503, 11848.526058957545], "value": 8177.288334504182, "unit": "score"}], "name": "dromaeo_css", "value": 7620.578170586064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 122.0, 111.0, 110.0, 111.0, 110.0, 109.0, 111.0, 110.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 115.0, 139.0, 175.0, 125.0, 143.0, 144.0, 117.0, 177.0, 116.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 160.0, 161.0, 160.0, 162.0, 160.0, 159.0, 161.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 76.0, 172.0, 75.0, 77.0, 75.0, 75.0, 160.0, 74.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 140.0, 83.0, 83.0, 84.0, 144.0, 84.0, 83.0, 140.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 51.0, 51.0, 50.0, 48.0, 49.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 73.0, 74.0, 78.0, 83.0, 72.0, 73.0, 74.0, 75.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 131.0, 128.0, 125.0, 125.0, 117.0, 116.0, 176.0, 121.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 153.0, 157.0, 152.0, 154.0, 151.0, 152.0, 152.0, 154.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 64.0, 60.0, 61.0, 61.0, 60.0, 61.0, 66.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1493.8999999999999, "alertThreshold": 2.0}]} 15:16:32 INFO - Return code: 0 15:16:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:16:32 INFO - # TBPL SUCCESS # 15:16:32 INFO - Running post-action listener: _resource_record_post_action 15:16:32 INFO - [mozharness: 2016-10-19 22:16:32.875305Z] Finished run-tests step (success) 15:16:32 INFO - Running post-run listener: _resource_record_post_run 15:16:32 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 290160640; Read time: 0; Write time: 158240 15:16:32 INFO - TinderboxPrint: CPU usage
12.9% 15:16:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:16:32 INFO - TinderboxPrint: I/O write bytes / time
290,160,640 / 158,240 15:16:32 INFO - TinderboxPrint: CPU idle
8,243.8 (87.1%) 15:16:32 INFO - TinderboxPrint: CPU user
1,181.6 (12.5%) 15:16:32 INFO - TinderboxPrint: Swap in / out
0 / 0 15:16:32 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71299072; Read time: 0; Write time: 30388 15:16:32 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 218861568; Read time: 0; Write time: 127852 15:16:33 INFO - Running post-run listener: _upload_blobber_files 15:16:33 INFO - Blob upload gear active. 15:16:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:16:33 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 15:16:33 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'] 15:16:33 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 15:16:33 INFO - (blobuploader) - INFO - Open directory for files ... 15:16:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:16:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:16:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:16:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:38 INFO - (blobuploader) - INFO - Done attempting. 15:16:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:16:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:16:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:16:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:39 INFO - (blobuploader) - INFO - Done attempting. 15:16:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:16:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:16:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:16:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:40 INFO - (blobuploader) - INFO - Done attempting. 15:16:40 INFO - (blobuploader) - INFO - Iteration through files over. 15:16:40 INFO - Return code: 0 15:16:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:16:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:16:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7b49bb6f1561998588499389f62bef0cc35497b46443fb929d306a0ca231cce17778dc5ab6a610fb0b9dabae180777b7c0e40a8f97ae0c7b2a1d939f286fa48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2acf581b403a5b9c0441df00c144adde7389131c9bea0b59f8e175a21b3d7f8804a4b082a9930c13d8e65ae077bd58b67ba79e166f246a60f36d56b4d100b6bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf762abe142babebae1fdf9e703af2b1540d932ca4bbcadf3331038119b67cf8300b6a3f72968bed0b95c2e2f80c9587a5663281da5fd7752eaad5a09b9ac13"} 15:16:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:16:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:16:40 INFO - Contents: 15:16:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7b49bb6f1561998588499389f62bef0cc35497b46443fb929d306a0ca231cce17778dc5ab6a610fb0b9dabae180777b7c0e40a8f97ae0c7b2a1d939f286fa48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2acf581b403a5b9c0441df00c144adde7389131c9bea0b59f8e175a21b3d7f8804a4b082a9930c13d8e65ae077bd58b67ba79e166f246a60f36d56b4d100b6bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf762abe142babebae1fdf9e703af2b1540d932ca4bbcadf3331038119b67cf8300b6a3f72968bed0b95c2e2f80c9587a5663281da5fd7752eaad5a09b9ac13"} 15:16:40 INFO - Running post-run listener: copy_logs_to_upload_dir 15:16:40 INFO - Copying logs to upload dir... 15:16:40 INFO - mkdir: /builds/slave/test/build/upload/logs 15:16:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.991112 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-19 15:16:40.052813) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 15:16:40.056587) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 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/a7b49bb6f1561998588499389f62bef0cc35497b46443fb929d306a0ca231cce17778dc5ab6a610fb0b9dabae180777b7c0e40a8f97ae0c7b2a1d939f286fa48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2acf581b403a5b9c0441df00c144adde7389131c9bea0b59f8e175a21b3d7f8804a4b082a9930c13d8e65ae077bd58b67ba79e166f246a60f36d56b4d100b6bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf762abe142babebae1fdf9e703af2b1540d932ca4bbcadf3331038119b67cf8300b6a3f72968bed0b95c2e2f80c9587a5663281da5fd7752eaad5a09b9ac13"} build_url:https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/LNqn3yMKRLKMizX-Z0SDqA/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/a7b49bb6f1561998588499389f62bef0cc35497b46443fb929d306a0ca231cce17778dc5ab6a610fb0b9dabae180777b7c0e40a8f97ae0c7b2a1d939f286fa48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2acf581b403a5b9c0441df00c144adde7389131c9bea0b59f8e175a21b3d7f8804a4b082a9930c13d8e65ae077bd58b67ba79e166f246a60f36d56b4d100b6bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf762abe142babebae1fdf9e703af2b1540d932ca4bbcadf3331038119b67cf8300b6a3f72968bed0b95c2e2f80c9587a5663281da5fd7752eaad5a09b9ac13"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 15:16:40.081520) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:16:40.081839) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1476913723.584940-606283222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:16:40.167338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 15:16:40.167668) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 15:16:40.167981) ========= ========= Total master_lag: 0.16 =========