builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0279 starttime: 1468373146.86 results: success (0) buildid: 20160712161824 builduid: 12ea95c8276d4112ad12035cb26e341f revision: 56d636b5d961f3accab65064e7f81be1f78ad81e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.863786) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.864199) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.864474) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.887060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.887365) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-12 18:25:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to 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% 21.7M=0.001s 2016-07-12 18:25:46 (21.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088375 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.997382) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:46.997695) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020161 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.046859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.047199) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 56d636b5d961f3accab65064e7f81be1f78ad81e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 56d636b5d961f3accab65064e7f81be1f78ad81e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-12 18:25:47,126 truncating revision to first 12 chars 2016-07-12 18:25:47,126 Setting DEBUG logging. 2016-07-12 18:25:47,126 attempt 1/10 2016-07-12 18:25:47,126 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/56d636b5d961?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 18:25:47,771 unpacking tar archive at: fx-team-56d636b5d961/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924129 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.995024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.995563) ========= script_repo_revision: 56d636b5d961f3accab65064e7f81be1f78ad81e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.996203) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:47.996636) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:25:48.015501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 18:25:48.015826) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:25:48 INFO - MultiFileLogger online at 20160712 18:25:48 in /builds/slave/test 18:25:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:25:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:25:48 INFO - {'append_to_log': False, 18:25:48 INFO - 'base_work_dir': '/builds/slave/test', 18:25:48 INFO - 'blob_upload_branch': 'Fx-Team', 18:25:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:25:48 INFO - 'branch': 'Fx-Team', 18:25:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:25:48 INFO - 'config_files': ('talos/mac_config.py',), 18:25:48 INFO - 'default_actions': ('clobber', 18:25:48 INFO - 'read-buildbot-config', 18:25:48 INFO - 'download-and-extract', 18:25:48 INFO - 'populate-webroot', 18:25:48 INFO - 'create-virtualenv', 18:25:48 INFO - 'install', 18:25:48 INFO - 'run-tests'), 18:25:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:25:48 INFO - 'download_minidump_stackwalk': True, 18:25:48 INFO - 'download_symbols': 'ondemand', 18:25:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:25:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:25:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:25:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:25:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:25:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:25:48 INFO - 'installer_path': 'installer.exe', 18:25:48 INFO - 'log_level': 'info', 18:25:48 INFO - 'log_name': 'talos', 18:25:48 INFO - 'log_to_console': True, 18:25:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:25:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:25:48 INFO - 'opt_config_files': (), 18:25:48 INFO - 'pip_index': False, 18:25:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:48 INFO - 'cmd': ('bash', 18:25:48 INFO - '-c', 18:25:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:48 INFO - 'enabled': True, 18:25:48 INFO - 'halt_on_failure': False, 18:25:48 INFO - 'name': 'check_screen_resolution'},), 18:25:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:48 INFO - 'cmd': ('bash', 18:25:48 INFO - '-c', 18:25:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:48 INFO - 'enabled': True, 18:25:48 INFO - 'halt_on_failure': False, 18:25:48 INFO - 'name': 'check_screen_resolution'},), 18:25:48 INFO - 'run_cmd_checks_enabled': True, 18:25:48 INFO - 'sps_profile': False, 18:25:48 INFO - 'sps_profile_interval': 0, 18:25:48 INFO - 'suite': 'dromaeojs', 18:25:48 INFO - 'system_bits': '32', 18:25:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:25:48 INFO - 'title': 't-yosemite-r7-0279', 18:25:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:25:48 INFO - 'use_talos_json': True, 18:25:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:25:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:25:48 INFO - 'work_dir': 'build'} 18:25:48 INFO - ##### 18:25:48 INFO - ##### Running clobber step. 18:25:48 INFO - ##### 18:25:48 INFO - Running pre-action listener: _resource_record_pre_action 18:25:48 INFO - Running main action method: clobber 18:25:48 INFO - rmtree: /builds/slave/test/build 18:25:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:25:49 INFO - Running post-action listener: _resource_record_post_action 18:25:49 INFO - ##### 18:25:49 INFO - ##### Finished clobber step (success) 18:25:49 INFO - ##### 18:25:49 INFO - ##### 18:25:49 INFO - ##### Running read-buildbot-config step. 18:25:49 INFO - ##### 18:25:49 INFO - Running pre-action listener: _resource_record_pre_action 18:25:49 INFO - Running main action method: read_buildbot_config 18:25:49 INFO - Using buildbot properties: 18:25:49 INFO - { 18:25:49 INFO - "project": "", 18:25:49 INFO - "product": "firefox", 18:25:49 INFO - "script_repo_revision": "production", 18:25:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:25:49 INFO - "repository": "", 18:25:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:25:49 INFO - "buildid": "20160712161824", 18:25:49 INFO - "pgo_build": "False", 18:25:49 INFO - "basedir": "/builds/slave/test", 18:25:49 INFO - "buildnumber": 101, 18:25:49 INFO - "slavename": "t-yosemite-r7-0279", 18:25:49 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:25:49 INFO - "platform": "yosemite_r7", 18:25:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:25:49 INFO - "branch": "fx-team", 18:25:49 INFO - "repo_path": "integration/fx-team", 18:25:49 INFO - "revision": "56d636b5d961f3accab65064e7f81be1f78ad81e", 18:25:49 INFO - "stage_platform": "macosx64", 18:25:49 INFO - "builduid": "12ea95c8276d4112ad12035cb26e341f", 18:25:49 INFO - "slavebuilddir": "test" 18:25:49 INFO - } 18:25:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:25:49 INFO - Running post-action listener: _resource_record_post_action 18:25:49 INFO - ##### 18:25:49 INFO - ##### Finished read-buildbot-config step (success) 18:25:49 INFO - ##### 18:25:49 INFO - ##### 18:25:49 INFO - ##### Running download-and-extract step. 18:25:49 INFO - ##### 18:25:49 INFO - Running pre-action listener: _resource_record_pre_action 18:25:49 INFO - Running main action method: download_and_extract 18:25:49 INFO - mkdir: /builds/slave/test/build/tests 18:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:25:49 INFO - https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:25:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:25:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:25:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 18:25:51 INFO - Downloaded 1199 bytes. 18:25:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:25:51 INFO - Using the following test package requirements: 18:25:51 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:25:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:25:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:25:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'jsshell-mac.zip'], 18:25:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:25:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:25:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:25:51 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:25:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:25:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:25:51 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:25:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:25:51 INFO - https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:25:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:53 INFO - Downloaded 18822425 bytes. 18:25:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:25:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:25:56 INFO - Return code: 0 18:25:56 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:25:56 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')]} 18:25:56 INFO - https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:25:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:25:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:58 INFO - Downloaded 18822425 bytes. 18:25:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:25:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:26:01 INFO - Return code: 0 18:26:01 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')]} 18:26:01 INFO - https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:26:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:26:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:26:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:26:02 INFO - Downloaded 14403317 bytes. 18:26:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:26:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:26:02 INFO - Return code: 0 18:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:02 INFO - https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:26:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:26:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:26:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 18:26:05 INFO - Downloaded 112416168 bytes. 18:26:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:26:05 INFO - mkdir: /builds/slave/test/properties 18:26:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:26:05 INFO - Writing to file /builds/slave/test/properties/build_url 18:26:05 INFO - Contents: 18:26:05 INFO - build_url:https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:26:06 INFO - Running post-action listener: _resource_record_post_action 18:26:06 INFO - Running post-action listener: set_extra_try_arguments 18:26:06 INFO - ##### 18:26:06 INFO - ##### Finished download-and-extract step (success) 18:26:06 INFO - ##### 18:26:06 INFO - ##### 18:26:06 INFO - ##### Running populate-webroot step. 18:26:06 INFO - ##### 18:26:06 INFO - Running pre-action listener: _resource_record_pre_action 18:26:06 INFO - Running main action method: populate_webroot 18:26:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:06 INFO - '--tppagecycles', 18:26:06 INFO - '7'], 18:26:06 INFO - 'tests': ['tsvgm']}}, 18:26:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:26:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:26:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:26:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['damp', 'tps']}, 18:26:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'tests': ['damp', 'tps']}, 18:26:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['dromaeo_dom']}, 18:26:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['basic_compositor_video']}, 18:26:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:26:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'other-e10s': {'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['a11yr', 18:26:06 INFO - 'ts_paint', 18:26:06 INFO - 'tpaint', 18:26:06 INFO - 'sessionrestore', 18:26:06 INFO - 'sessionrestore_no_auto_restore', 18:26:06 INFO - 'tabpaint']}, 18:26:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['tsvgx', 18:26:06 INFO - 'tsvgr_opacity', 18:26:06 INFO - 'tart', 18:26:06 INFO - 'tscrollx', 18:26:06 INFO - 'cart']}, 18:26:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:26:06 INFO - 'tsvgr_opacity', 18:26:06 INFO - 'tart', 18:26:06 INFO - 'tscrollx', 18:26:06 INFO - 'cart']}, 18:26:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'talos_options': ['--disable-e10s'], 18:26:06 INFO - 'tests': ['tp5o']}, 18:26:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'tests': ['tp5o']}, 18:26:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'talos_options': ['--disable-e10s', 18:26:06 INFO - '--xperf_path', 18:26:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:06 INFO - 'tests': ['tp5n']}, 18:26:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:06 INFO - 'talos_options': ['--xperf_path', 18:26:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:06 INFO - 'tests': ['tp5n']}}, 18:26:06 INFO - 'talos.zip': {'path': '', 18:26:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:06 INFO - Running post-action listener: _resource_record_post_action 18:26:06 INFO - ##### 18:26:06 INFO - ##### Finished populate-webroot step (success) 18:26:06 INFO - ##### 18:26:06 INFO - ##### 18:26:06 INFO - ##### Running create-virtualenv step. 18:26:06 INFO - ##### 18:26:06 INFO - Running pre-action listener: _resource_record_pre_action 18:26:06 INFO - Running main action method: create_virtualenv 18:26:06 INFO - Creating virtualenv /builds/slave/test/build/venv 18:26: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 18:26:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:26:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:07 INFO - Using real prefix '/tools/python27' 18:26:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:26:07 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:10 INFO - Installing pip.................done. 18:26:10 INFO - Return code: 0 18:26:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:26:10 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')]} 18:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:10 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')]} 18:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:10 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:10 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 18:26:10 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 18:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:10 INFO - 'HOME': '/Users/cltbld', 18:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:10 INFO - 'LOGNAME': 'cltbld', 18:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:10 INFO - 'MOZ_NO_REMOTE': '1', 18:26:10 INFO - 'NO_EM_RESTART': '1', 18:26:10 INFO - 'PAGER': '/bin/cat', 18:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:10 INFO - 'PWD': '/builds/slave/test', 18:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:10 INFO - 'SHELL': '/bin/bash', 18:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:10 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:10 INFO - 'USER': 'cltbld', 18:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:10 INFO - 'XPC_FLAGS': '0x0', 18:26:10 INFO - 'XPC_SERVICE_NAME': '0', 18:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:10 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 18:26:10 INFO - Cleaning up... 18:26:10 INFO - Return code: 0 18:26:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:26:10 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')]} 18:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:10 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')]} 18:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:10 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:10 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 18:26:10 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 18:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:10 INFO - 'HOME': '/Users/cltbld', 18:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:10 INFO - 'LOGNAME': 'cltbld', 18:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:10 INFO - 'MOZ_NO_REMOTE': '1', 18:26:10 INFO - 'NO_EM_RESTART': '1', 18:26:10 INFO - 'PAGER': '/bin/cat', 18:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:10 INFO - 'PWD': '/builds/slave/test', 18:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:10 INFO - 'SHELL': '/bin/bash', 18:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:10 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:10 INFO - 'USER': 'cltbld', 18:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:10 INFO - 'XPC_FLAGS': '0x0', 18:26:10 INFO - 'XPC_SERVICE_NAME': '0', 18:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:11 INFO - Downloading/unpacking psutil>=3.1.1 18:26:11 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 18:26:11 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 18:26:11 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 18:26:11 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 18:26:11 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 18:26:11 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 18:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:26:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:14 INFO - Installing collected packages: psutil 18:26:14 INFO - Running setup.py install for psutil 18:26:14 INFO - building 'psutil._psutil_osx' extension 18:26:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:26:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:26:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:26:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:26:14 INFO - building 'psutil._psutil_posix' extension 18:26:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:26:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:26:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:26:14 INFO - ^ 18:26:14 INFO - 1 warning generated. 18:26:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:26:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:15 INFO - Successfully installed psutil 18:26:15 INFO - Cleaning up... 18:26:15 INFO - Return code: 0 18:26:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:26:15 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')]} 18:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:15 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')]} 18:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:15 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:15 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 18:26:15 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 18:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:15 INFO - 'HOME': '/Users/cltbld', 18:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:15 INFO - 'LOGNAME': 'cltbld', 18:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:15 INFO - 'MOZ_NO_REMOTE': '1', 18:26:15 INFO - 'NO_EM_RESTART': '1', 18:26:15 INFO - 'PAGER': '/bin/cat', 18:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:15 INFO - 'PWD': '/builds/slave/test', 18:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:15 INFO - 'SHELL': '/bin/bash', 18:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:15 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:15 INFO - 'USER': 'cltbld', 18:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:15 INFO - 'XPC_FLAGS': '0x0', 18:26:15 INFO - 'XPC_SERVICE_NAME': '0', 18:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:26:15 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 18:26:15 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 18:26:15 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 18:26:15 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 18:26:15 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 18:26:15 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 18:26:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:26:17 INFO - Installing collected packages: mozsystemmonitor 18:26:17 INFO - Running setup.py install for mozsystemmonitor 18:26:18 INFO - Successfully installed mozsystemmonitor 18:26:18 INFO - Cleaning up... 18:26:18 INFO - Return code: 0 18:26:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:26:18 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')]} 18:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:18 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')]} 18:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:18 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:18 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 18:26:18 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 18:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:18 INFO - 'HOME': '/Users/cltbld', 18:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:18 INFO - 'LOGNAME': 'cltbld', 18:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:18 INFO - 'MOZ_NO_REMOTE': '1', 18:26:18 INFO - 'NO_EM_RESTART': '1', 18:26:18 INFO - 'PAGER': '/bin/cat', 18:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:18 INFO - 'PWD': '/builds/slave/test', 18:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:18 INFO - 'SHELL': '/bin/bash', 18:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:18 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:18 INFO - 'USER': 'cltbld', 18:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:18 INFO - 'XPC_FLAGS': '0x0', 18:26:18 INFO - 'XPC_SERVICE_NAME': '0', 18:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:18 INFO - Downloading/unpacking jsonschema==2.5.1 18:26:18 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 18:26:18 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 18:26:18 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 18:26:18 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 18:26:18 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 18:26:18 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 18:26:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:26:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:26:20 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 18:26:20 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 18:26:20 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 18:26:20 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 18:26:20 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 18:26:20 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 18:26:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:26:21 INFO - warning: no files found matching '*.txt' 18:26:21 INFO - no previously-included directories found matching 'build' 18:26:21 INFO - no previously-included directories found matching 'dist' 18:26:21 INFO - no previously-included directories found matching '.git*' 18:26:21 INFO - Installing collected packages: jsonschema, functools32 18:26:21 INFO - Running setup.py install for functools32 18:26:21 INFO - warning: no files found matching '*.txt' 18:26:21 INFO - no previously-included directories found matching 'build' 18:26:21 INFO - no previously-included directories found matching 'dist' 18:26:21 INFO - no previously-included directories found matching '.git*' 18:26:21 INFO - Successfully installed jsonschema functools32 18:26:21 INFO - Cleaning up... 18:26:21 INFO - Return code: 0 18:26:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:21 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 18:26:21 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 18:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:21 INFO - 'HOME': '/Users/cltbld', 18:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:21 INFO - 'LOGNAME': 'cltbld', 18:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:21 INFO - 'MOZ_NO_REMOTE': '1', 18:26:21 INFO - 'NO_EM_RESTART': '1', 18:26:21 INFO - 'PAGER': '/bin/cat', 18:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:21 INFO - 'PWD': '/builds/slave/test', 18:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:21 INFO - 'SHELL': '/bin/bash', 18:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:21 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:21 INFO - 'USER': 'cltbld', 18:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:21 INFO - 'XPC_FLAGS': '0x0', 18:26:21 INFO - 'XPC_SERVICE_NAME': '0', 18:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:21 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:21 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 18:26:21 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 18:26:21 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 18:26:21 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 18:26:21 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 18:26:21 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 18:26:24 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:24 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 18:26:24 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 18:26:24 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 18:26:24 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 18:26:24 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 18:26:24 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 18:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26: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 18:26:25 INFO - Downloading docopt-0.6.1.tar.gz 18:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:25 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:25 INFO - Running setup.py install for blobuploader 18:26:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:25 INFO - Running setup.py install for requests 18:26:26 INFO - Running setup.py install for docopt 18:26:26 INFO - Successfully installed blobuploader requests docopt 18:26:26 INFO - Cleaning up... 18:26:26 INFO - Return code: 0 18:26:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:26 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')]} 18:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:26 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')]} 18:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:26 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:26 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 18:26:26 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 18:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:26 INFO - 'HOME': '/Users/cltbld', 18:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:26 INFO - 'LOGNAME': 'cltbld', 18:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:26 INFO - 'MOZ_NO_REMOTE': '1', 18:26:26 INFO - 'NO_EM_RESTART': '1', 18:26:26 INFO - 'PAGER': '/bin/cat', 18:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:26 INFO - 'PWD': '/builds/slave/test', 18:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:26 INFO - 'SHELL': '/bin/bash', 18:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:26 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:26 INFO - 'USER': 'cltbld', 18:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:26 INFO - 'XPC_FLAGS': '0x0', 18:26:26 INFO - 'XPC_SERVICE_NAME': '0', 18:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:26 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-TBatXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:26 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-2jwvPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:26 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-zbTTxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-l5jI8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-5vC8PS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-S80Fpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-YnwEXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-o3owAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-pCXJvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-YrZ81F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-AjsKsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-O8QTP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:27 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-iX9Qlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:28 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-LK1MEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:28 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-ch_oQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:28 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-B7X1QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:28 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-OzIA4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:28 INFO - Running setup.py install for manifestparser 18:26:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:28 INFO - Running setup.py install for mozcrash 18:26:28 INFO - Running setup.py install for mozdebug 18:26:28 INFO - Running setup.py install for mozdevice 18:26:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Running setup.py install for mozfile 18:26:29 INFO - Running setup.py install for mozhttpd 18:26:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Running setup.py install for mozinfo 18:26:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Running setup.py install for mozInstall 18:26:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Running setup.py install for mozleak 18:26:29 INFO - Running setup.py install for mozlog 18:26:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:26:29 INFO - Running setup.py install for moznetwork 18:26:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:26:30 INFO - Running setup.py install for mozprocess 18:26:30 INFO - Running setup.py install for mozprofile 18:26:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:26:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:26:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:26:30 INFO - Running setup.py install for mozrunner 18:26:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:26:30 INFO - Running setup.py install for mozscreenshot 18:26:30 INFO - Running setup.py install for moztest 18:26:30 INFO - Running setup.py install for mozversion 18:26:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:26:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:26:31 INFO - Cleaning up... 18:26:31 INFO - Return code: 0 18:26:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:31 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:31 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 18:26:31 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 18:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:31 INFO - 'HOME': '/Users/cltbld', 18:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:31 INFO - 'LOGNAME': 'cltbld', 18:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:31 INFO - 'MOZ_NO_REMOTE': '1', 18:26:31 INFO - 'NO_EM_RESTART': '1', 18:26:31 INFO - 'PAGER': '/bin/cat', 18:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:31 INFO - 'PWD': '/builds/slave/test', 18:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:31 INFO - 'SHELL': '/bin/bash', 18:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:31 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:31 INFO - 'USER': 'cltbld', 18:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:31 INFO - 'XPC_FLAGS': '0x0', 18:26:31 INFO - 'XPC_SERVICE_NAME': '0', 18:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-kOX1AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-3wyaCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-cD6guz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-E34Hsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-gU9Skg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-cobPhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:31 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)) 18:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:31 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-TZM5Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-vNQwex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-jA7bQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-Bg_ABe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-cF7n1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-Z261cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-qQgvpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-_xhgxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-lSYggw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:32 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)) 18:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:32 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-RW3HEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:33 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)) 18:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:33 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-CFSJP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:33 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)) 18:26:33 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)) 18:26:33 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)) 18:26:33 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)) 18:26:33 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)) 18:26:33 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)) 18:26:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:35 INFO - Downloading blessings-1.6.tar.gz 18:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:26:35 INFO - Installing collected packages: blessings 18:26:35 INFO - Running setup.py install for blessings 18:26:35 INFO - Successfully installed blessings 18:26:35 INFO - Cleaning up... 18:26:35 INFO - Return code: 0 18:26:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:36 INFO - Reading from file tmpfile_stdout 18:26:36 INFO - Current package versions: 18:26:36 INFO - blessings == 1.6 18:26:36 INFO - blobuploader == 1.2.4 18:26:36 INFO - docopt == 0.6.1 18:26:36 INFO - functools32 == 3.2.3-2 18:26:36 INFO - jsonschema == 2.5.1 18:26:36 INFO - manifestparser == 1.1 18:26:36 INFO - mozInstall == 1.12 18:26:36 INFO - mozcrash == 0.17 18:26:36 INFO - mozdebug == 0.1 18:26:36 INFO - mozdevice == 0.48 18:26:36 INFO - mozfile == 1.2 18:26:36 INFO - mozhttpd == 0.7 18:26:36 INFO - mozinfo == 0.9 18:26:36 INFO - mozleak == 0.1 18:26:36 INFO - mozlog == 3.2 18:26:36 INFO - moznetwork == 0.27 18:26:36 INFO - mozprocess == 0.23 18:26:36 INFO - mozprofile == 0.28 18:26:36 INFO - mozrunner == 6.12 18:26:36 INFO - mozscreenshot == 0.1 18:26:36 INFO - mozsystemmonitor == 0.3 18:26:36 INFO - moztest == 0.7 18:26:36 INFO - mozversion == 1.4 18:26:36 INFO - psutil == 3.1.1 18:26:36 INFO - requests == 1.2.3 18:26:36 INFO - wsgiref == 0.1.2 18:26:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:36 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 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:36 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 18:26:36 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 18:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:36 INFO - 'HOME': '/Users/cltbld', 18:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:36 INFO - 'LOGNAME': 'cltbld', 18:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:36 INFO - 'MOZ_NO_REMOTE': '1', 18:26:36 INFO - 'NO_EM_RESTART': '1', 18:26:36 INFO - 'PAGER': '/bin/cat', 18:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:36 INFO - 'PWD': '/builds/slave/test', 18:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:36 INFO - 'SHELL': '/bin/bash', 18:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:36 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:36 INFO - 'USER': 'cltbld', 18:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:36 INFO - 'XPC_FLAGS': '0x0', 18:26:36 INFO - 'XPC_SERVICE_NAME': '0', 18:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 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)) 18:26:36 INFO - Cleaning up... 18:26:36 INFO - Return code: 0 18:26:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103932a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8cb4ad8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:26:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:36 INFO - 'HOME': '/Users/cltbld', 18:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:36 INFO - 'LOGNAME': 'cltbld', 18:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:36 INFO - 'MOZ_NO_REMOTE': '1', 18:26:36 INFO - 'NO_EM_RESTART': '1', 18:26:36 INFO - 'PAGER': '/bin/cat', 18:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:36 INFO - 'PWD': '/builds/slave/test', 18:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:36 INFO - 'SHELL': '/bin/bash', 18:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:26:36 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:36 INFO - 'USER': 'cltbld', 18:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:36 INFO - 'XPC_FLAGS': '0x0', 18:26:36 INFO - 'XPC_SERVICE_NAME': '0', 18:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:26:36 INFO - Cleaning up... 18:26:36 INFO - Return code: 0 18:26:36 INFO - Running post-action listener: _resource_record_post_action 18:26:36 INFO - Running post-action listener: _start_resource_monitoring 18:26:36 INFO - Starting resource monitoring. 18:26:36 INFO - ##### 18:26:36 INFO - ##### Finished create-virtualenv step (success) 18:26:36 INFO - ##### 18:26:36 INFO - ##### 18:26:36 INFO - ##### Running install step. 18:26:36 INFO - ##### 18:26:36 INFO - Running pre-action listener: _resource_record_pre_action 18:26:36 INFO - Running main action method: install 18:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:36 INFO - Reading from file tmpfile_stdout 18:26:36 INFO - Detecting whether we're running mozinstall >=1.0... 18:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:26:37 INFO - Reading from file tmpfile_stdout 18:26:37 INFO - Output received: 18:26:37 INFO - Usage: mozinstall [options] installer 18:26:37 INFO - Options: 18:26:37 INFO - -h, --help show this help message and exit 18:26:37 INFO - -d DEST, --destination=DEST 18:26:37 INFO - Directory to install application into. [default: 18:26:37 INFO - "/builds/slave/test"] 18:26:37 INFO - --app=APP Application being installed. [default: firefox] 18:26:37 INFO - mkdir: /builds/slave/test/build/application 18:26:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:27:11 INFO - Reading from file tmpfile_stdout 18:27:11 INFO - Output received: 18:27:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:27:11 INFO - Running post-action listener: _resource_record_post_action 18:27:11 INFO - ##### 18:27:11 INFO - ##### Finished install step (success) 18:27:11 INFO - ##### 18:27:11 INFO - ##### 18:27:11 INFO - ##### Running run-tests step. 18:27:11 INFO - ##### 18:27:11 INFO - Running pre-action listener: _resource_record_pre_action 18:27:11 INFO - Running main action method: run_tests 18:27:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:27:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:27:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:27:11 INFO - 2016-07-12 18:27:11.621 screenresolution[1932:18993] starting screenresolution argv=screenresolution get 18:27:11 INFO - 2016-07-12 18:27:11.624 screenresolution[1932:18993] Display 0: 1600x1200x32@60 18:27:11 INFO - 2016-07-12 18:27:11.631 screenresolution[1933:18996] starting screenresolution argv=screenresolution list 18:27:11 INFO - Available Modes on Display 0 18:27:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:27:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:27:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:27:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:27:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:27:11 INFO - Intel Iris: 18:27:11 INFO - Chipset Model: Intel Iris 18:27:11 INFO - Type: GPU 18:27:11 INFO - Bus: Built-In 18:27:11 INFO - VRAM (Dynamic, Max): 1536 MB 18:27:11 INFO - Vendor: Intel (0x8086) 18:27:11 INFO - Device ID: 0x0a2e 18:27:11 INFO - Revision ID: 0x0009 18:27:11 INFO - Displays: 18:27:11 INFO - Display: 18:27:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:27:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:27:11 INFO - Display Serial Number: 1600x1200 60 18:27:11 INFO - Main Display: Yes 18:27:11 INFO - Mirror: Off 18:27:11 INFO - Online: Yes 18:27:11 INFO - Rotation: Supported 18:27:11 INFO - Return code: 0 18:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:27:11 INFO - Python 2.7.3 18:27:11 INFO - Return code: 0 18:27:11 INFO - grabbing minidump binary from tooltool 18:27:11 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')]} 18:27:11 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/macosx64/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 0x103896d30>, '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 0x1035fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10388eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:27:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:27:11 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:27:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:27:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:11 INFO - Return code: 0 18:27:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:27:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:27:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:27:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:27:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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 18:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0279 --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 18:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render', 18:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:11 INFO - 'HOME': '/Users/cltbld', 18:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:11 INFO - 'LOGNAME': 'cltbld', 18:27:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:27:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:11 INFO - 'MOZ_NO_REMOTE': '1', 18:27:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:27:11 INFO - 'NO_EM_RESTART': '1', 18:27:11 INFO - 'PAGER': '/bin/cat', 18:27:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:11 INFO - 'PWD': '/builds/slave/test', 18:27:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:11 INFO - 'SHELL': '/bin/bash', 18:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners', 18:27:11 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 18:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:11 INFO - 'USER': 'cltbld', 18:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:11 INFO - 'XPC_FLAGS': '0x0', 18:27:11 INFO - 'XPC_SERVICE_NAME': '0', 18:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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 18:27:12 INFO - mozversion application_buildid: 20160712161824 18:27:12 INFO - mozversion application_changeset: 56d636b5d961f3accab65064e7f81be1f78ad81e 18:27:12 INFO - mozversion application_display_name: Nightly 18:27:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:12 INFO - mozversion application_name: Firefox 18:27:12 INFO - mozversion application_remotingname: firefox 18:27:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:27:12 INFO - mozversion application_vendor: Mozilla 18:27:12 INFO - mozversion application_version: 50.0a1 18:27:12 INFO - mozversion platform_buildid: 20160712161824 18:27:12 INFO - mozversion platform_changeset: 56d636b5d961f3accab65064e7f81be1f78ad81e 18:27:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:27:12 INFO - mozversion platform_version: 50.0a1 18:27:12 INFO - using testdate: 1468373232 18:27:12 INFO - actual date: 1468373232 18:27:12 INFO - starting webserver on 'localhost:54494' 18:27:12 INFO - SUITE-START | Running 2 tests 18:27:12 INFO - TEST-START | dromaeo_css 18:27:12 INFO - operating with platform_type : mac_ 18:27:12 INFO - Initialising browser for dromaeo_css test... 18:27:12 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpAEoiBT/profile http://localhost:54494/getInfo.html) 18:27:14 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 18:27:14 INFO - PROCESS | 1943 | colorDepth:24 18:27:14 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 18:27:14 INFO - PROCESS | 1943 | __metrics 18:27:14 INFO - PROCESS | 1943 | 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" 18:27:14 INFO - PROCESS | 1943 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:15 INFO - TEST-INFO | 1943: exit 0 18:27:15 INFO - Browser initialized. 18:27:15 INFO - Running cycle 1/1 for dromaeo_css test... 18:27:15 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpAEoiBT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:27 INFO - PROCESS | 1945 | RSS: Main: 243609600 18:27:27 INFO - PROCESS | 1945 | 18:28:18 INFO - PROCESS | 1945 | 1468373298950 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] 18:30:29 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54494/tests/dromaeo/cssquery-ext.html) 18:30:31 INFO - PROCESS | 1945 | RSS: Main: 385146880 18:30:31 INFO - PROCESS | 1945 | 18:33:34 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-ext.html (next: http://localhost:54494/tests/dromaeo/cssquery-jquery.html) 18:33:35 INFO - PROCESS | 1945 | RSS: Main: 384172032 18:33:35 INFO - PROCESS | 1945 | 18:36:37 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54494/tests/dromaeo/cssquery-mootools.html) 18:36:37 INFO - PROCESS | 1945 | RSS: Main: 375603200 18:36:37 INFO - PROCESS | 1945 | 18:39:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54494/tests/dromaeo/cssquery-prototype.html) 18:39:40 INFO - PROCESS | 1945 | RSS: Main: 373923840 18:39:40 INFO - PROCESS | 1945 | 18:42:44 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54494/tests/dromaeo/cssquery-yui.html) 18:42:44 INFO - PROCESS | 1945 | RSS: Main: 347336704 18:42:44 INFO - PROCESS | 1945 | 18:45:47 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54494/tests/dromaeo/cssquery-yui.html (next: http://localhost:54494/tests/dromaeo/cssquery-dojo.html) 18:45:47 INFO - PROCESS | 1945 | RSS: Main: 423247872 18:45:47 INFO - PROCESS | 1945 | 18:45:47 INFO - PROCESS | 1945 | __start_tp_report 18:45:47 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 18:45:47 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 18:45:47 INFO - PROCESS | 1945 | |i|pagename|runs| 18:45:47 INFO - PROCESS | 1945 | |0;dojo.html;2793.5251007328766;2812.001739382519;3136.560881476593;3314.834258287086;3336.8331583420822;5549.0289199390045;5592.217089607448;5640.323161220655;5739.56953228508;5748.856278593032;5632.239647647566;5684.346300175481;5688.459596338352;5689.772409103642;5707.828765137042;5142.562882155012;5179.611529135311;5248.317718696564;5250.579953603703;5285.920711189336;5245.108331583621;5295;5299.417064122963;5302.787888484456;5305.946940530604;5188;5203.349581302337;5212.5308722214995;5217.869553261161;5281.260623512711;5291.841244762663;5312.335958005249;5322.893542129174;5325.3875804282225;5401.810936617199;5091.083604951107;5099.617528685363;5105.897882042338;5219.060569097561;5225.581953443715;5611.046122159202;5645.463680950304;5681.431856814327;5682.687452190128;5685.857853553694;6255.624662520263;6265.216847894013;6276.340984196685;6277.246730392337;6282.246130464374;6094.531679962716;6202.503799696014;6263.185785847811;6265.436110750009;6282.057691346289;5128.666636668605;5814.447627475383;5890.263717035371;5890.352061273256;5915.881682366328;6262.780802671885;6282.465990390777;6283.654399008007;6290.496760259169;6290.622562646256;6019.879602407927;6079.6960151992225;6100.755969761249;6137.039921714258;6188.535859810532;6260.593061450991;6263.906041409383;6264.749410023548;6264.968675156595;6266.592671476339;6141.84645383869;6204.5967012144065;6213.161223234896;6218.689065546795;6223.564350495512;6042.728077236535;6115.4801841843055;6119.938800612026;6148.508119350441;6265.561410701207;4202.306619407764;4761.262004389325;4860.416749990024;4869.074875773663;4877.975610121997;4368.847090351823;4404.559544045634;4415.094905544356;4440.755758433253;4477.910441791211;5786.739596718157;5797.449242321973;5813.924423981577;5820.82537523875;5879.235699359056;6132.355508992545;6300.779472718525;6306.59007164542;6366.713497892606;6375.458086062644;5659.490645841894;5746.913796292975;5756.5682573807135;5759.740811663485;5761.596688231867;5403.270558474555;5412.783488660488;5419.972900135474;5424.996375670435;5427.728613569306;6261.467775239155;6275.623462592259;6294.433500984934;6298.527610429328;6315.368463153648;6281.15204447412;6288.182536270256;6289.433950944254;6290.622562646256;6292.874142517216;6316.147320111726;6319.652419116809;6321.841953951188;6330.525210609315;6340.048992651139;6061.787837425669;6109.070667813169;6128.387161283836;6168.136460895568;6182.165407670087;6274.498040156868;6301.180846489927;6322.715477803602;6335.65153916539;6344.429001389897;6148.323684394623;6166.845828854135;6176.444120029039;6181.412765787243;6197.938020619736;5028.698278103326;5979.282486101695;6041.305249896318;6060.363661815446;6112.235970503687;6007.579469437097;6018.518518518596;6028.367021462684;6029.095635654687;6031.065184896348;4720.669553131385;6155.169052177898;6184.938150618436;6190.659513726789;6213.316535181217;5786.276715410574;5853.2976042875125;5908.7045647718305;5959.135925290722;5963 18:45:47 INFO - PROCESS | 1945 | |1;ext.html;485.0952972705902;495.5985651622186;504.9671771334864;525.7712894890723;528.7726277700592;30257.243568910762;31942.881999130033;41835.79082104593;42985.14029719404;42996.78501607488;44169;44432.77783611086;44462;44556.66330010097;44719;9625.133737963582;9818.705438836847;9820.11618954294;9831.410115393064;9833.803323933516;8734.995475520302;8933.77665558363;8934.508602026886;9049.728508144766;9050.27597792175;11749.706257343592;11978;11979.940100299485;12162.331071791048;12180.390980450986;10448.95510448949;12002;12032.939835300855;12137.878621213764;12155.39223038849;8602.225799677997;11592.76814463706;11834.526618935231;11896.405179741065;11985.760284794342;41887;42216.57783422188;42319.7884010578;42370.78814605938;42436.78781606072;3392.1180493071734;3589.4974703541525;3610.9277814443826;3749.4938183345444;3875.3218186817194;31929.84035079833;32998.34003319944;33241.83379083113;33450.49824252638;33450.83274583612;2665.627201990976;2961.881524739008;3048.2988912550018;3048.314129320885;3050.771192160597;533191;537129;537653;537940.31029845;538980;60963;62346;62404.68797656074;62456;62472.687636561524;65322.67338663276;65368.67315663486;65396.67301663461;65435.345646542926;65510.344896551374;14106.435742570387;16662.916685416494;16883.493495195165;16886.662266754596;16891.324347026228;13089.345532723326;13159.342032898317;13167.209967401986;13187.076904616777;13200.47198112084;14562.053466524641;14895.404183832741;16200.675986480204;16606.9205501642;16840.15799210059;6282.654454004982;6571.7185148895505;6946.37482626566;7024.578525288499;7034.2262351141335;952.5996784726441;965.4207475514635;969.2004096620317;1000.6997900629782;1024.3085917005992;24898.502029959298;29840.9555665555;30753.384932301677;31088.68911310858;31961.680383196337;1072.5816931396762;1102.9117670586334;1109.3343993603773;1138.9806123519404;1184.2480025183897;13180.934095329461;15838.287277072559;15953.441629542911;15955.920220398824;16518.42185523501;682655;682901;688556;689927;691490;52764;60056.699716501134;60662.69668651628;60940.08589871156;61539.692301538205;1384.6053874645677;1393;1416.730821144;1417.2205287092265;1420.6235347632885;31360;32124.518132227568;32296;32946.67053329436;33162.83418582891;57152.714236428554;59005.40994589999;59031.70484147552;62869.37130628635;62908.37091629208;626648;641065;643324;644809;644838;26877.462450750485;27256.318592035357;27303.453930921667;27314.863425682743;27439;49353;49450.50549494603;50059;50883.47349579519;51053.74473127611;40396.78809635716;40636;40808.79595602003;41533.79233103815;41650;31471.37057258888;40334;40625.18749624932;41026.38460423036;41903.16193676049;39340.01649958774;39900.40149397819;40157.795266142064;40217.79891100526;40247.1950560993;29164.27089322784;29735.25661858471;34474.655253448145;36350.454743179405;36631;21693.240736574146;24338.148164814145;24691;26164.60753088743;26289.948402064467 18:45:47 INFO - PROCESS | 1945 | |2;jquery.html;340.1479294367609;348.4685854072539;355.27523851343244;384.67494966753094;405.32715691951364;13199.604011879646;17460.214290356933;20557.588848223044;20821.479463013395;20989.895050524727;21094.363204599424;21164.047617857195;22063.33809985703;22194;22206.555868882613;5764.9423505765035;5953.1070339449;6161.445469907708;6166.044263139198;6948.235694073648;6886.24251332353;6906.482013848957;6959.652017399135;6961.930380696179;6964.89552656708;6620.139381880349;6734.0572319875255;6793.592384456923;6821.795346139624;6850.794476165723;5699.401562835926;6064.818055458343;6705.731770729165;6716.932830671704;6738.561993470434;5697.202391665171;5920;5920.259967504062;5932.466078052996;5945.583809133363;13188.934055329757;13473.461061557526;13745.725085498334;13747;13752.518661846787;2170.8588941718895;2248.5952528544854;2314.9652755208685;2335.486193037529;2892.5516544935567;9359.596060590899;10033.247506437046;10423;12113.69715757113;15865.682686346325;1842.3459671816447;1858.823411775879;1887.2451019592136;1890.2627975089727;1906.2470324221854;106740.18385945295;108676.4566177172;129201.7079829199;129486.70513294841;133529.6647033527;9086.772830679285;11615.883841161649;11701.771314011907;11728.94135529317;11846.289222646668;14594.85405145935;14715.337809798584;14744.852551474563;14759.704805903822;14764.26178691061;7314.670839812221;8523.701670441505;8744.642370487849;8776.736697899074;8871.556422178865;8805.42764720291;8807.559622018873;8841.911580884109;8947.955260223658;8965.820683586422;3816.904577385588;3852.5569559500454;4522.638188944942;4597.241455159928;4634.328022436795;1888.8488920886296;1930.980690193108;1938.6510428123074;1939.3309308288617;1939.6023815117867;841.5918279634373;898.3621628643606;959.4770849886828;967.3035414501547;977.7115750853516;20759.065842037144;21593.78406215949;22273.777262227493;22274.888625556767;22294.108235670716;847.9236868681849;934.7709811096325;935.4621092871625;944.187998321443;971.8396464583406;11325.980661740483;11467.369294688706;11487.25332853362;11522.827157592643;11592.76814463706;600933;607280.9635951791;609694;614036;623211;123870;125132.37433812773;127934;133833.33083334522;136773;954.5847556313026;967.4388854464394;996.1582462818907;998.5905778630727;1001.6594357918198;19267.036648167254;19590.11844466974;19748.40754777359;19872.801271987675;19943.800561994194;51668.22497662613;52196.47803521916;52734.208986866;55683.72158139183;57416.71291643515;76478;82685.58657206675;83329.75005375045;83401;83465;8239.752807415787;8250.669973201246;8362.916370836214;8378.413511054412;8523.573821309034;12750;13315.06794524374;13321.933390333374;13326.733465330833;13378.331083445983;11459.541618335174;11471.082313415074;11513.827292590786;11589.768204636028;11767.470463828977;9318.72043838686;9326.860097098684;11361.829572556253;11516;11550.018248448976;11604;11746.530138794353;11782;11882.940585297018;11920.986716128878;7752.612369381395;8191.426600138171;8233.423660343718;8278.544680042656;8453.323734101381;8324.417290789586;11028.28316159447;11413.372264525531;11567.479463423997;11679.299242045505 18:45:47 INFO - PROCESS | 1945 | |3;mootools.html;1364.5633397312852;1508.2308022908317;1557.057979922147;1724.9223784929677;1881.1534809335803;7774.8445031099345;8578.871316930243;8822.558872056403;8852.336074794384;8950.597223124958;8614.31085513159;8729.258013068897;8784.78038049049;8802.031776504595;8805.383623146383;2331.7668233176632;2362.397588614901;2364.302530753423;2367.3134790910703;2368.656544801003;2321.605327094398;2341.800946919514;2361.25620429565;2365.31405892291;2369.39580406996;2322.129201549419;2336.906523739048;2353.2469609724894;2358.834881558283;2374.382660508272;2316.6872472216205;2337;2346.378209774411;2349.8590084594894;2353.376355265839;2328.953420931572;2331.265651319829;2332.7433982261937;2334.8599084055013;2339.181286549711;8716.041235464094;8860;8894.86657700135;8933.910660893373;8946.23956963659;5183.170692689189;5367.490088441592;5370.113931201347;5383.838484845422;5387.245785589981;3545.663161999606;3556.2531868307688;3596.065023093988;3642.7085833133287;3649.8905032848793;1088.4992903264508;1091.2688498705807;1091.4379094766202;1111.4053981120062;1111.9388433636148;11635.360055196961;11743.001844843198;11751.177417580773;11752.177347585672;11758.294502329802;4342.69601127918;4369.3227549729845;4560.543945605479;4566.931496027562;4590.403247577793;9178.449293042439;9195.126462986005;9198.310126740522;9206.677766278148;9232.122948319899;5228.215767634809;5241.764120614582;5245.947540524546;5246.7638956247065;5248.580113590903;4402.845900393535;4544.795484203219;4740.5733483986605;4903.779329930162;4915;3732.0669832541867;3763.0780458787226;3824.6175382461533;3843.942340864889;3865.6714179294513;1145.5360578965365;1147.9713007174887;1151.5336288803048;1158.0330424095973;1162.8546431696038;654.780648482754;745.8396444764402;914.5564401265381;936.0873148680118;952.9094736000069;5934.673592952343;5980.252468441445;6018.909716354258;6023.096535519707;6116.602420842631;476.211869356216;492.92050409602854;493.3487796109101;498.0213879726283;639.309545690653;3059.265776213693;4040.515138183378;4046.6964977626794;4109.301418758818;4126.484189476316;9087.954560227157;9191.586378612978;10402.375857448576;10718.785624287626;10840.349579025125;10685.946570267099;10769.084627806567;10779;10795;10821.188410869216;7775.3390961767755;8182.959085204655;8191.754247372588;8201.179882011871;8207.917920820835;6098.634081955097;6170.907436388368;6239.126522286974;6272.121902933502;6398.456131228898;9993.10062094415;10123;10285.53715082808;10293.228007899288;10303.948480257552;9286.489243091424;9377.390469619453;10190.847137292798;10464.319819211727;10481.056704896597;6385.904211436739;7610.771676849703;7694.4229182810395;7718.652660630398;7761.107472640493;8303.709370172015;8420;8423.831523369621;8424.031236407647;8424.115467875785;6110.2973158085615;7434.925650743423;7548.358389536951;7636.31273185416;7708.03649543807;7396.0754905636795;7499.987501687202;7549.358304544176;7607.657655405409;7643.1974642663945;7064.894026589708;7121.46589005837;7172.497925145399;7178.676959536729;7197.208307086112;5016.097102521581;5036.169032109661;5108.055009823194;5146.665466744799;5182.378114626269;5133.459962011307;5216.243644671577;5225.92161117576;5278.577713782812;5303.204519322133 18:45:47 INFO - PROCESS | 1945 | |4;prototype.html;303.7084398976982;310.6225935488381;313.17322269209285;316.91918560767;320.5480272815331;4521.751303678297;4841.080194762997;5394.946050539494;5396.244525766396;5406.702631355279;5290.365156181264;5291.867703307419;5307.363116426025;5376.1398176291805;5401.621886467949;3850.711196660248;3887.241987812378;4009.3785463253244;4028.6374226319626;4057.2088442753675;3967.2065586882595;4030.879073627781;4038.6163314485075;4048.75707457552;4057.655099316547;3323.351946370459;3685.981570092146;3970.642642162205;3976.880693579197;4002.7198096133284;3791.4881490998637;3917.9216415671813;3918.529776426833;3989.4414781930554;4002;3485.721142308609;3513.2797776455764;3517.84169712361;3518.489818976234;3519.260955199411;4633.054117270754;5067.467915868818;5098.643094983353;5111.463296353866;5124.410692770304;4419.53594872537;4571.2000399929875;4587.302698001728;4617.699849509771;4625.8149674013;4595.725116067534;4836.129496690594;4944.629152813553;5153.561947234452;5495.697736624484;3460.653934606544;3750.624937506255;3858.884233472972;3890.1830615570766;3907;5590.2732644756325;5709.8287051388525;5757.769689212427;5804.216430781833;5840.532757379399;3948.6841052715713;3952.3478626026385;3982.283189026003;3988.3818008208773;4000.8199631016673;3405.540252065989;4039.2931237033404;4054.6350828425584;4054.655354294918;4071.328230841937;4457.68796184264;4602.631789456836;4620.561046700558;4630.333080091164;4655.534446555317;4364.890877728082;4776.952230477651;5512.476314750093;5516.096946553433;5630.5495560355075;1342.1678559293105;1383.174210042754;1387.7571425000585;1391.8517223290826;1397.9091359061629;797.8563858505524;825.8926339575937;873.7553485024204;881.6737807011447;881.8015946412006;5023.39719233687;5072.315237442898;5234.42421333653;5256.737163141904;5260.580622595344;4805.687630304019;5247.921281180786;5693;5708.857278567986;5739.713014349266;4871.34236878017;4918.6798910735115;5238.223741417055;5249.343832020997;5289.841304760864;2276.6015947209503;2823.4070845122756;2971.777116716255;2981.6272965879266;3013.1111322159577;5212.609054320941;5932.436418540232;5979.820605381846;5985.426266705358;5995.340512543617;5585.804496842591;5902.8819423611285;5980.3686377993545;6045.425684559959;6048.697565121726;6044.886883255215;6044.919679216423;6052.54605904559;6076.483498902554;6083.726232319556;5467.6719396836315;5790.7973220937065;5908.497777688945;5931.3178984417345;5955.612885162451;4828.8792780180775;5560.860978475571;5754.56840736938;5757.5393968481585;5792.451321418563;5254.60590455725;6053.213082299449;6075.756969721162;6083.8174854754425;6160.815175544741;3672.192117734094;3729.6083911188935;3877.554081280689;3884.669803066658;3914.5693973662305;5854.502367298827;5869.589128760946;5874.94125058744;5890.1164825276555;5891.675957822361;4960.379952505937;5701.657900525815;5713.971430142823;5727.36998930109;5727.770889164387;4681.321208424827;4693.55411235932;4891.364122664031;5289.5503882168905;5643;5241.475852414728;5291.365036195682;5389.089891458569;5465.7813687453645;5552.5846256878385;5309.681419114865;5506.807261745819;5574.637648552831;5664.875671623141;5676.545876329801;4589.5639914208095;4622.722636641678;4947.950520494749;5542;5548.334199895877 18:45:47 INFO - PROCESS | 1945 | |5;yui.html;710.7405796884135;718.1274751177319;792.4690457393549;795.5107608820578;839.035109623932;15140.924295378521;16163.757543636855;16175.19124043799;16181.757273640875;16198.595035124128;15620.921895390507;16053.117078560674;16053.598660033505;16179.433719819808;16223.51329460115;707.4870718728906;880.3617377401381;901.202435844276;909.2634965677789;910.2990697163181;729.9087614048244;856.3534531428762;889.9110088991082;893.9821203575924;895.3642913531401;928.726025822384;932.1889955738517;932.6409332407038;933.4212788071405;936.6487567162314;729.7774178875441;929.4005366538579;930.3952430919888;931.3480563605435;931.3713243560569;673.6429692263059;696.2863065357998;699;699.5348093517805;700.4361489001352;15262.618434539114;15263.160526171056;15293.770593440997;15301.46444874424;15305.00517466361;13899.305034748324;14210.715785684228;14306.57080287593;14394.640133996629;14519.564413067625;12858.485660573566;12885.355732213351;12901.67745806353;13104.934475327658;13436.261005644687;4703.223968045303;4713.363695901044;4734.668573199878;4942.036302920932;4946.183879659852;86786.56606716926;111078.44460777644;111506.44246778796;112489;112647.43676281648;10945.83581246282;11088.611898583673;11213.831792523119;11387.943060284812;11527.250728702775;22639.43401414945;22682.773172268066;22705;22712.545749084926;22718.659220111713;8042.115367309591;8060.314873235723;8088.352931765445;8093.1502192270145;8103.432759706764;4241.278982572909;4401.889952751143;4403.84586539476;4492.348609451611;4498.955010449919;3409.812460314657;3527.4532447470683;3528.4883691864534;3531.717462603037;3531.8587256509963;654.8133781872143;661.1273119482333;674.2684187656349;676.617710993297;676.864627074587;609.2232403685248;611.8161357772724;613.6563524426335;636.4526507203801;646.7736292297658;8303.723196011737;8651.956740216385;8660.78348041304;8799.472031677991;8817.470951742916;597.7220592424543;618.264265524025;625.0873724362482;627.231641239478;633.7971849008273;4172.269852775752;4176.979115104405;4266.253405653998;4338.795750643448;4365.3670217818235;144241;145091.2745436287;150864;153111;159740;131240.37519249562;133517.3324133373;134851.32574337066;135382.3230883839;138493.3075334617;103910;112476.75046499024;116950.41524792492;117250;118295;8285;8295.253427191581;8297.131849527592;8404.075551689431;8502.872456913028;55722.72138639443;59781.701091494266;60708.696456517435;62096.68951655213;62164;78219.21780782119;85085;94884;97329.51335243278;97706.02293977253;12192.268463892195;13099.279539625031;13181.275029873448;13365;13620.250886200958;14374.425022998963;14640.780388294397;14917.104973701613;15008.073425682323;15507.534773956799;13676.610544463481;13767.518136865161;13860.306984650928;14021.158730476203;14174.00781945254;13316.866831331563;13455.529056482976;13632.727345453233;13674.384652690851;13779.173249605055;13241.602751917459;13252.904378910047;13394.531191408254;13512.79730804019;13863.584092477242;10926.125909927347;11257.21199516026;11335.319880807177;11837.348945808064;11958.760824783629;9904.653337133166;10348.586056557955;11300.265482743594;11490.023348015178;11566.65300041 18:45:47 INFO - PROCESS | 1945 | __end_tp_report 18:45:47 INFO - PROCESS | 1945 | __start_cc_report 18:45:47 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1308 18:45:47 INFO - PROCESS | 1945 | __end_cc_report 18:45:47 INFO - PROCESS | 1945 | __startTimestamp1468374347981__endTimestamp 18:45:47 INFO - PROCESS | 1945 | 18:45:47 INFO - PROCESS | 1945 | ------- Summary: start ------- 18:45:47 INFO - PROCESS | 1945 | Number of tests: 6 18:45:47 INFO - PROCESS | 1945 | 18:45:47 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5729.69 Median:5979.28 stddev:691.51 (11.6%) stddev-sans-first:654.15 18:45:47 INFO - PROCESS | 1945 | Values: 2793.5 2812.0 3136.6 3314.8 3336.8 5549.0 5592.2 5640.3 5739.6 5748.9 5632.2 5684.3 5688.5 5689.8 5707.8 5142.6 5179.6 5248.3 5250.6 5285.9 5245.1 5295.0 5299.4 5302.8 5305.9 5188.0 5203.3 5212.5 5217.9 5281.3 5291.8 5312.3 5322.9 5325.4 5401.8 5091.1 5099.6 5105.9 5219.1 5225.6 5611.0 5645.5 5681.4 5682.7 5685.9 6255.6 6265.2 6276.3 6277.2 6282.2 6094.5 6202.5 6263.2 6265.4 6282.1 5128.7 5814.4 5890.3 5890.4 5915.9 6262.8 6282.5 6283.7 6290.5 6290.6 6019.9 6079.7 6100.8 6137.0 6188.5 6260.6 6263.9 6264.7 6265.0 6266.6 6141.8 6204.6 6213.2 6218.7 6223.6 6042.7 6115.5 6119.9 6148.5 6265.6 4202.3 4761.3 4860.4 4869.1 4878.0 4368.8 4404.6 4415.1 4440.8 4477.9 5786.7 5797.4 5813.9 5820.8 5879.2 6132.4 6300.8 6306.6 6366.7 6375.5 5659.5 5746.9 5756.6 5759.7 5761.6 5403.3 5412.8 5420.0 5425.0 5427.7 6261.5 6275.6 6294.4 6298.5 6315.4 6281.2 6288.2 6289.4 6290.6 6292.9 6316.1 6319.7 6321.8 6330.5 6340.0 6061.8 6109.1 6128.4 6168.1 6182.2 6274.5 6301.2 6322.7 6335.7 6344.4 6148.3 6166.8 6176.4 6181.4 6197.9 5028.7 5979.3 6041.3 6060.4 6112.2 6007.6 6018.5 6028.4 6029.1 6031.1 4720.7 6155.2 6184.9 6190.7 6213.3 5786.3 5853.3 5908.7 5959.1 5963.0 18:45:47 INFO - PROCESS | 1945 | 18:45:47 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:75503.78 Median:40029.10 stddev:167132.80 (417.5%) stddev-sans-first:167506.74 18:45:47 INFO - PROCESS | 1945 | Values: 485.1 495.6 505.0 525.8 528.8 30257.2 31942.9 41835.8 42985.1 42996.8 44169.0 44432.8 44462.0 44556.7 44719.0 9625.1 9818.7 9820.1 9831.4 9833.8 8735.0 8933.8 8934.5 9049.7 9050.3 11749.7 11978.0 11979.9 12162.3 12180.4 10449.0 12002.0 12032.9 12137.9 12155.4 8602.2 11592.8 11834.5 11896.4 11985.8 41887.0 42216.6 42319.8 42370.8 42436.8 3392.1 3589.5 3610.9 3749.5 3875.3 31929.8 32998.3 33241.8 33450.5 33450.8 2665.6 2961.9 3048.3 3048.3 3050.8 533191.0 537129.0 537653.0 537940.3 538980.0 60963.0 62346.0 62404.7 62456.0 62472.7 65322.7 65368.7 65396.7 65435.3 65510.3 14106.4 16662.9 16883.5 16886.7 16891.3 13089.3 13159.3 13167.2 13187.1 13200.5 14562.1 14895.4 16200.7 16606.9 16840.2 6282.7 6571.7 6946.4 7024.6 7034.2 952.6 965.4 969.2 1000.7 1024.3 24898.5 29841.0 30753.4 31088.7 31961.7 1072.6 1102.9 1109.3 1139.0 1184.2 13180.9 15838.3 15953.4 15955.9 16518.4 682655.0 682901.0 688556.0 689927.0 691490.0 52764.0 60056.7 60662.7 60940.1 61539.7 1384.6 1393.0 1416.7 1417.2 1420.6 31360.0 32124.5 32296.0 32946.7 33162.8 57152.7 59005.4 59031.7 62869.4 62908.4 626648.0 641065.0 643324.0 644809.0 644838.0 26877.5 27256.3 27303.5 27314.9 27439.0 49353.0 49450.5 50059.0 50883.5 51053.7 40396.8 40636.0 40808.8 41533.8 41650.0 31471.4 40334.0 40625.2 41026.4 41903.2 39340.0 39900.4 40157.8 40217.8 40247.2 29164.3 29735.3 34474.7 36350.5 36631.0 21693.2 24338.1 24691.0 26164.6 26289.9 18:45:47 INFO - PROCESS | 1945 | 18:45:48 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:35554.85 Median:1616.35 stddev:102063.37 (6314.4%) stddev-sans-first:102315.44 18:45:48 INFO - PROCESS | 1945 | Values: 340.1 348.5 355.3 384.7 405.3 13199.6 17460.2 20557.6 20821.5 20989.9 21094.4 21164.0 22063.3 22194.0 22206.6 5764.9 5953.1 6161.4 6166.0 6948.2 6886.2 6906.5 6959.7 6961.9 6964.9 6620.1 6734.1 6793.6 6821.8 6850.8 5699.4 6064.8 6705.7 6716.9 6738.6 5697.2 5920.0 5920.3 5932.5 5945.6 13188.9 13473.5 13745.7 13747.0 13752.5 2170.9 2248.6 2315.0 2335.5 2892.6 9359.6 10033.2 10423.0 12113.7 15865.7 1842.3 1858.8 1887.2 1890.3 1906.2 106740.2 108676.5 129201.7 129486.7 133529.7 9086.8 11615.9 11701.8 11728.9 11846.3 14594.9 14715.3 14744.9 14759.7 14764.3 7314.7 8523.7 8744.6 8776.7 8871.6 8805.4 8807.6 8841.9 8948.0 8965.8 3816.9 3852.6 4522.6 4597.2 4634.3 1888.8 1931.0 1938.7 1939.3 1939.6 841.6 898.4 959.5 967.3 977.7 20759.1 21593.8 22273.8 22274.9 22294.1 847.9 934.8 935.5 944.2 971.8 11326.0 11467.4 11487.3 11522.8 11592.8 600933.0 607281.0 609694.0 614036.0 623211.0 123870.0 125132.4 127934.0 133833.3 136773.0 954.6 967.4 996.2 998.6 1001.7 19267.0 19590.1 19748.4 19872.8 19943.8 51668.2 52196.5 52734.2 55683.7 57416.7 76478.0 82685.6 83329.8 83401.0 83465.0 8239.8 8250.7 8362.9 8378.4 8523.6 12750.0 13315.1 13321.9 13326.7 13378.3 11459.5 11471.1 11513.8 11589.8 11767.5 9318.7 9326.9 11361.8 11516.0 11550.0 11604.0 11746.5 11782.0 11882.9 11921.0 7752.6 8191.4 8233.4 8278.5 8453.3 8324.4 11028.3 11413.4 11567.5 11679.3 18:45:48 INFO - PROCESS | 1945 | 18:45:48 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:5683.96 Median:5072.11 stddev:3196.69 (63.0%) stddev-sans-first:3189.18 18:45:48 INFO - PROCESS | 1945 | Values: 1364.6 1508.2 1557.1 1724.9 1881.2 7774.8 8578.9 8822.6 8852.3 8950.6 8614.3 8729.3 8784.8 8802.0 8805.4 2331.8 2362.4 2364.3 2367.3 2368.7 2321.6 2341.8 2361.3 2365.3 2369.4 2322.1 2336.9 2353.2 2358.8 2374.4 2316.7 2337.0 2346.4 2349.9 2353.4 2329.0 2331.3 2332.7 2334.9 2339.2 8716.0 8860.0 8894.9 8933.9 8946.2 5183.2 5367.5 5370.1 5383.8 5387.2 3545.7 3556.3 3596.1 3642.7 3649.9 1088.5 1091.3 1091.4 1111.4 1111.9 11635.4 11743.0 11751.2 11752.2 11758.3 4342.7 4369.3 4560.5 4566.9 4590.4 9178.4 9195.1 9198.3 9206.7 9232.1 5228.2 5241.8 5245.9 5246.8 5248.6 4402.8 4544.8 4740.6 4903.8 4915.0 3732.1 3763.1 3824.6 3843.9 3865.7 1145.5 1148.0 1151.5 1158.0 1162.9 654.8 745.8 914.6 936.1 952.9 5934.7 5980.3 6018.9 6023.1 6116.6 476.2 492.9 493.3 498.0 639.3 3059.3 4040.5 4046.7 4109.3 4126.5 9088.0 9191.6 10402.4 10718.8 10840.3 10685.9 10769.1 10779.0 10795.0 10821.2 7775.3 8183.0 8191.8 8201.2 8207.9 6098.6 6170.9 6239.1 6272.1 6398.5 9993.1 10123.0 10285.5 10293.2 10303.9 9286.5 9377.4 10190.8 10464.3 10481.1 6385.9 7610.8 7694.4 7718.7 7761.1 8303.7 8420.0 8423.8 8424.0 8424.1 6110.3 7434.9 7548.4 7636.3 7708.0 7396.1 7500.0 7549.4 7607.7 7643.2 7064.9 7121.5 7172.5 7178.7 7197.2 5016.1 5036.2 5108.1 5146.7 5182.4 5133.5 5216.2 5225.9 5278.6 5303.2 18:45:48 INFO - PROCESS | 1945 | 18:45:48 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:4551.23 Median:5069.89 stddev:1406.74 (27.7%) stddev-sans-first:1374.09 18:45:48 INFO - PROCESS | 1945 | Values: 303.7 310.6 313.2 316.9 320.5 4521.8 4841.1 5394.9 5396.2 5406.7 5290.4 5291.9 5307.4 5376.1 5401.6 3850.7 3887.2 4009.4 4028.6 4057.2 3967.2 4030.9 4038.6 4048.8 4057.7 3323.4 3686.0 3970.6 3976.9 4002.7 3791.5 3917.9 3918.5 3989.4 4002.0 3485.7 3513.3 3517.8 3518.5 3519.3 4633.1 5067.5 5098.6 5111.5 5124.4 4419.5 4571.2 4587.3 4617.7 4625.8 4595.7 4836.1 4944.6 5153.6 5495.7 3460.7 3750.6 3858.9 3890.2 3907.0 5590.3 5709.8 5757.8 5804.2 5840.5 3948.7 3952.3 3982.3 3988.4 4000.8 3405.5 4039.3 4054.6 4054.7 4071.3 4457.7 4602.6 4620.6 4630.3 4655.5 4364.9 4777.0 5512.5 5516.1 5630.5 1342.2 1383.2 1387.8 1391.9 1397.9 797.9 825.9 873.8 881.7 881.8 5023.4 5072.3 5234.4 5256.7 5260.6 4805.7 5247.9 5693.0 5708.9 5739.7 4871.3 4918.7 5238.2 5249.3 5289.8 2276.6 2823.4 2971.8 2981.6 3013.1 5212.6 5932.4 5979.8 5985.4 5995.3 5585.8 5902.9 5980.4 6045.4 6048.7 6044.9 6044.9 6052.5 6076.5 6083.7 5467.7 5790.8 5908.5 5931.3 5955.6 4828.9 5560.9 5754.6 5757.5 5792.5 5254.6 6053.2 6075.8 6083.8 6160.8 3672.2 3729.6 3877.6 3884.7 3914.6 5854.5 5869.6 5874.9 5890.1 5891.7 4960.4 5701.7 5714.0 5727.4 5727.8 4681.3 4693.6 4891.4 5289.6 5643.0 5241.5 5291.4 5389.1 5465.8 5552.6 5309.7 5506.8 5574.6 5664.9 5676.5 4589.6 4622.7 4948.0 5542.0 5548.3 18:45:48 INFO - PROCESS | 1945 | 18:45:48 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:25153.00 Median:3530.10 stddev:40158.90 (1137.6%) stddev-sans-first:40229.62 18:45:48 INFO - PROCESS | 1945 | Values: 710.7 718.1 792.5 795.5 839.0 15140.9 16163.8 16175.2 16181.8 16198.6 15620.9 16053.1 16053.6 16179.4 16223.5 707.5 880.4 901.2 909.3 910.3 729.9 856.4 889.9 894.0 895.4 928.7 932.2 932.6 933.4 936.6 729.8 929.4 930.4 931.3 931.4 673.6 696.3 699.0 699.5 700.4 15262.6 15263.2 15293.8 15301.5 15305.0 13899.3 14210.7 14306.6 14394.6 14519.6 12858.5 12885.4 12901.7 13104.9 13436.3 4703.2 4713.4 4734.7 4942.0 4946.2 86786.6 111078.4 111506.4 112489.0 112647.4 10945.8 11088.6 11213.8 11387.9 11527.3 22639.4 22682.8 22705.0 22712.5 22718.7 8042.1 8060.3 8088.4 8093.2 8103.4 4241.3 4401.9 4403.8 4492.3 4499.0 3409.8 3527.5 3528.5 3531.7 3531.9 654.8 661.1 674.3 676.6 676.9 609.2 611.8 613.7 636.5 646.8 8303.7 8652.0 8660.8 8799.5 8817.5 597.7 618.3 625.1 627.2 633.8 4172.3 4177.0 4266.3 4338.8 4365.4 144241.0 145091.3 150864.0 153111.0 159740.0 131240.4 133517.3 134851.3 135382.3 138493.3 103910.0 112476.8 116950.4 117250.0 118295.0 8285.0 8295.3 8297.1 8404.1 8502.9 55722.7 59781.7 60708.7 62096.7 62164.0 78219.2 85085.0 94884.0 97329.5 97706.0 12192.3 13099.3 13181.3 13365.0 13620.3 14374.4 14640.8 14917.1 15008.1 15507.5 13676.6 13767.5 13860.3 14021.2 14174.0 13316.9 13455.5 13632.7 13674.4 13779.2 13241.6 13252.9 13394.5 13512.8 13863.6 10926.1 11257.2 11335.3 11837.3 11958.8 9904.7 10348.6 11300.3 11490.0 11566.7 18:45:48 INFO - PROCESS | 1945 | -------- Summary: end -------- 18:45:48 INFO - PROCESS | 1945 | 18:45:48 INFO - TEST-INFO | 1945: exit 0 18:45:48 INFO - TEST-OK | dromaeo_css | took 1116408ms 18:45:48 INFO - TEST-START | kraken 18:45:48 INFO - operating with platform_type : mac_ 18:45:48 INFO - Initialising browser for kraken test... 18:45:48 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpmw2QGE/profile http://localhost:54494/getInfo.html) 18:45:50 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 18:45:50 INFO - PROCESS | 1950 | colorDepth:24 18:45:50 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 18:45:50 INFO - PROCESS | 1950 | __metrics 18:45:50 INFO - PROCESS | 1950 | 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" 18:45:50 INFO - PROCESS | 1950 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:45:51 INFO - TEST-INFO | 1950: exit 0 18:45:51 INFO - Browser initialized. 18:45:51 INFO - Running cycle 1/1 for kraken test... 18:45:51 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpmw2QGE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:03 INFO - PROCESS | 1951 | RSS: Main: 243658752 18:46:03 INFO - PROCESS | 1951 | 18:46:06 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54494/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:07 INFO - PROCESS | 1951 | RSS: Main: 294092800 18:46:07 INFO - PROCESS | 1951 | 18:46:09 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54494/tests/kraken/driver.html?testName=audio-dft) 18:46:10 INFO - PROCESS | 1951 | RSS: Main: 304066560 18:46:10 INFO - PROCESS | 1951 | 18:46:14 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54494/tests/kraken/driver.html?testName=audio-fft) 18:46:14 INFO - PROCESS | 1951 | RSS: Main: 389328896 18:46:14 INFO - PROCESS | 1951 | 18:46:16 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54494/tests/kraken/driver.html?testName=audio-oscillator) 18:46:17 INFO - PROCESS | 1951 | RSS: Main: 290881536 18:46:17 INFO - PROCESS | 1951 | 18:46:19 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54494/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:46:19 INFO - PROCESS | 1951 | RSS: Main: 295436288 18:46:19 INFO - PROCESS | 1951 | 18:46:22 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54494/tests/kraken/driver.html?testName=imaging-darkroom) 18:46:23 INFO - PROCESS | 1951 | RSS: Main: 331546624 18:46:23 INFO - PROCESS | 1951 | 18:46:26 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54494/tests/kraken/driver.html?testName=imaging-desaturate) 18:46:26 INFO - PROCESS | 1951 | RSS: Main: 334852096 18:46:26 INFO - PROCESS | 1951 | 18:46:29 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54494/tests/kraken/driver.html?testName=json-parse-financial) 18:46:30 INFO - PROCESS | 1951 | RSS: Main: 332922880 18:46:30 INFO - PROCESS | 1951 | 18:46:31 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54494/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:46:32 INFO - PROCESS | 1951 | RSS: Main: 313913344 18:46:32 INFO - PROCESS | 1951 | 18:46:34 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:46:35 INFO - PROCESS | 1951 | RSS: Main: 320225280 18:46:35 INFO - PROCESS | 1951 | 18:46:37 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:37 INFO - PROCESS | 1951 | RSS: Main: 318001152 18:46:37 INFO - PROCESS | 1951 | 18:46:40 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:41 INFO - PROCESS | 1951 | RSS: Main: 327258112 18:46:41 INFO - PROCESS | 1951 | 18:46:44 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:45 INFO - PROCESS | 1951 | RSS: Main: 328552448 18:46:45 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54494/tests/kraken/driver.html?testName=ai-astar) 18:46:46 INFO - PROCESS | 1951 | RSS: Main: 372535296 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | __start_tp_report 18:46:46 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 18:46:46 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 18:46:46 INFO - PROCESS | 1951 | |i|pagename|runs| 18:46:46 INFO - PROCESS | 1951 | |0;ai-astar;101;113;114;112;114;117;164;165;113;113 18:46:46 INFO - PROCESS | 1951 | |1;audio-beat-detection;144;116;138;192;138;107;127;130;175;107 18:46:46 INFO - PROCESS | 1951 | |2;audio-dft;182;184;186;186;186;184;185;182;182;182 18:46:46 INFO - PROCESS | 1951 | |3;audio-fft;80;75;67;106;58;55;63;65;83;56 18:46:46 INFO - PROCESS | 1951 | |4;audio-oscillator;82;92;92;71;71;68;96;69;67;101 18:46:46 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;89;87;91;87;91;85;90;87;90;86 18:46:46 INFO - PROCESS | 1951 | |6;imaging-darkroom;94;105;150;94;94;104;127;94;95;93 18:46:46 INFO - PROCESS | 1951 | |7;imaging-desaturate;85;87;86;86;86;85;86;84;86;87 18:46:46 INFO - PROCESS | 1951 | |8;json-parse-financial;56;58;54;55;53;54;54;54;55;54 18:46:46 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;66;73;72;77;72;71;71;71;70;72 18:46:46 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;83;80;83;83;83;85;81;83;83;80 18:46:46 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;138;140;148;148;142;152;145;131;171;149 18:46:46 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;161;163;163;163;162;163;159;221;171;162 18:46:46 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;66;66;67;66;67;67;65;66;66;64 18:46:46 INFO - PROCESS | 1951 | __end_tp_report 18:46:46 INFO - PROCESS | 1951 | __start_cc_report 18:46:46 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,2124 18:46:46 INFO - PROCESS | 1951 | __end_cc_report 18:46:46 INFO - PROCESS | 1951 | __startTimestamp1468374406967__endTimestamp 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | ------- Summary: start ------- 18:46:46 INFO - PROCESS | 1951 | Number of tests: 14 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:122.60 Median:114.00 stddev:22.48 (19.7%) stddev-sans-first:22.44 18:46:46 INFO - PROCESS | 1951 | Values: 101.0 113.0 114.0 112.0 114.0 117.0 164.0 165.0 113.0 113.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:138.00 stddev:27.71 (20.1%) stddev-sans-first:29.28 18:46:46 INFO - PROCESS | 1951 | Values: 144.0 116.0 138.0 192.0 138.0 107.0 127.0 130.0 175.0 107.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.79 (1.0%) stddev-sans-first:1.76 18:46:46 INFO - PROCESS | 1951 | Values: 182.0 184.0 186.0 186.0 186.0 184.0 185.0 182.0 182.0 182.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:70.80 Median:66.00 stddev:15.75 (23.9%) stddev-sans-first:16.35 18:46:46 INFO - PROCESS | 1951 | Values: 80.0 75.0 67.0 106.0 58.0 55.0 63.0 65.0 83.0 56.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:76.50 stddev:13.24 (17.3%) stddev-sans-first:14.03 18:46:46 INFO - PROCESS | 1951 | Values: 82.0 92.0 92.0 71.0 71.0 68.0 96.0 69.0 67.0 101.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.50 stddev:2.16 (2.4%) stddev-sans-first:2.28 18:46:46 INFO - PROCESS | 1951 | Values: 89.0 87.0 91.0 87.0 91.0 85.0 90.0 87.0 90.0 86.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:94.00 stddev:18.97 (20.2%) stddev-sans-first:19.70 18:46:46 INFO - PROCESS | 1951 | Values: 94.0 105.0 150.0 94.0 94.0 104.0 127.0 94.0 95.0 93.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:0.92 (1.1%) stddev-sans-first:0.93 18:46:46 INFO - PROCESS | 1951 | Values: 85.0 87.0 86.0 86.0 86.0 85.0 86.0 84.0 86.0 87.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:1.42 (2.6%) stddev-sans-first:1.42 18:46:46 INFO - PROCESS | 1951 | Values: 56.0 58.0 54.0 55.0 53.0 54.0 54.0 54.0 55.0 54.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:2.72 (3.8%) stddev-sans-first:2.03 18:46:46 INFO - PROCESS | 1951 | Values: 66.0 73.0 72.0 77.0 72.0 71.0 71.0 71.0 70.0 72.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.58 (1.9%) stddev-sans-first:1.66 18:46:46 INFO - PROCESS | 1951 | Values: 83.0 80.0 83.0 83.0 83.0 85.0 81.0 83.0 83.0 80.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:148.00 stddev:10.64 (7.2%) stddev-sans-first:10.84 18:46:46 INFO - PROCESS | 1951 | Values: 138.0 140.0 148.0 148.0 142.0 152.0 145.0 131.0 171.0 149.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:163.00 stddev:18.60 (11.4%) stddev-sans-first:19.51 18:46:46 INFO - PROCESS | 1951 | Values: 161.0 163.0 163.0 163.0 162.0 163.0 159.0 221.0 171.0 162.0 18:46:46 INFO - PROCESS | 1951 | 18:46:46 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 18:46:46 INFO - PROCESS | 1951 | Values: 66.0 66.0 67.0 66.0 67.0 67.0 65.0 66.0 66.0 64.0 18:46:46 INFO - PROCESS | 1951 | -------- Summary: end -------- 18:46:46 INFO - PROCESS | 1951 | 18:46:47 INFO - TEST-INFO | 1951: exit 0 18:46:47 INFO - TEST-OK | kraken | took 59170ms 18:46:47 INFO - SUITE-END | took 1175s 18:46:48 INFO - Completed test suite (00:19:35) 18:46:48 INFO - javascript benchmark 18:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2793.5251007328766, 2812.001739382519, 3136.560881476593, 3314.834258287086, 3336.8331583420822, 5549.0289199390045, 5592.217089607448, 5640.323161220655, 5739.56953228508, 5748.856278593032, 5632.239647647566, 5684.346300175481, 5688.459596338352, 5689.772409103642, 5707.828765137042, 5142.562882155012, 5179.611529135311, 5248.317718696564, 5250.579953603703, 5285.920711189336, 5245.108331583621, 5295.0, 5299.417064122963, 5302.787888484456, 5305.946940530604, 5188.0, 5203.349581302337, 5212.5308722214995, 5217.869553261161, 5281.260623512711, 5291.841244762663, 5312.335958005249, 5322.893542129174, 5325.3875804282225, 5401.810936617199, 5091.083604951107, 5099.617528685363, 5105.897882042338, 5219.060569097561, 5225.581953443715, 5611.046122159202, 5645.463680950304, 5681.431856814327, 5682.687452190128, 5685.857853553694, 6255.624662520263, 6265.216847894013, 6276.340984196685, 6277.246730392337, 6282.246130464374, 6094.531679962716, 6202.503799696014, 6263.185785847811, 6265.436110750009, 6282.057691346289, 5128.666636668605, 5814.447627475383, 5890.263717035371, 5890.352061273256, 5915.881682366328, 6262.780802671885, 6282.465990390777, 6283.654399008007, 6290.496760259169, 6290.622562646256, 6019.879602407927, 6079.6960151992225, 6100.755969761249, 6137.039921714258, 6188.535859810532, 6260.593061450991, 6263.906041409383, 6264.749410023548, 6264.968675156595, 6266.592671476339, 6141.84645383869, 6204.5967012144065, 6213.161223234896, 6218.689065546795, 6223.564350495512, 6042.728077236535, 6115.4801841843055, 6119.938800612026, 6148.508119350441, 6265.561410701207, 4202.306619407764, 4761.262004389325, 4860.416749990024, 4869.074875773663, 4877.975610121997, 4368.847090351823, 4404.559544045634, 4415.094905544356, 4440.755758433253, 4477.910441791211, 5786.739596718157, 5797.449242321973, 5813.924423981577, 5820.82537523875, 5879.235699359056, 6132.355508992545, 6300.779472718525, 6306.59007164542, 6366.713497892606, 6375.458086062644, 5659.490645841894, 5746.913796292975, 5756.5682573807135, 5759.740811663485, 5761.596688231867, 5403.270558474555, 5412.783488660488, 5419.972900135474, 5424.996375670435, 5427.728613569306, 6261.467775239155, 6275.623462592259, 6294.433500984934, 6298.527610429328, 6315.368463153648, 6281.15204447412, 6288.182536270256, 6289.433950944254, 6290.622562646256, 6292.874142517216, 6316.147320111726, 6319.652419116809, 6321.841953951188, 6330.525210609315, 6340.048992651139, 6061.787837425669, 6109.070667813169, 6128.387161283836, 6168.136460895568, 6182.165407670087, 6274.498040156868, 6301.180846489927, 6322.715477803602, 6335.65153916539, 6344.429001389897, 6148.323684394623, 6166.845828854135, 6176.444120029039, 6181.412765787243, 6197.938020619736, 5028.698278103326, 5979.282486101695, 6041.305249896318, 6060.363661815446, 6112.235970503687, 6007.579469437097, 6018.518518518596, 6028.367021462684, 6029.095635654687, 6031.065184896348, 4720.669553131385, 6155.169052177898, 6184.938150618436, 6190.659513726789, 6213.316535181217, 5786.276715410574, 5853.2976042875125, 5908.7045647718305, 5959.135925290722, 5963.0], "value": 5680.708543573129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.0952972705902, 495.5985651622186, 504.9671771334864, 525.7712894890723, 528.7726277700592, 30257.243568910762, 31942.881999130033, 41835.79082104593, 42985.14029719404, 42996.78501607488, 44169.0, 44432.77783611086, 44462.0, 44556.66330010097, 44719.0, 9625.133737963582, 9818.705438836847, 9820.11618954294, 9831.410115393064, 9833.803323933516, 8734.995475520302, 8933.77665558363, 8934.508602026886, 9049.728508144766, 9050.27597792175, 11749.706257343592, 11978.0, 11979.940100299485, 12162.331071791048, 12180.390980450986, 10448.95510448949, 12002.0, 12032.939835300855, 12137.878621213764, 12155.39223038849, 8602.225799677997, 11592.76814463706, 11834.526618935231, 11896.405179741065, 11985.760284794342, 41887.0, 42216.57783422188, 42319.7884010578, 42370.78814605938, 42436.78781606072, 3392.1180493071734, 3589.4974703541525, 3610.9277814443826, 3749.4938183345444, 3875.3218186817194, 31929.84035079833, 32998.34003319944, 33241.83379083113, 33450.49824252638, 33450.83274583612, 2665.627201990976, 2961.881524739008, 3048.2988912550018, 3048.314129320885, 3050.771192160597, 533191.0, 537129.0, 537653.0, 537940.31029845, 538980.0, 60963.0, 62346.0, 62404.68797656074, 62456.0, 62472.687636561524, 65322.67338663276, 65368.67315663486, 65396.67301663461, 65435.345646542926, 65510.344896551374, 14106.435742570387, 16662.916685416494, 16883.493495195165, 16886.662266754596, 16891.324347026228, 13089.345532723326, 13159.342032898317, 13167.209967401986, 13187.076904616777, 13200.47198112084, 14562.053466524641, 14895.404183832741, 16200.675986480204, 16606.9205501642, 16840.15799210059, 6282.654454004982, 6571.7185148895505, 6946.37482626566, 7024.578525288499, 7034.2262351141335, 952.5996784726441, 965.4207475514635, 969.2004096620317, 1000.6997900629782, 1024.3085917005992, 24898.502029959298, 29840.9555665555, 30753.384932301677, 31088.68911310858, 31961.680383196337, 1072.5816931396762, 1102.9117670586334, 1109.3343993603773, 1138.9806123519404, 1184.2480025183897, 13180.934095329461, 15838.287277072559, 15953.441629542911, 15955.920220398824, 16518.42185523501, 682655.0, 682901.0, 688556.0, 689927.0, 691490.0, 52764.0, 60056.699716501134, 60662.69668651628, 60940.08589871156, 61539.692301538205, 1384.6053874645677, 1393.0, 1416.730821144, 1417.2205287092265, 1420.6235347632885, 31360.0, 32124.518132227568, 32296.0, 32946.67053329436, 33162.83418582891, 57152.714236428554, 59005.40994589999, 59031.70484147552, 62869.37130628635, 62908.37091629208, 626648.0, 641065.0, 643324.0, 644809.0, 644838.0, 26877.462450750485, 27256.318592035357, 27303.453930921667, 27314.863425682743, 27439.0, 49353.0, 49450.50549494603, 50059.0, 50883.47349579519, 51053.74473127611, 40396.78809635716, 40636.0, 40808.79595602003, 41533.79233103815, 41650.0, 31471.37057258888, 40334.0, 40625.18749624932, 41026.38460423036, 41903.16193676049, 39340.01649958774, 39900.40149397819, 40157.795266142064, 40217.79891100526, 40247.1950560993, 29164.27089322784, 29735.25661858471, 34474.655253448145, 36350.454743179405, 36631.0, 21693.240736574146, 24338.148164814145, 24691.0, 26164.60753088743, 26289.948402064467], "value": 20689.21587859806, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.1479294367609, 348.4685854072539, 355.27523851343244, 384.67494966753094, 405.32715691951364, 13199.604011879646, 17460.214290356933, 20557.588848223044, 20821.479463013395, 20989.895050524727, 21094.363204599424, 21164.047617857195, 22063.33809985703, 22194.0, 22206.555868882613, 5764.9423505765035, 5953.1070339449, 6161.445469907708, 6166.044263139198, 6948.235694073648, 6886.24251332353, 6906.482013848957, 6959.652017399135, 6961.930380696179, 6964.89552656708, 6620.139381880349, 6734.0572319875255, 6793.592384456923, 6821.795346139624, 6850.794476165723, 5699.401562835926, 6064.818055458343, 6705.731770729165, 6716.932830671704, 6738.561993470434, 5697.202391665171, 5920.0, 5920.259967504062, 5932.466078052996, 5945.583809133363, 13188.934055329757, 13473.461061557526, 13745.725085498334, 13747.0, 13752.518661846787, 2170.8588941718895, 2248.5952528544854, 2314.9652755208685, 2335.486193037529, 2892.5516544935567, 9359.596060590899, 10033.247506437046, 10423.0, 12113.69715757113, 15865.682686346325, 1842.3459671816447, 1858.823411775879, 1887.2451019592136, 1890.2627975089727, 1906.2470324221854, 106740.18385945295, 108676.4566177172, 129201.7079829199, 129486.70513294841, 133529.6647033527, 9086.772830679285, 11615.883841161649, 11701.771314011907, 11728.94135529317, 11846.289222646668, 14594.85405145935, 14715.337809798584, 14744.852551474563, 14759.704805903822, 14764.26178691061, 7314.670839812221, 8523.701670441505, 8744.642370487849, 8776.736697899074, 8871.556422178865, 8805.42764720291, 8807.559622018873, 8841.911580884109, 8947.955260223658, 8965.820683586422, 3816.904577385588, 3852.5569559500454, 4522.638188944942, 4597.241455159928, 4634.328022436795, 1888.8488920886296, 1930.980690193108, 1938.6510428123074, 1939.3309308288617, 1939.6023815117867, 841.5918279634373, 898.3621628643606, 959.4770849886828, 967.3035414501547, 977.7115750853516, 20759.065842037144, 21593.78406215949, 22273.777262227493, 22274.888625556767, 22294.108235670716, 847.9236868681849, 934.7709811096325, 935.4621092871625, 944.187998321443, 971.8396464583406, 11325.980661740483, 11467.369294688706, 11487.25332853362, 11522.827157592643, 11592.76814463706, 600933.0, 607280.9635951791, 609694.0, 614036.0, 623211.0, 123870.0, 125132.37433812773, 127934.0, 133833.33083334522, 136773.0, 954.5847556313026, 967.4388854464394, 996.1582462818907, 998.5905778630727, 1001.6594357918198, 19267.036648167254, 19590.11844466974, 19748.40754777359, 19872.801271987675, 19943.800561994194, 51668.22497662613, 52196.47803521916, 52734.208986866, 55683.72158139183, 57416.71291643515, 76478.0, 82685.58657206675, 83329.75005375045, 83401.0, 83465.0, 8239.752807415787, 8250.669973201246, 8362.916370836214, 8378.413511054412, 8523.573821309034, 12750.0, 13315.06794524374, 13321.933390333374, 13326.733465330833, 13378.331083445983, 11459.541618335174, 11471.082313415074, 11513.827292590786, 11589.768204636028, 11767.470463828977, 9318.72043838686, 9326.860097098684, 11361.829572556253, 11516.0, 11550.018248448976, 11604.0, 11746.530138794353, 11782.0, 11882.940585297018, 11920.986716128878, 7752.612369381395, 8191.426600138171, 8233.423660343718, 8278.544680042656, 8453.323734101381, 8324.417290789586, 11028.28316159447, 11413.372264525531, 11567.479463423997, 11679.299242045505], "value": 9665.427776223212, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.5633397312852, 1508.2308022908317, 1557.057979922147, 1724.9223784929677, 1881.1534809335803, 7774.8445031099345, 8578.871316930243, 8822.558872056403, 8852.336074794384, 8950.597223124958, 8614.31085513159, 8729.258013068897, 8784.78038049049, 8802.031776504595, 8805.383623146383, 2331.7668233176632, 2362.397588614901, 2364.302530753423, 2367.3134790910703, 2368.656544801003, 2321.605327094398, 2341.800946919514, 2361.25620429565, 2365.31405892291, 2369.39580406996, 2322.129201549419, 2336.906523739048, 2353.2469609724894, 2358.834881558283, 2374.382660508272, 2316.6872472216205, 2337.0, 2346.378209774411, 2349.8590084594894, 2353.376355265839, 2328.953420931572, 2331.265651319829, 2332.7433982261937, 2334.8599084055013, 2339.181286549711, 8716.041235464094, 8860.0, 8894.86657700135, 8933.910660893373, 8946.23956963659, 5183.170692689189, 5367.490088441592, 5370.113931201347, 5383.838484845422, 5387.245785589981, 3545.663161999606, 3556.2531868307688, 3596.065023093988, 3642.7085833133287, 3649.8905032848793, 1088.4992903264508, 1091.2688498705807, 1091.4379094766202, 1111.4053981120062, 1111.9388433636148, 11635.360055196961, 11743.001844843198, 11751.177417580773, 11752.177347585672, 11758.294502329802, 4342.69601127918, 4369.3227549729845, 4560.543945605479, 4566.931496027562, 4590.403247577793, 9178.449293042439, 9195.126462986005, 9198.310126740522, 9206.677766278148, 9232.122948319899, 5228.215767634809, 5241.764120614582, 5245.947540524546, 5246.7638956247065, 5248.580113590903, 4402.845900393535, 4544.795484203219, 4740.5733483986605, 4903.779329930162, 4915.0, 3732.0669832541867, 3763.0780458787226, 3824.6175382461533, 3843.942340864889, 3865.6714179294513, 1145.5360578965365, 1147.9713007174887, 1151.5336288803048, 1158.0330424095973, 1162.8546431696038, 654.780648482754, 745.8396444764402, 914.5564401265381, 936.0873148680118, 952.9094736000069, 5934.673592952343, 5980.252468441445, 6018.909716354258, 6023.096535519707, 6116.602420842631, 476.211869356216, 492.92050409602854, 493.3487796109101, 498.0213879726283, 639.309545690653, 3059.265776213693, 4040.515138183378, 4046.6964977626794, 4109.301418758818, 4126.484189476316, 9087.954560227157, 9191.586378612978, 10402.375857448576, 10718.785624287626, 10840.349579025125, 10685.946570267099, 10769.084627806567, 10779.0, 10795.0, 10821.188410869216, 7775.3390961767755, 8182.959085204655, 8191.754247372588, 8201.179882011871, 8207.917920820835, 6098.634081955097, 6170.907436388368, 6239.126522286974, 6272.121902933502, 6398.456131228898, 9993.10062094415, 10123.0, 10285.53715082808, 10293.228007899288, 10303.948480257552, 9286.489243091424, 9377.390469619453, 10190.847137292798, 10464.319819211727, 10481.056704896597, 6385.904211436739, 7610.771676849703, 7694.4229182810395, 7718.652660630398, 7761.107472640493, 8303.709370172015, 8420.0, 8423.831523369621, 8424.031236407647, 8424.115467875785, 6110.2973158085615, 7434.925650743423, 7548.358389536951, 7636.31273185416, 7708.03649543807, 7396.0754905636795, 7499.987501687202, 7549.358304544176, 7607.657655405409, 7643.1974642663945, 7064.894026589708, 7121.46589005837, 7172.497925145399, 7178.676959536729, 7197.208307086112, 5016.097102521581, 5036.169032109661, 5108.055009823194, 5146.665466744799, 5182.378114626269, 5133.459962011307, 5216.243644671577, 5225.92161117576, 5278.577713782812, 5303.204519322133], "value": 4494.529117486501, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7084398976982, 310.6225935488381, 313.17322269209285, 316.91918560767, 320.5480272815331, 4521.751303678297, 4841.080194762997, 5394.946050539494, 5396.244525766396, 5406.702631355279, 5290.365156181264, 5291.867703307419, 5307.363116426025, 5376.1398176291805, 5401.621886467949, 3850.711196660248, 3887.241987812378, 4009.3785463253244, 4028.6374226319626, 4057.2088442753675, 3967.2065586882595, 4030.879073627781, 4038.6163314485075, 4048.75707457552, 4057.655099316547, 3323.351946370459, 3685.981570092146, 3970.642642162205, 3976.880693579197, 4002.7198096133284, 3791.4881490998637, 3917.9216415671813, 3918.529776426833, 3989.4414781930554, 4002.0, 3485.721142308609, 3513.2797776455764, 3517.84169712361, 3518.489818976234, 3519.260955199411, 4633.054117270754, 5067.467915868818, 5098.643094983353, 5111.463296353866, 5124.410692770304, 4419.53594872537, 4571.2000399929875, 4587.302698001728, 4617.699849509771, 4625.8149674013, 4595.725116067534, 4836.129496690594, 4944.629152813553, 5153.561947234452, 5495.697736624484, 3460.653934606544, 3750.624937506255, 3858.884233472972, 3890.1830615570766, 3907.0, 5590.2732644756325, 5709.8287051388525, 5757.769689212427, 5804.216430781833, 5840.532757379399, 3948.6841052715713, 3952.3478626026385, 3982.283189026003, 3988.3818008208773, 4000.8199631016673, 3405.540252065989, 4039.2931237033404, 4054.6350828425584, 4054.655354294918, 4071.328230841937, 4457.68796184264, 4602.631789456836, 4620.561046700558, 4630.333080091164, 4655.534446555317, 4364.890877728082, 4776.952230477651, 5512.476314750093, 5516.096946553433, 5630.5495560355075, 1342.1678559293105, 1383.174210042754, 1387.7571425000585, 1391.8517223290826, 1397.9091359061629, 797.8563858505524, 825.8926339575937, 873.7553485024204, 881.6737807011447, 881.8015946412006, 5023.39719233687, 5072.315237442898, 5234.42421333653, 5256.737163141904, 5260.580622595344, 4805.687630304019, 5247.921281180786, 5693.0, 5708.857278567986, 5739.713014349266, 4871.34236878017, 4918.6798910735115, 5238.223741417055, 5249.343832020997, 5289.841304760864, 2276.6015947209503, 2823.4070845122756, 2971.777116716255, 2981.6272965879266, 3013.1111322159577, 5212.609054320941, 5932.436418540232, 5979.820605381846, 5985.426266705358, 5995.340512543617, 5585.804496842591, 5902.8819423611285, 5980.3686377993545, 6045.425684559959, 6048.697565121726, 6044.886883255215, 6044.919679216423, 6052.54605904559, 6076.483498902554, 6083.726232319556, 5467.6719396836315, 5790.7973220937065, 5908.497777688945, 5931.3178984417345, 5955.612885162451, 4828.8792780180775, 5560.860978475571, 5754.56840736938, 5757.5393968481585, 5792.451321418563, 5254.60590455725, 6053.213082299449, 6075.756969721162, 6083.8174854754425, 6160.815175544741, 3672.192117734094, 3729.6083911188935, 3877.554081280689, 3884.669803066658, 3914.5693973662305, 5854.502367298827, 5869.589128760946, 5874.94125058744, 5890.1164825276555, 5891.675957822361, 4960.379952505937, 5701.657900525815, 5713.971430142823, 5727.36998930109, 5727.770889164387, 4681.321208424827, 4693.55411235932, 4891.364122664031, 5289.5503882168905, 5643.0, 5241.475852414728, 5291.365036195682, 5389.089891458569, 5465.7813687453645, 5552.5846256878385, 5309.681419114865, 5506.807261745819, 5574.637648552831, 5664.875671623141, 5676.545876329801, 4589.5639914208095, 4622.722636641678, 4947.950520494749, 5542.0, 5548.334199895877], "value": 4102.758290519478, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.7405796884135, 718.1274751177319, 792.4690457393549, 795.5107608820578, 839.035109623932, 15140.924295378521, 16163.757543636855, 16175.19124043799, 16181.757273640875, 16198.595035124128, 15620.921895390507, 16053.117078560674, 16053.598660033505, 16179.433719819808, 16223.51329460115, 707.4870718728906, 880.3617377401381, 901.202435844276, 909.2634965677789, 910.2990697163181, 729.9087614048244, 856.3534531428762, 889.9110088991082, 893.9821203575924, 895.3642913531401, 928.726025822384, 932.1889955738517, 932.6409332407038, 933.4212788071405, 936.6487567162314, 729.7774178875441, 929.4005366538579, 930.3952430919888, 931.3480563605435, 931.3713243560569, 673.6429692263059, 696.2863065357998, 699.0, 699.5348093517805, 700.4361489001352, 15262.618434539114, 15263.160526171056, 15293.770593440997, 15301.46444874424, 15305.00517466361, 13899.305034748324, 14210.715785684228, 14306.57080287593, 14394.640133996629, 14519.564413067625, 12858.485660573566, 12885.355732213351, 12901.67745806353, 13104.934475327658, 13436.261005644687, 4703.223968045303, 4713.363695901044, 4734.668573199878, 4942.036302920932, 4946.183879659852, 86786.56606716926, 111078.44460777644, 111506.44246778796, 112489.0, 112647.43676281648, 10945.83581246282, 11088.611898583673, 11213.831792523119, 11387.943060284812, 11527.250728702775, 22639.43401414945, 22682.773172268066, 22705.0, 22712.545749084926, 22718.659220111713, 8042.115367309591, 8060.314873235723, 8088.352931765445, 8093.1502192270145, 8103.432759706764, 4241.278982572909, 4401.889952751143, 4403.84586539476, 4492.348609451611, 4498.955010449919, 3409.812460314657, 3527.4532447470683, 3528.4883691864534, 3531.717462603037, 3531.8587256509963, 654.8133781872143, 661.1273119482333, 674.2684187656349, 676.617710993297, 676.864627074587, 609.2232403685248, 611.8161357772724, 613.6563524426335, 636.4526507203801, 646.7736292297658, 8303.723196011737, 8651.956740216385, 8660.78348041304, 8799.472031677991, 8817.470951742916, 597.7220592424543, 618.264265524025, 625.0873724362482, 627.231641239478, 633.7971849008273, 4172.269852775752, 4176.979115104405, 4266.253405653998, 4338.795750643448, 4365.3670217818235, 144241.0, 145091.2745436287, 150864.0, 153111.0, 159740.0, 131240.37519249562, 133517.3324133373, 134851.32574337066, 135382.3230883839, 138493.3075334617, 103910.0, 112476.75046499024, 116950.41524792492, 117250.0, 118295.0, 8285.0, 8295.253427191581, 8297.131849527592, 8404.075551689431, 8502.872456913028, 55722.72138639443, 59781.701091494266, 60708.696456517435, 62096.68951655213, 62164.0, 78219.21780782119, 85085.0, 94884.0, 97329.51335243278, 97706.02293977253, 12192.268463892195, 13099.279539625031, 13181.275029873448, 13365.0, 13620.250886200958, 14374.425022998963, 14640.780388294397, 14917.104973701613, 15008.073425682323, 15507.534773956799, 13676.610544463481, 13767.518136865161, 13860.306984650928, 14021.158730476203, 14174.00781945254, 13316.866831331563, 13455.529056482976, 13632.727345453233, 13674.384652690851, 13779.173249605055, 13241.602751917459, 13252.904378910047, 13394.531191408254, 13512.79730804019, 13863.584092477242, 10926.125909927347, 11257.21199516026, 11335.319880807177, 11837.348945808064, 11958.760824783629, 9904.653337133166, 10348.586056557955, 11300.265482743594, 11490.023348015178, 11566.65300041], "value": 7936.594074454099, "unit": "score"}], "name": "dromaeo_css", "value": 7415.407361285645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 112.0, 114.0, 117.0, 164.0, 165.0, 113.0, 113.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 138.0, 192.0, 138.0, 107.0, 127.0, 130.0, 175.0, 107.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 186.0, 186.0, 186.0, 184.0, 185.0, 182.0, 182.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 67.0, 106.0, 58.0, 55.0, 63.0, 65.0, 83.0, 56.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 92.0, 71.0, 71.0, 68.0, 96.0, 69.0, 67.0, 101.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 91.0, 85.0, 90.0, 87.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 105.0, 150.0, 94.0, 94.0, 104.0, 127.0, 94.0, 95.0, 93.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 86.0, 86.0, 85.0, 86.0, 84.0, 86.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 54.0, 55.0, 53.0, 54.0, 54.0, 54.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 72.0, 77.0, 72.0, 71.0, 71.0, 71.0, 70.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 83.0, 83.0, 83.0, 85.0, 81.0, 83.0, 83.0, 80.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 148.0, 148.0, 142.0, 152.0, 145.0, 131.0, 171.0, 149.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 163.0, 162.0, 163.0, 159.0, 221.0, 171.0, 162.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 65.0, 66.0, 66.0, 64.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1464.5, "alertThreshold": 2.0}]} 18:46:48 INFO - Return code: 0 18:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:46:48 INFO - # TBPL SUCCESS # 18:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:46:48 INFO - 2016-07-12 18:46:48.265 screenresolution[1955:20916] starting screenresolution argv=screenresolution get 18:46:48 INFO - 2016-07-12 18:46:48.267 screenresolution[1955:20916] Display 0: 1600x1200x32@60 18:46:48 INFO - 2016-07-12 18:46:48.273 screenresolution[1956:20919] starting screenresolution argv=screenresolution list 18:46:48 INFO - Available Modes on Display 0 18:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:46:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:46:48 INFO - Intel Iris: 18:46:48 INFO - Chipset Model: Intel Iris 18:46:48 INFO - Type: GPU 18:46:48 INFO - Bus: Built-In 18:46:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:46:48 INFO - Vendor: Intel (0x8086) 18:46:48 INFO - Device ID: 0x0a2e 18:46:48 INFO - Revision ID: 0x0009 18:46:48 INFO - Displays: 18:46:48 INFO - Display: 18:46:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:46:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:46:48 INFO - Display Serial Number: 1600x1200 60 18:46:48 INFO - Main Display: Yes 18:46:48 INFO - Mirror: Off 18:46:48 INFO - Online: Yes 18:46:48 INFO - Rotation: Supported 18:46:48 INFO - Return code: 0 18:46:48 INFO - Running post-action listener: _resource_record_post_action 18:46:48 INFO - ##### 18:46:48 INFO - ##### Finished run-tests step (success) 18:46:48 INFO - ##### 18:46:48 INFO - Running post-run listener: _resource_record_post_run 18:46:48 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 901120; Write bytes: 702345728; Read time: 10; Write time: 1962 18:46:48 INFO - TinderboxPrint: CPU usage
53.4% 18:46:48 INFO - TinderboxPrint: I/O read bytes / time
901,120 / 10 18:46:48 INFO - TinderboxPrint: I/O write bytes / time
702,345,728 / 1,962 18:46:48 INFO - TinderboxPrint: CPU idle
2,259.8 (46.6%) 18:46:48 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 18:46:48 INFO - TinderboxPrint: CPU user
1,616.9 (33.4%) 18:46:48 INFO - TinderboxPrint: Swap in / out
407,392,256 / 0 18:46:48 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387369472; Write bytes: 381622272; Read time: 28887; Write time: 820 18:46:48 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 901120; Write bytes: 293419520; Read time: 10; Write time: 1077 18:46:48 INFO - Running post-run listener: _upload_blobber_files 18:46:48 INFO - Blob upload gear active. 18:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:46:48 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 18:46:48 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:46:48 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 18:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:50 INFO - (blobuploader) - INFO - Done attempting. 18:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:51 INFO - (blobuploader) - INFO - Done attempting. 18:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:53 INFO - (blobuploader) - INFO - Done attempting. 18:46:53 INFO - (blobuploader) - INFO - Iteration through files over. 18:46:53 INFO - Return code: 0 18:46:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:46:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:46:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/920f82acc3cfe3b031c25647aa2d8f1131c6bff3a4036b82f3883b2d384d57796684e2c49b449ef224000f4f68104ea1b147bf5a80b88e85526e2d8a05e3ff5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d6b9796b5fb7faae7d94917a7487747094e3f46bf4d71b9db003fbe8369f9b06e3e12d62a28524c8c27534f21d07e7c0508293fb508a158667d4a421968cddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05e62484c2ed2accd844ccc22997f1f437a1c5b46cc31375eff190cacd77a4f8e04d0f07ef952eeba39dec303f212aa082425fee07222f94937d226e479e4e3c"} 18:46:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:46:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:46:53 INFO - Contents: 18:46:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/920f82acc3cfe3b031c25647aa2d8f1131c6bff3a4036b82f3883b2d384d57796684e2c49b449ef224000f4f68104ea1b147bf5a80b88e85526e2d8a05e3ff5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d6b9796b5fb7faae7d94917a7487747094e3f46bf4d71b9db003fbe8369f9b06e3e12d62a28524c8c27534f21d07e7c0508293fb508a158667d4a421968cddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05e62484c2ed2accd844ccc22997f1f437a1c5b46cc31375eff190cacd77a4f8e04d0f07ef952eeba39dec303f212aa082425fee07222f94937d226e479e4e3c"} 18:46:53 INFO - Running post-run listener: copy_logs_to_upload_dir 18:46:53 INFO - Copying logs to upload dir... 18:46:53 INFO - mkdir: /builds/slave/test/build/upload/logs 18:46:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.974924 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 18:46:54.041282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:46:54.045309) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/920f82acc3cfe3b031c25647aa2d8f1131c6bff3a4036b82f3883b2d384d57796684e2c49b449ef224000f4f68104ea1b147bf5a80b88e85526e2d8a05e3ff5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d6b9796b5fb7faae7d94917a7487747094e3f46bf4d71b9db003fbe8369f9b06e3e12d62a28524c8c27534f21d07e7c0508293fb508a158667d4a421968cddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05e62484c2ed2accd844ccc22997f1f437a1c5b46cc31375eff190cacd77a4f8e04d0f07ef952eeba39dec303f212aa082425fee07222f94937d226e479e4e3c"} build_url:https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010957 build_url: 'https://queue.taskcluster.net/v1/task/EQ_bJWCET8qIznnex9GoiQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/920f82acc3cfe3b031c25647aa2d8f1131c6bff3a4036b82f3883b2d384d57796684e2c49b449ef224000f4f68104ea1b147bf5a80b88e85526e2d8a05e3ff5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d6b9796b5fb7faae7d94917a7487747094e3f46bf4d71b9db003fbe8369f9b06e3e12d62a28524c8c27534f21d07e7c0508293fb508a158667d4a421968cddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05e62484c2ed2accd844ccc22997f1f437a1c5b46cc31375eff190cacd77a4f8e04d0f07ef952eeba39dec303f212aa082425fee07222f94937d226e479e4e3c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:46:54.080914) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:46:54.081636) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cv0aSXCg6Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Kl2ih4dPR4/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005245 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:46:54.134265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:46:54.134977) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:46:54.135540) ========= ========= Total master_lag: 0.20 =========