builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1475688086.13 results: success (0) buildid: 20161005071100 builduid: dceed1fb36894c6f99345612ba78b5e5 revision: 568a9ce78d2f816af262945b40b888a0e6d98951 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.126032) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.126736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.127106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.143127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.143779) ========= 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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 10:21:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-10-05 10:21:26 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093045 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.252301) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.252657) ========= 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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022230 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.302605) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.302986) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 568a9ce78d2f816af262945b40b888a0e6d98951 --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 568a9ce78d2f816af262945b40b888a0e6d98951 --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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 10:21:26,876 truncating revision to first 12 chars 2016-10-05 10:21:26,876 Setting DEBUG logging. 2016-10-05 10:21:26,876 attempt 1/10 2016-10-05 10:21:26,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/568a9ce78d2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 10:21:27,197 unpacking tar archive at: mozilla-beta-568a9ce78d2f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.660411 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.977197) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.977545) ========= script_repo_revision: 568a9ce78d2f816af262945b40b888a0e6d98951 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.978014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.978330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 10:21:26.989264) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-10-05 10:21:26.989569) ========= /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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:21:27 INFO - MultiFileLogger online at 20161005 10:21:27 in /builds/slave/test 10:21:27 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 10:21:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:21:27 INFO - {'append_to_log': False, 10:21:27 INFO - 'base_work_dir': '/builds/slave/test', 10:21:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:21:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:21:27 INFO - 'branch': 'Mozilla-Beta', 10:21:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:21:27 INFO - 'config_files': ('talos/linux_config.py',), 10:21:27 INFO - 'default_actions': ('clobber', 10:21:27 INFO - 'read-buildbot-config', 10:21:27 INFO - 'download-and-extract', 10:21:27 INFO - 'populate-webroot', 10:21:27 INFO - 'create-virtualenv', 10:21:27 INFO - 'install', 10:21:27 INFO - 'run-tests'), 10:21:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:21:27 INFO - 'download_minidump_stackwalk': True, 10:21:27 INFO - 'download_symbols': 'ondemand', 10:21:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:21:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:21:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:21:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:21:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:21:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:21:27 INFO - 'installer_path': 'installer.exe', 10:21:27 INFO - 'log_level': 'info', 10:21:27 INFO - 'log_name': 'talos', 10:21:27 INFO - 'log_to_console': True, 10:21:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:21:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:21:27 INFO - 'opt_config_files': (), 10:21:27 INFO - 'pip_index': False, 10:21:27 INFO - 'sps_profile': False, 10:21:27 INFO - 'sps_profile_interval': 0, 10:21:27 INFO - 'suite': 'dromaeojs', 10:21:27 INFO - 'system_bits': '32', 10:21:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:21:27 INFO - 'title': 'talos-linux64-ix-027', 10:21:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:21:27 INFO - 'use_talos_json': True, 10:21:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:21:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:21:27 INFO - 'work_dir': 'build'} 10:21:27 INFO - [mozharness: 2016-10-05 17:21:27.659129Z] Running clobber step. 10:21:27 INFO - Running pre-action listener: _resource_record_pre_action 10:21:27 INFO - Running main action method: clobber 10:21:27 INFO - rmtree: /builds/slave/test/build 10:21:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:21:28 INFO - Running post-action listener: _resource_record_post_action 10:21:28 INFO - [mozharness: 2016-10-05 17:21:28.639991Z] Finished clobber step (success) 10:21:28 INFO - [mozharness: 2016-10-05 17:21:28.640091Z] Running read-buildbot-config step. 10:21:28 INFO - Running pre-action listener: _resource_record_pre_action 10:21:28 INFO - Running main action method: read_buildbot_config 10:21:28 INFO - Using buildbot properties: 10:21:28 INFO - { 10:21:28 INFO - "project": "", 10:21:28 INFO - "product": "firefox", 10:21:28 INFO - "script_repo_revision": "production", 10:21:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:21:28 INFO - "repository": "", 10:21:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:21:28 INFO - "buildid": "20161005071100", 10:21:28 INFO - "pgo_build": "True", 10:21:28 INFO - "basedir": "/builds/slave/test", 10:21:28 INFO - "buildnumber": 265, 10:21:28 INFO - "slavename": "talos-linux64-ix-027", 10:21:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:21:28 INFO - "platform": "ubuntu64_hw", 10:21:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:21:28 INFO - "branch": "mozilla-beta", 10:21:28 INFO - "repo_path": "releases/mozilla-beta", 10:21:28 INFO - "revision": "568a9ce78d2f816af262945b40b888a0e6d98951", 10:21:28 INFO - "stage_platform": "linux64-pgo", 10:21:28 INFO - "builduid": "dceed1fb36894c6f99345612ba78b5e5", 10:21:28 INFO - "slavebuilddir": "test" 10:21:28 INFO - } 10:21:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:21:28 INFO - Running post-action listener: _resource_record_post_action 10:21:28 INFO - [mozharness: 2016-10-05 17:21:28.642501Z] Finished read-buildbot-config step (success) 10:21:28 INFO - [mozharness: 2016-10-05 17:21:28.642589Z] Running download-and-extract step. 10:21:28 INFO - Running pre-action listener: _resource_record_pre_action 10:21:28 INFO - Running main action method: download_and_extract 10:21:28 INFO - mkdir: /builds/slave/test/build/tests 10:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:28 INFO - https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:21:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:21:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:21:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:21:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:21:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:21:28 INFO - retry: Failed, sleeping 30 seconds before retrying 10:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:21:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:21:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:21:58 INFO - retry: Failed, sleeping 60 seconds before retrying 10:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:22:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:22:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:22:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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! 10:22:58 INFO - Caught exception: HTTP Error 404: Not Found 10:22:58 INFO - Caught exception: HTTP Error 404: Not Found 10:22:58 INFO - Caught exception: HTTP Error 404: Not Found 10:22:58 INFO - trying https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:22:59 INFO - Downloaded 1327 bytes. 10:22:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:22:59 INFO - Using the following test package requirements: 10:22:59 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:22:59 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:22:59 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:22:59 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'jsshell-linux-x86_64.zip'], 10:22:59 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:22:59 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:22:59 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:22:59 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:22:59 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:22:59 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:22:59 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:22:59 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:22:59 INFO - https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:22:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:22:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:22:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:22:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:22:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:22:59 INFO - retry: Failed, sleeping 30 seconds before retrying 10:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:23:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:23:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:23:29 INFO - retry: Failed, sleeping 60 seconds before retrying 10:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:24:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:24:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:24:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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! 10:24:29 INFO - Caught exception: HTTP Error 404: Not Found 10:24:29 INFO - Caught exception: HTTP Error 404: Not Found 10:24:29 INFO - Caught exception: HTTP Error 404: Not Found 10:24:29 INFO - trying https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:24:29 INFO - Downloading https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:24:30 INFO - Downloaded 21772367 bytes. 10:24:30 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'] 10:24:30 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 10:24:31 INFO - Return code: 0 10:24:31 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 10:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:31 INFO - https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:24:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:24:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:24:31 INFO - retry: Failed, sleeping 30 seconds before retrying 10:25:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:25:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:25:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:25:01 INFO - retry: Failed, sleeping 60 seconds before retrying 10:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:26:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:26:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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! 10:26:01 INFO - Caught exception: HTTP Error 404: Not Found 10:26:01 INFO - Caught exception: HTTP Error 404: Not Found 10:26:01 INFO - Caught exception: HTTP Error 404: Not Found 10:26:01 INFO - trying https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:26:01 INFO - Downloading https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:02 INFO - Downloaded 21772367 bytes. 10:26:02 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'] 10:26:02 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 10:26:03 INFO - Return code: 0 10:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:03 INFO - https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:26:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:26:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:26:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:26:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:26:03 INFO - retry: Failed, sleeping 30 seconds before retrying 10:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:26:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:26:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:26:33 INFO - retry: Failed, sleeping 60 seconds before retrying 10:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:27:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:27:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:27:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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! 10:27:33 INFO - Caught exception: HTTP Error 404: Not Found 10:27:33 INFO - Caught exception: HTTP Error 404: Not Found 10:27:33 INFO - Caught exception: HTTP Error 404: Not Found 10:27:33 INFO - trying https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:27:33 INFO - Downloading https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:27:34 INFO - Downloaded 14413740 bytes. 10:27:34 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'] 10:27:34 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 10:27:34 INFO - Return code: 0 10:27: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')]} 10:27:34 INFO - https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:27:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:27:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:27:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:27:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:27:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:27:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:27:34 INFO - retry: Failed, sleeping 30 seconds before retrying 10:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:28:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:28:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:28:04 INFO - retry: Failed, sleeping 60 seconds before retrying 10:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:29:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:29:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:29:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O-eem0s3TgC62wCVGnzWBA/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! 10:29:04 INFO - Caught exception: HTTP Error 404: Not Found 10:29:04 INFO - Caught exception: HTTP Error 404: Not Found 10:29:04 INFO - Caught exception: HTTP Error 404: Not Found 10:29:04 INFO - trying https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:29:04 INFO - Downloading https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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 10:29:06 INFO - Downloaded 56792881 bytes. 10:29:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:29:06 INFO - mkdir: /builds/slave/test/properties 10:29:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:06 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:06 INFO - Contents: 10:29:06 INFO - build_url:https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:29:06 INFO - Running post-action listener: _resource_record_post_action 10:29:06 INFO - Running post-action listener: set_extra_try_arguments 10:29:06 INFO - [mozharness: 2016-10-05 17:29:06.726524Z] Finished download-and-extract step (success) 10:29:06 INFO - [mozharness: 2016-10-05 17:29:06.726759Z] Running populate-webroot step. 10:29:06 INFO - Running pre-action listener: _resource_record_pre_action 10:29:06 INFO - Running main action method: populate_webroot 10:29:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:06 INFO - '--tppagecycles', 10:29:06 INFO - '7'], 10:29:06 INFO - 'tests': ['tsvgm']}}, 10:29:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['damp', 'tps']}, 10:29:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'tests': ['damp', 'tps']}, 10:29:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['dromaeo_dom']}, 10:29:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['basic_compositor_video']}, 10:29:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['a11yr', 10:29:06 INFO - 'ts_paint', 10:29:06 INFO - 'tpaint', 10:29:06 INFO - 'sessionrestore', 10:29:06 INFO - 'sessionrestore_no_auto_restore', 10:29:06 INFO - 'tabpaint']}, 10:29:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['tsvgx', 10:29:06 INFO - 'tsvgr_opacity', 10:29:06 INFO - 'tart', 10:29:06 INFO - 'tscrollx', 10:29:06 INFO - 'cart']}, 10:29:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:06 INFO - 'tsvgr_opacity', 10:29:06 INFO - 'tart', 10:29:06 INFO - 'tscrollx', 10:29:06 INFO - 'cart']}, 10:29:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'talos_options': ['--disable-e10s'], 10:29:06 INFO - 'tests': ['tp5o']}, 10:29:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'tests': ['tp5o']}, 10:29:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'talos_options': ['--disable-e10s', 10:29:06 INFO - '--xperf_path', 10:29:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:06 INFO - 'tests': ['tp5n']}, 10:29:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:06 INFO - 'talos_options': ['--xperf_path', 10:29:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:06 INFO - 'tests': ['tp5n']}}, 10:29:06 INFO - 'talos.zip': {'path': '', 10:29:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:06 INFO - Running post-action listener: _resource_record_post_action 10:29:06 INFO - [mozharness: 2016-10-05 17:29:06.749226Z] Finished populate-webroot step (success) 10:29:06 INFO - [mozharness: 2016-10-05 17:29:06.749315Z] Running create-virtualenv step. 10:29:06 INFO - Running pre-action listener: _resource_record_pre_action 10:29:06 INFO - Running main action method: create_virtualenv 10:29:06 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:29:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:06 INFO - Using real prefix '/usr' 10:29:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:07 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:09 INFO - Installing pip.................done. 10:29:09 INFO - Return code: 0 10:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:29:09 INFO - Using env: {'DISPLAY': ':0', 10:29:09 INFO - 'HOME': '/home/cltbld', 10:29:09 INFO - 'LANG': 'en_US.UTF-8', 10:29:09 INFO - 'LANGUAGE': 'en_US:en', 10:29:09 INFO - 'LOGNAME': 'cltbld', 10:29:09 INFO - 'MAIL': '/var/mail/cltbld', 10:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:09 INFO - 'MOZ_NO_REMOTE': '1', 10:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:09 INFO - 'NO_EM_RESTART': '1', 10:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:09 INFO - 'PWD': '/builds/slave/test', 10:29:09 INFO - 'SHELL': '/bin/bash', 10:29:09 INFO - 'SHLVL': '1', 10:29:09 INFO - 'TERM': 'linux', 10:29:09 INFO - 'TMOUT': '86400', 10:29:09 INFO - 'USER': 'cltbld', 10:29:09 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:09 INFO - '_': '/tools/buildbot/bin/python'} 10:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:29:09 INFO - Cleaning up... 10:29:09 INFO - Return code: 0 10:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:29:09 INFO - Using env: {'DISPLAY': ':0', 10:29:09 INFO - 'HOME': '/home/cltbld', 10:29:09 INFO - 'LANG': 'en_US.UTF-8', 10:29:09 INFO - 'LANGUAGE': 'en_US:en', 10:29:09 INFO - 'LOGNAME': 'cltbld', 10:29:09 INFO - 'MAIL': '/var/mail/cltbld', 10:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:09 INFO - 'MOZ_NO_REMOTE': '1', 10:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:09 INFO - 'NO_EM_RESTART': '1', 10:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:09 INFO - 'PWD': '/builds/slave/test', 10:29:09 INFO - 'SHELL': '/bin/bash', 10:29:09 INFO - 'SHLVL': '1', 10:29:09 INFO - 'TERM': 'linux', 10:29:09 INFO - 'TMOUT': '86400', 10:29:09 INFO - 'USER': 'cltbld', 10:29:09 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:09 INFO - '_': '/tools/buildbot/bin/python'} 10:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:09 INFO - Downloading/unpacking psutil>=3.1.1 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:11 INFO - Installing collected packages: psutil 10:29:11 INFO - Running setup.py install for psutil 10:29:11 INFO - building 'psutil._psutil_linux' extension 10:29:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:29:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:29:12 INFO - building 'psutil._psutil_posix' extension 10:29:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:29:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:29:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:12 INFO - Successfully installed psutil 10:29:12 INFO - Cleaning up... 10:29:12 INFO - Return code: 0 10:29:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:12 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:29:12 INFO - Using env: {'DISPLAY': ':0', 10:29:12 INFO - 'HOME': '/home/cltbld', 10:29:12 INFO - 'LANG': 'en_US.UTF-8', 10:29:12 INFO - 'LANGUAGE': 'en_US:en', 10:29:12 INFO - 'LOGNAME': 'cltbld', 10:29:12 INFO - 'MAIL': '/var/mail/cltbld', 10:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:12 INFO - 'MOZ_NO_REMOTE': '1', 10:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:12 INFO - 'NO_EM_RESTART': '1', 10:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:12 INFO - 'PWD': '/builds/slave/test', 10:29:12 INFO - 'SHELL': '/bin/bash', 10:29:12 INFO - 'SHLVL': '1', 10:29:12 INFO - 'TERM': 'linux', 10:29:12 INFO - 'TMOUT': '86400', 10:29:12 INFO - 'USER': 'cltbld', 10:29:12 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:12 INFO - '_': '/tools/buildbot/bin/python'} 10:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:14 INFO - Installing collected packages: mozsystemmonitor 10:29:14 INFO - Running setup.py install for mozsystemmonitor 10:29:14 INFO - Successfully installed mozsystemmonitor 10:29:14 INFO - Cleaning up... 10:29:14 INFO - Return code: 0 10:29:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:14 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:29:14 INFO - Using env: {'DISPLAY': ':0', 10:29:14 INFO - 'HOME': '/home/cltbld', 10:29:14 INFO - 'LANG': 'en_US.UTF-8', 10:29:14 INFO - 'LANGUAGE': 'en_US:en', 10:29:14 INFO - 'LOGNAME': 'cltbld', 10:29:14 INFO - 'MAIL': '/var/mail/cltbld', 10:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:14 INFO - 'MOZ_NO_REMOTE': '1', 10:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:14 INFO - 'NO_EM_RESTART': '1', 10:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:14 INFO - 'PWD': '/builds/slave/test', 10:29:14 INFO - 'SHELL': '/bin/bash', 10:29:14 INFO - 'SHLVL': '1', 10:29:14 INFO - 'TERM': 'linux', 10:29:14 INFO - 'TMOUT': '86400', 10:29:14 INFO - 'USER': 'cltbld', 10:29:14 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:14 INFO - '_': '/tools/buildbot/bin/python'} 10:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:14 INFO - Downloading/unpacking jsonschema==2.5.1 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:16 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:16 INFO - warning: no files found matching '*.txt' 10:29:16 INFO - no previously-included directories found matching 'build' 10:29:16 INFO - no previously-included directories found matching 'dist' 10:29:16 INFO - no previously-included directories found matching '.git*' 10:29:16 INFO - Installing collected packages: jsonschema, functools32 10:29:17 INFO - Running setup.py install for functools32 10:29:17 INFO - warning: no files found matching '*.txt' 10:29:17 INFO - no previously-included directories found matching 'build' 10:29:17 INFO - no previously-included directories found matching 'dist' 10:29:17 INFO - no previously-included directories found matching '.git*' 10:29:17 INFO - Successfully installed jsonschema functools32 10:29:17 INFO - Cleaning up... 10:29:17 INFO - Return code: 0 10:29:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:17 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:29:17 INFO - Using env: {'DISPLAY': ':0', 10:29:17 INFO - 'HOME': '/home/cltbld', 10:29:17 INFO - 'LANG': 'en_US.UTF-8', 10:29:17 INFO - 'LANGUAGE': 'en_US:en', 10:29:17 INFO - 'LOGNAME': 'cltbld', 10:29:17 INFO - 'MAIL': '/var/mail/cltbld', 10:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:17 INFO - 'MOZ_NO_REMOTE': '1', 10:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:17 INFO - 'NO_EM_RESTART': '1', 10:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:17 INFO - 'PWD': '/builds/slave/test', 10:29:17 INFO - 'SHELL': '/bin/bash', 10:29:17 INFO - 'SHLVL': '1', 10:29:17 INFO - 'TERM': 'linux', 10:29:17 INFO - 'TMOUT': '86400', 10:29:17 INFO - 'USER': 'cltbld', 10:29:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:17 INFO - '_': '/tools/buildbot/bin/python'} 10:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:17 INFO - Cleaning up... 10:29:17 INFO - Return code: 0 10:29:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:17 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:29:17 INFO - Using env: {'DISPLAY': ':0', 10:29:17 INFO - 'HOME': '/home/cltbld', 10:29:17 INFO - 'LANG': 'en_US.UTF-8', 10:29:17 INFO - 'LANGUAGE': 'en_US:en', 10:29:17 INFO - 'LOGNAME': 'cltbld', 10:29:17 INFO - 'MAIL': '/var/mail/cltbld', 10:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:17 INFO - 'MOZ_NO_REMOTE': '1', 10:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:17 INFO - 'NO_EM_RESTART': '1', 10:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:17 INFO - 'PWD': '/builds/slave/test', 10:29:17 INFO - 'SHELL': '/bin/bash', 10:29:17 INFO - 'SHLVL': '1', 10:29:17 INFO - 'TERM': 'linux', 10:29:17 INFO - 'TMOUT': '86400', 10:29:17 INFO - 'USER': 'cltbld', 10:29:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:17 INFO - '_': '/tools/buildbot/bin/python'} 10:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:17 INFO - Downloading/unpacking blobuploader==1.2.4 10:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:19 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:20 INFO - Downloading docopt-0.6.1.tar.gz 10:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:20 INFO - Running setup.py install for blobuploader 10:29:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:20 INFO - Running setup.py install for requests 10:29:20 INFO - Running setup.py install for docopt 10:29:20 INFO - Successfully installed blobuploader requests docopt 10:29:20 INFO - Cleaning up... 10:29:20 INFO - Return code: 0 10:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:20 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:20 INFO - Using env: {'DISPLAY': ':0', 10:29:20 INFO - 'HOME': '/home/cltbld', 10:29:20 INFO - 'LANG': 'en_US.UTF-8', 10:29:20 INFO - 'LANGUAGE': 'en_US:en', 10:29:20 INFO - 'LOGNAME': 'cltbld', 10:29:20 INFO - 'MAIL': '/var/mail/cltbld', 10:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:20 INFO - 'MOZ_NO_REMOTE': '1', 10:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:20 INFO - 'NO_EM_RESTART': '1', 10:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:20 INFO - 'PWD': '/builds/slave/test', 10:29:20 INFO - 'SHELL': '/bin/bash', 10:29:20 INFO - 'SHLVL': '1', 10:29:20 INFO - 'TERM': 'linux', 10:29:20 INFO - 'TMOUT': '86400', 10:29:20 INFO - 'USER': 'cltbld', 10:29:20 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:20 INFO - '_': '/tools/buildbot/bin/python'} 10:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:21 INFO - Running setup.py (path:/tmp/pip-3cqqz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:21 INFO - Running setup.py (path:/tmp/pip-_xOhkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:21 INFO - Running setup.py (path:/tmp/pip-Ha9Wy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:21 INFO - Running setup.py (path:/tmp/pip-ZdVJiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:21 INFO - Running setup.py (path:/tmp/pip-D0Ck9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:21 INFO - Running setup.py (path:/tmp/pip-DgeL6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:21 INFO - Running setup.py (path:/tmp/pip-RWayNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:21 INFO - Running setup.py (path:/tmp/pip-dbJfxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:21 INFO - Running setup.py (path:/tmp/pip-oIsosz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:21 INFO - Running setup.py (path:/tmp/pip-GObMIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:21 INFO - Running setup.py (path:/tmp/pip-R4rtWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:21 INFO - Running setup.py (path:/tmp/pip-WfT4Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:21 INFO - Running setup.py (path:/tmp/pip-6UVADE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:22 INFO - Running setup.py (path:/tmp/pip-dYmFTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:22 INFO - Running setup.py (path:/tmp/pip-jrD6fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:22 INFO - Running setup.py (path:/tmp/pip-7OLPKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:22 INFO - Running setup.py (path:/tmp/pip-J_YXP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:29:22 INFO - Running setup.py install for manifestparser 10:29:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:29:22 INFO - Running setup.py install for mozcrash 10:29:22 INFO - Running setup.py install for mozdebug 10:29:22 INFO - Running setup.py install for mozdevice 10:29:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:29:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:29:22 INFO - Running setup.py install for mozfile 10:29:22 INFO - Running setup.py install for mozhttpd 10:29:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:29:22 INFO - Running setup.py install for mozinfo 10:29:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozInstall 10:29:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozleak 10:29:23 INFO - Running setup.py install for mozlog 10:29:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for moznetwork 10:29:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozprocess 10:29:23 INFO - Running setup.py install for mozprofile 10:29:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozrunner 10:29:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozscreenshot 10:29:23 INFO - Running setup.py install for moztest 10:29:23 INFO - Running setup.py install for mozversion 10:29:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:29:24 INFO - Cleaning up... 10:29:24 INFO - Return code: 0 10:29:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:24 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:24 INFO - Using env: {'DISPLAY': ':0', 10:29:24 INFO - 'HOME': '/home/cltbld', 10:29:24 INFO - 'LANG': 'en_US.UTF-8', 10:29:24 INFO - 'LANGUAGE': 'en_US:en', 10:29:24 INFO - 'LOGNAME': 'cltbld', 10:29:24 INFO - 'MAIL': '/var/mail/cltbld', 10:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:24 INFO - 'MOZ_NO_REMOTE': '1', 10:29:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:24 INFO - 'NO_EM_RESTART': '1', 10:29:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:24 INFO - 'PWD': '/builds/slave/test', 10:29:24 INFO - 'SHELL': '/bin/bash', 10:29:24 INFO - 'SHLVL': '1', 10:29:24 INFO - 'TERM': 'linux', 10:29:24 INFO - 'TMOUT': '86400', 10:29:24 INFO - 'USER': 'cltbld', 10:29:24 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:24 INFO - '_': '/tools/buildbot/bin/python'} 10:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:24 INFO - Running setup.py (path:/tmp/pip-5CGVS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:24 INFO - Running setup.py (path:/tmp/pip-TNfi_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:24 INFO - Running setup.py (path:/tmp/pip-QW1CSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:24 INFO - Running setup.py (path:/tmp/pip-e46p7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:24 INFO - Running setup.py (path:/tmp/pip-hmWQgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:24 INFO - Running setup.py (path:/tmp/pip-qwAYWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:24 INFO - Running setup.py (path:/tmp/pip-N8Ab7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:24 INFO - Running setup.py (path:/tmp/pip-srPY1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:24 INFO - Running setup.py (path:/tmp/pip-s_lGM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:24 INFO - Running setup.py (path:/tmp/pip-e7_EB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:25 INFO - Running setup.py (path:/tmp/pip-ZXGwxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:25 INFO - Running setup.py (path:/tmp/pip-d6xhwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:25 INFO - Running setup.py (path:/tmp/pip-IKzd8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:25 INFO - Running setup.py (path:/tmp/pip-ATyJ_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:25 INFO - Running setup.py (path:/tmp/pip-KykzFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:25 INFO - Running setup.py (path:/tmp/pip-3zCgQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:25 INFO - Running setup.py (path:/tmp/pip-tc83QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:27 INFO - Downloading blessings-1.6.tar.gz 10:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:29:27 INFO - Installing collected packages: blessings 10:29:27 INFO - Running setup.py install for blessings 10:29:27 INFO - Successfully installed blessings 10:29:27 INFO - Cleaning up... 10:29:27 INFO - Return code: 0 10:29:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:29:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:27 INFO - Reading from file tmpfile_stdout 10:29:27 INFO - Current package versions: 10:29:27 INFO - argparse == 1.2.1 10:29:27 INFO - blessings == 1.6 10:29:27 INFO - blobuploader == 1.2.4 10:29:27 INFO - docopt == 0.6.1 10:29:27 INFO - functools32 == 3.2.3-2 10:29:27 INFO - jsonschema == 2.5.1 10:29:27 INFO - manifestparser == 1.1 10:29:27 INFO - mozInstall == 1.12 10:29:27 INFO - mozcrash == 0.17 10:29:27 INFO - mozdebug == 0.1 10:29:27 INFO - mozdevice == 0.48 10:29:27 INFO - mozfile == 1.2 10:29:27 INFO - mozhttpd == 0.7 10:29:27 INFO - mozinfo == 0.9 10:29:27 INFO - mozleak == 0.1 10:29:27 INFO - mozlog == 3.3 10:29:27 INFO - moznetwork == 0.27 10:29:27 INFO - mozprocess == 0.23 10:29:27 INFO - mozprofile == 0.28 10:29:27 INFO - mozrunner == 6.12 10:29:27 INFO - mozscreenshot == 0.1 10:29:27 INFO - mozsystemmonitor == 0.3 10:29:27 INFO - moztest == 0.7 10:29:27 INFO - mozversion == 1.4 10:29:27 INFO - psutil == 3.1.1 10:29:27 INFO - requests == 1.2.3 10:29:27 INFO - wsgiref == 0.1.2 10:29:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:27 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 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:27 INFO - Using env: {'DISPLAY': ':0', 10:29:27 INFO - 'HOME': '/home/cltbld', 10:29:27 INFO - 'LANG': 'en_US.UTF-8', 10:29:27 INFO - 'LANGUAGE': 'en_US:en', 10:29:27 INFO - 'LOGNAME': 'cltbld', 10:29:27 INFO - 'MAIL': '/var/mail/cltbld', 10:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:27 INFO - 'MOZ_NO_REMOTE': '1', 10:29:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:27 INFO - 'NO_EM_RESTART': '1', 10:29:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:27 INFO - 'PWD': '/builds/slave/test', 10:29:27 INFO - 'SHELL': '/bin/bash', 10:29:27 INFO - 'SHLVL': '1', 10:29:27 INFO - 'TERM': 'linux', 10:29:27 INFO - 'TMOUT': '86400', 10:29:27 INFO - 'USER': 'cltbld', 10:29:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:27 INFO - '_': '/tools/buildbot/bin/python'} 10:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:29:28 INFO - Cleaning up... 10:29:28 INFO - Return code: 0 10:29:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, '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': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:29:28 INFO - Using env: {'DISPLAY': ':0', 10:29:28 INFO - 'HOME': '/home/cltbld', 10:29:28 INFO - 'LANG': 'en_US.UTF-8', 10:29:28 INFO - 'LANGUAGE': 'en_US:en', 10:29:28 INFO - 'LOGNAME': 'cltbld', 10:29:28 INFO - 'MAIL': '/var/mail/cltbld', 10:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:28 INFO - 'MOZ_NO_REMOTE': '1', 10:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:28 INFO - 'NO_EM_RESTART': '1', 10:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:28 INFO - 'PWD': '/builds/slave/test', 10:29:28 INFO - 'SHELL': '/bin/bash', 10:29:28 INFO - 'SHLVL': '1', 10:29:28 INFO - 'TERM': 'linux', 10:29:28 INFO - 'TMOUT': '86400', 10:29:28 INFO - 'USER': 'cltbld', 10:29:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:28 INFO - '_': '/tools/buildbot/bin/python'} 10:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:29:28 INFO - Cleaning up... 10:29:28 INFO - Return code: 0 10:29:28 INFO - Running post-action listener: _resource_record_post_action 10:29:28 INFO - Running post-action listener: _start_resource_monitoring 10:29:28 INFO - Starting resource monitoring. 10:29:28 INFO - [mozharness: 2016-10-05 17:29:28.403304Z] Finished create-virtualenv step (success) 10:29:28 INFO - [mozharness: 2016-10-05 17:29:28.403785Z] Running install step. 10:29:28 INFO - Running pre-action listener: _resource_record_pre_action 10:29:28 INFO - Running main action method: install 10:29:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:28 INFO - Reading from file tmpfile_stdout 10:29:28 INFO - Detecting whether we're running mozinstall >=1.0... 10:29:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:29:28 INFO - Reading from file tmpfile_stdout 10:29:28 INFO - Output received: 10:29:28 INFO - Usage: mozinstall [options] installer 10:29:28 INFO - Options: 10:29:28 INFO - -h, --help show this help message and exit 10:29:28 INFO - -d DEST, --destination=DEST 10:29:28 INFO - Directory to install application into. [default: 10:29:28 INFO - "/builds/slave/test"] 10:29:28 INFO - --app=APP Application being installed. [default: firefox] 10:29:28 INFO - mkdir: /builds/slave/test/build/application 10:29:28 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'] 10:29:28 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 10:29:42 INFO - Reading from file tmpfile_stdout 10:29:42 INFO - Output received: 10:29:42 INFO - /builds/slave/test/build/application/firefox/firefox 10:29:42 INFO - Running post-action listener: _resource_record_post_action 10:29:42 INFO - [mozharness: 2016-10-05 17:29:42.735397Z] Finished install step (success) 10:29:42 INFO - [mozharness: 2016-10-05 17:29:42.735675Z] Running run-tests step. 10:29:42 INFO - Running pre-action listener: _resource_record_pre_action 10:29:42 INFO - Running main action method: run_tests 10:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:29:42 INFO - Python 2.7.3 10:29:42 INFO - Return code: 0 10:29:42 INFO - grabbing minidump binary from tooltool 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:29:42 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 0x20e43b0>, '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 0x20e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20e5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:29:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:29:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:29:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:29:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:29:42 INFO - Return code: 0 10:29:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:29:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:29:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:29:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:29:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:29:42 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/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 10:29:42 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/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 10:29:42 INFO - Using env: {'DISPLAY': ':0', 10:29:42 INFO - 'HOME': '/home/cltbld', 10:29:42 INFO - 'LANG': 'en_US.UTF-8', 10:29:42 INFO - 'LANGUAGE': 'en_US:en', 10:29:42 INFO - 'LOGNAME': 'cltbld', 10:29:42 INFO - 'MAIL': '/var/mail/cltbld', 10:29:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:29:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:42 INFO - 'MOZ_NO_REMOTE': '1', 10:29:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:42 INFO - 'NO_EM_RESTART': '1', 10:29:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:42 INFO - 'PWD': '/builds/slave/test', 10:29:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:29:42 INFO - 'SHELL': '/bin/bash', 10:29:42 INFO - 'SHLVL': '1', 10:29:42 INFO - 'TERM': 'linux', 10:29:42 INFO - 'TMOUT': '86400', 10:29:42 INFO - 'USER': 'cltbld', 10:29:42 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359', 10:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:42 INFO - '_': '/tools/buildbot/bin/python'} 10:29:42 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/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 10:29:43 INFO - mozversion application_buildid: 20161005071100 10:29:43 INFO - mozversion application_changeset: 568a9ce78d2f816af262945b40b888a0e6d98951 10:29:43 INFO - mozversion application_display_name: Firefox 10:29:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:29:43 INFO - mozversion application_name: Firefox 10:29:43 INFO - mozversion application_remotingname: firefox 10:29:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:29:43 INFO - mozversion application_vendor: Mozilla 10:29:43 INFO - mozversion application_version: 50.0 10:29:43 INFO - mozversion platform_buildid: 20161005071100 10:29:43 INFO - mozversion platform_changeset: 568a9ce78d2f816af262945b40b888a0e6d98951 10:29:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:29:43 INFO - mozversion platform_version: 50.0 10:29:43 INFO - using testdate: 1475688583 10:29:43 INFO - actual date: 1475688583 10:29:43 INFO - starting webserver on 'localhost:57380' 10:29:43 INFO - SUITE-START | Running 2 tests 10:29:43 INFO - TEST-START | dromaeo_css 10:29:43 INFO - operating with platform_type : linux_ 10:29:43 INFO - Initialising browser for dromaeo_css test... 10:29:43 INFO - TEST-INFO | started process 24559 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI6iJ9N/profile http://localhost:57380/getInfo.html) 10:29:46 INFO - PROCESS | 24559 | __metrics Screen width/height:1600/1200 10:29:46 INFO - PROCESS | 24559 | colorDepth:24 10:29:46 INFO - PROCESS | 24559 | Browser inner width/height: 1024/697 10:29:46 INFO - PROCESS | 24559 | __metrics 10:29:47 INFO - PROCESS | 24559 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:29:47 INFO - PROCESS | 24559 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:48 INFO - TEST-INFO | 24559: exit 0 10:29:48 INFO - Browser initialized. 10:29:48 INFO - Running cycle 1/1 for dromaeo_css test... 10:29:48 INFO - TEST-INFO | started process 24654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI6iJ9N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:00 INFO - PROCESS | 24654 | RSS: Main: 143908864 10:30:00 INFO - PROCESS | 24654 | 10:30:51 INFO - PROCESS | 24654 | 1475688651668 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:32:59 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57380/tests/dromaeo/cssquery-ext.html) 10:33:00 INFO - PROCESS | 24654 | RSS: Main: 263864320 10:33:00 INFO - PROCESS | 24654 | 10:36:02 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-ext.html (next: http://localhost:57380/tests/dromaeo/cssquery-jquery.html) 10:36:03 INFO - PROCESS | 24654 | RSS: Main: 232697856 10:36:03 INFO - PROCESS | 24654 | 10:39:05 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57380/tests/dromaeo/cssquery-mootools.html) 10:39:05 INFO - PROCESS | 24654 | RSS: Main: 178462720 10:39:05 INFO - PROCESS | 24654 | 10:42:07 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57380/tests/dromaeo/cssquery-prototype.html) 10:42:08 INFO - PROCESS | 24654 | RSS: Main: 177549312 10:42:08 INFO - PROCESS | 24654 | 10:45:10 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57380/tests/dromaeo/cssquery-yui.html) 10:45:11 INFO - PROCESS | 24654 | RSS: Main: 177602560 10:45:11 INFO - PROCESS | 24654 | 10:48:13 INFO - PROCESS | 24654 | Cycle 1(1): loaded http://localhost:57380/tests/dromaeo/cssquery-yui.html (next: http://localhost:57380/tests/dromaeo/cssquery-dojo.html) 10:48:14 INFO - PROCESS | 24654 | RSS: Main: 263548928 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | __start_tp_report 10:48:14 INFO - PROCESS | 24654 | _x_x_mozilla_page_load 10:48:14 INFO - PROCESS | 24654 | _x_x_mozilla_page_load_details 10:48:14 INFO - PROCESS | 24654 | |i|pagename|runs| 10:48:14 INFO - PROCESS | 24654 | |0;dojo.html;3212.3093534889995;3220.3237320162757;3239.1254361322444;3254.690804373584;3281.60620725513;5555.194496797962;5634.661920284786;5649.350324712659;5659.688717120557;5689.203511508387;5516.475934786199;5550.056490396632;5566.3598686151;5567.164925261202;5613.747381367838;5042.470540593231;5062.443131255559;5092.898142037157;5115.10485665009;5130.743462826862;4900.852974410774;4927.50724927508;5110.846674599749;5142.7428628568605;5148.382194136709;5025.698458092508;5030.170021946375;5054.974725126369;5055.595552355821;5090.083784918713;5088.567471764904;5132.153194722866;5184.403793563713;5191.965979196209;5223.112070947948;4913.975430122862;5087.49125087492;5113.642045056848;5140.331756871583;5154.819581314636;5441.809536666197;5493.121100623921;5532.972335138298;5545.556355491551;5548.0845660465975;5993.310769261503;6036.63780173191;6048.8185354439;6052;6053.122297266872;5730.942690573124;5960.433758792908;6075.088736689488;6081.118237855486;6088.9391106088815;5601.859953501154;5602.4957753802355;5627.943720562742;5638.041532939369;5650.576206784466;6135.263768347827;6142.201513803177;6168.814935551941;6178.073289006685;6201.6899155042065;5677.460641239076;5759.22250496186;5813.680247586339;5909.704514774244;5917.822465326047;5947.9702601486715;5951.196588460502;5952.940470595238;5981.192539007266;5997.250343707036;5914.4381283777975;5919.200907877467;5919.911201331984;5924.4667979882015;5924.644521328734;5714.285714285715;5826.038703613941;5832.1834943108;5924.970375148183;5931.110333450017;4547.136044151601;4571.588557029883;4579.9084018319445;4609.907801843944;4637.350770892078;4088.795560221977;4097.467329247179;4099.282625540578;4105.220008198493;4110.876673699794;5554.611177217556;5737.3688894221605;5743.54051675865;5749.022666146765;5752.712364381764;5916.704164791743;5986.2217911671205;6003.849903752441;6032.668203248863;6079.604825686316;5755.3381361144;5767.538596912237;5777.393373695785;5777.62445441045;5787.247657804459;5023.824166154167;5246.029484545371;5253.632245742761;5260.500252476008;5272.367315922037;6124.3809352984035;6219.346968568235;6220.471259942865;6287.339829317856;6293.622382657055;5900.64596124234;5990.46085852258;6009.167204002646;6016.939830601638;6047.395260473917;6094.939050609437;6247.156633854372;6278.183836101461;6290.056491526308;6293.937060629518;5869.7945571904775;5910.1134829774155;5928.318243402064;5969.552283578836;6016.308124565731;6060.969695151496;6073.5748497604745;6076.179715738319;6078.422549857756;6079.118527813531;5599.076152434803;5925.288965324189;5927.762889484372;5929.584929054925;5940.673262970406;5781.566382521243;5836.9416305838095;5844.532437405082;5912.201852749996;5930.377310382348;5817.970910145422;5849.590528662953;5854.707264636666;5867.794627188028;5872.588918775816;5859.179714839841;5915.378885217162;5928.407159284209;5932.228810254639;5932.555058370725;5482.616216864782;5631.8592035199445;5686.658800471985;5697.743601537858;5770.3652598215 10:48:14 INFO - PROCESS | 24654 | |1;ext.html;592.4579010205662;647.1813156357208;659.3670076726346;661.5931202310579;671.4259308291407;35387.82306088472;38594.42108368377;38595.61404385959;45738.31392529115;46783.29825052618;48245.75877120618;48843.75578122113;48876.266855997186;48876.266855997186;48919.75540122303;9299.349045566812;9345.87849458066;9434.622615095388;9459.479728614924;9468.71593852182;8567.443116197459;11144.665660030172;11379.658610241706;11381.260218085798;11750.11874109441;11431;11504.654860354161;11521.193516453852;11526.884731152664;11535.365554894477;11353.886461135366;11431.59989400367;11436.942815285953;11436.999999999958;11438.1421393395;11126.109911207164;11171.385573793437;11180.329180249211;11196.66410007701;11201.775964480747;44890.55109448929;45188.3221751674;45259;45278;45328;3747.6439738224826;3795.5065841440437;3838.213166300902;3869.671077958377;3939.074317535377;34474.655253447396;34744.65255347439;34752.47871281933;34936.82531587351;36401.27197456036;4700.365450664185;4775.331453596499;4777.593904518086;4800.303955926371;4835.685680430761;386476;386626;386870;389581;389963;37873.81063094667;61147.694261528406;61417.385826141166;61453.69273153606;61675.38324616786;38572.421413678814;39504.209915801526;39665.40501892474;39695.60304396919;39716.801415993315;16355.918220408821;16401.671966560603;16432.178391080397;16434.83565164357;16439.589010274838;12641.115121941375;12944.805827912589;14675.412983480755;14714.484993025193;14761.55715328542;8936.597853096626;9107.635694572144;9108.81782364349;9162.450252984709;9268;3482.703970162514;3487.5466189395215;3517.3492903812876;3531.7527773056154;3575.8378526978317;1051.7107795356337;1099.9780004399868;1108.634150730257;1135.4152611404968;1150.1201580790703;34649.82675086659;34674.47988280178;35138.47292290618;35141.82429087838;35364.33101932747;1141.8858114188515;1146.6846617180174;1171.0631494804122;1185.4487663236637;1239.0521251242806;18081.186346614435;18207.271709131488;18315.816841831678;18348.724769128472;18560.350387736635;166980;167074;167087;167144;167259;64017.35982640207;64364;64427;64484.35515644783;64531;1323.3449442526012;1411.9364628591738;1436.367998080841;1443.1557538839686;1463.6267751723242;34283.485747713305;35830;36034;36087.09782255465;36120.638793611724;53837.46162538324;54411.72794136163;54548.72725636346;54570.181447279116;54591.45408545864;461767;463845;464033;464105;465200;29927.102186933556;30973.380532389678;31206.375872482877;31227.84386078055;31235.531467028468;51669.22496162636;51921.48078519318;52342.95314093773;53395.466045339046;53440.46559534355;43902;44440.02307952483;44670;45711.54288457206;45869.77065114653;44292;44434;45548.31677524774;45606.31590526211;46484.7675761619;43755.34366984434;44436.333454997555;45107.32339014983;45349.31976020296;45491;37040.62959370372;39775.403368950065;39819.40270895881;39845.80077099596;40139.63688442675;29356;29663.851680742322;30266.697333026386;30281.69718302877;30387 10:48:14 INFO - PROCESS | 24654 | |2;jquery.html;408.40984776997215;411.14481877694396;423.8431780241311;436.6441350299506;443.55201246740785;19010.90494547527;20236.898815505938;20356.69464958023;20566.383008509732;20771.68842467364;18774.812251877476;19509.609807803834;19511.90244048778;20090.196392144335;20204.59590808183;5673.971630141854;5905.704714764244;5909.468147866691;5913.113033045056;5921.111833225029;4248.553901840293;5875.294964604253;5964.731587078571;6260.499160067184;6487.318831522693;6598.241202261754;6603.1085803416645;6607.306232845554;6614.50391220658;6618.139641846553;6385.425311721944;6566.835829104287;6578.76974305897;6591.505637077238;6591.967040164793;5846.356900740915;5863.648181109147;5895.823125306205;5906.143609176688;5925.34821169671;24927.376815579573;24938.251852444457;25059.122930697522;25253.494930101297;25304.240872773848;2320.1299512682745;2531.531666641659;2535.6450096986437;2539.1240022192314;2559.4241295708503;10724.302920310154;10925.344479331265;11064.889351106547;13926.93036534821;14212.715745685131;2009.3971808457404;2015.3550863723615;2088.1438610169907;2091.7489901211798;2093.1208892265286;87170;87266;87530.56234718849;88924.66613000874;89891.10108898893;11342.773144537228;11347.148963827745;11418;11666.475008624633;11887.346195959133;16945.745813812802;16968.40610578624;16996.405125820536;16998.575035623962;17023.14884255782;8258.339332853357;8612.354073444516;8617.310615150773;8648.783780405414;8700.825983480296;8447.155284471504;8510.957445212734;8557.957210214034;8721.380627039707;8772.517511536798;4997.725125118153;5033.597312215014;5089.720065396439;5130.281760553526;5168.121419358643;1780.983505114777;1962.2151139544296;2048.385484354688;2069.0756000718193;2083.885386303768;993.4536005197112;1004.6584161385018;1039.7126322091428;1062.8990245926625;1078.3853203673987;19180.520486987658;19811.60376792456;20219.090140943692;20485.2830150944;20485.487862803548;1004.3020101029714;1011.6206422591529;1041.5937784264142;1062.0706881478707;1072.8337107748312;10648.733781655521;11538.711532211762;11573.247738897113;11579.13156513265;11601;517628;530716;533085;541669;548190;80372.5981370101;98252;99024;100180;100424.49787751015;986.0878687214441;1024.8411496218098;1035.3322107240788;1047.4239168457318;1049.2497864027257;17492.300307987538;17886.731699024785;18318;18353.54116147107;18513.35203267933;44276.55723442853;44575;44627;45209;46051.07897841957;57798;61728.69135654293;61788.38211617826;63424.68287658532;64370.67814660897;8775.956120219358;8900.777480563038;8945.552722363725;9161.908380916373;9173.220276276325;11491.965723084963;13236.408599577248;13478.73042539124;13564.796528052284;13830.515931942333;11637.476313565741;11833.112516561465;11884.227525210834;12108.091893108229;12266.877331226931;11846.763064738829;11990;12447.315397653216;12486.250824950532;12632.43154058088;6414.967925160345;11570.19008669385;11831.40842957866;11840.703982400164;11913.940430297793;8749.1250874912;8756.255718263765;8947.19475247231;9007;9109.633554966807;11556;11593.550806149231;11787.823182652095;11841.23032002917;12161.39193040362 10:48:14 INFO - PROCESS | 24654 | |3;mootools.html;1569.2546040630698;1604.2700571240084;1745.458907738601;1860.4976656302806;1864.3008871673123;9312.81374372513;10094.646687365943;10096.091351778348;10104.989501049891;10105.393676379414;8146.877796833038;9171.174594286513;9409.388389754678;9475.336726429152;9794.657186998456;2502.1617758051075;2532.5061612985473;2547.6688030556;2547.8343907646035;2548.2227920484247;2512.6105453654623;2586.0560895273184;2587.2238328501467;2590;2592.364870606704;2571.1643715792347;2577.9613305800426;2583.160472846323;2586.159498163095;2586.7154612992554;2507.4232926427026;2512.0454227393566;2517.874106294687;2533.265353047614;2535.822492425531;2480.417101981033;2489.9128530501343;2498.0757119865766;2498.837575557583;2500.237427584586;9877.60489580416;9947.55236014381;10003.649872254437;10035.79928401428;10042.497875106215;5717.428257174291;5877.412258774131;5900.350961394243;5908.556858235649;5915.556333275022;3510.280392519553;3622.112582417298;3632.7275454340765;3673.1000904778502;3711.1278849470355;1093.6336327330355;1161.250993109448;1184.3722826901756;1186.6914602203403;1188.1564089496467;13964.93017534916;13984.650383740387;13998.160110393408;14050.016498845085;14086.084404513776;5134;5167.328247327899;5333.173358129496;5368.543673787772;5403.918941215885;10752;10778.514966826511;10825.350478971286;10828.24202305831;10832.729181770519;5803.419658034247;5809.186713860063;5869.735861886225;5870.941290587125;5874.94125058744;4705.95294047062;4806.327114204014;5354.303940487712;5402.513773760302;5406.648567843077;4232.4286221360335;4267.914641707149;4312.849050283225;4343.913121737548;4348.717333373384;1244.3964677131585;1263.646179069862;1271.1483306184878;1278.520554791953;1288.4266501406971;968.2592816495387;992.265723364705;1042.0569384706853;1078.1590359519587;1089.9128069754402;6617.371349721769;6618.933810661928;6631.966840165865;6890.552114112566;6904.240533541307;500.8247113510315;525.8422473258083;1082.577794660083;1129.4127053932073;1154.492023509653;4455.9554404456185;4460.1525710114975;4510.0979804039325;4591.8392856249875;4673.836415725434;11996.880031199751;12441.377931103409;12442.937785311016;12448.377581120907;12456.314902680258;12309.015278777677;12319.260844349188;12346.753064938652;12378.876211237772;12384.071194660437;8690.695825646071;9002.819943601222;9009.054049324857;9247.630094796268;9269.953650231706;5876.382979787232;6082.330943596113;6142.541518754739;6142.9078563820685;6167.383261673796;9558.091981261769;9620.807583848424;9826.606935722777;9832.26258030665;9834.164096051916;9968.55141518619;9971.850422243817;9972.152367048882;10043.548040338348;10067.244956628136;7765.72819951299;8476.576171191538;8667.089955554577;8760.868586971328;8908.777280568038;9618.326717129734;9806.558704858156;9808;9813.411195328303;9814.607415703293;8507.617157227816;8640.179182977607;8666.480011199348;8742.431741936754;8786.209241168326;8492.108328625653;8520.105388934073;8562.871556926526;8637.093105223861;8683.305335573265;7644.961775191311;7656.196099409704;7670.501417407851;7712.9614351927885;7721.459497835098;5676.971615141898;5683.3748287687495;5765.4811067004175;5799.246098007232;5936.643801371932;5680.11958146488;5728.856778580569;5763.769449222152;5809.912851307318;5818.418158184148 10:48:14 INFO - PROCESS | 24654 | |4;prototype.html;338.937296600129;346.0552691153152;347.63671962798907;349.6189153822333;356.29631477831276;5445.156000819869;5477.3427188737305;5500.67983683916;5518.227448157261;5544.4455554444585;5395.406505284425;5448.509634132927;5465.316835395575;5478.123500239953;5509.421510741375;3865.2849222893856;4086.734362266443;4239.745615263078;4270.4234928284595;4286.5640010596335;4234.618884300412;4246.97876510617;4264.317709166534;4266.807993640294;4269.765162916038;4250.766207858566;4253.170631726799;4267.615914567688;4312.956870431302;4320.3735458358515;4198.349255865315;4257.276263035292;4260.829566817323;4298.688899885534;4304.31131019037;3751.568569614502;3762.5484941806885;3768.9811550942068;3771.208046310278;3775.2826962876966;5281.63028587999;5296.576273898079;5323.334583177103;5398.163284690879;5420.512153906167;4712;4876.414830220361;4928.852134435972;4965.652404331699;4977.352944117278;5638.097904335286;5647.4469520882085;5649.446402239376;5667.178259152441;5724;4210.9578904210875;4215.030542975132;4223.598758117974;4226.239276930151;4244.19360321541;5830.737616807211;5872.735726892342;5889.941100588982;5967;6001.459868611846;4240.978795106066;4268.295731204378;4270.380794784739;4277.144571085795;4278.122984788173;4336.479622445327;4342.392065110887;4343.847965321199;4344.283193273074;4354.368616550582;4761.642876784255;4893.535114164149;4923.556879880828;4934.753262336869;4945.505449455098;5651.547876169897;5655.886882262332;5665;5687.175359572839;5694.231278777394;1467.985320146785;1486.8179797061146;1489.359575382596;1496.423876807437;1500.189897455362;922.7047344849718;966.9951650241704;968.8809425113864;971.2905286695467;986.1223511075149;4941.407031156285;4978.47725988766;4983.377077865267;5064.2150466676985;5068.23976403535;5135.922961155586;5175.301334319819;5182.585393168538;5190.754218987416;5213.556847667915;4788.754923719858;4862.878428039327;4999.725015124203;4999.825111054507;5017.297578339036;2738.0553708970374;2756.8897244110412;2768.5847122931723;2777.9027734029214;2806.775457963358;5352.705601191894;5390.757415916293;5393.9730301348245;5397;5397.379301380392;4921.434035085939;5210.739463026834;5234.528892399626;5235.764390602432;5247.793007608271;5889.469947704727;5994.880102398015;6020.638761674313;6027.517798576104;6030.909536356959;5855.853603659943;5865.979020804374;5880.824095084846;5887.234659494238;5919.171316015675;5589.021921163861;5819.94180058194;5848.619839710405;5855.092460668603;5878.941210587839;6187.535934804818;6198.659073750807;6220.284667263141;6225.408586184305;6231.9688401557705;4235.4917409911;4249.36259561056;4272.1669274492415;4287.421198138336;4291.098869237495;5803.535717142534;5893.941060589339;5911.881762364815;5919.792807251725;5921.61509501881;5748.798792042258;5773.278340207474;5773.3072031356505;5800.796972105956;5801.941980580309;5656.773729050957;5675.716214189357;5745.770169193185;5789.681567513827;5818.301803783404;5762.30852297727;5768.653880767168;5770;5773.24947756789;5775.942240577709;5752.022156233366;5774.133879918046;5835.620684655484;5840.262232171329;5887.146363777143;5497.340319161726;5587.441255874543;5631.465010823965;5645.802396916089;5688.089905615082 10:48:14 INFO - PROCESS | 24654 | |5;yui.html;791.540906274361;802.3420794948141;805.3062505917705;878.4377998081227;883.5008220355503;14548.63628409289;15322.15728134952;15334.156621385819;15334.46329378472;15342.463013794519;15456.613584660388;15546;15574.532764017098;15579;15583.220838958036;699.2308460693222;814.1004190369645;872.9607167677469;877.1929824561387;887.1128871128872;880.282569705691;881.9029906710257;883.2933653077545;883.7039591736743;884.1777147253052;923.4182465047011;924.0528458330198;924.6717415317567;926.1109335038371;926.2358554192817;921.9032001639832;923.5336155241628;926.0137953079982;926.8748718922926;926.9212116970066;680.5372346804171;684.7021545627618;685.2736099734298;685.9382655560973;686.0395446375065;15404.768928466083;15491.767623485657;15509.146996915166;15513.224338783015;15521.922390387976;13865.168089914638;14546.636334091627;14633.853661463356;14889.851101488955;14949.925250373788;14261.144331340152;14268.92865535676;14333.49832755859;14381.137131772128;14519.20144392058;4602.3786788783755;4642.535746425364;4659.138059459011;4703.929441058387;4713.269443236304;78616;79681.20318796796;79961.20038799595;80320.59839700823;80558.59720701359;12084.456199471046;12185.32980686053;12279.631611051504;12325.938370308091;12493.18794278369;22057.11771529121;22128.44678883041;22134.22530211467;22171.556568868855;22199.1120355184;8928.758525377822;9004.549772511347;9047.592858321392;9104.362694611315;9176.632934682671;4883.658143929891;4892.559669629679;4930.408350997832;5009.173486374781;5132.999065182328;3717.107894105395;3830.693544516432;3831.9808400958373;3835.1562656215588;3835.32881745699;654.3619970528697;654.7446495866616;660.8447014951482;678.1353773938268;678.9117414736053;635.2757856044113;677.2076670295766;690.5787469643514;692.2212510925209;708.2810946888912;9619.90380096204;9665.90334096664;10126.949365253275;10175.643852465128;10373.31183630402;626.6584711332334;664.6377724140356;750.8047907543969;770.7726220764888;790.1426951757408;4591.747453890068;4614.353990441341;4645.767711614473;4659.464161621389;4689.788959496831;130940.34529827289;132233;132601;132986;136004;65458.672706636164;82404;107638;109105.45447272713;111098.44450777695;61745.07382389178;64167.358326417074;99063;100240;101043;8806.251468624981;8815.382923195315;8839.646414143363;8977.461352318882;9230.769230769285;46956;47784;51212.97574048424;51994.22008669798;52766.73616631892;74179.25820741724;74881.25118748743;74984;76605;77062;13904.721905562035;14055.156690598596;14074.366653500821;14361.64095897611;14669.633259168606;15905.522834314988;15998.840011600201;16202.918985404998;16355.2640131192;17177.141142942553;14670.63323416923;15683.921580392025;15695.450659226872;15908.840911590736;16422.589435264214;13828.37772300269;14589.05171163871;14637.194954277616;15607;15875.364985400454;8796.516191609522;14291.714165716836;14905.180215088274;15302.387904483696;15417.460388886335;12439.440225189706;12555.183913045623;12897.613071607866;13373.732525349633;13591.86408135906;12429.502819887104;12572.30852303132;12710.809337859755;12846.164999275017;12858.485660573473 10:48:14 INFO - PROCESS | 24654 | __end_tp_report 10:48:14 INFO - PROCESS | 24654 | __start_cc_report 10:48:14 INFO - PROCESS | 24654 | _x_x_mozilla_cycle_collect,1035 10:48:14 INFO - PROCESS | 24654 | __end_cc_report 10:48:14 INFO - PROCESS | 24654 | __startTimestamp1475689694005__endTimestamp 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | ------- Summary: start ------- 10:48:14 INFO - PROCESS | 24654 | Number of tests: 6 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#0] dojo.html Cycles:165 Average:5568.30 Median:5777.39 stddev:635.07 (11.0%) stddev-sans-first:609.53 10:48:14 INFO - PROCESS | 24654 | Values: 3212.3 3220.3 3239.1 3254.7 3281.6 5555.2 5634.7 5649.4 5659.7 5689.2 5516.5 5550.1 5566.4 5567.2 5613.7 5042.5 5062.4 5092.9 5115.1 5130.7 4900.9 4927.5 5110.8 5142.7 5148.4 5025.7 5030.2 5055.0 5055.6 5090.1 5088.6 5132.2 5184.4 5192.0 5223.1 4914.0 5087.5 5113.6 5140.3 5154.8 5441.8 5493.1 5533.0 5545.6 5548.1 5993.3 6036.6 6048.8 6052.0 6053.1 5730.9 5960.4 6075.1 6081.1 6088.9 5601.9 5602.5 5627.9 5638.0 5650.6 6135.3 6142.2 6168.8 6178.1 6201.7 5677.5 5759.2 5813.7 5909.7 5917.8 5948.0 5951.2 5952.9 5981.2 5997.3 5914.4 5919.2 5919.9 5924.5 5924.6 5714.3 5826.0 5832.2 5925.0 5931.1 4547.1 4571.6 4579.9 4609.9 4637.4 4088.8 4097.5 4099.3 4105.2 4110.9 5554.6 5737.4 5743.5 5749.0 5752.7 5916.7 5986.2 6003.8 6032.7 6079.6 5755.3 5767.5 5777.4 5777.6 5787.2 5023.8 5246.0 5253.6 5260.5 5272.4 6124.4 6219.3 6220.5 6287.3 6293.6 5900.6 5990.5 6009.2 6016.9 6047.4 6094.9 6247.2 6278.2 6290.1 6293.9 5869.8 5910.1 5928.3 5969.6 6016.3 6061.0 6073.6 6076.2 6078.4 6079.1 5599.1 5925.3 5927.8 5929.6 5940.7 5781.6 5836.9 5844.5 5912.2 5930.4 5818.0 5849.6 5854.7 5867.8 5872.6 5859.2 5915.4 5928.4 5932.2 5932.6 5482.6 5631.9 5686.7 5697.7 5770.4 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#1] ext.html Cycles:180 Average:52522.26 Median:38583.42 stddev:95977.38 (248.8%) stddev-sans-first:96167.43 10:48:14 INFO - PROCESS | 24654 | Values: 592.5 647.2 659.4 661.6 671.4 35387.8 38594.4 38595.6 45738.3 46783.3 48245.8 48843.8 48876.3 48876.3 48919.8 9299.3 9345.9 9434.6 9459.5 9468.7 8567.4 11144.7 11379.7 11381.3 11750.1 11431.0 11504.7 11521.2 11526.9 11535.4 11353.9 11431.6 11436.9 11437.0 11438.1 11126.1 11171.4 11180.3 11196.7 11201.8 44890.6 45188.3 45259.0 45278.0 45328.0 3747.6 3795.5 3838.2 3869.7 3939.1 34474.7 34744.7 34752.5 34936.8 36401.3 4700.4 4775.3 4777.6 4800.3 4835.7 386476.0 386626.0 386870.0 389581.0 389963.0 37873.8 61147.7 61417.4 61453.7 61675.4 38572.4 39504.2 39665.4 39695.6 39716.8 16355.9 16401.7 16432.2 16434.8 16439.6 12641.1 12944.8 14675.4 14714.5 14761.6 8936.6 9107.6 9108.8 9162.5 9268.0 3482.7 3487.5 3517.3 3531.8 3575.8 1051.7 1100.0 1108.6 1135.4 1150.1 34649.8 34674.5 35138.5 35141.8 35364.3 1141.9 1146.7 1171.1 1185.4 1239.1 18081.2 18207.3 18315.8 18348.7 18560.4 166980.0 167074.0 167087.0 167144.0 167259.0 64017.4 64364.0 64427.0 64484.4 64531.0 1323.3 1411.9 1436.4 1443.2 1463.6 34283.5 35830.0 36034.0 36087.1 36120.6 53837.5 54411.7 54548.7 54570.2 54591.5 461767.0 463845.0 464033.0 464105.0 465200.0 29927.1 30973.4 31206.4 31227.8 31235.5 51669.2 51921.5 52343.0 53395.5 53440.5 43902.0 44440.0 44670.0 45711.5 45869.8 44292.0 44434.0 45548.3 45606.3 46484.8 43755.3 44436.3 45107.3 45349.3 45491.0 37040.6 39775.4 39819.4 39845.8 40139.6 29356.0 29663.9 30266.7 30281.7 30387.0 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#2] jquery.html Cycles:180 Average:31131.01 Median:24932.81 stddev:88057.75 (353.2%) stddev-sans-first:88274.56 10:48:14 INFO - PROCESS | 24654 | Values: 408.4 411.1 423.8 436.6 443.6 19010.9 20236.9 20356.7 20566.4 20771.7 18774.8 19509.6 19511.9 20090.2 20204.6 5674.0 5905.7 5909.5 5913.1 5921.1 4248.6 5875.3 5964.7 6260.5 6487.3 6598.2 6603.1 6607.3 6614.5 6618.1 6385.4 6566.8 6578.8 6591.5 6592.0 5846.4 5863.6 5895.8 5906.1 5925.3 24927.4 24938.3 25059.1 25253.5 25304.2 2320.1 2531.5 2535.6 2539.1 2559.4 10724.3 10925.3 11064.9 13926.9 14212.7 2009.4 2015.4 2088.1 2091.7 2093.1 87170.0 87266.0 87530.6 88924.7 89891.1 11342.8 11347.1 11418.0 11666.5 11887.3 16945.7 16968.4 16996.4 16998.6 17023.1 8258.3 8612.4 8617.3 8648.8 8700.8 8447.2 8511.0 8558.0 8721.4 8772.5 4997.7 5033.6 5089.7 5130.3 5168.1 1781.0 1962.2 2048.4 2069.1 2083.9 993.5 1004.7 1039.7 1062.9 1078.4 19180.5 19811.6 20219.1 20485.3 20485.5 1004.3 1011.6 1041.6 1062.1 1072.8 10648.7 11538.7 11573.2 11579.1 11601.0 517628.0 530716.0 533085.0 541669.0 548190.0 80372.6 98252.0 99024.0 100180.0 100424.5 986.1 1024.8 1035.3 1047.4 1049.2 17492.3 17886.7 18318.0 18353.5 18513.4 44276.6 44575.0 44627.0 45209.0 46051.1 57798.0 61728.7 61788.4 63424.7 64370.7 8776.0 8900.8 8945.6 9161.9 9173.2 11492.0 13236.4 13478.7 13564.8 13830.5 11637.5 11833.1 11884.2 12108.1 12266.9 11846.8 11990.0 12447.3 12486.3 12632.4 6415.0 11570.2 11831.4 11840.7 11913.9 8749.1 8756.3 8947.2 9007.0 9109.6 11556.0 11593.6 11787.8 11841.2 12161.4 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#3] mootools.html Cycles:180 Average:6343.62 Median:4756.14 stddev:3623.92 (76.2%) stddev-sans-first:3616.32 10:48:14 INFO - PROCESS | 24654 | Values: 1569.3 1604.3 1745.5 1860.5 1864.3 9312.8 10094.6 10096.1 10105.0 10105.4 8146.9 9171.2 9409.4 9475.3 9794.7 2502.2 2532.5 2547.7 2547.8 2548.2 2512.6 2586.1 2587.2 2590.0 2592.4 2571.2 2578.0 2583.2 2586.2 2586.7 2507.4 2512.0 2517.9 2533.3 2535.8 2480.4 2489.9 2498.1 2498.8 2500.2 9877.6 9947.6 10003.6 10035.8 10042.5 5717.4 5877.4 5900.4 5908.6 5915.6 3510.3 3622.1 3632.7 3673.1 3711.1 1093.6 1161.3 1184.4 1186.7 1188.2 13964.9 13984.7 13998.2 14050.0 14086.1 5134.0 5167.3 5333.2 5368.5 5403.9 10752.0 10778.5 10825.4 10828.2 10832.7 5803.4 5809.2 5869.7 5870.9 5874.9 4706.0 4806.3 5354.3 5402.5 5406.6 4232.4 4267.9 4312.8 4343.9 4348.7 1244.4 1263.6 1271.1 1278.5 1288.4 968.3 992.3 1042.1 1078.2 1089.9 6617.4 6618.9 6632.0 6890.6 6904.2 500.8 525.8 1082.6 1129.4 1154.5 4456.0 4460.2 4510.1 4591.8 4673.8 11996.9 12441.4 12442.9 12448.4 12456.3 12309.0 12319.3 12346.8 12378.9 12384.1 8690.7 9002.8 9009.1 9247.6 9270.0 5876.4 6082.3 6142.5 6142.9 6167.4 9558.1 9620.8 9826.6 9832.3 9834.2 9968.6 9971.9 9972.2 10043.5 10067.2 7765.7 8476.6 8667.1 8760.9 8908.8 9618.3 9806.6 9808.0 9813.4 9814.6 8507.6 8640.2 8666.5 8742.4 8786.2 8492.1 8520.1 8562.9 8637.1 8683.3 7645.0 7656.2 7670.5 7713.0 7721.5 5677.0 5683.4 5765.5 5799.2 5936.6 5680.1 5728.9 5763.8 5809.9 5818.4 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#4] prototype.html Cycles:180 Average:4746.90 Median:5289.10 stddev:1388.33 (26.2%) stddev-sans-first:1352.23 10:48:14 INFO - PROCESS | 24654 | Values: 338.9 346.1 347.6 349.6 356.3 5445.2 5477.3 5500.7 5518.2 5544.4 5395.4 5448.5 5465.3 5478.1 5509.4 3865.3 4086.7 4239.7 4270.4 4286.6 4234.6 4247.0 4264.3 4266.8 4269.8 4250.8 4253.2 4267.6 4313.0 4320.4 4198.3 4257.3 4260.8 4298.7 4304.3 3751.6 3762.5 3769.0 3771.2 3775.3 5281.6 5296.6 5323.3 5398.2 5420.5 4712.0 4876.4 4928.9 4965.7 4977.4 5638.1 5647.4 5649.4 5667.2 5724.0 4211.0 4215.0 4223.6 4226.2 4244.2 5830.7 5872.7 5889.9 5967.0 6001.5 4241.0 4268.3 4270.4 4277.1 4278.1 4336.5 4342.4 4343.8 4344.3 4354.4 4761.6 4893.5 4923.6 4934.8 4945.5 5651.5 5655.9 5665.0 5687.2 5694.2 1468.0 1486.8 1489.4 1496.4 1500.2 922.7 967.0 968.9 971.3 986.1 4941.4 4978.5 4983.4 5064.2 5068.2 5135.9 5175.3 5182.6 5190.8 5213.6 4788.8 4862.9 4999.7 4999.8 5017.3 2738.1 2756.9 2768.6 2777.9 2806.8 5352.7 5390.8 5394.0 5397.0 5397.4 4921.4 5210.7 5234.5 5235.8 5247.8 5889.5 5994.9 6020.6 6027.5 6030.9 5855.9 5866.0 5880.8 5887.2 5919.2 5589.0 5819.9 5848.6 5855.1 5878.9 6187.5 6198.7 6220.3 6225.4 6232.0 4235.5 4249.4 4272.2 4287.4 4291.1 5803.5 5893.9 5911.9 5919.8 5921.6 5748.8 5773.3 5773.3 5800.8 5801.9 5656.8 5675.7 5745.8 5789.7 5818.3 5762.3 5768.7 5770.0 5773.2 5775.9 5752.0 5774.1 5835.6 5840.3 5887.1 5497.3 5587.4 5631.5 5645.8 5688.1 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - PROCESS | 24654 | [#5] yui.html Cycles:180 Average:21726.21 Median:4644.15 stddev:31645.86 (681.4%) stddev-sans-first:31695.59 10:48:14 INFO - PROCESS | 24654 | Values: 791.5 802.3 805.3 878.4 883.5 14548.6 15322.2 15334.2 15334.5 15342.5 15456.6 15546.0 15574.5 15579.0 15583.2 699.2 814.1 873.0 877.2 887.1 880.3 881.9 883.3 883.7 884.2 923.4 924.1 924.7 926.1 926.2 921.9 923.5 926.0 926.9 926.9 680.5 684.7 685.3 685.9 686.0 15404.8 15491.8 15509.1 15513.2 15521.9 13865.2 14546.6 14633.9 14889.9 14949.9 14261.1 14268.9 14333.5 14381.1 14519.2 4602.4 4642.5 4659.1 4703.9 4713.3 78616.0 79681.2 79961.2 80320.6 80558.6 12084.5 12185.3 12279.6 12325.9 12493.2 22057.1 22128.4 22134.2 22171.6 22199.1 8928.8 9004.5 9047.6 9104.4 9176.6 4883.7 4892.6 4930.4 5009.2 5133.0 3717.1 3830.7 3832.0 3835.2 3835.3 654.4 654.7 660.8 678.1 678.9 635.3 677.2 690.6 692.2 708.3 9619.9 9665.9 10126.9 10175.6 10373.3 626.7 664.6 750.8 770.8 790.1 4591.7 4614.4 4645.8 4659.5 4689.8 130940.3 132233.0 132601.0 132986.0 136004.0 65458.7 82404.0 107638.0 109105.5 111098.4 61745.1 64167.4 99063.0 100240.0 101043.0 8806.3 8815.4 8839.6 8977.5 9230.8 46956.0 47784.0 51213.0 51994.2 52766.7 74179.3 74881.3 74984.0 76605.0 77062.0 13904.7 14055.2 14074.4 14361.6 14669.6 15905.5 15998.8 16202.9 16355.3 17177.1 14670.6 15683.9 15695.5 15908.8 16422.6 13828.4 14589.1 14637.2 15607.0 15875.4 8796.5 14291.7 14905.2 15302.4 15417.5 12439.4 12555.2 12897.6 13373.7 13591.9 12429.5 12572.3 12710.8 12846.2 12858.5 10:48:14 INFO - PROCESS | 24654 | -------- Summary: end -------- 10:48:14 INFO - PROCESS | 24654 | 10:48:14 INFO - TEST-INFO | 24654: exit 0 10:48:14 INFO - TEST-OK | dromaeo_css | took 1111757ms 10:48:14 INFO - TEST-START | kraken 10:48:14 INFO - operating with platform_type : linux_ 10:48:14 INFO - Initialising browser for kraken test... 10:48:14 INFO - TEST-INFO | started process 24844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF1grXj/profile http://localhost:57380/getInfo.html) 10:48:18 INFO - PROCESS | 24844 | __metrics Screen width/height:1600/1200 10:48:18 INFO - PROCESS | 24844 | colorDepth:24 10:48:18 INFO - PROCESS | 24844 | Browser inner width/height: 1024/697 10:48:18 INFO - PROCESS | 24844 | __metrics 10:48:19 INFO - PROCESS | 24844 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:48:19 INFO - PROCESS | 24844 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:19 INFO - TEST-INFO | 24844: exit 0 10:48:19 INFO - Browser initialized. 10:48:19 INFO - Running cycle 1/1 for kraken test... 10:48:19 INFO - TEST-INFO | started process 24934 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF1grXj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:31 INFO - PROCESS | 24934 | RSS: Main: 143400960 10:48:31 INFO - PROCESS | 24934 | 10:48:34 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57380/tests/kraken/driver.html?testName=audio-beat-detection) 10:48:35 INFO - PROCESS | 24934 | RSS: Main: 198705152 10:48:35 INFO - PROCESS | 24934 | 10:48:37 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57380/tests/kraken/driver.html?testName=audio-dft) 10:48:38 INFO - PROCESS | 24934 | RSS: Main: 183091200 10:48:38 INFO - PROCESS | 24934 | 10:48:41 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57380/tests/kraken/driver.html?testName=audio-fft) 10:48:41 INFO - PROCESS | 24934 | RSS: Main: 273952768 10:48:41 INFO - PROCESS | 24934 | 10:48:43 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57380/tests/kraken/driver.html?testName=audio-oscillator) 10:48:44 INFO - PROCESS | 24934 | RSS: Main: 179957760 10:48:44 INFO - PROCESS | 24934 | 10:48:46 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57380/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:48:47 INFO - PROCESS | 24934 | RSS: Main: 178454528 10:48:47 INFO - PROCESS | 24934 | 10:48:50 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57380/tests/kraken/driver.html?testName=imaging-darkroom) 10:48:50 INFO - PROCESS | 24934 | RSS: Main: 214118400 10:48:50 INFO - PROCESS | 24934 | 10:48:53 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57380/tests/kraken/driver.html?testName=imaging-desaturate) 10:48:54 INFO - PROCESS | 24934 | RSS: Main: 213647360 10:48:54 INFO - PROCESS | 24934 | 10:48:57 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57380/tests/kraken/driver.html?testName=json-parse-financial) 10:48:57 INFO - PROCESS | 24934 | RSS: Main: 213127168 10:48:57 INFO - PROCESS | 24934 | 10:48:59 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57380/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:48:59 INFO - PROCESS | 24934 | RSS: Main: 175439872 10:48:59 INFO - PROCESS | 24934 | 10:49:01 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:02 INFO - PROCESS | 24934 | RSS: Main: 182255616 10:49:02 INFO - PROCESS | 24934 | 10:49:04 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:49:04 INFO - PROCESS | 24934 | RSS: Main: 189095936 10:49:04 INFO - PROCESS | 24934 | 10:49:07 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:49:08 INFO - PROCESS | 24934 | RSS: Main: 197591040 10:49:08 INFO - PROCESS | 24934 | 10:49:10 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:49:11 INFO - PROCESS | 24934 | RSS: Main: 193916928 10:49:11 INFO - PROCESS | 24934 | 10:49:12 INFO - PROCESS | 24934 | Cycle 1(1): loaded http://localhost:57380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57380/tests/kraken/driver.html?testName=ai-astar) 10:49:13 INFO - PROCESS | 24934 | RSS: Main: 243953664 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | __start_tp_report 10:49:13 INFO - PROCESS | 24934 | _x_x_mozilla_page_load 10:49:13 INFO - PROCESS | 24934 | _x_x_mozilla_page_load_details 10:49:13 INFO - PROCESS | 24934 | |i|pagename|runs| 10:49:13 INFO - PROCESS | 24934 | |0;ai-astar;110;111;120;110;110;110;108;112;110;111 10:49:13 INFO - PROCESS | 24934 | |1;audio-beat-detection;145;120;149;183;121;155;143;119;177;120 10:49:13 INFO - PROCESS | 24934 | |2;audio-dft;161;161;161;161;162;163;162;162;163;164 10:49:13 INFO - PROCESS | 24934 | |3;audio-fft;75;74;75;175;75;75;75;75;172;76 10:49:13 INFO - PROCESS | 24934 | |4;audio-oscillator;84;84;156;83;83;84;140;84;84;139 10:49:13 INFO - PROCESS | 24934 | |5;imaging-gaussian-blur;116;116;117;116;117;116;117;117;117;116 10:49:13 INFO - PROCESS | 24934 | |6;imaging-darkroom;146;147;146;147;147;146;147;146;145;145 10:49:13 INFO - PROCESS | 24934 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;95;94 10:49:13 INFO - PROCESS | 24934 | |8;json-parse-financial;52;50;52;51;52;52;52;52;51;51 10:49:13 INFO - PROCESS | 24934 | |9;json-stringify-tinderbox;60;61;60;61;61;60;59;61;60;61 10:49:13 INFO - PROCESS | 24934 | |10;stanford-crypto-aes;74;75;72;75;84;73;73;84;73;72 10:49:13 INFO - PROCESS | 24934 | |11;stanford-crypto-ccm;120;129;123;128;119;117;125;117;165;117 10:49:13 INFO - PROCESS | 24934 | |12;stanford-crypto-pbkdf2;150;151;147;153;151;153;149;151;150;156 10:49:13 INFO - PROCESS | 24934 | |13;stanford-crypto-sha256-iterative;63;61;64;61;62;64;61;62;61;60 10:49:13 INFO - PROCESS | 24934 | __end_tp_report 10:49:13 INFO - PROCESS | 24934 | __start_cc_report 10:49:13 INFO - PROCESS | 24934 | _x_x_mozilla_cycle_collect,1638 10:49:13 INFO - PROCESS | 24934 | __end_cc_report 10:49:13 INFO - PROCESS | 24934 | __startTimestamp1475689753083__endTimestamp 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | ------- Summary: start ------- 10:49:13 INFO - PROCESS | 24934 | Number of tests: 14 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#0] ai-astar Cycles:10 Average:111.20 Median:110.50 stddev:3.26 (2.9%) stddev-sans-first:3.43 10:49:13 INFO - PROCESS | 24934 | Values: 110.0 111.0 120.0 110.0 110.0 110.0 108.0 112.0 110.0 111.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#1] audio-beat-detection Cycles:10 Average:143.20 Median:147.00 stddev:23.71 (16.1%) stddev-sans-first:25.13 10:49:13 INFO - PROCESS | 24934 | Values: 145.0 120.0 149.0 183.0 121.0 155.0 143.0 119.0 177.0 120.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 10:49:13 INFO - PROCESS | 24934 | Values: 161.0 161.0 161.0 161.0 162.0 163.0 162.0 162.0 163.0 164.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.00 stddev:41.54 (55.4%) stddev-sans-first:43.44 10:49:13 INFO - PROCESS | 24934 | Values: 75.0 74.0 75.0 175.0 75.0 75.0 75.0 75.0 172.0 76.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:84.00 stddev:29.95 (35.7%) stddev-sans-first:31.04 10:49:13 INFO - PROCESS | 24934 | Values: 84.0 84.0 156.0 83.0 83.0 84.0 140.0 84.0 84.0 139.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:49:13 INFO - PROCESS | 24934 | Values: 116.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 10:49:13 INFO - PROCESS | 24934 | Values: 146.0 147.0 146.0 147.0 147.0 146.0 147.0 146.0 145.0 145.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:49:13 INFO - PROCESS | 24934 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.71 (1.4%) stddev-sans-first:0.73 10:49:13 INFO - PROCESS | 24934 | Values: 52.0 50.0 52.0 51.0 52.0 52.0 52.0 52.0 51.0 51.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:61.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 10:49:13 INFO - PROCESS | 24934 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 59.0 61.0 60.0 61.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:74.50 stddev:4.60 (6.2%) stddev-sans-first:4.85 10:49:13 INFO - PROCESS | 24934 | Values: 74.0 75.0 72.0 75.0 84.0 73.0 73.0 84.0 73.0 72.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:124.00 stddev:14.42 (11.6%) stddev-sans-first:15.13 10:49:13 INFO - PROCESS | 24934 | Values: 120.0 129.0 123.0 128.0 119.0 117.0 125.0 117.0 165.0 117.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:151.00 stddev:2.47 (1.6%) stddev-sans-first:2.59 10:49:13 INFO - PROCESS | 24934 | Values: 150.0 151.0 147.0 153.0 151.0 153.0 149.0 151.0 150.0 156.0 10:49:13 INFO - PROCESS | 24934 | 10:49:13 INFO - PROCESS | 24934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.37 (2.2%) stddev-sans-first:1.39 10:49:13 INFO - PROCESS | 24934 | Values: 63.0 61.0 64.0 61.0 62.0 64.0 61.0 62.0 61.0 60.0 10:49:13 INFO - PROCESS | 24934 | -------- Summary: end -------- 10:49:13 INFO - PROCESS | 24934 | 10:49:14 INFO - TEST-INFO | 24934: exit 0 10:49:14 INFO - TEST-OK | kraken | took 59349ms 10:49:14 INFO - SUITE-END | took 1171s 10:49:14 INFO - Completed test suite (00:19:31) 10:49:14 INFO - javascript benchmark 10:49:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3212.3093534889995, 3220.3237320162757, 3239.1254361322444, 3254.690804373584, 3281.60620725513, 5555.194496797962, 5634.661920284786, 5649.350324712659, 5659.688717120557, 5689.203511508387, 5516.475934786199, 5550.056490396632, 5566.3598686151, 5567.164925261202, 5613.747381367838, 5042.470540593231, 5062.443131255559, 5092.898142037157, 5115.10485665009, 5130.743462826862, 4900.852974410774, 4927.50724927508, 5110.846674599749, 5142.7428628568605, 5148.382194136709, 5025.698458092508, 5030.170021946375, 5054.974725126369, 5055.595552355821, 5090.083784918713, 5088.567471764904, 5132.153194722866, 5184.403793563713, 5191.965979196209, 5223.112070947948, 4913.975430122862, 5087.49125087492, 5113.642045056848, 5140.331756871583, 5154.819581314636, 5441.809536666197, 5493.121100623921, 5532.972335138298, 5545.556355491551, 5548.0845660465975, 5993.310769261503, 6036.63780173191, 6048.8185354439, 6052.0, 6053.122297266872, 5730.942690573124, 5960.433758792908, 6075.088736689488, 6081.118237855486, 6088.9391106088815, 5601.859953501154, 5602.4957753802355, 5627.943720562742, 5638.041532939369, 5650.576206784466, 6135.263768347827, 6142.201513803177, 6168.814935551941, 6178.073289006685, 6201.6899155042065, 5677.460641239076, 5759.22250496186, 5813.680247586339, 5909.704514774244, 5917.822465326047, 5947.9702601486715, 5951.196588460502, 5952.940470595238, 5981.192539007266, 5997.250343707036, 5914.4381283777975, 5919.200907877467, 5919.911201331984, 5924.4667979882015, 5924.644521328734, 5714.285714285715, 5826.038703613941, 5832.1834943108, 5924.970375148183, 5931.110333450017, 4547.136044151601, 4571.588557029883, 4579.9084018319445, 4609.907801843944, 4637.350770892078, 4088.795560221977, 4097.467329247179, 4099.282625540578, 4105.220008198493, 4110.876673699794, 5554.611177217556, 5737.3688894221605, 5743.54051675865, 5749.022666146765, 5752.712364381764, 5916.704164791743, 5986.2217911671205, 6003.849903752441, 6032.668203248863, 6079.604825686316, 5755.3381361144, 5767.538596912237, 5777.393373695785, 5777.62445441045, 5787.247657804459, 5023.824166154167, 5246.029484545371, 5253.632245742761, 5260.500252476008, 5272.367315922037, 6124.3809352984035, 6219.346968568235, 6220.471259942865, 6287.339829317856, 6293.622382657055, 5900.64596124234, 5990.46085852258, 6009.167204002646, 6016.939830601638, 6047.395260473917, 6094.939050609437, 6247.156633854372, 6278.183836101461, 6290.056491526308, 6293.937060629518, 5869.7945571904775, 5910.1134829774155, 5928.318243402064, 5969.552283578836, 6016.308124565731, 6060.969695151496, 6073.5748497604745, 6076.179715738319, 6078.422549857756, 6079.118527813531, 5599.076152434803, 5925.288965324189, 5927.762889484372, 5929.584929054925, 5940.673262970406, 5781.566382521243, 5836.9416305838095, 5844.532437405082, 5912.201852749996, 5930.377310382348, 5817.970910145422, 5849.590528662953, 5854.707264636666, 5867.794627188028, 5872.588918775816, 5859.179714839841, 5915.378885217162, 5928.407159284209, 5932.228810254639, 5932.555058370725, 5482.616216864782, 5631.8592035199445, 5686.658800471985, 5697.743601537858, 5770.3652598215], "value": 5525.397515393108, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.4579010205662, 647.1813156357208, 659.3670076726346, 661.5931202310579, 671.4259308291407, 35387.82306088472, 38594.42108368377, 38595.61404385959, 45738.31392529115, 46783.29825052618, 48245.75877120618, 48843.75578122113, 48876.266855997186, 48876.266855997186, 48919.75540122303, 9299.349045566812, 9345.87849458066, 9434.622615095388, 9459.479728614924, 9468.71593852182, 8567.443116197459, 11144.665660030172, 11379.658610241706, 11381.260218085798, 11750.11874109441, 11431.0, 11504.654860354161, 11521.193516453852, 11526.884731152664, 11535.365554894477, 11353.886461135366, 11431.59989400367, 11436.942815285953, 11436.999999999958, 11438.1421393395, 11126.109911207164, 11171.385573793437, 11180.329180249211, 11196.66410007701, 11201.775964480747, 44890.55109448929, 45188.3221751674, 45259.0, 45278.0, 45328.0, 3747.6439738224826, 3795.5065841440437, 3838.213166300902, 3869.671077958377, 3939.074317535377, 34474.655253447396, 34744.65255347439, 34752.47871281933, 34936.82531587351, 36401.27197456036, 4700.365450664185, 4775.331453596499, 4777.593904518086, 4800.303955926371, 4835.685680430761, 386476.0, 386626.0, 386870.0, 389581.0, 389963.0, 37873.81063094667, 61147.694261528406, 61417.385826141166, 61453.69273153606, 61675.38324616786, 38572.421413678814, 39504.209915801526, 39665.40501892474, 39695.60304396919, 39716.801415993315, 16355.918220408821, 16401.671966560603, 16432.178391080397, 16434.83565164357, 16439.589010274838, 12641.115121941375, 12944.805827912589, 14675.412983480755, 14714.484993025193, 14761.55715328542, 8936.597853096626, 9107.635694572144, 9108.81782364349, 9162.450252984709, 9268.0, 3482.703970162514, 3487.5466189395215, 3517.3492903812876, 3531.7527773056154, 3575.8378526978317, 1051.7107795356337, 1099.9780004399868, 1108.634150730257, 1135.4152611404968, 1150.1201580790703, 34649.82675086659, 34674.47988280178, 35138.47292290618, 35141.82429087838, 35364.33101932747, 1141.8858114188515, 1146.6846617180174, 1171.0631494804122, 1185.4487663236637, 1239.0521251242806, 18081.186346614435, 18207.271709131488, 18315.816841831678, 18348.724769128472, 18560.350387736635, 166980.0, 167074.0, 167087.0, 167144.0, 167259.0, 64017.35982640207, 64364.0, 64427.0, 64484.35515644783, 64531.0, 1323.3449442526012, 1411.9364628591738, 1436.367998080841, 1443.1557538839686, 1463.6267751723242, 34283.485747713305, 35830.0, 36034.0, 36087.09782255465, 36120.638793611724, 53837.46162538324, 54411.72794136163, 54548.72725636346, 54570.181447279116, 54591.45408545864, 461767.0, 463845.0, 464033.0, 464105.0, 465200.0, 29927.102186933556, 30973.380532389678, 31206.375872482877, 31227.84386078055, 31235.531467028468, 51669.22496162636, 51921.48078519318, 52342.95314093773, 53395.466045339046, 53440.46559534355, 43902.0, 44440.02307952483, 44670.0, 45711.54288457206, 45869.77065114653, 44292.0, 44434.0, 45548.31677524774, 45606.31590526211, 46484.7675761619, 43755.34366984434, 44436.333454997555, 45107.32339014983, 45349.31976020296, 45491.0, 37040.62959370372, 39775.403368950065, 39819.40270895881, 39845.80077099596, 40139.63688442675, 29356.0, 29663.851680742322, 30266.697333026386, 30281.69718302877, 30387.0], "value": 20025.600844538585, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.40984776997215, 411.14481877694396, 423.8431780241311, 436.6441350299506, 443.55201246740785, 19010.90494547527, 20236.898815505938, 20356.69464958023, 20566.383008509732, 20771.68842467364, 18774.812251877476, 19509.609807803834, 19511.90244048778, 20090.196392144335, 20204.59590808183, 5673.971630141854, 5905.704714764244, 5909.468147866691, 5913.113033045056, 5921.111833225029, 4248.553901840293, 5875.294964604253, 5964.731587078571, 6260.499160067184, 6487.318831522693, 6598.241202261754, 6603.1085803416645, 6607.306232845554, 6614.50391220658, 6618.139641846553, 6385.425311721944, 6566.835829104287, 6578.76974305897, 6591.505637077238, 6591.967040164793, 5846.356900740915, 5863.648181109147, 5895.823125306205, 5906.143609176688, 5925.34821169671, 24927.376815579573, 24938.251852444457, 25059.122930697522, 25253.494930101297, 25304.240872773848, 2320.1299512682745, 2531.531666641659, 2535.6450096986437, 2539.1240022192314, 2559.4241295708503, 10724.302920310154, 10925.344479331265, 11064.889351106547, 13926.93036534821, 14212.715745685131, 2009.3971808457404, 2015.3550863723615, 2088.1438610169907, 2091.7489901211798, 2093.1208892265286, 87170.0, 87266.0, 87530.56234718849, 88924.66613000874, 89891.10108898893, 11342.773144537228, 11347.148963827745, 11418.0, 11666.475008624633, 11887.346195959133, 16945.745813812802, 16968.40610578624, 16996.405125820536, 16998.575035623962, 17023.14884255782, 8258.339332853357, 8612.354073444516, 8617.310615150773, 8648.783780405414, 8700.825983480296, 8447.155284471504, 8510.957445212734, 8557.957210214034, 8721.380627039707, 8772.517511536798, 4997.725125118153, 5033.597312215014, 5089.720065396439, 5130.281760553526, 5168.121419358643, 1780.983505114777, 1962.2151139544296, 2048.385484354688, 2069.0756000718193, 2083.885386303768, 993.4536005197112, 1004.6584161385018, 1039.7126322091428, 1062.8990245926625, 1078.3853203673987, 19180.520486987658, 19811.60376792456, 20219.090140943692, 20485.2830150944, 20485.487862803548, 1004.3020101029714, 1011.6206422591529, 1041.5937784264142, 1062.0706881478707, 1072.8337107748312, 10648.733781655521, 11538.711532211762, 11573.247738897113, 11579.13156513265, 11601.0, 517628.0, 530716.0, 533085.0, 541669.0, 548190.0, 80372.5981370101, 98252.0, 99024.0, 100180.0, 100424.49787751015, 986.0878687214441, 1024.8411496218098, 1035.3322107240788, 1047.4239168457318, 1049.2497864027257, 17492.300307987538, 17886.731699024785, 18318.0, 18353.54116147107, 18513.35203267933, 44276.55723442853, 44575.0, 44627.0, 45209.0, 46051.07897841957, 57798.0, 61728.69135654293, 61788.38211617826, 63424.68287658532, 64370.67814660897, 8775.956120219358, 8900.777480563038, 8945.552722363725, 9161.908380916373, 9173.220276276325, 11491.965723084963, 13236.408599577248, 13478.73042539124, 13564.796528052284, 13830.515931942333, 11637.476313565741, 11833.112516561465, 11884.227525210834, 12108.091893108229, 12266.877331226931, 11846.763064738829, 11990.0, 12447.315397653216, 12486.250824950532, 12632.43154058088, 6414.967925160345, 11570.19008669385, 11831.40842957866, 11840.703982400164, 11913.940430297793, 8749.1250874912, 8756.255718263765, 8947.19475247231, 9007.0, 9109.633554966807, 11556.0, 11593.550806149231, 11787.823182652095, 11841.23032002917, 12161.39193040362], "value": 9729.395038122362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.2546040630698, 1604.2700571240084, 1745.458907738601, 1860.4976656302806, 1864.3008871673123, 9312.81374372513, 10094.646687365943, 10096.091351778348, 10104.989501049891, 10105.393676379414, 8146.877796833038, 9171.174594286513, 9409.388389754678, 9475.336726429152, 9794.657186998456, 2502.1617758051075, 2532.5061612985473, 2547.6688030556, 2547.8343907646035, 2548.2227920484247, 2512.6105453654623, 2586.0560895273184, 2587.2238328501467, 2590.0, 2592.364870606704, 2571.1643715792347, 2577.9613305800426, 2583.160472846323, 2586.159498163095, 2586.7154612992554, 2507.4232926427026, 2512.0454227393566, 2517.874106294687, 2533.265353047614, 2535.822492425531, 2480.417101981033, 2489.9128530501343, 2498.0757119865766, 2498.837575557583, 2500.237427584586, 9877.60489580416, 9947.55236014381, 10003.649872254437, 10035.79928401428, 10042.497875106215, 5717.428257174291, 5877.412258774131, 5900.350961394243, 5908.556858235649, 5915.556333275022, 3510.280392519553, 3622.112582417298, 3632.7275454340765, 3673.1000904778502, 3711.1278849470355, 1093.6336327330355, 1161.250993109448, 1184.3722826901756, 1186.6914602203403, 1188.1564089496467, 13964.93017534916, 13984.650383740387, 13998.160110393408, 14050.016498845085, 14086.084404513776, 5134.0, 5167.328247327899, 5333.173358129496, 5368.543673787772, 5403.918941215885, 10752.0, 10778.514966826511, 10825.350478971286, 10828.24202305831, 10832.729181770519, 5803.419658034247, 5809.186713860063, 5869.735861886225, 5870.941290587125, 5874.94125058744, 4705.95294047062, 4806.327114204014, 5354.303940487712, 5402.513773760302, 5406.648567843077, 4232.4286221360335, 4267.914641707149, 4312.849050283225, 4343.913121737548, 4348.717333373384, 1244.3964677131585, 1263.646179069862, 1271.1483306184878, 1278.520554791953, 1288.4266501406971, 968.2592816495387, 992.265723364705, 1042.0569384706853, 1078.1590359519587, 1089.9128069754402, 6617.371349721769, 6618.933810661928, 6631.966840165865, 6890.552114112566, 6904.240533541307, 500.8247113510315, 525.8422473258083, 1082.577794660083, 1129.4127053932073, 1154.492023509653, 4455.9554404456185, 4460.1525710114975, 4510.0979804039325, 4591.8392856249875, 4673.836415725434, 11996.880031199751, 12441.377931103409, 12442.937785311016, 12448.377581120907, 12456.314902680258, 12309.015278777677, 12319.260844349188, 12346.753064938652, 12378.876211237772, 12384.071194660437, 8690.695825646071, 9002.819943601222, 9009.054049324857, 9247.630094796268, 9269.953650231706, 5876.382979787232, 6082.330943596113, 6142.541518754739, 6142.9078563820685, 6167.383261673796, 9558.091981261769, 9620.807583848424, 9826.606935722777, 9832.26258030665, 9834.164096051916, 9968.55141518619, 9971.850422243817, 9972.152367048882, 10043.548040338348, 10067.244956628136, 7765.72819951299, 8476.576171191538, 8667.089955554577, 8760.868586971328, 8908.777280568038, 9618.326717129734, 9806.558704858156, 9808.0, 9813.411195328303, 9814.607415703293, 8507.617157227816, 8640.179182977607, 8666.480011199348, 8742.431741936754, 8786.209241168326, 8492.108328625653, 8520.105388934073, 8562.871556926526, 8637.093105223861, 8683.305335573265, 7644.961775191311, 7656.196099409704, 7670.501417407851, 7712.9614351927885, 7721.459497835098, 5676.971615141898, 5683.3748287687495, 5765.4811067004175, 5799.246098007232, 5936.643801371932, 5680.11958146488, 5728.856778580569, 5763.769449222152, 5809.912851307318, 5818.418158184148], "value": 5040.8298339589755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.937296600129, 346.0552691153152, 347.63671962798907, 349.6189153822333, 356.29631477831276, 5445.156000819869, 5477.3427188737305, 5500.67983683916, 5518.227448157261, 5544.4455554444585, 5395.406505284425, 5448.509634132927, 5465.316835395575, 5478.123500239953, 5509.421510741375, 3865.2849222893856, 4086.734362266443, 4239.745615263078, 4270.4234928284595, 4286.5640010596335, 4234.618884300412, 4246.97876510617, 4264.317709166534, 4266.807993640294, 4269.765162916038, 4250.766207858566, 4253.170631726799, 4267.615914567688, 4312.956870431302, 4320.3735458358515, 4198.349255865315, 4257.276263035292, 4260.829566817323, 4298.688899885534, 4304.31131019037, 3751.568569614502, 3762.5484941806885, 3768.9811550942068, 3771.208046310278, 3775.2826962876966, 5281.63028587999, 5296.576273898079, 5323.334583177103, 5398.163284690879, 5420.512153906167, 4712.0, 4876.414830220361, 4928.852134435972, 4965.652404331699, 4977.352944117278, 5638.097904335286, 5647.4469520882085, 5649.446402239376, 5667.178259152441, 5724.0, 4210.9578904210875, 4215.030542975132, 4223.598758117974, 4226.239276930151, 4244.19360321541, 5830.737616807211, 5872.735726892342, 5889.941100588982, 5967.0, 6001.459868611846, 4240.978795106066, 4268.295731204378, 4270.380794784739, 4277.144571085795, 4278.122984788173, 4336.479622445327, 4342.392065110887, 4343.847965321199, 4344.283193273074, 4354.368616550582, 4761.642876784255, 4893.535114164149, 4923.556879880828, 4934.753262336869, 4945.505449455098, 5651.547876169897, 5655.886882262332, 5665.0, 5687.175359572839, 5694.231278777394, 1467.985320146785, 1486.8179797061146, 1489.359575382596, 1496.423876807437, 1500.189897455362, 922.7047344849718, 966.9951650241704, 968.8809425113864, 971.2905286695467, 986.1223511075149, 4941.407031156285, 4978.47725988766, 4983.377077865267, 5064.2150466676985, 5068.23976403535, 5135.922961155586, 5175.301334319819, 5182.585393168538, 5190.754218987416, 5213.556847667915, 4788.754923719858, 4862.878428039327, 4999.725015124203, 4999.825111054507, 5017.297578339036, 2738.0553708970374, 2756.8897244110412, 2768.5847122931723, 2777.9027734029214, 2806.775457963358, 5352.705601191894, 5390.757415916293, 5393.9730301348245, 5397.0, 5397.379301380392, 4921.434035085939, 5210.739463026834, 5234.528892399626, 5235.764390602432, 5247.793007608271, 5889.469947704727, 5994.880102398015, 6020.638761674313, 6027.517798576104, 6030.909536356959, 5855.853603659943, 5865.979020804374, 5880.824095084846, 5887.234659494238, 5919.171316015675, 5589.021921163861, 5819.94180058194, 5848.619839710405, 5855.092460668603, 5878.941210587839, 6187.535934804818, 6198.659073750807, 6220.284667263141, 6225.408586184305, 6231.9688401557705, 4235.4917409911, 4249.36259561056, 4272.1669274492415, 4287.421198138336, 4291.098869237495, 5803.535717142534, 5893.941060589339, 5911.881762364815, 5919.792807251725, 5921.61509501881, 5748.798792042258, 5773.278340207474, 5773.3072031356505, 5800.796972105956, 5801.941980580309, 5656.773729050957, 5675.716214189357, 5745.770169193185, 5789.681567513827, 5818.301803783404, 5762.30852297727, 5768.653880767168, 5770.0, 5773.24947756789, 5775.942240577709, 5752.022156233366, 5774.133879918046, 5835.620684655484, 5840.262232171329, 5887.146363777143, 5497.340319161726, 5587.441255874543, 5631.465010823965, 5645.802396916089, 5688.089905615082], "value": 4307.852987853653, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.540906274361, 802.3420794948141, 805.3062505917705, 878.4377998081227, 883.5008220355503, 14548.63628409289, 15322.15728134952, 15334.156621385819, 15334.46329378472, 15342.463013794519, 15456.613584660388, 15546.0, 15574.532764017098, 15579.0, 15583.220838958036, 699.2308460693222, 814.1004190369645, 872.9607167677469, 877.1929824561387, 887.1128871128872, 880.282569705691, 881.9029906710257, 883.2933653077545, 883.7039591736743, 884.1777147253052, 923.4182465047011, 924.0528458330198, 924.6717415317567, 926.1109335038371, 926.2358554192817, 921.9032001639832, 923.5336155241628, 926.0137953079982, 926.8748718922926, 926.9212116970066, 680.5372346804171, 684.7021545627618, 685.2736099734298, 685.9382655560973, 686.0395446375065, 15404.768928466083, 15491.767623485657, 15509.146996915166, 15513.224338783015, 15521.922390387976, 13865.168089914638, 14546.636334091627, 14633.853661463356, 14889.851101488955, 14949.925250373788, 14261.144331340152, 14268.92865535676, 14333.49832755859, 14381.137131772128, 14519.20144392058, 4602.3786788783755, 4642.535746425364, 4659.138059459011, 4703.929441058387, 4713.269443236304, 78616.0, 79681.20318796796, 79961.20038799595, 80320.59839700823, 80558.59720701359, 12084.456199471046, 12185.32980686053, 12279.631611051504, 12325.938370308091, 12493.18794278369, 22057.11771529121, 22128.44678883041, 22134.22530211467, 22171.556568868855, 22199.1120355184, 8928.758525377822, 9004.549772511347, 9047.592858321392, 9104.362694611315, 9176.632934682671, 4883.658143929891, 4892.559669629679, 4930.408350997832, 5009.173486374781, 5132.999065182328, 3717.107894105395, 3830.693544516432, 3831.9808400958373, 3835.1562656215588, 3835.32881745699, 654.3619970528697, 654.7446495866616, 660.8447014951482, 678.1353773938268, 678.9117414736053, 635.2757856044113, 677.2076670295766, 690.5787469643514, 692.2212510925209, 708.2810946888912, 9619.90380096204, 9665.90334096664, 10126.949365253275, 10175.643852465128, 10373.31183630402, 626.6584711332334, 664.6377724140356, 750.8047907543969, 770.7726220764888, 790.1426951757408, 4591.747453890068, 4614.353990441341, 4645.767711614473, 4659.464161621389, 4689.788959496831, 130940.34529827289, 132233.0, 132601.0, 132986.0, 136004.0, 65458.672706636164, 82404.0, 107638.0, 109105.45447272713, 111098.44450777695, 61745.07382389178, 64167.358326417074, 99063.0, 100240.0, 101043.0, 8806.251468624981, 8815.382923195315, 8839.646414143363, 8977.461352318882, 9230.769230769285, 46956.0, 47784.0, 51212.97574048424, 51994.22008669798, 52766.73616631892, 74179.25820741724, 74881.25118748743, 74984.0, 76605.0, 77062.0, 13904.721905562035, 14055.156690598596, 14074.366653500821, 14361.64095897611, 14669.633259168606, 15905.522834314988, 15998.840011600201, 16202.918985404998, 16355.2640131192, 17177.141142942553, 14670.63323416923, 15683.921580392025, 15695.450659226872, 15908.840911590736, 16422.589435264214, 13828.37772300269, 14589.05171163871, 14637.194954277616, 15607.0, 15875.364985400454, 8796.516191609522, 14291.714165716836, 14905.180215088274, 15302.387904483696, 15417.460388886335, 12439.440225189706, 12555.183913045623, 12897.613071607866, 13373.732525349633, 13591.86408135906, 12429.502819887104, 12572.30852303132, 12710.809337859755, 12846.164999275017, 12858.485660573473], "value": 7999.407024784679, "unit": "score"}], "name": "dromaeo_css", "value": 7562.223998126302, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 110.0, 110.0, 110.0, 108.0, 112.0, 110.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 149.0, 183.0, 121.0, 155.0, 143.0, 119.0, 177.0, 120.0], "value": 143.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 163.0, 162.0, 162.0, 163.0, 164.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 175.0, 75.0, 75.0, 75.0, 75.0, 172.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 156.0, 83.0, 83.0, 84.0, 140.0, 84.0, 84.0, 139.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 145.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 59.0, 61.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 72.0, 75.0, 84.0, 73.0, 73.0, 84.0, 73.0, 72.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 129.0, 123.0, 128.0, 119.0, 117.0, 125.0, 117.0, 165.0, 117.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 147.0, 153.0, 151.0, 153.0, 149.0, 151.0, 150.0, 156.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 64.0, 61.0, 62.0, 64.0, 61.0, 62.0, 61.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1497.0, "alertThreshold": 2.0}]} 10:49:14 INFO - Return code: 0 10:49:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:49:14 INFO - # TBPL SUCCESS # 10:49:14 INFO - Running post-action listener: _resource_record_post_action 10:49:14 INFO - [mozharness: 2016-10-05 17:49:14.376784Z] Finished run-tests step (success) 10:49:14 INFO - Running post-run listener: _resource_record_post_run 10:49:14 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 278732800; Read time: 0; Write time: 196052 10:49:14 INFO - TinderboxPrint: CPU usage
12.9% 10:49:14 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 10:49:14 INFO - TinderboxPrint: I/O write bytes / time
278,732,800 / 196,052 10:49:14 INFO - TinderboxPrint: CPU idle
8,235.8 (87.0%) 10:49:14 INFO - TinderboxPrint: CPU user
1,181.6 (12.5%) 10:49:14 INFO - TinderboxPrint: Swap in / out
0 / 0 10:49:14 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70098944; Read time: 0; Write time: 82632 10:49:14 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 208633856; Read time: 0; Write time: 113420 10:49:15 INFO - Running post-run listener: _upload_blobber_files 10:49:15 INFO - Blob upload gear active. 10:49:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:49:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:49:15 INFO - (blobuploader) - INFO - Open directory for files ... 10:49:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:49:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:18 INFO - (blobuploader) - INFO - Done attempting. 10:49:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:49:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:49:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:19 INFO - (blobuploader) - INFO - Done attempting. 10:49:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:49:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:49:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:20 INFO - (blobuploader) - INFO - Done attempting. 10:49:20 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:20 INFO - Return code: 0 10:49:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:49:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:49:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58b48b4d4ab8980b72cfa367e4f177c525c7479e75d9ea287a57249525f66fd34fbecb94fef5f1dbd5357c33099a6dd3696077969a01da2c5b29858d7d3fa10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7a4d30d25b04a755e0216ff3e5210df4e5efc7e2ca0302152fe5e1a4efc0bb9c648ff5f513aa30474e8c6873b66776d5dc06b0d6e78296b063fb0c9baa670ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c87fcea06e01972d5ba759c573cb308553579c2ba2acf8b6b42484901f2a03be9593ed3caa7a62cc2cf7a4ba4f9359a6f93a9ab0546e22dbbef188da38eb940"} 10:49:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:49:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:49:20 INFO - Contents: 10:49:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58b48b4d4ab8980b72cfa367e4f177c525c7479e75d9ea287a57249525f66fd34fbecb94fef5f1dbd5357c33099a6dd3696077969a01da2c5b29858d7d3fa10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7a4d30d25b04a755e0216ff3e5210df4e5efc7e2ca0302152fe5e1a4efc0bb9c648ff5f513aa30474e8c6873b66776d5dc06b0d6e78296b063fb0c9baa670ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c87fcea06e01972d5ba759c573cb308553579c2ba2acf8b6b42484901f2a03be9593ed3caa7a62cc2cf7a4ba4f9359a6f93a9ab0546e22dbbef188da38eb940"} 10:49:20 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:20 INFO - Copying logs to upload dir... 10:49:20 INFO - mkdir: /builds/slave/test/build/upload/logs 10:49:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1672.644139 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-10-05 10:49:19.650806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 10:49:19.654147) ========= 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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 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/58b48b4d4ab8980b72cfa367e4f177c525c7479e75d9ea287a57249525f66fd34fbecb94fef5f1dbd5357c33099a6dd3696077969a01da2c5b29858d7d3fa10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7a4d30d25b04a755e0216ff3e5210df4e5efc7e2ca0302152fe5e1a4efc0bb9c648ff5f513aa30474e8c6873b66776d5dc06b0d6e78296b063fb0c9baa670ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c87fcea06e01972d5ba759c573cb308553579c2ba2acf8b6b42484901f2a03be9593ed3caa7a62cc2cf7a4ba4f9359a6f93a9ab0546e22dbbef188da38eb940"} build_url:https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/O-eem0s3TgC62wCVGnzWBA/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/58b48b4d4ab8980b72cfa367e4f177c525c7479e75d9ea287a57249525f66fd34fbecb94fef5f1dbd5357c33099a6dd3696077969a01da2c5b29858d7d3fa10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7a4d30d25b04a755e0216ff3e5210df4e5efc7e2ca0302152fe5e1a4efc0bb9c648ff5f513aa30474e8c6873b66776d5dc06b0d6e78296b063fb0c9baa670ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c87fcea06e01972d5ba759c573cb308553579c2ba2acf8b6b42484901f2a03be9593ed3caa7a62cc2cf7a4ba4f9359a6f93a9ab0546e22dbbef188da38eb940"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 10:49:19.679732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:49:19.680084) ========= 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=1f5564757af27664ca02d71f000001b0-1475688084.846846-1508261359 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:49:19.762858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 10:49:19.763243) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 10:49:19.763595) ========= ========= Total master_lag: 0.17 =========