builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1477429783.55 results: success (0) buildid: 20161025111824 builduid: 213d74a711cb444db6eb1bc883c20fd8 revision: 49d808e29f639134d1f1510084a962f7924d9977 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.548152) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.548639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.549100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.569524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.569862) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-25 14:09:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-10-25 14:09:44 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098312 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.692475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.693068) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019533 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:43.755750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 14:09:43.756089) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 49d808e29f639134d1f1510084a962f7924d9977 --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 49d808e29f639134d1f1510084a962f7924d9977 --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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-25 14:09:44,199 truncating revision to first 12 chars 2016-10-25 14:09:44,199 Setting DEBUG logging. 2016-10-25 14:09:44,199 attempt 1/10 2016-10-25 14:09:44,200 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/49d808e29f63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 14:09:45,511 unpacking tar archive at: mozilla-beta-49d808e29f63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.641213 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 14:09:45.413988) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:45.414348) ========= script_repo_revision: 49d808e29f639134d1f1510084a962f7924d9977 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:45.414812) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:45.415113) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 14:09:45.423632) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-25 14:09:45.423963) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:09:45 INFO - MultiFileLogger online at 20161025 14:09:45 in /builds/slave/test 14:09: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:09:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:45 INFO - {'append_to_log': False, 14:09:45 INFO - 'base_work_dir': '/builds/slave/test', 14:09:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:09:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:45 INFO - 'branch': 'Mozilla-Beta', 14:09:45 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:45 INFO - 'config_files': ('talos/linux_config.py',), 14:09:45 INFO - 'default_actions': ('clobber', 14:09:45 INFO - 'read-buildbot-config', 14:09:45 INFO - 'download-and-extract', 14:09:45 INFO - 'populate-webroot', 14:09:45 INFO - 'create-virtualenv', 14:09:45 INFO - 'install', 14:09:45 INFO - 'run-tests'), 14:09:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:45 INFO - 'download_minidump_stackwalk': True, 14:09:45 INFO - 'download_symbols': 'ondemand', 14:09:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:45 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:45 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:45 INFO - 'installer_path': 'installer.exe', 14:09:45 INFO - 'log_level': 'info', 14:09:45 INFO - 'log_name': 'talos', 14:09:45 INFO - 'log_to_console': True, 14:09:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:09:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:09:45 INFO - 'opt_config_files': (), 14:09:45 INFO - 'pip_index': False, 14:09:45 INFO - 'sps_profile': False, 14:09:45 INFO - 'sps_profile_interval': 0, 14:09:45 INFO - 'suite': 'dromaeojs', 14:09:45 INFO - 'system_bits': '32', 14:09:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:45 INFO - 'title': 'talos-linux64-ix-057', 14:09:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:45 INFO - 'use_talos_json': True, 14:09:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:45 INFO - 'work_dir': 'build'} 14:09:45 INFO - [mozharness: 2016-10-25 21:09:45.955913Z] Running clobber step. 14:09:45 INFO - Running pre-action listener: _resource_record_pre_action 14:09:45 INFO - Running main action method: clobber 14:09:45 INFO - rmtree: /builds/slave/test/build 14:09:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:46 INFO - Running post-action listener: _resource_record_post_action 14:09:46 INFO - [mozharness: 2016-10-25 21:09:46.929166Z] Finished clobber step (success) 14:09:46 INFO - [mozharness: 2016-10-25 21:09:46.929266Z] Running read-buildbot-config step. 14:09:46 INFO - Running pre-action listener: _resource_record_pre_action 14:09:46 INFO - Running main action method: read_buildbot_config 14:09:46 INFO - Using buildbot properties: 14:09:46 INFO - { 14:09:46 INFO - "project": "", 14:09:46 INFO - "product": "firefox", 14:09:46 INFO - "script_repo_revision": "production", 14:09:46 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:09:46 INFO - "repository": "", 14:09:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:09:46 INFO - "buildid": "20161025111824", 14:09:46 INFO - "pgo_build": "True", 14:09:46 INFO - "basedir": "/builds/slave/test", 14:09:46 INFO - "buildnumber": 445, 14:09:46 INFO - "slavename": "talos-linux64-ix-057", 14:09:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:09:46 INFO - "platform": "ubuntu64_hw", 14:09:46 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:09:46 INFO - "branch": "mozilla-beta", 14:09:46 INFO - "repo_path": "releases/mozilla-beta", 14:09:46 INFO - "revision": "49d808e29f639134d1f1510084a962f7924d9977", 14:09:46 INFO - "stage_platform": "linux64-pgo", 14:09:46 INFO - "builduid": "213d74a711cb444db6eb1bc883c20fd8", 14:09:46 INFO - "slavebuilddir": "test" 14:09:46 INFO - } 14:09:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 14:09:46 INFO - Running post-action listener: _resource_record_post_action 14:09:46 INFO - [mozharness: 2016-10-25 21:09:46.931704Z] Finished read-buildbot-config step (success) 14:09:46 INFO - [mozharness: 2016-10-25 21:09:46.931793Z] Running download-and-extract step. 14:09:46 INFO - Running pre-action listener: _resource_record_pre_action 14:09:46 INFO - Running main action method: download_and_extract 14:09:46 INFO - mkdir: /builds/slave/test/build/tests 14:09: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:09:46 INFO - https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:09:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:09:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:09:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:09:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:09:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:09:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:09:46 INFO - retry: Failed, sleeping 30 seconds before retrying 14:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:10:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:10:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:10:16 INFO - retry: Failed, sleeping 60 seconds before retrying 14:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:11:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:11:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 INFO - Caught exception: HTTP Error 404: Not Found 14:11:17 INFO - Caught exception: HTTP Error 404: Not Found 14:11:17 INFO - Caught exception: HTTP Error 404: Not Found 14:11:17 INFO - trying https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:11:17 INFO - Downloading https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 INFO - Downloaded 1327 bytes. 14:11:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:11:17 INFO - Using the following test package requirements: 14:11:17 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:11:17 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:11:17 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:11:17 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'jsshell-linux-x86_64.zip'], 14:11:17 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:11:17 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:11:17 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:11:17 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:11:17 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:11:17 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:11:17 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:11:17 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:17 INFO - https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:11:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:11:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:11:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:11:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:11:17 INFO - retry: Failed, sleeping 30 seconds before retrying 14:11:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:11:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:11:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:11:47 INFO - retry: Failed, sleeping 60 seconds before retrying 14:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:12:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:12:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:47 INFO - Caught exception: HTTP Error 404: Not Found 14:12:47 INFO - Caught exception: HTTP Error 404: Not Found 14:12:47 INFO - Caught exception: HTTP Error 404: Not Found 14:12:47 INFO - trying https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:12:47 INFO - Downloading https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:48 INFO - Downloaded 21775621 bytes. 14:12:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:12:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:12:49 INFO - Return code: 0 14:12:49 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:49 INFO - https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:12:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:12:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:12:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:12:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:12:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:12:49 INFO - retry: Failed, sleeping 30 seconds before retrying 14:13:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:13:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:13:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:13:19 INFO - retry: Failed, sleeping 60 seconds before retrying 14:14:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:14:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:14:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:19 INFO - Caught exception: HTTP Error 404: Not Found 14:14:19 INFO - Caught exception: HTTP Error 404: Not Found 14:14:19 INFO - Caught exception: HTTP Error 404: Not Found 14:14:19 INFO - trying https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:14:19 INFO - Downloading https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:20 INFO - Downloaded 21775621 bytes. 14:14: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:14: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:14:22 INFO - Return code: 0 14:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:14:22 INFO - https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:14:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:14:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:14:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:14:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:14:22 INFO - retry: Failed, sleeping 30 seconds before retrying 14:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:14:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:14:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:14:52 INFO - retry: Failed, sleeping 60 seconds before retrying 14:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:15:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:15:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:52 INFO - Caught exception: HTTP Error 404: Not Found 14:15:52 INFO - Caught exception: HTTP Error 404: Not Found 14:15:52 INFO - Caught exception: HTTP Error 404: Not Found 14:15:52 INFO - trying https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:15:52 INFO - Downloading https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:53 INFO - Downloaded 14413762 bytes. 14:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:15:53 INFO - Return code: 0 14:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:53 INFO - https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:15:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:15:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:15:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:15:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:15:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:15:53 INFO - retry: Failed, sleeping 30 seconds before retrying 14:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:16:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:16:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:16:23 INFO - retry: Failed, sleeping 60 seconds before retrying 14:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:17:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:17:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:17:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EfYekiOJTV6scfVAW-s12w/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:17:23 INFO - Caught exception: HTTP Error 404: Not Found 14:17:23 INFO - Caught exception: HTTP Error 404: Not Found 14:17:23 INFO - Caught exception: HTTP Error 404: Not Found 14:17:23 INFO - trying https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:17:23 INFO - Downloading https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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:17:25 INFO - Downloaded 56799897 bytes. 14:17:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:17:25 INFO - mkdir: /builds/slave/test/properties 14:17:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:17:25 INFO - Writing to file /builds/slave/test/properties/build_url 14:17:25 INFO - Contents: 14:17:25 INFO - build_url:https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:17:26 INFO - Running post-action listener: _resource_record_post_action 14:17:26 INFO - Running post-action listener: set_extra_try_arguments 14:17:26 INFO - [mozharness: 2016-10-25 21:17:26.752727Z] Finished download-and-extract step (success) 14:17:26 INFO - [mozharness: 2016-10-25 21:17:26.752957Z] Running populate-webroot step. 14:17:26 INFO - Running pre-action listener: _resource_record_pre_action 14:17:26 INFO - Running main action method: populate_webroot 14:17:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:17:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:17:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:17:26 INFO - '--tppagecycles', 14:17:26 INFO - '7'], 14:17:26 INFO - 'tests': ['tsvgm']}}, 14:17:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:17:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:17:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:17:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:17:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['damp', 'tps']}, 14:17:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'tests': ['damp', 'tps']}, 14:17:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['dromaeo_dom']}, 14:17:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:17:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['basic_compositor_video']}, 14:17:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:17:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'other-e10s': {'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['a11yr', 14:17:26 INFO - 'ts_paint', 14:17:26 INFO - 'tpaint', 14:17:26 INFO - 'sessionrestore', 14:17:26 INFO - 'sessionrestore_no_auto_restore', 14:17:26 INFO - 'tabpaint']}, 14:17:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['tsvgx', 14:17:26 INFO - 'tsvgr_opacity', 14:17:26 INFO - 'tart', 14:17:26 INFO - 'tscrollx', 14:17:26 INFO - 'cart']}, 14:17:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:17:26 INFO - 'tsvgr_opacity', 14:17:26 INFO - 'tart', 14:17:26 INFO - 'tscrollx', 14:17:26 INFO - 'cart']}, 14:17:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'talos_options': ['--disable-e10s'], 14:17:26 INFO - 'tests': ['tp5o']}, 14:17:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'tests': ['tp5o']}, 14:17:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'talos_options': ['--disable-e10s', 14:17:26 INFO - '--xperf_path', 14:17:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:26 INFO - 'tests': ['tp5n']}, 14:17:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:26 INFO - 'talos_options': ['--xperf_path', 14:17:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:26 INFO - 'tests': ['tp5n']}}, 14:17:26 INFO - 'talos.zip': {'path': '', 14:17:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:17:26 INFO - Running post-action listener: _resource_record_post_action 14:17:26 INFO - [mozharness: 2016-10-25 21:17:26.774024Z] Finished populate-webroot step (success) 14:17:26 INFO - [mozharness: 2016-10-25 21:17:26.774112Z] Running create-virtualenv step. 14:17:26 INFO - Running pre-action listener: _resource_record_pre_action 14:17:26 INFO - Running main action method: create_virtualenv 14:17:26 INFO - Creating virtualenv /builds/slave/test/build/venv 14:17:26 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:17:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:17:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:17:26 INFO - Using real prefix '/usr' 14:17:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:17:27 INFO - Installing distribute.............................................................................................................................................................................................done. 14:17:29 INFO - Installing pip.................done. 14:17:29 INFO - Return code: 0 14:17:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:17:29 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:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:29 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:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:29 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:29 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:17:29 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:17:29 INFO - Using env: {'DISPLAY': ':0', 14:17:29 INFO - 'HOME': '/home/cltbld', 14:17:29 INFO - 'LANG': 'en_US.UTF-8', 14:17:29 INFO - 'LANGUAGE': 'en_US:en', 14:17:29 INFO - 'LOGNAME': 'cltbld', 14:17:29 INFO - 'MAIL': '/var/mail/cltbld', 14:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:29 INFO - 'MOZ_NO_REMOTE': '1', 14:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:29 INFO - 'NO_EM_RESTART': '1', 14:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:29 INFO - 'PWD': '/builds/slave/test', 14:17:29 INFO - 'SHELL': '/bin/bash', 14:17:29 INFO - 'SHLVL': '1', 14:17:29 INFO - 'TERM': 'linux', 14:17:29 INFO - 'TMOUT': '86400', 14:17:29 INFO - 'USER': 'cltbld', 14:17:29 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:29 INFO - '_': '/tools/buildbot/bin/python'} 14:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:29 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:17:29 INFO - Cleaning up... 14:17:29 INFO - Return code: 0 14:17:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:17:29 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:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:29 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:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:29 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:29 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:17:29 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:17:29 INFO - Using env: {'DISPLAY': ':0', 14:17:29 INFO - 'HOME': '/home/cltbld', 14:17:29 INFO - 'LANG': 'en_US.UTF-8', 14:17:29 INFO - 'LANGUAGE': 'en_US:en', 14:17:29 INFO - 'LOGNAME': 'cltbld', 14:17:29 INFO - 'MAIL': '/var/mail/cltbld', 14:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:29 INFO - 'MOZ_NO_REMOTE': '1', 14:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:29 INFO - 'NO_EM_RESTART': '1', 14:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:29 INFO - 'PWD': '/builds/slave/test', 14:17:29 INFO - 'SHELL': '/bin/bash', 14:17:29 INFO - 'SHLVL': '1', 14:17:29 INFO - 'TERM': 'linux', 14:17:29 INFO - 'TMOUT': '86400', 14:17:29 INFO - 'USER': 'cltbld', 14:17:29 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:29 INFO - '_': '/tools/buildbot/bin/python'} 14:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:29 INFO - Downloading/unpacking psutil>=3.1.1 14:17:29 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:17:29 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:17:29 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:17:29 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:17:29 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:17:29 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:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:17:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:31 INFO - Installing collected packages: psutil 14:17:31 INFO - Running setup.py install for psutil 14:17:31 INFO - building 'psutil._psutil_linux' extension 14:17:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:17:32 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:17:32 INFO - building 'psutil._psutil_posix' extension 14:17:32 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:17:32 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:17:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:32 INFO - Successfully installed psutil 14:17:32 INFO - Cleaning up... 14:17:32 INFO - Return code: 0 14:17:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:17: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:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:17: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 mozsystemmonitor==0.3 14:17:32 INFO - Using env: {'DISPLAY': ':0', 14:17:32 INFO - 'HOME': '/home/cltbld', 14:17:32 INFO - 'LANG': 'en_US.UTF-8', 14:17:32 INFO - 'LANGUAGE': 'en_US:en', 14:17:32 INFO - 'LOGNAME': 'cltbld', 14:17:32 INFO - 'MAIL': '/var/mail/cltbld', 14:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:32 INFO - 'MOZ_NO_REMOTE': '1', 14:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:32 INFO - 'NO_EM_RESTART': '1', 14:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:32 INFO - 'PWD': '/builds/slave/test', 14:17:32 INFO - 'SHELL': '/bin/bash', 14:17:32 INFO - 'SHLVL': '1', 14:17:32 INFO - 'TERM': 'linux', 14:17:32 INFO - 'TMOUT': '86400', 14:17:32 INFO - 'USER': 'cltbld', 14:17:32 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:32 INFO - '_': '/tools/buildbot/bin/python'} 14:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:17: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:17: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:17: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:17: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:17: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:17: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:17:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:17:34 INFO - Installing collected packages: mozsystemmonitor 14:17:34 INFO - Running setup.py install for mozsystemmonitor 14:17:34 INFO - Successfully installed mozsystemmonitor 14:17:34 INFO - Cleaning up... 14:17:34 INFO - Return code: 0 14:17:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:34 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:34 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:17:34 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:17:34 INFO - Using env: {'DISPLAY': ':0', 14:17:34 INFO - 'HOME': '/home/cltbld', 14:17:34 INFO - 'LANG': 'en_US.UTF-8', 14:17:34 INFO - 'LANGUAGE': 'en_US:en', 14:17:34 INFO - 'LOGNAME': 'cltbld', 14:17:34 INFO - 'MAIL': '/var/mail/cltbld', 14:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:34 INFO - 'MOZ_NO_REMOTE': '1', 14:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:34 INFO - 'NO_EM_RESTART': '1', 14:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:34 INFO - 'PWD': '/builds/slave/test', 14:17:34 INFO - 'SHELL': '/bin/bash', 14:17:34 INFO - 'SHLVL': '1', 14:17:34 INFO - 'TERM': 'linux', 14:17:34 INFO - 'TMOUT': '86400', 14:17:34 INFO - 'USER': 'cltbld', 14:17:34 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:34 INFO - '_': '/tools/buildbot/bin/python'} 14:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:34 INFO - Downloading/unpacking jsonschema==2.5.1 14:17: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:17: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:17: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:17: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:17: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:17: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:17:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:37 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:37 INFO - warning: no files found matching '*.txt' 14:17:37 INFO - no previously-included directories found matching 'build' 14:17:37 INFO - no previously-included directories found matching 'dist' 14:17:37 INFO - no previously-included directories found matching '.git*' 14:17:37 INFO - Installing collected packages: jsonschema, functools32 14:17:37 INFO - Running setup.py install for functools32 14:17:37 INFO - warning: no files found matching '*.txt' 14:17:37 INFO - no previously-included directories found matching 'build' 14:17:37 INFO - no previously-included directories found matching 'dist' 14:17:37 INFO - no previously-included directories found matching '.git*' 14:17:37 INFO - Successfully installed jsonschema functools32 14:17:37 INFO - Cleaning up... 14:17:37 INFO - Return code: 0 14:17:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:37 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 14:17:37 INFO - 'HOME': '/home/cltbld', 14:17:37 INFO - 'LANG': 'en_US.UTF-8', 14:17:37 INFO - 'LANGUAGE': 'en_US:en', 14:17:37 INFO - 'LOGNAME': 'cltbld', 14:17:37 INFO - 'MAIL': '/var/mail/cltbld', 14:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:37 INFO - 'MOZ_NO_REMOTE': '1', 14:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:37 INFO - 'NO_EM_RESTART': '1', 14:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:37 INFO - 'PWD': '/builds/slave/test', 14:17:37 INFO - 'SHELL': '/bin/bash', 14:17:37 INFO - 'SHLVL': '1', 14:17:37 INFO - 'TERM': 'linux', 14:17:37 INFO - 'TMOUT': '86400', 14:17:37 INFO - 'USER': 'cltbld', 14:17:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:37 INFO - '_': '/tools/buildbot/bin/python'} 14:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:17:37 INFO - Cleaning up... 14:17:37 INFO - Return code: 0 14:17:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:37 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 14:17:37 INFO - 'HOME': '/home/cltbld', 14:17:37 INFO - 'LANG': 'en_US.UTF-8', 14:17:37 INFO - 'LANGUAGE': 'en_US:en', 14:17:37 INFO - 'LOGNAME': 'cltbld', 14:17:37 INFO - 'MAIL': '/var/mail/cltbld', 14:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:37 INFO - 'MOZ_NO_REMOTE': '1', 14:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:37 INFO - 'NO_EM_RESTART': '1', 14:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:37 INFO - 'PWD': '/builds/slave/test', 14:17:37 INFO - 'SHELL': '/bin/bash', 14:17:37 INFO - 'SHLVL': '1', 14:17:37 INFO - 'TERM': 'linux', 14:17:37 INFO - 'TMOUT': '86400', 14:17:37 INFO - 'USER': 'cltbld', 14:17:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:37 INFO - '_': '/tools/buildbot/bin/python'} 14:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:37 INFO - Downloading/unpacking blobuploader==1.2.4 14:17: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:17: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:17: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:17: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:17: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:17: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:17:39 INFO - Downloading blobuploader-1.2.4.tar.gz 14:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:17:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:17:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:40 INFO - Downloading docopt-0.6.1.tar.gz 14:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:17:40 INFO - Installing collected packages: blobuploader, requests, docopt 14:17:40 INFO - Running setup.py install for blobuploader 14:17:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:17:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:17:40 INFO - Running setup.py install for requests 14:17:40 INFO - Running setup.py install for docopt 14:17:40 INFO - Successfully installed blobuploader requests docopt 14:17:40 INFO - Cleaning up... 14:17:41 INFO - Return code: 0 14:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:41 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:41 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:17:41 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:17:41 INFO - Using env: {'DISPLAY': ':0', 14:17:41 INFO - 'HOME': '/home/cltbld', 14:17:41 INFO - 'LANG': 'en_US.UTF-8', 14:17:41 INFO - 'LANGUAGE': 'en_US:en', 14:17:41 INFO - 'LOGNAME': 'cltbld', 14:17:41 INFO - 'MAIL': '/var/mail/cltbld', 14:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:41 INFO - 'MOZ_NO_REMOTE': '1', 14:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:41 INFO - 'NO_EM_RESTART': '1', 14:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:41 INFO - 'PWD': '/builds/slave/test', 14:17:41 INFO - 'SHELL': '/bin/bash', 14:17:41 INFO - 'SHLVL': '1', 14:17:41 INFO - 'TERM': 'linux', 14:17:41 INFO - 'TMOUT': '86400', 14:17:41 INFO - 'USER': 'cltbld', 14:17:41 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:41 INFO - '_': '/tools/buildbot/bin/python'} 14:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:41 INFO - Running setup.py (path:/tmp/pip-y_ZOAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:41 INFO - Running setup.py (path:/tmp/pip-Wnsn8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:41 INFO - Running setup.py (path:/tmp/pip-pGOazg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:41 INFO - Running setup.py (path:/tmp/pip-Px4Bjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:41 INFO - Running setup.py (path:/tmp/pip-7YXUfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:41 INFO - Running setup.py (path:/tmp/pip-6RJYq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:41 INFO - Running setup.py (path:/tmp/pip-wgk2xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:41 INFO - Running setup.py (path:/tmp/pip-96iVrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:41 INFO - Running setup.py (path:/tmp/pip-jItKve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:41 INFO - Running setup.py (path:/tmp/pip-0urQ49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:41 INFO - Running setup.py (path:/tmp/pip-RdnWfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:42 INFO - Running setup.py (path:/tmp/pip-i9fBah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:42 INFO - Running setup.py (path:/tmp/pip-L1IrZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:42 INFO - Running setup.py (path:/tmp/pip-ukIXNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:42 INFO - Running setup.py (path:/tmp/pip-FjqQ2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:42 INFO - Running setup.py (path:/tmp/pip-Ro0r0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:42 INFO - Running setup.py (path:/tmp/pip-vSr1sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:17:42 INFO - Running setup.py install for manifestparser 14:17:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:17:42 INFO - Running setup.py install for mozcrash 14:17:42 INFO - Running setup.py install for mozdebug 14:17:42 INFO - Running setup.py install for mozdevice 14:17:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:17:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:17:42 INFO - Running setup.py install for mozfile 14:17:43 INFO - Running setup.py install for mozhttpd 14:17:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozinfo 14:17:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozInstall 14:17:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozleak 14:17:43 INFO - Running setup.py install for mozlog 14:17:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for moznetwork 14:17:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozprocess 14:17:43 INFO - Running setup.py install for mozprofile 14:17:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozrunner 14:17:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:17:43 INFO - Running setup.py install for mozscreenshot 14:17:44 INFO - Running setup.py install for moztest 14:17:44 INFO - Running setup.py install for mozversion 14:17:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:17:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:17:44 INFO - Cleaning up... 14:17:44 INFO - Return code: 0 14:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:44 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:44 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:17:44 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:17:44 INFO - Using env: {'DISPLAY': ':0', 14:17:44 INFO - 'HOME': '/home/cltbld', 14:17:44 INFO - 'LANG': 'en_US.UTF-8', 14:17:44 INFO - 'LANGUAGE': 'en_US:en', 14:17:44 INFO - 'LOGNAME': 'cltbld', 14:17:44 INFO - 'MAIL': '/var/mail/cltbld', 14:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:44 INFO - 'MOZ_NO_REMOTE': '1', 14:17:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:44 INFO - 'NO_EM_RESTART': '1', 14:17:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:44 INFO - 'PWD': '/builds/slave/test', 14:17:44 INFO - 'SHELL': '/bin/bash', 14:17:44 INFO - 'SHLVL': '1', 14:17:44 INFO - 'TERM': 'linux', 14:17:44 INFO - 'TMOUT': '86400', 14:17:44 INFO - 'USER': 'cltbld', 14:17:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:44 INFO - '_': '/tools/buildbot/bin/python'} 14:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:44 INFO - Running setup.py (path:/tmp/pip-HzbaAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:44 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:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:44 INFO - Running setup.py (path:/tmp/pip-eJqaI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:44 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:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:44 INFO - Running setup.py (path:/tmp/pip-Z8ZuqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:44 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:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:44 INFO - Running setup.py (path:/tmp/pip-6H3H0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:44 INFO - Running setup.py (path:/tmp/pip-Y60iap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:44 INFO - Running setup.py (path:/tmp/pip-GlieBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:44 INFO - Running setup.py (path:/tmp/pip-EGA7D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:45 INFO - Running setup.py (path:/tmp/pip-UirTqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:45 INFO - Running setup.py (path:/tmp/pip-2cgesi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:45 INFO - Running setup.py (path:/tmp/pip-zm3K6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:45 INFO - Running setup.py (path:/tmp/pip-m7hT0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:45 INFO - Running setup.py (path:/tmp/pip-XyzQsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:45 INFO - Running setup.py (path:/tmp/pip-hqHAxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:45 INFO - Running setup.py (path:/tmp/pip-Z7Z7yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:45 INFO - Running setup.py (path:/tmp/pip-d3MSXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:45 INFO - Running setup.py (path:/tmp/pip-USuLfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:45 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:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:45 INFO - Running setup.py (path:/tmp/pip-IDWa8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:47 INFO - Downloading blessings-1.6.tar.gz 14:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:17:47 INFO - Installing collected packages: blessings 14:17:47 INFO - Running setup.py install for blessings 14:17:47 INFO - Successfully installed blessings 14:17:47 INFO - Cleaning up... 14:17:47 INFO - Return code: 0 14:17:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:17:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:48 INFO - Reading from file tmpfile_stdout 14:17:48 INFO - Current package versions: 14:17:48 INFO - argparse == 1.2.1 14:17:48 INFO - blessings == 1.6 14:17:48 INFO - blobuploader == 1.2.4 14:17:48 INFO - docopt == 0.6.1 14:17:48 INFO - functools32 == 3.2.3-2 14:17:48 INFO - jsonschema == 2.5.1 14:17:48 INFO - manifestparser == 1.1 14:17:48 INFO - mozInstall == 1.12 14:17:48 INFO - mozcrash == 0.17 14:17:48 INFO - mozdebug == 0.1 14:17:48 INFO - mozdevice == 0.48 14:17:48 INFO - mozfile == 1.2 14:17:48 INFO - mozhttpd == 0.7 14:17:48 INFO - mozinfo == 0.9 14:17:48 INFO - mozleak == 0.1 14:17:48 INFO - mozlog == 3.3 14:17:48 INFO - moznetwork == 0.27 14:17:48 INFO - mozprocess == 0.23 14:17:48 INFO - mozprofile == 0.28 14:17:48 INFO - mozrunner == 6.12 14:17:48 INFO - mozscreenshot == 0.1 14:17:48 INFO - mozsystemmonitor == 0.3 14:17:48 INFO - moztest == 0.7 14:17:48 INFO - mozversion == 1.4 14:17:48 INFO - psutil == 3.1.1 14:17:48 INFO - requests == 1.2.3 14:17:48 INFO - wsgiref == 0.1.2 14:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17: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:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:48 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:48 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:17:48 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:17:48 INFO - Using env: {'DISPLAY': ':0', 14:17:48 INFO - 'HOME': '/home/cltbld', 14:17:48 INFO - 'LANG': 'en_US.UTF-8', 14:17:48 INFO - 'LANGUAGE': 'en_US:en', 14:17:48 INFO - 'LOGNAME': 'cltbld', 14:17:48 INFO - 'MAIL': '/var/mail/cltbld', 14:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:48 INFO - 'MOZ_NO_REMOTE': '1', 14:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:48 INFO - 'NO_EM_RESTART': '1', 14:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:48 INFO - 'PWD': '/builds/slave/test', 14:17:48 INFO - 'SHELL': '/bin/bash', 14:17:48 INFO - 'SHLVL': '1', 14:17:48 INFO - 'TERM': 'linux', 14:17:48 INFO - 'TMOUT': '86400', 14:17:48 INFO - 'USER': 'cltbld', 14:17:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:48 INFO - '_': '/tools/buildbot/bin/python'} 14:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 INFO - Cleaning up... 14:17:48 INFO - Return code: 0 14:17:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:17: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:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:48 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 0xf76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1237180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', '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:17:48 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:17:48 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:17:48 INFO - Using env: {'DISPLAY': ':0', 14:17:48 INFO - 'HOME': '/home/cltbld', 14:17:48 INFO - 'LANG': 'en_US.UTF-8', 14:17:48 INFO - 'LANGUAGE': 'en_US:en', 14:17:48 INFO - 'LOGNAME': 'cltbld', 14:17:48 INFO - 'MAIL': '/var/mail/cltbld', 14:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:48 INFO - 'MOZ_NO_REMOTE': '1', 14:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:48 INFO - 'NO_EM_RESTART': '1', 14:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:48 INFO - 'PWD': '/builds/slave/test', 14:17:48 INFO - 'SHELL': '/bin/bash', 14:17:48 INFO - 'SHLVL': '1', 14:17:48 INFO - 'TERM': 'linux', 14:17:48 INFO - 'TMOUT': '86400', 14:17:48 INFO - 'USER': 'cltbld', 14:17:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:48 INFO - '_': '/tools/buildbot/bin/python'} 14:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:17:48 INFO - Cleaning up... 14:17:48 INFO - Return code: 0 14:17:48 INFO - Running post-action listener: _resource_record_post_action 14:17:48 INFO - Running post-action listener: _start_resource_monitoring 14:17:48 INFO - Starting resource monitoring. 14:17:48 INFO - [mozharness: 2016-10-25 21:17:48.606632Z] Finished create-virtualenv step (success) 14:17:48 INFO - [mozharness: 2016-10-25 21:17:48.607064Z] Running install step. 14:17:48 INFO - Running pre-action listener: _resource_record_pre_action 14:17:48 INFO - Running main action method: install 14:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:48 INFO - Reading from file tmpfile_stdout 14:17:48 INFO - Detecting whether we're running mozinstall >=1.0... 14:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:17:48 INFO - Reading from file tmpfile_stdout 14:17:48 INFO - Output received: 14:17:48 INFO - Usage: mozinstall [options] installer 14:17:48 INFO - Options: 14:17:48 INFO - -h, --help show this help message and exit 14:17:48 INFO - -d DEST, --destination=DEST 14:17:48 INFO - Directory to install application into. [default: 14:17:48 INFO - "/builds/slave/test"] 14:17:48 INFO - --app=APP Application being installed. [default: firefox] 14:17:48 INFO - mkdir: /builds/slave/test/build/application 14:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:18:02 INFO - Reading from file tmpfile_stdout 14:18:02 INFO - Output received: 14:18:02 INFO - /builds/slave/test/build/application/firefox/firefox 14:18:02 INFO - Running post-action listener: _resource_record_post_action 14:18:02 INFO - [mozharness: 2016-10-25 21:18:02.409446Z] Finished install step (success) 14:18:02 INFO - [mozharness: 2016-10-25 21:18:02.409698Z] Running run-tests step. 14:18:02 INFO - Running pre-action listener: _resource_record_pre_action 14:18:02 INFO - Running main action method: run_tests 14:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:18:02 INFO - Python 2.7.3 14:18:02 INFO - Return code: 0 14:18:02 INFO - grabbing minidump binary from tooltool 14:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12353b0>, '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 0x12362b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1236740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:18:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:18:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:18:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:18:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:18:02 INFO - Return code: 0 14:18:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:18:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:18:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:18:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:18:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --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:18:02 INFO - Using env: {'DISPLAY': ':0', 14:18:02 INFO - 'HOME': '/home/cltbld', 14:18:02 INFO - 'LANG': 'en_US.UTF-8', 14:18:02 INFO - 'LANGUAGE': 'en_US:en', 14:18:02 INFO - 'LOGNAME': 'cltbld', 14:18:02 INFO - 'MAIL': '/var/mail/cltbld', 14:18:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:18:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:02 INFO - 'MOZ_NO_REMOTE': '1', 14:18:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:02 INFO - 'NO_EM_RESTART': '1', 14:18:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:02 INFO - 'PWD': '/builds/slave/test', 14:18:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:18:02 INFO - 'SHELL': '/bin/bash', 14:18:02 INFO - 'SHLVL': '1', 14:18:02 INFO - 'TERM': 'linux', 14:18:02 INFO - 'TMOUT': '86400', 14:18:02 INFO - 'USER': 'cltbld', 14:18:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457', 14:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:02 INFO - '_': '/tools/buildbot/bin/python'} 14:18:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:18:02 INFO - mozversion application_buildid: 20161025111824 14:18:02 INFO - mozversion application_changeset: 49d808e29f639134d1f1510084a962f7924d9977 14:18:02 INFO - mozversion application_display_name: Firefox 14:18:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:18:02 INFO - mozversion application_name: Firefox 14:18:02 INFO - mozversion application_remotingname: firefox 14:18:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:18:02 INFO - mozversion application_vendor: Mozilla 14:18:02 INFO - mozversion application_version: 50.0 14:18:02 INFO - mozversion platform_buildid: 20161025111824 14:18:02 INFO - mozversion platform_changeset: 49d808e29f639134d1f1510084a962f7924d9977 14:18:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:18:02 INFO - mozversion platform_version: 50.0 14:18:02 INFO - using testdate: 1477430282 14:18:02 INFO - actual date: 1477430282 14:18:02 INFO - starting webserver on 'localhost:37302' 14:18:02 INFO - SUITE-START | Running 2 tests 14:18:02 INFO - TEST-START | dromaeo_css 14:18:02 INFO - operating with platform_type : linux_ 14:18:02 INFO - Initialising browser for dromaeo_css test... 14:18:02 INFO - TEST-INFO | started process 15006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWROfhP/profile http://localhost:37302/getInfo.html) 14:18:06 INFO - PROCESS | 15006 | __metrics Screen width/height:1600/1200 14:18:06 INFO - PROCESS | 15006 | colorDepth:24 14:18:06 INFO - PROCESS | 15006 | Browser inner width/height: 1024/697 14:18:06 INFO - PROCESS | 15006 | __metrics 14:18:07 INFO - PROCESS | 15006 | 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:18:07 INFO - PROCESS | 15006 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:18:08 INFO - PROCESS | 15006 | console.error: PushService: 14:18:08 INFO - PROCESS | 15006 | stateChangeProcessEnqueue: Error transitioning state 14:18:08 INFO - PROCESS | 15006 | UnknownError 14:18:08 INFO - TEST-INFO | 15006: exit 0 14:18:08 INFO - Browser initialized. 14:18:08 INFO - Running cycle 1/1 for dromaeo_css test... 14:18:08 INFO - TEST-INFO | started process 15095 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWROfhP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:20 INFO - PROCESS | 15095 | RSS: Main: 143699968 14:18:20 INFO - PROCESS | 15095 | 14:19:12 INFO - PROCESS | 15095 | 1477430352212 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] 14:21:19 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37302/tests/dromaeo/cssquery-ext.html) 14:21:21 INFO - PROCESS | 15095 | RSS: Main: 272465920 14:21:21 INFO - PROCESS | 15095 | 14:24:23 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-ext.html (next: http://localhost:37302/tests/dromaeo/cssquery-jquery.html) 14:24:23 INFO - PROCESS | 15095 | RSS: Main: 237109248 14:24:23 INFO - PROCESS | 15095 | 14:27:25 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37302/tests/dromaeo/cssquery-mootools.html) 14:27:26 INFO - PROCESS | 15095 | RSS: Main: 203665408 14:27:26 INFO - PROCESS | 15095 | 14:30:28 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37302/tests/dromaeo/cssquery-prototype.html) 14:30:28 INFO - PROCESS | 15095 | RSS: Main: 201924608 14:30:28 INFO - PROCESS | 15095 | 14:33:31 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37302/tests/dromaeo/cssquery-yui.html) 14:33:31 INFO - PROCESS | 15095 | RSS: Main: 177770496 14:33:31 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | Cycle 1(1): loaded http://localhost:37302/tests/dromaeo/cssquery-yui.html (next: http://localhost:37302/tests/dromaeo/cssquery-dojo.html) 14:36:34 INFO - PROCESS | 15095 | RSS: Main: 255602688 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | __start_tp_report 14:36:34 INFO - PROCESS | 15095 | _x_x_mozilla_page_load 14:36:34 INFO - PROCESS | 15095 | _x_x_mozilla_page_load_details 14:36:34 INFO - PROCESS | 15095 | |i|pagename|runs| 14:36:34 INFO - PROCESS | 15095 | |0;dojo.html;3178.761592880534;3186.840657967102;3213.983930080349;3232.2242661761156;3246.9512957305647;5545.611807173499;5623.268975033245;5634.295713035871;5634.802781902634;5638.40796716345;5575.609707320489;5606.859828504279;5632.07070833312;5645.096784514479;5679.176519404684;5067.543921047096;5094.388673359202;5153.510416510444;5154.639175257734;5160.458151894053;5045;5152.819651312205;5177.430482646906;5180.3265575475325;5181.689098654093;4985.576226020793;5004.924926126111;5022.949770502285;5035.496450354954;5036.269740887587;5029;5050.0152470268495;5069.746512674388;5155.175171972466;5187.04039752647;4984.750762461862;5061.962297728956;5118.872028199287;5122.436531981479;5140.8457746267295;5380.354357477127;5455.372632147314;5490.121580547093;5491.670499770026;5522.30971128609;5899.085641725539;5911.408859114097;5971.0744834550715;6022.156898034279;6027.246594175728;5871.1193321001765;6034.426729460694;6043.153958445821;6055.878882422327;6062;5526.972365138189;5547.8890422191735;5588.161775733632;5614.214010038598;5649.293838270175;6176.8764624707255;6180.8763824723255;6187.721552530057;6189.81430557084;6204.968975155186;5757.280339957505;5822.359540450547;5950.851228719317;5958.769975931774;6000.759969601254;5936.851578710481;6003.459688628044;6009.369016253318;6045.637261764308;6049.395060494004;5991.910121348184;5994.6403215807195;5999.730012149464;6002.39976002405;6007.399260074045;5675.31896172462;5838.2702162229725;5990.700464976734;5995.550333724988;6005.129256257818;4583.472900616472;4616.146012987608;4625.699329543636;4627.259638457831;4658.231391820379;4072.3484242521645;4110.609492098246;4122.690798190148;4125.66994640428;4146.1914926589025;5490.725463726798;5656.717164141776;5746.798862039808;5749.942500574941;5749.9712501437225;6053.909191362133;6075.878482430415;6076.815021070849;6102.847428814315;6110.847228819226;5792.218050563204;5793.102069179284;5803.651780893076;5807.245058142499;5809.390014048548;5006.799728010911;5091;5119.564836988898;5183.5593974512585;5195.168772996302;6322.114903913548;6327.4938004959495;6337.207849018872;6353.714082866282;6354.586951848115;6076.240469941257;6090.878182436415;6103.938960610337;6108.205933228652;6108.725107370268;5996.820095397145;6052.03167493199;6083.209182806207;6254.186955695731;6364.586301890362;5768.50967667753;5798.449147330997;5806;5841.707914604338;5873.4126587340925;5985.700714964321;5993.640381577119;5996.190514280691;6010.549208809444;6013.458788709037;5598.10430331145;5816.447437493432;5828.125781132864;5836.620619659709;5848.181254624271;5887.7056147191615;5910.763569457174;5912.320083190488;5943.078822782476;5967.880642387041;5841.795537156179;5844.094165404369;5852.590318677822;5889.087191485327;5891.3224979126235;5741.167530708108;5814.244148260699;5858.882822343615;5891.38140495259;5942.227510423617;5795.768169273182;5798.913016304675;5805.3614102447855;5805.593608447368;5827.79602713903 14:36:34 INFO - PROCESS | 15095 | |1;ext.html;585.6515373352856;622.1352320274818;658.8715200535896;676.4622125410299;682.8770821252173;35587.644123558755;47663.285050724226;48505.75747121259;48582.75708621452;48603;49245;49862.750686246516;49917.75041124798;49931;49948;8527.95736021322;9324.06759324069;9465.384749991263;9468.715938521855;11133.276337038107;11266.211365204439;11348.43257837109;11348.716282092972;11358.829617555743;11365.31808091517;11365.545378184863;11388.54445822166;11425.771484570303;11430.942845285803;11436.313821170716;11382.544698212061;11452.656420307321;11461;11463.02564282037;11471.082313414949;11095.611653592085;11170.553177872875;11186.776264474747;11213.215074944757;11221.495032723466;44893.32660010103;45409.31886021712;45460.09079818418;45507.31739023917;45508.77245613784;3875.670568001723;3936.075022369741;4001.0397504598977;4018.2164477926817;4433.202023635744;35656.28687426263;35656.821715891514;35668.64331356679;35930.64069359299;35971.64028359709;4370.300751879684;4381;4411;4433.9556604433865;4496.910061798746;393654;394146;394266;394379;395919;64720.676396618655;65068.34931650718;65159.67420162869;65215;65252.34747652463;39282.21435571273;39994.2001159981;40293.79853100716;41989.11049002802;42024.159516809494;17516;17547.64904701899;17618.647627047645;17662.735058974125;17715.29138834458;12953.740925181444;13070.803937940938;13293;13301.534446294527;13331.40008699611;20901.686474702892;20941.790582093985;20999.790002099784;21010.894945525175;21060.368188954355;7331.376832969151;7472;7662.61686915652;7679.19368466303;7693.653785579662;1032.4476405123223;1048.3657387280584;1138.4876805437586;1148.7759886821989;1151.3840095548842;34273.314533709185;34976.125596859776;35152;35381.64618353835;35409.291814163575;1135.6138912769698;1173.7183076061683;1176.1825531255865;1186.8397766301612;1222.8288039674453;17339.21973511195;18079.367222147426;18377.81622183788;18381.172847221907;18465.7230141548;682078;684581;687043;687361;691046;63751.6812415935;64014;64172;64296;64325.67837160784;1335.6660834791303;1421.7583908688207;1460.978085328721;1473.2633683158635;1476.6086986948467;32950.83524582362;35361.82319088388;35836.28327433489;35937.82031089828;36094;53967.46032539624;55315.44684553103;55327.72336138294;55346.44653553574;55420;333223;333391;336268;336664;337180;28103.156905292875;30218.2445438857;30893.845530772203;31053.223669408446;32482.512762309056;51396;51902;52214.47785522096;52450.47549524456;52914;43331.78334108309;43465.565344347415;44201.33697994468;44202.778986104866;45561;43405.78297108494;43501.78249108734;44385;44684.776576116914;45068.54931450775;42951.355729664705;43423.34864976965;43803.34294985514;44354.77822610866;44414;36213.63786362103;38987.61012389953;39239.60760392359;39297.214055718156;40057.1988560233;29189;29640.258993525335;29684.109476715734;29860.402791944474;29904.70095299019 14:36:34 INFO - PROCESS | 15095 | |2;jquery.html;394.79667970994944;396.4964495091234;396.5816064052423;400.89376315276456;420.86321945367746;14742.484013059518;19792.208311667535;21430.57138857224;21470.463238419048;21885.12459501618;18662.440126796184;19404.708929366072;19424;20981.370558883256;21343;5114.590832733373;5259.658122222062;5261.158214685651;5261.921071183935;5268.157094864822;5215.791368345262;5237.3715154181555;5245.239440281157;5247.39654939683;5250.422453530108;5078.5175408336145;5250.31745873036;5254.8686282842855;5309.707966061865;5317.202419637066;5251.2385704072885;5286.920696189561;5288.629795914288;5290.470952904717;5294.100002999499;5004.824831130929;5159.25190847325;5225.08561001827;5269.367675878919;5341.519263266325;12975.610731678065;13009.54466593674;13137.34313284332;19896;24239.03043878243;2306.6655334976335;2374.3233178544206;2649.5098406794614;2722.91831245061;2970.3465237647683;9511.952440237754;9751.609935602639;9932.851007234898;9981.700548983543;10005.099541041269;1901.91441385138;1939.6411663842093;1960.0689672405492;1985.4639247403265;2015.8185763281374;81271;83824.16175838225;84740.15259847384;85097.57451212766;86081.13918860856;11321.150913681508;11449.084073274118;11601.767964640829;11640.126990475748;11765.705857353463;16539.090350030623;16562.751558726628;16569.91715041417;16584.917075414785;16648.75026874598;8423.957880210559;8532.274756645755;8773.473591584525;8827.293816494664;8893.866592001124;8418.536980466133;8493.362997775068;8500.787480313042;8664.740057798275;8690.478571285743;4781.665283430127;4937.1606826839525;5015.4733752956145;5018.799248030038;5130.076586214517;1972.9901350493155;2045.4170561389933;2045.6215600114026;2089.529855782467;2105.157936825282;890.5547226386807;991.6430085169334;1015.7054454208197;1070.475467021154;1076.1982323169289;19959.501012474804;20984.475388115115;21161.365159045254;21192.894035529724;21352.359429216838;1008.3445760255746;1013.8225810483136;1022.4121130350078;1029.2177944762034;1067.6370034188258;10588.629397971034;11637.650870473899;11780.11649126319;11861.940690296493;11865.16943813942;458930;461979;563153;563625;571029;99432;99813.50093249488;99946.5002674982;102950.48524757328;103271.96728032624;1012.8531362952479;1038.4651904269308;1051.059301924773;1054.0618849224197;1055.3615062886997;17841.821581784017;18327.72508412348;18488.537786555444;18839.437181917863;18973.146208420378;37655.2468950614;43161.35257971196;43933.56066439422;44676.77661611801;45657.771711141235;53551.46448535465;57233.356998526535;57973.13040304483;58846.4115358858;59246;8279.586020699062;8483.83032339362;8630.913690863012;8642.697505587274;8666.956665216634;12800.231986080866;13879.514217002357;14070.648233794236;14232.928835355757;14307.141571505743;11341;12459.75080498403;12489.812652810397;12588.433520491782;12799.616011519669;12109.152359334763;12240.816387754012;12518.186317889307;12576.434060467118;13027.934860325639;12708.555200568306;12723.23660580368;12744.171628844095;12846.67883302925;13163.144395614638;8386.329093672613;8449.66201351939;8483.151684831468;8508.319334453105;9317.86023209639;12127.575534856238;12218.20581662189;12251;12262.75474490523;12265.386730663253 14:36:34 INFO - PROCESS | 15095 | |3;mootools.html;1421.5593166118497;1547.969040619188;1564.4524416454242;1730.255989924332;1871.9532011699714;9088.13662702043;9417.57620907059;9533.99999999999;9842;9842.360246583967;9589.760255993586;9700.175485083759;9715.174210192143;9719.125278724914;9752.65865694701;2504.4365017871014;2517.974820251792;2518.546661600911;2528.190978886748;2529.3423709835392;2363.5627408929404;2459.3974476253343;2465.5315490056923;2467.2721547143638;2470.505898820234;2418.758124187576;2434.9634755478683;2447.865367404792;2454.5827209374447;2461.1755062054235;2238.473958619723;2255.064148378421;2357.1278626907983;2380.5238952209543;2395.784379405853;2325.5930212212793;2335.1826860598826;2336.1823361823394;2336.229044415356;2341.5785158671433;9510.714678559654;9620.278479114095;9700.902990970071;9712.417254964725;9783.951080244624;5466.781328746845;5655.802046928337;5668.291463567054;5679.772809087632;5692.089265717465;3522.20750331176;3537.2925414917117;3575.570931488212;3594.281143771256;3601.5137956375816;1027.2193133218732;1031.9226058045676;1041.5885725138603;1056.6829951014665;1059.0891833023593;14162.36269367881;14220.57338279843;14310.141391516543;14316.141031538142;14319.928400358036;4662.650301227353;4692.741899195509;4733.242681170997;4804.993424539216;4891.290762839369;10983.39591322469;10995.615153469591;11008;11014.229003969645;11020.724481887857;5506.559475241971;5506.559475241971;5533.6403133796175;5534.280543529316;5549.472800083986;4619.653525985565;4847.200211965057;5127.051495473349;5130.820421285237;5175.301334319894;3987.7837259635508;4055.148418832019;4087.4890638670167;4110.46563946691;4210.136921931058;1187.3469591724518;1189.4885199364358;1194.3013337197838;1200.3338147328143;1201.195199216527;1020.2603112743202;1024.6106479537877;1079.185215162563;1079.5142186016324;1087.255230167338;6470.611763294123;6489.513286503437;6639.767608133692;6841.350071743276;6847.281035491302;462.9768511574475;471.4154448483855;1078.4607696151925;1153.405995912106;1179.33367647279;4309.159713855768;4377.146456440963;4454.821807127679;4479.641628669699;4642.767861606973;12309.753804923852;12612.054095942658;12635.43140558677;12645.304508251951;12675.302858342695;12479.812802807965;12535.059870509749;12562.183458075197;12572;12588.433520491417;8769;9117.270618350516;9129.269658427309;9172.816543669089;9349.672761453452;6740.224874139537;6832.180138383425;6852.588844669336;6853.725850965906;7042.260562641054;11427.885721143015;11513.36676482768;11609.767804644029;11626.418679066182;11696.35670038156;10273.897261027294;10580.4709764513;11796.587119450778;11980.28118312904;12040.698982525508;7820.569868657279;8102.878456823035;8248.092709801796;8338.416310858423;8499.405041647025;9452.669156579486;9527.952360238154;9619.90380096218;9675.080867317594;9706.951465242628;8237.958810205912;8308.626111825104;8314.542700151307;8365.163483651586;8385.077641459313;7740.41946853977;7768;7775.261350171724;7802.141764406025;7903.3677305816545;7560.508566943131;7711.922880771345;7803.258690424401;7811.062672479339;7822.13956464777;5493.313335833021;5601.887962240814;5625.578081643811;5656.60403771732;5720.9988252056555;5736.799212027559;5844.970775146267;5857.384974577608;5913.349531551612;5919.704014799329 14:36:34 INFO - PROCESS | 15095 | |4;prototype.html;319.50317256665875;323.90768630940187;329.2378144595262;331.6766153000823;333.84976760457795;5338.27933229014;5423.213634023069;5438.178210299545;5482.369527504338;5498.213080748753;5068.1890897456415;5166.741662916858;5294.894102117955;5312.459386777829;5382.407935127143;4175.434212170436;4191.245575796371;4191.329387298033;4205.306124489455;4207.727826973431;4231.555686652903;4240.109576988821;4241.766702831343;4248.4052232687445;4249.553796851317;4133.276676581586;4140.50313962325;4152.16956608678;4180.226658068252;4202.91594168118;4143.440635514227;4159.5216550096675;4166.812493437803;4173.1862286854075;4175.0397408596045;3639.1266096137;3674.7978861162624;3698.4822124902535;3708.091517578184;3730;5021.5229553192385;5144.485551444863;5228;5237.869053273661;5298.867528311785;4755.286706993944;4761.738104404257;4762.738049407281;4769.523047695237;4778.307145463888;5533.3083364579425;5547.306586676666;5601.495865372095;5615.410381909923;5623;3796.14586717989;3924.1955399143108;4049.493813273341;4078.143589846136;4087.4890638670167;5729.1119876419225;5829.329627092896;5832.416758324176;5836.824895253107;5845.561582881259;3989.6409323160474;4005.679545636342;4013.7591744495426;4014.397840323975;4054.1486287879284;3843.9039024024623;4098;4105.158442519277;4106.322456794595;4108;4605.148047611203;4609.469910960215;4616.6537509686905;4617.191991401492;4649.139909116824;5593.3847276799515;5597;5620.185073164368;5631.986242476311;5642.576806739429;1364.222393235846;1396.322783450026;1420.176297747304;1426.522115091456;1427.7430062588833;884.5798245833306;885.818407226517;886.3883920095113;896.66375109334;905.3933864310795;4854.805807767721;4866.343043689128;4895.241237608177;4895.461499235082;4941.838667913037;5025.874353141127;5047.268146118792;5079.644424890296;5085.389753229636;5126.128558145124;4909.5090490950615;5251.789928402821;5588.357338905997;5591.567668064405;5595.496405323541;3065.4788685923445;3098.806959320633;3103.596532450767;3106.580611617448;3113.673064328213;5871.209281169199;6051.213342265477;6089.451949324582;6104.877902441926;6155.415235552616;5863.560232982543;5869.8239052828485;5894.528437724972;5906.8818623627285;5911.349751527328;6081.939180608226;6082.209312789398;6085.300190478151;6087.482563982111;6095.4761309672585;5679.858003549944;5750.568707347049;5793.275840519935;5801.564882633735;5835.387284335084;5692;5715.257016587817;5732.512736417451;5736.19693242938;5755.309362876482;5724.542036637143;5963.135345374983;6072.8785424292155;6122.204113465221;6142.447179753665;4062.6546743527133;4081.2245673321972;4081.8775436736946;4103.138340948315;4104.610062044101;5535.916961245504;5668.7165641718575;5768.125359258174;5799.391063938226;5854.941450585439;5620.4098569649595;5635.746391412315;5648.898464799325;5655.604107712421;5677.68772717488;5523.530499907552;5614.803481878114;5646.85882852932;5662.320521537442;5671.829845104653;5594.664320140804;5607;5609.49514543693;5620.662760234399;5627.184058311604;5691.7438715257085;5714.571407144564;5724.198612194379;5769.59612827098;5801.158831969426;5430.484104010113;5552.750631107969;5566.749496272759;5569.693666848362;5607.663540187602 14:36:34 INFO - PROCESS | 15095 | |5;yui.html;659.5218466611706;679.2121139478203;700.0234672631678;734.3941248470013;752.1463139336851;15459.768103478444;15790.4473343433;15834.524964251063;15875.920620396897;15905;16095.195240238;16293.592660183502;16301.918490407532;16336.101514416703;16338.18309084544;714.814148321435;827.5365795154718;836.1053672570353;855.7389996051202;866.1987661413199;859.4499520307008;860.7761981884723;861.1775754154767;862.3704695572236;863.0937515608617;911.8085202107566;912.3841407050247;912.7900582865944;914.2503147419119;914.3919293669702;908.5638893331205;908.7410088124918;910.1308250620641;910.4127837544779;911.0388540090169;667.1827011910419;667.8664267146542;669.1869378704897;669.3306693306694;669.6484345718488;15510.844891551165;15516.224188790515;15565.610859728597;15565.766513502307;15597.532074037797;14146.787798183035;14847.480338188112;14884.851151488454;14945;15157.242137893172;11577.768444631145;11652.708682282926;11774.175807693466;11787.469563869647;12373.453318335209;3978.5424676162324;4072.572379900127;4119.691023173244;4169.457970463851;4199.433076534659;87853.56073219593;88645.55677221573;88794;89200.5539972296;89214;11117.666470005914;11389.886101139049;11455.484503197376;11610.361430121424;11775.823362649568;21736.456588585097;21787.78212217889;21815.781842181692;21817;21817.454563636034;8624.310055195569;8682.408984006423;8893.377463577488;8943.46339219636;9053.818923621491;5002.574781143643;5127.2052831811125;5195;5246.973765131226;5371.113766228606;3660.1398671312754;3774.0564858785306;3784.110733977513;3799.962000380016;3804.7336686432236;669.912911321525;684.9041134241211;695.9826004349932;695.9965200174069;699.1330749870226;629.9055141728686;648.0441349010266;666.1872515531045;666.5833853841349;711.8006958051753;9156.771080723036;9488;9497.145256926771;9734.172595329335;9991.300608957448;643.2313385504277;644.7614382678345;669.2170160911745;670.6278015701236;697.4072038767109;4460.1971645104195;4461.678013576391;4504.819807207675;4611.792469338888;4666.300054991711;74312.25687743162;121173.39413302876;137606.43164625842;138104;142040.28979855034;117111;117189;117618;117821.41089294499;121673;62147.689261554304;78277.21722782814;102666.48666756767;103159;106072.46963765286;9531.76170595713;9621.037896210322;9699.757506062404;9799.65701200483;9990.750231244278;51956.48043519516;52850.73574632102;53250.46749532455;53912.460875390745;54961.1755823671;69557.65221173862;70324.29675703382;73109.26890731238;79597.23423508836;79914;12663.113582049167;12727.363631818558;12787.296698681661;12904;13199.274039927896;13238.139520931109;13870.375833087333;13952.860471395563;14097.929510352382;14124.717505650035;12755.298458584495;12867.420966056363;13066.085374023727;13084.738305233652;13358.799618005543;12750;12911.160774549624;13252;13546.119502232323;13883.86116138866;12329.753404931671;12894.935525322313;13134.540291089766;13235.933820330836;13249.867501324863;10972.561097556327;11319.320840749582;11605.071594272607;11797.52809887595;12042.819357709817;11347.54609815598;11553.422328883355;11746.471408786796;11767.52929882795;12219.51121955112 14:36:34 INFO - PROCESS | 15095 | __end_tp_report 14:36:34 INFO - PROCESS | 15095 | __start_cc_report 14:36:34 INFO - PROCESS | 15095 | _x_x_mozilla_cycle_collect,1043 14:36:34 INFO - PROCESS | 15095 | __end_cc_report 14:36:34 INFO - PROCESS | 15095 | __startTimestamp1477431394360__endTimestamp 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | ------- Summary: start ------- 14:36:34 INFO - PROCESS | 15095 | Number of tests: 6 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#0] dojo.html Cycles:165 Average:5576.13 Median:5805.59 stddev:642.54 (11.1%) stddev-sans-first:616.37 14:36:34 INFO - PROCESS | 15095 | Values: 3178.8 3186.8 3214.0 3232.2 3247.0 5545.6 5623.3 5634.3 5634.8 5638.4 5575.6 5606.9 5632.1 5645.1 5679.2 5067.5 5094.4 5153.5 5154.6 5160.5 5045.0 5152.8 5177.4 5180.3 5181.7 4985.6 5004.9 5022.9 5035.5 5036.3 5029.0 5050.0 5069.7 5155.2 5187.0 4984.8 5062.0 5118.9 5122.4 5140.8 5380.4 5455.4 5490.1 5491.7 5522.3 5899.1 5911.4 5971.1 6022.2 6027.2 5871.1 6034.4 6043.2 6055.9 6062.0 5527.0 5547.9 5588.2 5614.2 5649.3 6176.9 6180.9 6187.7 6189.8 6205.0 5757.3 5822.4 5950.9 5958.8 6000.8 5936.9 6003.5 6009.4 6045.6 6049.4 5991.9 5994.6 5999.7 6002.4 6007.4 5675.3 5838.3 5990.7 5995.6 6005.1 4583.5 4616.1 4625.7 4627.3 4658.2 4072.3 4110.6 4122.7 4125.7 4146.2 5490.7 5656.7 5746.8 5749.9 5750.0 6053.9 6075.9 6076.8 6102.8 6110.8 5792.2 5793.1 5803.7 5807.2 5809.4 5006.8 5091.0 5119.6 5183.6 5195.2 6322.1 6327.5 6337.2 6353.7 6354.6 6076.2 6090.9 6103.9 6108.2 6108.7 5996.8 6052.0 6083.2 6254.2 6364.6 5768.5 5798.4 5806.0 5841.7 5873.4 5985.7 5993.6 5996.2 6010.5 6013.5 5598.1 5816.4 5828.1 5836.6 5848.2 5887.7 5910.8 5912.3 5943.1 5967.9 5841.8 5844.1 5852.6 5889.1 5891.3 5741.2 5814.2 5858.9 5891.4 5942.2 5795.8 5798.9 5805.4 5805.6 5827.8 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#1] ext.html Cycles:180 Average:64354.56 Median:39260.91 stddev:132385.62 (337.2%) stddev-sans-first:132670.42 14:36:34 INFO - PROCESS | 15095 | Values: 585.7 622.1 658.9 676.5 682.9 35587.6 47663.3 48505.8 48582.8 48603.0 49245.0 49862.8 49917.8 49931.0 49948.0 8528.0 9324.1 9465.4 9468.7 11133.3 11266.2 11348.4 11348.7 11358.8 11365.3 11365.5 11388.5 11425.8 11430.9 11436.3 11382.5 11452.7 11461.0 11463.0 11471.1 11095.6 11170.6 11186.8 11213.2 11221.5 44893.3 45409.3 45460.1 45507.3 45508.8 3875.7 3936.1 4001.0 4018.2 4433.2 35656.3 35656.8 35668.6 35930.6 35971.6 4370.3 4381.0 4411.0 4434.0 4496.9 393654.0 394146.0 394266.0 394379.0 395919.0 64720.7 65068.3 65159.7 65215.0 65252.3 39282.2 39994.2 40293.8 41989.1 42024.2 17516.0 17547.6 17618.6 17662.7 17715.3 12953.7 13070.8 13293.0 13301.5 13331.4 20901.7 20941.8 20999.8 21010.9 21060.4 7331.4 7472.0 7662.6 7679.2 7693.7 1032.4 1048.4 1138.5 1148.8 1151.4 34273.3 34976.1 35152.0 35381.6 35409.3 1135.6 1173.7 1176.2 1186.8 1222.8 17339.2 18079.4 18377.8 18381.2 18465.7 682078.0 684581.0 687043.0 687361.0 691046.0 63751.7 64014.0 64172.0 64296.0 64325.7 1335.7 1421.8 1461.0 1473.3 1476.6 32950.8 35361.8 35836.3 35937.8 36094.0 53967.5 55315.4 55327.7 55346.4 55420.0 333223.0 333391.0 336268.0 336664.0 337180.0 28103.2 30218.2 30893.8 31053.2 32482.5 51396.0 51902.0 52214.5 52450.5 52914.0 43331.8 43465.6 44201.3 44202.8 45561.0 43405.8 43501.8 44385.0 44684.8 45068.5 42951.4 43423.3 43803.3 44354.8 44414.0 36213.6 38987.6 39239.6 39297.2 40057.2 29189.0 29640.3 29684.1 29860.4 29904.7 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#2] jquery.html Cycles:180 Average:30396.53 Median:2340.49 stddev:86844.44 (3710.5%) stddev-sans-first:87058.84 14:36:34 INFO - PROCESS | 15095 | Values: 394.8 396.5 396.6 400.9 420.9 14742.5 19792.2 21430.6 21470.5 21885.1 18662.4 19404.7 19424.0 20981.4 21343.0 5114.6 5259.7 5261.2 5261.9 5268.2 5215.8 5237.4 5245.2 5247.4 5250.4 5078.5 5250.3 5254.9 5309.7 5317.2 5251.2 5286.9 5288.6 5290.5 5294.1 5004.8 5159.3 5225.1 5269.4 5341.5 12975.6 13009.5 13137.3 19896.0 24239.0 2306.7 2374.3 2649.5 2722.9 2970.3 9512.0 9751.6 9932.9 9981.7 10005.1 1901.9 1939.6 1960.1 1985.5 2015.8 81271.0 83824.2 84740.2 85097.6 86081.1 11321.2 11449.1 11601.8 11640.1 11765.7 16539.1 16562.8 16569.9 16584.9 16648.8 8424.0 8532.3 8773.5 8827.3 8893.9 8418.5 8493.4 8500.8 8664.7 8690.5 4781.7 4937.2 5015.5 5018.8 5130.1 1973.0 2045.4 2045.6 2089.5 2105.2 890.6 991.6 1015.7 1070.5 1076.2 19959.5 20984.5 21161.4 21192.9 21352.4 1008.3 1013.8 1022.4 1029.2 1067.6 10588.6 11637.7 11780.1 11861.9 11865.2 458930.0 461979.0 563153.0 563625.0 571029.0 99432.0 99813.5 99946.5 102950.5 103272.0 1012.9 1038.5 1051.1 1054.1 1055.4 17841.8 18327.7 18488.5 18839.4 18973.1 37655.2 43161.4 43933.6 44676.8 45657.8 53551.5 57233.4 57973.1 58846.4 59246.0 8279.6 8483.8 8630.9 8642.7 8667.0 12800.2 13879.5 14070.6 14232.9 14307.1 11341.0 12459.8 12489.8 12588.4 12799.6 12109.2 12240.8 12518.2 12576.4 13027.9 12708.6 12723.2 12744.2 12846.7 13163.1 8386.3 8449.7 8483.2 8508.3 9317.9 12127.6 12218.2 12251.0 12262.8 12265.4 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#3] mootools.html Cycles:180 Average:6356.61 Median:4467.23 stddev:3783.14 (84.7%) stddev-sans-first:3775.58 14:36:34 INFO - PROCESS | 15095 | Values: 1421.6 1548.0 1564.5 1730.3 1872.0 9088.1 9417.6 9534.0 9842.0 9842.4 9589.8 9700.2 9715.2 9719.1 9752.7 2504.4 2518.0 2518.5 2528.2 2529.3 2363.6 2459.4 2465.5 2467.3 2470.5 2418.8 2435.0 2447.9 2454.6 2461.2 2238.5 2255.1 2357.1 2380.5 2395.8 2325.6 2335.2 2336.2 2336.2 2341.6 9510.7 9620.3 9700.9 9712.4 9784.0 5466.8 5655.8 5668.3 5679.8 5692.1 3522.2 3537.3 3575.6 3594.3 3601.5 1027.2 1031.9 1041.6 1056.7 1059.1 14162.4 14220.6 14310.1 14316.1 14319.9 4662.7 4692.7 4733.2 4805.0 4891.3 10983.4 10995.6 11008.0 11014.2 11020.7 5506.6 5506.6 5533.6 5534.3 5549.5 4619.7 4847.2 5127.1 5130.8 5175.3 3987.8 4055.1 4087.5 4110.5 4210.1 1187.3 1189.5 1194.3 1200.3 1201.2 1020.3 1024.6 1079.2 1079.5 1087.3 6470.6 6489.5 6639.8 6841.4 6847.3 463.0 471.4 1078.5 1153.4 1179.3 4309.2 4377.1 4454.8 4479.6 4642.8 12309.8 12612.1 12635.4 12645.3 12675.3 12479.8 12535.1 12562.2 12572.0 12588.4 8769.0 9117.3 9129.3 9172.8 9349.7 6740.2 6832.2 6852.6 6853.7 7042.3 11427.9 11513.4 11609.8 11626.4 11696.4 10273.9 10580.5 11796.6 11980.3 12040.7 7820.6 8102.9 8248.1 8338.4 8499.4 9452.7 9528.0 9619.9 9675.1 9707.0 8238.0 8308.6 8314.5 8365.2 8385.1 7740.4 7768.0 7775.3 7802.1 7903.4 7560.5 7711.9 7803.3 7811.1 7822.1 5493.3 5601.9 5625.6 5656.6 5721.0 5736.8 5845.0 5857.4 5913.3 5919.7 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#4] prototype.html Cycles:180 Average:4704.57 Median:5325.37 stddev:1399.95 (26.3%) stddev-sans-first:1364.64 14:36:34 INFO - PROCESS | 15095 | Values: 319.5 323.9 329.2 331.7 333.8 5338.3 5423.2 5438.2 5482.4 5498.2 5068.2 5166.7 5294.9 5312.5 5382.4 4175.4 4191.2 4191.3 4205.3 4207.7 4231.6 4240.1 4241.8 4248.4 4249.6 4133.3 4140.5 4152.2 4180.2 4202.9 4143.4 4159.5 4166.8 4173.2 4175.0 3639.1 3674.8 3698.5 3708.1 3730.0 5021.5 5144.5 5228.0 5237.9 5298.9 4755.3 4761.7 4762.7 4769.5 4778.3 5533.3 5547.3 5601.5 5615.4 5623.0 3796.1 3924.2 4049.5 4078.1 4087.5 5729.1 5829.3 5832.4 5836.8 5845.6 3989.6 4005.7 4013.8 4014.4 4054.1 3843.9 4098.0 4105.2 4106.3 4108.0 4605.1 4609.5 4616.7 4617.2 4649.1 5593.4 5597.0 5620.2 5632.0 5642.6 1364.2 1396.3 1420.2 1426.5 1427.7 884.6 885.8 886.4 896.7 905.4 4854.8 4866.3 4895.2 4895.5 4941.8 5025.9 5047.3 5079.6 5085.4 5126.1 4909.5 5251.8 5588.4 5591.6 5595.5 3065.5 3098.8 3103.6 3106.6 3113.7 5871.2 6051.2 6089.5 6104.9 6155.4 5863.6 5869.8 5894.5 5906.9 5911.3 6081.9 6082.2 6085.3 6087.5 6095.5 5679.9 5750.6 5793.3 5801.6 5835.4 5692.0 5715.3 5732.5 5736.2 5755.3 5724.5 5963.1 6072.9 6122.2 6142.4 4062.7 4081.2 4081.9 4103.1 4104.6 5535.9 5668.7 5768.1 5799.4 5854.9 5620.4 5635.7 5648.9 5655.6 5677.7 5523.5 5614.8 5646.9 5662.3 5671.8 5594.7 5607.0 5609.5 5620.7 5627.2 5691.7 5714.6 5724.2 5769.6 5801.2 5430.5 5552.8 5566.7 5569.7 5607.7 14:36:34 INFO - PROCESS | 15095 | 14:36:34 INFO - PROCESS | 15095 | [#5] yui.html Cycles:180 Average:22150.17 Median:4184.45 stddev:33427.41 (798.8%) stddev-sans-first:33482.24 14:36:34 INFO - PROCESS | 15095 | Values: 659.5 679.2 700.0 734.4 752.1 15459.8 15790.4 15834.5 15875.9 15905.0 16095.2 16293.6 16301.9 16336.1 16338.2 714.8 827.5 836.1 855.7 866.2 859.4 860.8 861.2 862.4 863.1 911.8 912.4 912.8 914.3 914.4 908.6 908.7 910.1 910.4 911.0 667.2 667.9 669.2 669.3 669.6 15510.8 15516.2 15565.6 15565.8 15597.5 14146.8 14847.5 14884.9 14945.0 15157.2 11577.8 11652.7 11774.2 11787.5 12373.5 3978.5 4072.6 4119.7 4169.5 4199.4 87853.6 88645.6 88794.0 89200.6 89214.0 11117.7 11389.9 11455.5 11610.4 11775.8 21736.5 21787.8 21815.8 21817.0 21817.5 8624.3 8682.4 8893.4 8943.5 9053.8 5002.6 5127.2 5195.0 5247.0 5371.1 3660.1 3774.1 3784.1 3800.0 3804.7 669.9 684.9 696.0 696.0 699.1 629.9 648.0 666.2 666.6 711.8 9156.8 9488.0 9497.1 9734.2 9991.3 643.2 644.8 669.2 670.6 697.4 4460.2 4461.7 4504.8 4611.8 4666.3 74312.3 121173.4 137606.4 138104.0 142040.3 117111.0 117189.0 117618.0 117821.4 121673.0 62147.7 78277.2 102666.5 103159.0 106072.5 9531.8 9621.0 9699.8 9799.7 9990.8 51956.5 52850.7 53250.5 53912.5 54961.2 69557.7 70324.3 73109.3 79597.2 79914.0 12663.1 12727.4 12787.3 12904.0 13199.3 13238.1 13870.4 13952.9 14097.9 14124.7 12755.3 12867.4 13066.1 13084.7 13358.8 12750.0 12911.2 13252.0 13546.1 13883.9 12329.8 12894.9 13134.5 13235.9 13249.9 10972.6 11319.3 11605.1 11797.5 12042.8 11347.5 11553.4 11746.5 11767.5 12219.5 14:36:34 INFO - PROCESS | 15095 | -------- Summary: end -------- 14:36:34 INFO - PROCESS | 15095 | 14:36:35 INFO - TEST-INFO | 15095: exit 0 14:36:35 INFO - TEST-OK | dromaeo_css | took 1112418ms 14:36:35 INFO - TEST-START | kraken 14:36:35 INFO - operating with platform_type : linux_ 14:36:35 INFO - Initialising browser for kraken test... 14:36:35 INFO - TEST-INFO | started process 15282 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU2n6HQ/profile http://localhost:37302/getInfo.html) 14:36:38 INFO - PROCESS | 15282 | __metrics Screen width/height:1600/1200 14:36:38 INFO - PROCESS | 15282 | colorDepth:24 14:36:38 INFO - PROCESS | 15282 | Browser inner width/height: 1024/697 14:36:38 INFO - PROCESS | 15282 | __metrics 14:36:39 INFO - PROCESS | 15282 | 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:36:39 INFO - PROCESS | 15282 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:36:40 INFO - PROCESS | 15282 | console.error: PushService: 14:36:40 INFO - PROCESS | 15282 | stateChangeProcessEnqueue: Error transitioning state 14:36:40 INFO - PROCESS | 15282 | UnknownError 14:36:40 INFO - TEST-INFO | 15282: exit 0 14:36:40 INFO - Browser initialized. 14:36:40 INFO - Running cycle 1/1 for kraken test... 14:36:40 INFO - TEST-INFO | started process 15367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU2n6HQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:36:52 INFO - PROCESS | 15367 | RSS: Main: 143958016 14:36:52 INFO - PROCESS | 15367 | 14:36:54 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37302/tests/kraken/driver.html?testName=audio-beat-detection) 14:36:55 INFO - PROCESS | 15367 | RSS: Main: 198803456 14:36:55 INFO - PROCESS | 15367 | 14:36:58 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37302/tests/kraken/driver.html?testName=audio-dft) 14:36:58 INFO - PROCESS | 15367 | RSS: Main: 183951360 14:36:58 INFO - PROCESS | 15367 | 14:37:01 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37302/tests/kraken/driver.html?testName=audio-fft) 14:37:02 INFO - PROCESS | 15367 | RSS: Main: 274726912 14:37:02 INFO - PROCESS | 15367 | 14:37:04 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37302/tests/kraken/driver.html?testName=audio-oscillator) 14:37:05 INFO - PROCESS | 15367 | RSS: Main: 180568064 14:37:05 INFO - PROCESS | 15367 | 14:37:07 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37302/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:37:07 INFO - PROCESS | 15367 | RSS: Main: 178835456 14:37:07 INFO - PROCESS | 15367 | 14:37:10 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37302/tests/kraken/driver.html?testName=imaging-darkroom) 14:37:11 INFO - PROCESS | 15367 | RSS: Main: 215289856 14:37:11 INFO - PROCESS | 15367 | 14:37:14 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37302/tests/kraken/driver.html?testName=imaging-desaturate) 14:37:15 INFO - PROCESS | 15367 | RSS: Main: 214048768 14:37:15 INFO - PROCESS | 15367 | 14:37:18 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37302/tests/kraken/driver.html?testName=json-parse-financial) 14:37:18 INFO - PROCESS | 15367 | RSS: Main: 214081536 14:37:18 INFO - PROCESS | 15367 | 14:37:20 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37302/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:37:20 INFO - PROCESS | 15367 | RSS: Main: 175783936 14:37:20 INFO - PROCESS | 15367 | 14:37:22 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:37:23 INFO - PROCESS | 15367 | RSS: Main: 183455744 14:37:23 INFO - PROCESS | 15367 | 14:37:25 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:37:25 INFO - PROCESS | 15367 | RSS: Main: 189632512 14:37:25 INFO - PROCESS | 15367 | 14:37:28 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:37:28 INFO - PROCESS | 15367 | RSS: Main: 197517312 14:37:28 INFO - PROCESS | 15367 | 14:37:31 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:37:32 INFO - PROCESS | 15367 | RSS: Main: 193556480 14:37:32 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | Cycle 1(1): loaded http://localhost:37302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37302/tests/kraken/driver.html?testName=ai-astar) 14:37:33 INFO - PROCESS | 15367 | RSS: Main: 243302400 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | __start_tp_report 14:37:33 INFO - PROCESS | 15367 | _x_x_mozilla_page_load 14:37:33 INFO - PROCESS | 15367 | _x_x_mozilla_page_load_details 14:37:33 INFO - PROCESS | 15367 | |i|pagename|runs| 14:37:33 INFO - PROCESS | 15367 | |0;ai-astar;111;111;122;109;111;112;110;111;111;110 14:37:33 INFO - PROCESS | 15367 | |1;audio-beat-detection;143;118;137;170;121;150;143;120;183;117 14:37:33 INFO - PROCESS | 15367 | |2;audio-dft;161;159;161;159;160;161;161;159;161;160 14:37:33 INFO - PROCESS | 15367 | |3;audio-fft;83;75;75;176;75;75;73;75;165;74 14:37:33 INFO - PROCESS | 15367 | |4;audio-oscillator;84;83;152;82;83;83;137;84;83;145 14:37:33 INFO - PROCESS | 15367 | |5;imaging-gaussian-blur;117;116;117;117;117;117;118;116;117;117 14:37:33 INFO - PROCESS | 15367 | |6;imaging-darkroom;146;147;146;146;147;146;146;145;147;148 14:37:33 INFO - PROCESS | 15367 | |7;imaging-desaturate;95;95;95;95;95;95;96;94;96;95 14:37:33 INFO - PROCESS | 15367 | |8;json-parse-financial;54;49;50;50;49;49;51;50;50;50 14:37:33 INFO - PROCESS | 15367 | |9;json-stringify-tinderbox;60;61;60;60;61;59;60;61;60;61 14:37:33 INFO - PROCESS | 15367 | |10;stanford-crypto-aes;74;82;74;80;78;74;74;74;74;72 14:37:33 INFO - PROCESS | 15367 | |11;stanford-crypto-ccm;131;119;119;132;121;117;129;116;157;126 14:37:33 INFO - PROCESS | 15367 | |12;stanford-crypto-pbkdf2;154;154;151;154;153;152;158;156;152;153 14:37:33 INFO - PROCESS | 15367 | |13;stanford-crypto-sha256-iterative;61;60;63;61;63;61;64;62;62;61 14:37:33 INFO - PROCESS | 15367 | __end_tp_report 14:37:33 INFO - PROCESS | 15367 | __start_cc_report 14:37:33 INFO - PROCESS | 15367 | _x_x_mozilla_cycle_collect,1708 14:37:33 INFO - PROCESS | 15367 | __end_cc_report 14:37:33 INFO - PROCESS | 15367 | __startTimestamp1477431453972__endTimestamp 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | ------- Summary: start ------- 14:37:33 INFO - PROCESS | 15367 | Number of tests: 14 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#0] ai-astar Cycles:10 Average:111.80 Median:111.00 stddev:3.68 (3.3%) stddev-sans-first:3.89 14:37:33 INFO - PROCESS | 15367 | Values: 111.0 111.0 122.0 109.0 111.0 112.0 110.0 111.0 111.0 110.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:143.00 stddev:22.73 (15.9%) stddev-sans-first:24.09 14:37:33 INFO - PROCESS | 15367 | Values: 143.0 118.0 137.0 170.0 121.0 150.0 143.0 120.0 183.0 117.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 14:37:33 INFO - PROCESS | 15367 | Values: 161.0 159.0 161.0 159.0 160.0 161.0 161.0 159.0 161.0 160.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:40.18 (53.6%) stddev-sans-first:42.40 14:37:33 INFO - PROCESS | 15367 | Values: 83.0 75.0 75.0 176.0 75.0 75.0 73.0 75.0 165.0 74.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.00 stddev:29.93 (36.1%) stddev-sans-first:31.06 14:37:33 INFO - PROCESS | 15367 | Values: 84.0 83.0 152.0 82.0 83.0 83.0 137.0 84.0 83.0 145.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:37:33 INFO - PROCESS | 15367 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 14:37:33 INFO - PROCESS | 15367 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 145.0 147.0 148.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 14:37:33 INFO - PROCESS | 15367 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 96.0 95.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:1.48 (3.0%) stddev-sans-first:0.67 14:37:33 INFO - PROCESS | 15367 | Values: 54.0 49.0 50.0 50.0 49.0 49.0 51.0 50.0 50.0 50.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 14:37:33 INFO - PROCESS | 15367 | Values: 60.0 61.0 60.0 60.0 61.0 59.0 60.0 61.0 60.0 61.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:74.00 stddev:3.24 (4.4%) stddev-sans-first:3.38 14:37:33 INFO - PROCESS | 15367 | Values: 74.0 82.0 74.0 80.0 78.0 74.0 74.0 74.0 74.0 72.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:127.50 stddev:12.16 (9.5%) stddev-sans-first:12.79 14:37:33 INFO - PROCESS | 15367 | Values: 131.0 119.0 119.0 132.0 121.0 117.0 129.0 116.0 157.0 126.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.00 stddev:2.06 (1.3%) stddev-sans-first:2.18 14:37:33 INFO - PROCESS | 15367 | Values: 154.0 154.0 151.0 154.0 153.0 152.0 158.0 156.0 152.0 153.0 14:37:33 INFO - PROCESS | 15367 | 14:37:33 INFO - PROCESS | 15367 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.27 14:37:33 INFO - PROCESS | 15367 | Values: 61.0 60.0 63.0 61.0 63.0 61.0 64.0 62.0 62.0 61.0 14:37:33 INFO - PROCESS | 15367 | -------- Summary: end -------- 14:37:33 INFO - PROCESS | 15367 | 14:37:34 INFO - TEST-INFO | 15367: exit 0 14:37:35 INFO - TEST-OK | kraken | took 59948ms 14:37:35 INFO - SUITE-END | took 1172s 14:37:35 INFO - Completed test suite (00:19:32) 14:37:35 INFO - javascript benchmark 14:37:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3178.761592880534, 3186.840657967102, 3213.983930080349, 3232.2242661761156, 3246.9512957305647, 5545.611807173499, 5623.268975033245, 5634.295713035871, 5634.802781902634, 5638.40796716345, 5575.609707320489, 5606.859828504279, 5632.07070833312, 5645.096784514479, 5679.176519404684, 5067.543921047096, 5094.388673359202, 5153.510416510444, 5154.639175257734, 5160.458151894053, 5045.0, 5152.819651312205, 5177.430482646906, 5180.3265575475325, 5181.689098654093, 4985.576226020793, 5004.924926126111, 5022.949770502285, 5035.496450354954, 5036.269740887587, 5029.0, 5050.0152470268495, 5069.746512674388, 5155.175171972466, 5187.04039752647, 4984.750762461862, 5061.962297728956, 5118.872028199287, 5122.436531981479, 5140.8457746267295, 5380.354357477127, 5455.372632147314, 5490.121580547093, 5491.670499770026, 5522.30971128609, 5899.085641725539, 5911.408859114097, 5971.0744834550715, 6022.156898034279, 6027.246594175728, 5871.1193321001765, 6034.426729460694, 6043.153958445821, 6055.878882422327, 6062.0, 5526.972365138189, 5547.8890422191735, 5588.161775733632, 5614.214010038598, 5649.293838270175, 6176.8764624707255, 6180.8763824723255, 6187.721552530057, 6189.81430557084, 6204.968975155186, 5757.280339957505, 5822.359540450547, 5950.851228719317, 5958.769975931774, 6000.759969601254, 5936.851578710481, 6003.459688628044, 6009.369016253318, 6045.637261764308, 6049.395060494004, 5991.910121348184, 5994.6403215807195, 5999.730012149464, 6002.39976002405, 6007.399260074045, 5675.31896172462, 5838.2702162229725, 5990.700464976734, 5995.550333724988, 6005.129256257818, 4583.472900616472, 4616.146012987608, 4625.699329543636, 4627.259638457831, 4658.231391820379, 4072.3484242521645, 4110.609492098246, 4122.690798190148, 4125.66994640428, 4146.1914926589025, 5490.725463726798, 5656.717164141776, 5746.798862039808, 5749.942500574941, 5749.9712501437225, 6053.909191362133, 6075.878482430415, 6076.815021070849, 6102.847428814315, 6110.847228819226, 5792.218050563204, 5793.102069179284, 5803.651780893076, 5807.245058142499, 5809.390014048548, 5006.799728010911, 5091.0, 5119.564836988898, 5183.5593974512585, 5195.168772996302, 6322.114903913548, 6327.4938004959495, 6337.207849018872, 6353.714082866282, 6354.586951848115, 6076.240469941257, 6090.878182436415, 6103.938960610337, 6108.205933228652, 6108.725107370268, 5996.820095397145, 6052.03167493199, 6083.209182806207, 6254.186955695731, 6364.586301890362, 5768.50967667753, 5798.449147330997, 5806.0, 5841.707914604338, 5873.4126587340925, 5985.700714964321, 5993.640381577119, 5996.190514280691, 6010.549208809444, 6013.458788709037, 5598.10430331145, 5816.447437493432, 5828.125781132864, 5836.620619659709, 5848.181254624271, 5887.7056147191615, 5910.763569457174, 5912.320083190488, 5943.078822782476, 5967.880642387041, 5841.795537156179, 5844.094165404369, 5852.590318677822, 5889.087191485327, 5891.3224979126235, 5741.167530708108, 5814.244148260699, 5858.882822343615, 5891.38140495259, 5942.227510423617, 5795.768169273182, 5798.913016304675, 5805.3614102447855, 5805.593608447368, 5827.79602713903], "value": 5532.127711776699, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.6515373352856, 622.1352320274818, 658.8715200535896, 676.4622125410299, 682.8770821252173, 35587.644123558755, 47663.285050724226, 48505.75747121259, 48582.75708621452, 48603.0, 49245.0, 49862.750686246516, 49917.75041124798, 49931.0, 49948.0, 8527.95736021322, 9324.06759324069, 9465.384749991263, 9468.715938521855, 11133.276337038107, 11266.211365204439, 11348.43257837109, 11348.716282092972, 11358.829617555743, 11365.31808091517, 11365.545378184863, 11388.54445822166, 11425.771484570303, 11430.942845285803, 11436.313821170716, 11382.544698212061, 11452.656420307321, 11461.0, 11463.02564282037, 11471.082313414949, 11095.611653592085, 11170.553177872875, 11186.776264474747, 11213.215074944757, 11221.495032723466, 44893.32660010103, 45409.31886021712, 45460.09079818418, 45507.31739023917, 45508.77245613784, 3875.670568001723, 3936.075022369741, 4001.0397504598977, 4018.2164477926817, 4433.202023635744, 35656.28687426263, 35656.821715891514, 35668.64331356679, 35930.64069359299, 35971.64028359709, 4370.300751879684, 4381.0, 4411.0, 4433.9556604433865, 4496.910061798746, 393654.0, 394146.0, 394266.0, 394379.0, 395919.0, 64720.676396618655, 65068.34931650718, 65159.67420162869, 65215.0, 65252.34747652463, 39282.21435571273, 39994.2001159981, 40293.79853100716, 41989.11049002802, 42024.159516809494, 17516.0, 17547.64904701899, 17618.647627047645, 17662.735058974125, 17715.29138834458, 12953.740925181444, 13070.803937940938, 13293.0, 13301.534446294527, 13331.40008699611, 20901.686474702892, 20941.790582093985, 20999.790002099784, 21010.894945525175, 21060.368188954355, 7331.376832969151, 7472.0, 7662.61686915652, 7679.19368466303, 7693.653785579662, 1032.4476405123223, 1048.3657387280584, 1138.4876805437586, 1148.7759886821989, 1151.3840095548842, 34273.314533709185, 34976.125596859776, 35152.0, 35381.64618353835, 35409.291814163575, 1135.6138912769698, 1173.7183076061683, 1176.1825531255865, 1186.8397766301612, 1222.8288039674453, 17339.21973511195, 18079.367222147426, 18377.81622183788, 18381.172847221907, 18465.7230141548, 682078.0, 684581.0, 687043.0, 687361.0, 691046.0, 63751.6812415935, 64014.0, 64172.0, 64296.0, 64325.67837160784, 1335.6660834791303, 1421.7583908688207, 1460.978085328721, 1473.2633683158635, 1476.6086986948467, 32950.83524582362, 35361.82319088388, 35836.28327433489, 35937.82031089828, 36094.0, 53967.46032539624, 55315.44684553103, 55327.72336138294, 55346.44653553574, 55420.0, 333223.0, 333391.0, 336268.0, 336664.0, 337180.0, 28103.156905292875, 30218.2445438857, 30893.845530772203, 31053.223669408446, 32482.512762309056, 51396.0, 51902.0, 52214.47785522096, 52450.47549524456, 52914.0, 43331.78334108309, 43465.565344347415, 44201.33697994468, 44202.778986104866, 45561.0, 43405.78297108494, 43501.78249108734, 44385.0, 44684.776576116914, 45068.54931450775, 42951.355729664705, 43423.34864976965, 43803.34294985514, 44354.77822610866, 44414.0, 36213.63786362103, 38987.61012389953, 39239.60760392359, 39297.214055718156, 40057.1988560233, 29189.0, 29640.258993525335, 29684.109476715734, 29860.402791944474, 29904.70095299019], "value": 21744.532919371388, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.79667970994944, 396.4964495091234, 396.5816064052423, 400.89376315276456, 420.86321945367746, 14742.484013059518, 19792.208311667535, 21430.57138857224, 21470.463238419048, 21885.12459501618, 18662.440126796184, 19404.708929366072, 19424.0, 20981.370558883256, 21343.0, 5114.590832733373, 5259.658122222062, 5261.158214685651, 5261.921071183935, 5268.157094864822, 5215.791368345262, 5237.3715154181555, 5245.239440281157, 5247.39654939683, 5250.422453530108, 5078.5175408336145, 5250.31745873036, 5254.8686282842855, 5309.707966061865, 5317.202419637066, 5251.2385704072885, 5286.920696189561, 5288.629795914288, 5290.470952904717, 5294.100002999499, 5004.824831130929, 5159.25190847325, 5225.08561001827, 5269.367675878919, 5341.519263266325, 12975.610731678065, 13009.54466593674, 13137.34313284332, 19896.0, 24239.03043878243, 2306.6655334976335, 2374.3233178544206, 2649.5098406794614, 2722.91831245061, 2970.3465237647683, 9511.952440237754, 9751.609935602639, 9932.851007234898, 9981.700548983543, 10005.099541041269, 1901.91441385138, 1939.6411663842093, 1960.0689672405492, 1985.4639247403265, 2015.8185763281374, 81271.0, 83824.16175838225, 84740.15259847384, 85097.57451212766, 86081.13918860856, 11321.150913681508, 11449.084073274118, 11601.767964640829, 11640.126990475748, 11765.705857353463, 16539.090350030623, 16562.751558726628, 16569.91715041417, 16584.917075414785, 16648.75026874598, 8423.957880210559, 8532.274756645755, 8773.473591584525, 8827.293816494664, 8893.866592001124, 8418.536980466133, 8493.362997775068, 8500.787480313042, 8664.740057798275, 8690.478571285743, 4781.665283430127, 4937.1606826839525, 5015.4733752956145, 5018.799248030038, 5130.076586214517, 1972.9901350493155, 2045.4170561389933, 2045.6215600114026, 2089.529855782467, 2105.157936825282, 890.5547226386807, 991.6430085169334, 1015.7054454208197, 1070.475467021154, 1076.1982323169289, 19959.501012474804, 20984.475388115115, 21161.365159045254, 21192.894035529724, 21352.359429216838, 1008.3445760255746, 1013.8225810483136, 1022.4121130350078, 1029.2177944762034, 1067.6370034188258, 10588.629397971034, 11637.650870473899, 11780.11649126319, 11861.940690296493, 11865.16943813942, 458930.0, 461979.0, 563153.0, 563625.0, 571029.0, 99432.0, 99813.50093249488, 99946.5002674982, 102950.48524757328, 103271.96728032624, 1012.8531362952479, 1038.4651904269308, 1051.059301924773, 1054.0618849224197, 1055.3615062886997, 17841.821581784017, 18327.72508412348, 18488.537786555444, 18839.437181917863, 18973.146208420378, 37655.2468950614, 43161.35257971196, 43933.56066439422, 44676.77661611801, 45657.771711141235, 53551.46448535465, 57233.356998526535, 57973.13040304483, 58846.4115358858, 59246.0, 8279.586020699062, 8483.83032339362, 8630.913690863012, 8642.697505587274, 8666.956665216634, 12800.231986080866, 13879.514217002357, 14070.648233794236, 14232.928835355757, 14307.141571505743, 11341.0, 12459.75080498403, 12489.812652810397, 12588.433520491782, 12799.616011519669, 12109.152359334763, 12240.816387754012, 12518.186317889307, 12576.434060467118, 13027.934860325639, 12708.555200568306, 12723.23660580368, 12744.171628844095, 12846.67883302925, 13163.144395614638, 8386.329093672613, 8449.66201351939, 8483.151684831468, 8508.319334453105, 9317.86023209639, 12127.575534856238, 12218.20581662189, 12251.0, 12262.75474490523, 12265.386730663253], "value": 9383.301105315466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.5593166118497, 1547.969040619188, 1564.4524416454242, 1730.255989924332, 1871.9532011699714, 9088.13662702043, 9417.57620907059, 9533.99999999999, 9842.0, 9842.360246583967, 9589.760255993586, 9700.175485083759, 9715.174210192143, 9719.125278724914, 9752.65865694701, 2504.4365017871014, 2517.974820251792, 2518.546661600911, 2528.190978886748, 2529.3423709835392, 2363.5627408929404, 2459.3974476253343, 2465.5315490056923, 2467.2721547143638, 2470.505898820234, 2418.758124187576, 2434.9634755478683, 2447.865367404792, 2454.5827209374447, 2461.1755062054235, 2238.473958619723, 2255.064148378421, 2357.1278626907983, 2380.5238952209543, 2395.784379405853, 2325.5930212212793, 2335.1826860598826, 2336.1823361823394, 2336.229044415356, 2341.5785158671433, 9510.714678559654, 9620.278479114095, 9700.902990970071, 9712.417254964725, 9783.951080244624, 5466.781328746845, 5655.802046928337, 5668.291463567054, 5679.772809087632, 5692.089265717465, 3522.20750331176, 3537.2925414917117, 3575.570931488212, 3594.281143771256, 3601.5137956375816, 1027.2193133218732, 1031.9226058045676, 1041.5885725138603, 1056.6829951014665, 1059.0891833023593, 14162.36269367881, 14220.57338279843, 14310.141391516543, 14316.141031538142, 14319.928400358036, 4662.650301227353, 4692.741899195509, 4733.242681170997, 4804.993424539216, 4891.290762839369, 10983.39591322469, 10995.615153469591, 11008.0, 11014.229003969645, 11020.724481887857, 5506.559475241971, 5506.559475241971, 5533.6403133796175, 5534.280543529316, 5549.472800083986, 4619.653525985565, 4847.200211965057, 5127.051495473349, 5130.820421285237, 5175.301334319894, 3987.7837259635508, 4055.148418832019, 4087.4890638670167, 4110.46563946691, 4210.136921931058, 1187.3469591724518, 1189.4885199364358, 1194.3013337197838, 1200.3338147328143, 1201.195199216527, 1020.2603112743202, 1024.6106479537877, 1079.185215162563, 1079.5142186016324, 1087.255230167338, 6470.611763294123, 6489.513286503437, 6639.767608133692, 6841.350071743276, 6847.281035491302, 462.9768511574475, 471.4154448483855, 1078.4607696151925, 1153.405995912106, 1179.33367647279, 4309.159713855768, 4377.146456440963, 4454.821807127679, 4479.641628669699, 4642.767861606973, 12309.753804923852, 12612.054095942658, 12635.43140558677, 12645.304508251951, 12675.302858342695, 12479.812802807965, 12535.059870509749, 12562.183458075197, 12572.0, 12588.433520491417, 8769.0, 9117.270618350516, 9129.269658427309, 9172.816543669089, 9349.672761453452, 6740.224874139537, 6832.180138383425, 6852.588844669336, 6853.725850965906, 7042.260562641054, 11427.885721143015, 11513.36676482768, 11609.767804644029, 11626.418679066182, 11696.35670038156, 10273.897261027294, 10580.4709764513, 11796.587119450778, 11980.28118312904, 12040.698982525508, 7820.569868657279, 8102.878456823035, 8248.092709801796, 8338.416310858423, 8499.405041647025, 9452.669156579486, 9527.952360238154, 9619.90380096218, 9675.080867317594, 9706.951465242628, 8237.958810205912, 8308.626111825104, 8314.542700151307, 8365.163483651586, 8385.077641459313, 7740.41946853977, 7768.0, 7775.261350171724, 7802.141764406025, 7903.3677305816545, 7560.508566943131, 7711.922880771345, 7803.258690424401, 7811.062672479339, 7822.13956464777, 5493.313335833021, 5601.887962240814, 5625.578081643811, 5656.60403771732, 5720.9988252056555, 5736.799212027559, 5844.970775146267, 5857.384974577608, 5913.349531551612, 5919.704014799329], "value": 4967.743885559152, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.50317256665875, 323.90768630940187, 329.2378144595262, 331.6766153000823, 333.84976760457795, 5338.27933229014, 5423.213634023069, 5438.178210299545, 5482.369527504338, 5498.213080748753, 5068.1890897456415, 5166.741662916858, 5294.894102117955, 5312.459386777829, 5382.407935127143, 4175.434212170436, 4191.245575796371, 4191.329387298033, 4205.306124489455, 4207.727826973431, 4231.555686652903, 4240.109576988821, 4241.766702831343, 4248.4052232687445, 4249.553796851317, 4133.276676581586, 4140.50313962325, 4152.16956608678, 4180.226658068252, 4202.91594168118, 4143.440635514227, 4159.5216550096675, 4166.812493437803, 4173.1862286854075, 4175.0397408596045, 3639.1266096137, 3674.7978861162624, 3698.4822124902535, 3708.091517578184, 3730.0, 5021.5229553192385, 5144.485551444863, 5228.0, 5237.869053273661, 5298.867528311785, 4755.286706993944, 4761.738104404257, 4762.738049407281, 4769.523047695237, 4778.307145463888, 5533.3083364579425, 5547.306586676666, 5601.495865372095, 5615.410381909923, 5623.0, 3796.14586717989, 3924.1955399143108, 4049.493813273341, 4078.143589846136, 4087.4890638670167, 5729.1119876419225, 5829.329627092896, 5832.416758324176, 5836.824895253107, 5845.561582881259, 3989.6409323160474, 4005.679545636342, 4013.7591744495426, 4014.397840323975, 4054.1486287879284, 3843.9039024024623, 4098.0, 4105.158442519277, 4106.322456794595, 4108.0, 4605.148047611203, 4609.469910960215, 4616.6537509686905, 4617.191991401492, 4649.139909116824, 5593.3847276799515, 5597.0, 5620.185073164368, 5631.986242476311, 5642.576806739429, 1364.222393235846, 1396.322783450026, 1420.176297747304, 1426.522115091456, 1427.7430062588833, 884.5798245833306, 885.818407226517, 886.3883920095113, 896.66375109334, 905.3933864310795, 4854.805807767721, 4866.343043689128, 4895.241237608177, 4895.461499235082, 4941.838667913037, 5025.874353141127, 5047.268146118792, 5079.644424890296, 5085.389753229636, 5126.128558145124, 4909.5090490950615, 5251.789928402821, 5588.357338905997, 5591.567668064405, 5595.496405323541, 3065.4788685923445, 3098.806959320633, 3103.596532450767, 3106.580611617448, 3113.673064328213, 5871.209281169199, 6051.213342265477, 6089.451949324582, 6104.877902441926, 6155.415235552616, 5863.560232982543, 5869.8239052828485, 5894.528437724972, 5906.8818623627285, 5911.349751527328, 6081.939180608226, 6082.209312789398, 6085.300190478151, 6087.482563982111, 6095.4761309672585, 5679.858003549944, 5750.568707347049, 5793.275840519935, 5801.564882633735, 5835.387284335084, 5692.0, 5715.257016587817, 5732.512736417451, 5736.19693242938, 5755.309362876482, 5724.542036637143, 5963.135345374983, 6072.8785424292155, 6122.204113465221, 6142.447179753665, 4062.6546743527133, 4081.2245673321972, 4081.8775436736946, 4103.138340948315, 4104.610062044101, 5535.916961245504, 5668.7165641718575, 5768.125359258174, 5799.391063938226, 5854.941450585439, 5620.4098569649595, 5635.746391412315, 5648.898464799325, 5655.604107712421, 5677.68772717488, 5523.530499907552, 5614.803481878114, 5646.85882852932, 5662.320521537442, 5671.829845104653, 5594.664320140804, 5607.0, 5609.49514543693, 5620.662760234399, 5627.184058311604, 5691.7438715257085, 5714.571407144564, 5724.198612194379, 5769.59612827098, 5801.158831969426, 5430.484104010113, 5552.750631107969, 5566.749496272759, 5569.693666848362, 5607.663540187602], "value": 4252.529605523525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.5218466611706, 679.2121139478203, 700.0234672631678, 734.3941248470013, 752.1463139336851, 15459.768103478444, 15790.4473343433, 15834.524964251063, 15875.920620396897, 15905.0, 16095.195240238, 16293.592660183502, 16301.918490407532, 16336.101514416703, 16338.18309084544, 714.814148321435, 827.5365795154718, 836.1053672570353, 855.7389996051202, 866.1987661413199, 859.4499520307008, 860.7761981884723, 861.1775754154767, 862.3704695572236, 863.0937515608617, 911.8085202107566, 912.3841407050247, 912.7900582865944, 914.2503147419119, 914.3919293669702, 908.5638893331205, 908.7410088124918, 910.1308250620641, 910.4127837544779, 911.0388540090169, 667.1827011910419, 667.8664267146542, 669.1869378704897, 669.3306693306694, 669.6484345718488, 15510.844891551165, 15516.224188790515, 15565.610859728597, 15565.766513502307, 15597.532074037797, 14146.787798183035, 14847.480338188112, 14884.851151488454, 14945.0, 15157.242137893172, 11577.768444631145, 11652.708682282926, 11774.175807693466, 11787.469563869647, 12373.453318335209, 3978.5424676162324, 4072.572379900127, 4119.691023173244, 4169.457970463851, 4199.433076534659, 87853.56073219593, 88645.55677221573, 88794.0, 89200.5539972296, 89214.0, 11117.666470005914, 11389.886101139049, 11455.484503197376, 11610.361430121424, 11775.823362649568, 21736.456588585097, 21787.78212217889, 21815.781842181692, 21817.0, 21817.454563636034, 8624.310055195569, 8682.408984006423, 8893.377463577488, 8943.46339219636, 9053.818923621491, 5002.574781143643, 5127.2052831811125, 5195.0, 5246.973765131226, 5371.113766228606, 3660.1398671312754, 3774.0564858785306, 3784.110733977513, 3799.962000380016, 3804.7336686432236, 669.912911321525, 684.9041134241211, 695.9826004349932, 695.9965200174069, 699.1330749870226, 629.9055141728686, 648.0441349010266, 666.1872515531045, 666.5833853841349, 711.8006958051753, 9156.771080723036, 9488.0, 9497.145256926771, 9734.172595329335, 9991.300608957448, 643.2313385504277, 644.7614382678345, 669.2170160911745, 670.6278015701236, 697.4072038767109, 4460.1971645104195, 4461.678013576391, 4504.819807207675, 4611.792469338888, 4666.300054991711, 74312.25687743162, 121173.39413302876, 137606.43164625842, 138104.0, 142040.28979855034, 117111.0, 117189.0, 117618.0, 117821.41089294499, 121673.0, 62147.689261554304, 78277.21722782814, 102666.48666756767, 103159.0, 106072.46963765286, 9531.76170595713, 9621.037896210322, 9699.757506062404, 9799.65701200483, 9990.750231244278, 51956.48043519516, 52850.73574632102, 53250.46749532455, 53912.460875390745, 54961.1755823671, 69557.65221173862, 70324.29675703382, 73109.26890731238, 79597.23423508836, 79914.0, 12663.113582049167, 12727.363631818558, 12787.296698681661, 12904.0, 13199.274039927896, 13238.139520931109, 13870.375833087333, 13952.860471395563, 14097.929510352382, 14124.717505650035, 12755.298458584495, 12867.420966056363, 13066.085374023727, 13084.738305233652, 13358.799618005543, 12750.0, 12911.160774549624, 13252.0, 13546.119502232323, 13883.86116138866, 12329.753404931671, 12894.935525322313, 13134.540291089766, 13235.933820330836, 13249.867501324863, 10972.561097556327, 11319.320840749582, 11605.071594272607, 11797.52809887595, 12042.819357709817, 11347.54609815598, 11553.422328883355, 11746.471408786796, 11767.52929882795, 12219.51121955112], "value": 7766.436004379575, "unit": "score"}], "name": "dromaeo_css", "value": 7549.969914094107, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 122.0, 109.0, 111.0, 112.0, 110.0, 111.0, 111.0, 110.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 137.0, 170.0, 121.0, 150.0, 143.0, 120.0, 183.0, 117.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 159.0, 160.0, 161.0, 161.0, 159.0, 161.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 75.0, 176.0, 75.0, 75.0, 73.0, 75.0, 165.0, 74.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 152.0, 82.0, 83.0, 83.0, 137.0, 84.0, 83.0, 145.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 148.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 49.0, 50.0, 50.0, 49.0, 49.0, 51.0, 50.0, 50.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 59.0, 60.0, 61.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 74.0, 80.0, 78.0, 74.0, 74.0, 74.0, 74.0, 72.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 119.0, 119.0, 132.0, 121.0, 117.0, 129.0, 116.0, 157.0, 126.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 151.0, 154.0, 153.0, 152.0, 158.0, 156.0, 152.0, 153.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 63.0, 61.0, 63.0, 61.0, 64.0, 62.0, 62.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1495.1, "alertThreshold": 2.0}]} 14:37:35 INFO - Return code: 0 14:37:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:37:35 INFO - # TBPL SUCCESS # 14:37:35 INFO - Running post-action listener: _resource_record_post_action 14:37:35 INFO - [mozharness: 2016-10-25 21:37:35.297703Z] Finished run-tests step (success) 14:37:35 INFO - Running post-run listener: _resource_record_post_run 14:37:35 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289660928; Read time: 28; Write time: 154184 14:37:35 INFO - TinderboxPrint: CPU usage
12.9% 14:37:35 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 14:37:35 INFO - TinderboxPrint: I/O write bytes / time
289,660,928 / 154,184 14:37:35 INFO - TinderboxPrint: CPU idle
8,245.1 (87.1%) 14:37:35 INFO - TinderboxPrint: CPU user
1,179.2 (12.5%) 14:37:35 INFO - TinderboxPrint: Swap in / out
0 / 0 14:37:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 66433024; Read time: 0; Write time: 50224 14:37:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 218832896; Read time: 28; Write time: 103544 14:37:35 INFO - Running post-run listener: _upload_blobber_files 14:37:35 INFO - Blob upload gear active. 14:37:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:37:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:37:36 INFO - (blobuploader) - INFO - Open directory for files ... 14:37:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:37:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:37:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:37 INFO - (blobuploader) - INFO - Done attempting. 14:37:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:37:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:37:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:40 INFO - (blobuploader) - INFO - Done attempting. 14:37:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:37:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:37:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:41 INFO - (blobuploader) - INFO - Done attempting. 14:37:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:37:41 INFO - Return code: 0 14:37:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:37:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:37:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8087fa01762e387425dc93218b4d3673faf001ff62ec142dcf423ae84260f47aa3aca5ca2b7ab6617495946b763fc62eadb35c640961e95a59d106df38c2987", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e02f3e4d304b2a2b478c058051dfb7c3fe6d211395402bfb9259d24b9f57cf36e3dbe7fb4f115eef72461592b2f3e77de115bd09657062302eb04ce650ba62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd68e83270af97ae8840df9f5ad51a10c8d967d29384a5842812ecccd1bb3e090ba93ce67a41295d015701be3ce60741ba6143bf3c1c0958374ba3691e1a04f"} 14:37:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:37:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:37:41 INFO - Contents: 14:37:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8087fa01762e387425dc93218b4d3673faf001ff62ec142dcf423ae84260f47aa3aca5ca2b7ab6617495946b763fc62eadb35c640961e95a59d106df38c2987", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e02f3e4d304b2a2b478c058051dfb7c3fe6d211395402bfb9259d24b9f57cf36e3dbe7fb4f115eef72461592b2f3e77de115bd09657062302eb04ce650ba62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd68e83270af97ae8840df9f5ad51a10c8d967d29384a5842812ecccd1bb3e090ba93ce67a41295d015701be3ce60741ba6143bf3c1c0958374ba3691e1a04f"} 14:37:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:37:41 INFO - Copying logs to upload dir... 14:37:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:37:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.258016 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-25 14:37:40.872955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 14:37:40.876696) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 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/c8087fa01762e387425dc93218b4d3673faf001ff62ec142dcf423ae84260f47aa3aca5ca2b7ab6617495946b763fc62eadb35c640961e95a59d106df38c2987", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e02f3e4d304b2a2b478c058051dfb7c3fe6d211395402bfb9259d24b9f57cf36e3dbe7fb4f115eef72461592b2f3e77de115bd09657062302eb04ce650ba62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd68e83270af97ae8840df9f5ad51a10c8d967d29384a5842812ecccd1bb3e090ba93ce67a41295d015701be3ce60741ba6143bf3c1c0958374ba3691e1a04f"} build_url:https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/EfYekiOJTV6scfVAW-s12w/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/c8087fa01762e387425dc93218b4d3673faf001ff62ec142dcf423ae84260f47aa3aca5ca2b7ab6617495946b763fc62eadb35c640961e95a59d106df38c2987", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e02f3e4d304b2a2b478c058051dfb7c3fe6d211395402bfb9259d24b9f57cf36e3dbe7fb4f115eef72461592b2f3e77de115bd09657062302eb04ce650ba62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd68e83270af97ae8840df9f5ad51a10c8d967d29384a5842812ecccd1bb3e090ba93ce67a41295d015701be3ce60741ba6143bf3c1c0958374ba3691e1a04f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 14:37:40.901172) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:37:40.901498) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1477428655.160111-1850805457 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 14:37:40.950832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 14:37:40.951118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 14:37:40.951397) ========= ========= Total master_lag: 0.33 =========