builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0176 starttime: 1467416401.07 results: success (0) buildid: 20160701144623 builduid: 3ee81b4b4ea947d79cae7c45fb7f471b revision: 9b5be9da64bafc31baba3bc81c5d42785081e3d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:01.070414) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:01.070810) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:01.076586) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:01.225017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:01.225489) ========= 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.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-01 16:40:01-- 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% 17.0M=0.001s 2016-07-01 16:40:02 (17.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.845629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:02.094343) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:02.094619) ========= 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.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.032475 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:02.147277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-01 16:40:02.147658) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9b5be9da64bafc31baba3bc81c5d42785081e3d9 --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 9b5be9da64bafc31baba3bc81c5d42785081e3d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-01 16:40:02,221 truncating revision to first 12 chars 2016-07-01 16:40:02,221 Setting DEBUG logging. 2016-07-01 16:40:02,221 attempt 1/10 2016-07-01 16:40:02,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9b5be9da64ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-01 16:40:04,149 unpacking tar archive at: fx-team-9b5be9da64ba/testing/mozharness/ program finished with exit code 0 elapsedTime=2.191122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-01 16:40:04.361742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:04.362095) ========= script_repo_revision: 9b5be9da64bafc31baba3bc81c5d42785081e3d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:04.362506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:04.362792) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 16:40:04.383743) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-01 16:40:04.384183) ========= /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.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:04 INFO - MultiFileLogger online at 20160701 16:40:04 in /builds/slave/test 16:40:04 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 16:40:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:40:04 INFO - {'append_to_log': False, 16:40:04 INFO - 'base_work_dir': '/builds/slave/test', 16:40:04 INFO - 'blob_upload_branch': 'Fx-Team', 16:40:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:40:04 INFO - 'branch': 'Fx-Team', 16:40:04 INFO - 'buildbot_json_path': 'buildprops.json', 16:40:04 INFO - 'config_files': ('talos/mac_config.py',), 16:40:04 INFO - 'default_actions': ('clobber', 16:40:04 INFO - 'read-buildbot-config', 16:40:04 INFO - 'download-and-extract', 16:40:04 INFO - 'populate-webroot', 16:40:04 INFO - 'create-virtualenv', 16:40:04 INFO - 'install', 16:40:04 INFO - 'run-tests'), 16:40:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:40:04 INFO - 'download_minidump_stackwalk': True, 16:40:04 INFO - 'download_symbols': 'ondemand', 16:40:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:40:04 INFO - 'tooltool.py': '/tools/tooltool.py', 16:40:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:40:04 INFO - '/tools/misc-python/virtualenv.py')}, 16:40:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:40:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:40:04 INFO - 'installer_path': 'installer.exe', 16:40:04 INFO - 'log_level': 'info', 16:40:04 INFO - 'log_name': 'talos', 16:40:04 INFO - 'log_to_console': True, 16:40:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:40:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:40:04 INFO - 'opt_config_files': (), 16:40:04 INFO - 'pip_index': False, 16:40:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:40:04 INFO - 'cmd': ('bash', 16:40:04 INFO - '-c', 16:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:40:04 INFO - 'enabled': True, 16:40:04 INFO - 'halt_on_failure': False, 16:40:04 INFO - 'name': 'check_screen_resolution'},), 16:40:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:40:04 INFO - 'cmd': ('bash', 16:40:04 INFO - '-c', 16:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:40:04 INFO - 'enabled': True, 16:40:04 INFO - 'halt_on_failure': False, 16:40:04 INFO - 'name': 'check_screen_resolution'},), 16:40:04 INFO - 'run_cmd_checks_enabled': True, 16:40:04 INFO - 'sps_profile': False, 16:40:04 INFO - 'sps_profile_interval': 0, 16:40:04 INFO - 'suite': 'dromaeojs', 16:40:04 INFO - 'system_bits': '32', 16:40:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:40:04 INFO - 'title': 't-yosemite-r7-0176', 16:40:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:40:04 INFO - 'use_talos_json': True, 16:40:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:40:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:40:04 INFO - 'work_dir': 'build'} 16:40:04 INFO - ##### 16:40:04 INFO - ##### Running clobber step. 16:40:04 INFO - ##### 16:40:04 INFO - Running pre-action listener: _resource_record_pre_action 16:40:04 INFO - Running main action method: clobber 16:40:04 INFO - rmtree: /builds/slave/test/build 16:40:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:40:06 INFO - Running post-action listener: _resource_record_post_action 16:40:06 INFO - ##### 16:40:06 INFO - ##### Finished clobber step (success) 16:40:06 INFO - ##### 16:40:06 INFO - ##### 16:40:06 INFO - ##### Running read-buildbot-config step. 16:40:06 INFO - ##### 16:40:06 INFO - Running pre-action listener: _resource_record_pre_action 16:40:06 INFO - Running main action method: read_buildbot_config 16:40:06 INFO - Using buildbot properties: 16:40:06 INFO - { 16:40:06 INFO - "project": "", 16:40:06 INFO - "product": "firefox", 16:40:06 INFO - "script_repo_revision": "production", 16:40:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:40:06 INFO - "repository": "", 16:40:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:40:06 INFO - "buildid": "20160701144623", 16:40:06 INFO - "pgo_build": "False", 16:40:06 INFO - "basedir": "/builds/slave/test", 16:40:06 INFO - "buildnumber": 67, 16:40:06 INFO - "slavename": "t-yosemite-r7-0176", 16:40:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:40:06 INFO - "platform": "yosemite_r7", 16:40:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:40:06 INFO - "branch": "fx-team", 16:40:06 INFO - "repo_path": "integration/fx-team", 16:40:06 INFO - "revision": "9b5be9da64bafc31baba3bc81c5d42785081e3d9", 16:40:06 INFO - "stage_platform": "macosx64", 16:40:06 INFO - "builduid": "3ee81b4b4ea947d79cae7c45fb7f471b", 16:40:06 INFO - "slavebuilddir": "test" 16:40:06 INFO - } 16:40:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:40:06 INFO - Running post-action listener: _resource_record_post_action 16:40:06 INFO - ##### 16:40:06 INFO - ##### Finished read-buildbot-config step (success) 16:40:06 INFO - ##### 16:40:06 INFO - ##### 16:40:06 INFO - ##### Running download-and-extract step. 16:40:06 INFO - ##### 16:40:06 INFO - Running pre-action listener: _resource_record_pre_action 16:40:06 INFO - Running main action method: download_and_extract 16:40:06 INFO - mkdir: /builds/slave/test/build/tests 16:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:06 INFO - https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:40:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:40:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:40:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:11 INFO - Downloaded 1199 bytes. 16:40:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:40:11 INFO - Using the following test package requirements: 16:40:11 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:40:11 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:40:11 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:40:11 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'jsshell-mac.zip'], 16:40:11 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:40:11 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:40:11 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:40:11 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:40:11 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:40:11 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:40:11 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:40:11 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:40: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')]} 16:40:11 INFO - https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:40:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:40:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:40:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:13 INFO - Downloaded 18789565 bytes. 16:40:13 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'] 16:40:13 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 16:40:16 INFO - Return code: 0 16:40:16 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 16:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:16 INFO - https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:40:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:40:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:40:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:22 INFO - Downloaded 18789565 bytes. 16:40:22 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'] 16:40:22 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 16:40:26 INFO - Return code: 0 16:40: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')]} 16:40:26 INFO - https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:40:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:40:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:40:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:27 INFO - Downloaded 14405242 bytes. 16:40:27 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'] 16:40:27 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 16:40:27 INFO - Return code: 0 16:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:27 INFO - https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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 16:40:32 INFO - Downloaded 112415831 bytes. 16:40:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:40:32 INFO - mkdir: /builds/slave/test/properties 16:40:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:40:32 INFO - Writing to file /builds/slave/test/properties/build_url 16:40:32 INFO - Contents: 16:40:32 INFO - build_url:https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:40:32 INFO - Running post-action listener: _resource_record_post_action 16:40:32 INFO - Running post-action listener: set_extra_try_arguments 16:40:32 INFO - ##### 16:40:32 INFO - ##### Finished download-and-extract step (success) 16:40:32 INFO - ##### 16:40:32 INFO - ##### 16:40:32 INFO - ##### Running populate-webroot step. 16:40:32 INFO - ##### 16:40:32 INFO - Running pre-action listener: _resource_record_pre_action 16:40:32 INFO - Running main action method: populate_webroot 16:40:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:40:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:40:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:40:32 INFO - '--tppagecycles', 16:40:32 INFO - '7'], 16:40:32 INFO - 'tests': ['tsvgm']}}, 16:40:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:40:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:40:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:40:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:40:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['damp', 'tps']}, 16:40:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'tests': ['damp', 'tps']}, 16:40:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['dromaeo_dom']}, 16:40:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:40:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['basic_compositor_video']}, 16:40:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:40:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'other-e10s': {'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['a11yr', 16:40:32 INFO - 'ts_paint', 16:40:32 INFO - 'tpaint', 16:40:32 INFO - 'sessionrestore', 16:40:32 INFO - 'sessionrestore_no_auto_restore', 16:40:32 INFO - 'tabpaint']}, 16:40:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['tsvgx', 16:40:32 INFO - 'tsvgr_opacity', 16:40:32 INFO - 'tart', 16:40:32 INFO - 'tscrollx', 16:40:32 INFO - 'cart']}, 16:40:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:40:32 INFO - 'tsvgr_opacity', 16:40:32 INFO - 'tart', 16:40:32 INFO - 'tscrollx', 16:40:32 INFO - 'cart']}, 16:40:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'talos_options': ['--disable-e10s'], 16:40:32 INFO - 'tests': ['tp5o']}, 16:40:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'tests': ['tp5o']}, 16:40:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'talos_options': ['--disable-e10s', 16:40:32 INFO - '--xperf_path', 16:40:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:32 INFO - 'tests': ['tp5n']}, 16:40:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:32 INFO - 'talos_options': ['--xperf_path', 16:40:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:32 INFO - 'tests': ['tp5n']}}, 16:40:32 INFO - 'talos.zip': {'path': '', 16:40:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:40:32 INFO - Running post-action listener: _resource_record_post_action 16:40:32 INFO - ##### 16:40:32 INFO - ##### Finished populate-webroot step (success) 16:40:32 INFO - ##### 16:40:32 INFO - ##### 16:40:32 INFO - ##### Running create-virtualenv step. 16:40:32 INFO - ##### 16:40:32 INFO - Running pre-action listener: _resource_record_pre_action 16:40:32 INFO - Running main action method: create_virtualenv 16:40:32 INFO - Creating virtualenv /builds/slave/test/build/venv 16:40:32 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 16:40:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:40:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:40:32 INFO - Using real prefix '/tools/python27' 16:40:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:40:33 INFO - Installing distribute.............................................................................................................................................................................................done. 16:40:36 INFO - Installing pip.................done. 16:40:36 INFO - Return code: 0 16:40:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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')]} 16:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40: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', 'pip>=1.5'] in /builds/slave/test/build 16:40: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 pip>=1.5 16:40:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:36 INFO - 'HOME': '/Users/cltbld', 16:40:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PAGER': '/bin/cat', 16:40:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:36 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - 'XPC_FLAGS': '0x0', 16:40:36 INFO - 'XPC_SERVICE_NAME': '0', 16:40:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:36 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 16:40:36 INFO - Cleaning up... 16:40:36 INFO - Return code: 0 16:40:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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')]} 16:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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', '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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:40: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 psutil>=3.1.1 16:40:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:36 INFO - 'HOME': '/Users/cltbld', 16:40:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PAGER': '/bin/cat', 16:40:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:36 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - 'XPC_FLAGS': '0x0', 16:40:36 INFO - 'XPC_SERVICE_NAME': '0', 16:40:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:37 INFO - Downloading/unpacking psutil>=3.1.1 16:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:40:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:39 INFO - Installing collected packages: psutil 16:40:39 INFO - Running setup.py install for psutil 16:40:40 INFO - building 'psutil._psutil_osx' extension 16:40:40 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 16:40:40 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 16:40:40 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 16:40:40 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 16:40:40 INFO - building 'psutil._psutil_posix' extension 16:40:40 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 16:40:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:40:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:40:40 INFO - ^ 16:40:40 INFO - 1 warning generated. 16:40:40 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 16:40:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:41 INFO - Successfully installed psutil 16:40:41 INFO - Cleaning up... 16:40:41 INFO - Return code: 0 16:40:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:41 INFO - 'HOME': '/Users/cltbld', 16:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:41 INFO - 'LOGNAME': 'cltbld', 16:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:41 INFO - 'MOZ_NO_REMOTE': '1', 16:40:41 INFO - 'NO_EM_RESTART': '1', 16:40:41 INFO - 'PAGER': '/bin/cat', 16:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:41 INFO - 'PWD': '/builds/slave/test', 16:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:41 INFO - 'SHELL': '/bin/bash', 16:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:41 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:41 INFO - 'USER': 'cltbld', 16:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:41 INFO - 'XPC_FLAGS': '0x0', 16:40:41 INFO - 'XPC_SERVICE_NAME': '0', 16:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:40:44 INFO - Installing collected packages: mozsystemmonitor 16:40:44 INFO - Running setup.py install for mozsystemmonitor 16:40:44 INFO - Successfully installed mozsystemmonitor 16:40:44 INFO - Cleaning up... 16:40:44 INFO - Return code: 0 16:40:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:44 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:44 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 16:40:44 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 16:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:44 INFO - 'HOME': '/Users/cltbld', 16:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:44 INFO - 'LOGNAME': 'cltbld', 16:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:44 INFO - 'MOZ_NO_REMOTE': '1', 16:40:44 INFO - 'NO_EM_RESTART': '1', 16:40:44 INFO - 'PAGER': '/bin/cat', 16:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:44 INFO - 'PWD': '/builds/slave/test', 16:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:44 INFO - 'SHELL': '/bin/bash', 16:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:44 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:44 INFO - 'USER': 'cltbld', 16:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:44 INFO - 'XPC_FLAGS': '0x0', 16:40:44 INFO - 'XPC_SERVICE_NAME': '0', 16:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:44 INFO - Downloading/unpacking jsonschema==2.5.1 16:40:44 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 16:40:44 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 16:40:44 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 16:40:44 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 16:40:44 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 16:40:44 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 16:40:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:40:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:40:46 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 16:40:46 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 16:40:46 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 16:40:46 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 16:40:46 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 16:40:46 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 16:40:47 INFO - Downloading functools32-3.2.3-2.tar.gz 16:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:40:47 INFO - warning: no files found matching '*.txt' 16:40:47 INFO - no previously-included directories found matching 'build' 16:40:47 INFO - no previously-included directories found matching 'dist' 16:40:47 INFO - no previously-included directories found matching '.git*' 16:40:47 INFO - Installing collected packages: jsonschema, functools32 16:40:47 INFO - Running setup.py install for functools32 16:40:47 INFO - warning: no files found matching '*.txt' 16:40:47 INFO - no previously-included directories found matching 'build' 16:40:47 INFO - no previously-included directories found matching 'dist' 16:40:47 INFO - no previously-included directories found matching '.git*' 16:40:47 INFO - Successfully installed jsonschema functools32 16:40:47 INFO - Cleaning up... 16:40:47 INFO - Return code: 0 16:40:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:47 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:47 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 16:40:47 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 16:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:47 INFO - 'HOME': '/Users/cltbld', 16:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:47 INFO - 'LOGNAME': 'cltbld', 16:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:47 INFO - 'MOZ_NO_REMOTE': '1', 16:40:47 INFO - 'NO_EM_RESTART': '1', 16:40:47 INFO - 'PAGER': '/bin/cat', 16:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:47 INFO - 'PWD': '/builds/slave/test', 16:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:47 INFO - 'SHELL': '/bin/bash', 16:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:47 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:47 INFO - 'USER': 'cltbld', 16:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:47 INFO - 'XPC_FLAGS': '0x0', 16:40:47 INFO - 'XPC_SERVICE_NAME': '0', 16:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:47 INFO - Downloading/unpacking blobuploader==1.2.4 16:40:47 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 16:40:47 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 16:40:47 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 16:40:47 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 16:40:47 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 16:40:47 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 16:40:50 INFO - Downloading blobuploader-1.2.4.tar.gz 16:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:40:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:40:50 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 16:40:50 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 16:40:50 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 16:40:50 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 16:40:50 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 16:40:50 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 16:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:40:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:40:51 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 16:40:51 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 16:40:51 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 16:40:51 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 16:40:51 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 16:40:51 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 16:40:51 INFO - Downloading docopt-0.6.1.tar.gz 16:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:40:51 INFO - Installing collected packages: blobuploader, requests, docopt 16:40:51 INFO - Running setup.py install for blobuploader 16:40:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:40:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:40:51 INFO - Running setup.py install for requests 16:40:52 INFO - Running setup.py install for docopt 16:40:52 INFO - Successfully installed blobuploader requests docopt 16:40:52 INFO - Cleaning up... 16:40:52 INFO - Return code: 0 16:40:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:52 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:52 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 16:40:52 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 16:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:52 INFO - 'HOME': '/Users/cltbld', 16:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:52 INFO - 'LOGNAME': 'cltbld', 16:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:52 INFO - 'MOZ_NO_REMOTE': '1', 16:40:52 INFO - 'NO_EM_RESTART': '1', 16:40:52 INFO - 'PAGER': '/bin/cat', 16:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:52 INFO - 'PWD': '/builds/slave/test', 16:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:52 INFO - 'SHELL': '/bin/bash', 16:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:52 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:52 INFO - 'USER': 'cltbld', 16:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:52 INFO - 'XPC_FLAGS': '0x0', 16:40:52 INFO - 'XPC_SERVICE_NAME': '0', 16:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:52 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2ECBwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:52 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-xiuI9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:52 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-cJhMiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-9DIrYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-7UkwgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2hR2OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mLYnl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-qv4MrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JZTCPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-xzm1Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-AECWWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:53 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-p332Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:54 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-WM2pUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:54 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-6L7tkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:54 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-qeFYci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:54 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-TRagVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:54 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-bsrATy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:40:54 INFO - Running setup.py install for manifestparser 16:40:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:40:54 INFO - Running setup.py install for mozcrash 16:40:54 INFO - Running setup.py install for mozdebug 16:40:54 INFO - Running setup.py install for mozdevice 16:40:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Running setup.py install for mozfile 16:40:55 INFO - Running setup.py install for mozhttpd 16:40:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Running setup.py install for mozinfo 16:40:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Running setup.py install for mozInstall 16:40:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:40:55 INFO - Running setup.py install for mozleak 16:40:55 INFO - Running setup.py install for mozlog 16:40:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Running setup.py install for moznetwork 16:40:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Running setup.py install for mozprocess 16:40:56 INFO - Running setup.py install for mozprofile 16:40:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Running setup.py install for mozrunner 16:40:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:40:56 INFO - Running setup.py install for mozscreenshot 16:40:56 INFO - Running setup.py install for moztest 16:40:56 INFO - Running setup.py install for mozversion 16:40:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:40:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:40:57 INFO - Cleaning up... 16:40:57 INFO - Return code: 0 16:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:57 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')]} 16:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:57 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')]} 16:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:57 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:40:57 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 16:40:57 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 16:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:57 INFO - 'HOME': '/Users/cltbld', 16:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:57 INFO - 'LOGNAME': 'cltbld', 16:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:57 INFO - 'MOZ_NO_REMOTE': '1', 16:40:57 INFO - 'NO_EM_RESTART': '1', 16:40:57 INFO - 'PAGER': '/bin/cat', 16:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:57 INFO - 'PWD': '/builds/slave/test', 16:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:57 INFO - 'SHELL': '/bin/bash', 16:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:40:57 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:57 INFO - 'USER': 'cltbld', 16:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:57 INFO - 'XPC_FLAGS': '0x0', 16:40:57 INFO - 'XPC_SERVICE_NAME': '0', 16:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Wmx2W5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:57 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)) 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Q3wvSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:57 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)) 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-o4u7eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:57 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)) 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-HOSdoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:57 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)) 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-k0MGS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:57 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)) 16:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Ho7Gk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-oQRywd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-30PKYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-BbUn2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5GHhc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-vEMkR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-V9h5A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-tDsbxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-3DNZxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JzHlSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:58 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)) 16:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-_NFCfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:59 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)) 16:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:59 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5QlLHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:59 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)) 16:40:59 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)) 16:40:59 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)) 16:40:59 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)) 16:40:59 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)) 16:40:59 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)) 16:40:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:40:59 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 16:40:59 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 16:40:59 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 16:40:59 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 16:40:59 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 16:40:59 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 16:41:01 INFO - Downloading blessings-1.6.tar.gz 16:41:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:41:01 INFO - Installing collected packages: blessings 16:41:01 INFO - Running setup.py install for blessings 16:41:01 INFO - Successfully installed blessings 16:41:01 INFO - Cleaning up... 16:41:02 INFO - Return code: 0 16:41:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:41:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:02 INFO - Reading from file tmpfile_stdout 16:41:02 INFO - Current package versions: 16:41:02 INFO - blessings == 1.6 16:41:02 INFO - blobuploader == 1.2.4 16:41:02 INFO - docopt == 0.6.1 16:41:02 INFO - functools32 == 3.2.3-2 16:41:02 INFO - jsonschema == 2.5.1 16:41:02 INFO - manifestparser == 1.1 16:41:02 INFO - mozInstall == 1.12 16:41:02 INFO - mozcrash == 0.17 16:41:02 INFO - mozdebug == 0.1 16:41:02 INFO - mozdevice == 0.48 16:41:02 INFO - mozfile == 1.2 16:41:02 INFO - mozhttpd == 0.7 16:41:02 INFO - mozinfo == 0.9 16:41:02 INFO - mozleak == 0.1 16:41:02 INFO - mozlog == 3.2 16:41:02 INFO - moznetwork == 0.27 16:41:02 INFO - mozprocess == 0.22 16:41:02 INFO - mozprofile == 0.28 16:41:02 INFO - mozrunner == 6.11 16:41:02 INFO - mozscreenshot == 0.1 16:41:02 INFO - mozsystemmonitor == 0.3 16:41:02 INFO - moztest == 0.7 16:41:02 INFO - mozversion == 1.4 16:41:02 INFO - psutil == 3.1.1 16:41:02 INFO - requests == 1.2.3 16:41:02 INFO - wsgiref == 0.1.2 16:41:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:02 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:41:02 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 16:41:02 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 16:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:02 INFO - 'HOME': '/Users/cltbld', 16:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:02 INFO - 'LOGNAME': 'cltbld', 16:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:02 INFO - 'MOZ_NO_REMOTE': '1', 16:41:02 INFO - 'NO_EM_RESTART': '1', 16:41:02 INFO - 'PAGER': '/bin/cat', 16:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:02 INFO - 'PWD': '/builds/slave/test', 16:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:02 INFO - 'SHELL': '/bin/bash', 16:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:41:02 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:02 INFO - 'USER': 'cltbld', 16:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:02 INFO - 'XPC_FLAGS': '0x0', 16:41:02 INFO - 'XPC_SERVICE_NAME': '0', 16:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 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)) 16:41:02 INFO - Cleaning up... 16:41:02 INFO - Return code: 0 16:41:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:02 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 0x102c8ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d23618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc15a4ddb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, '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.5NfhuD30iM/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.9bS7j3khRR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:41:02 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 16:41:02 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 16:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:02 INFO - 'HOME': '/Users/cltbld', 16:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:02 INFO - 'LOGNAME': 'cltbld', 16:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:02 INFO - 'MOZ_NO_REMOTE': '1', 16:41:02 INFO - 'NO_EM_RESTART': '1', 16:41:02 INFO - 'PAGER': '/bin/cat', 16:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:02 INFO - 'PWD': '/builds/slave/test', 16:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:02 INFO - 'SHELL': '/bin/bash', 16:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:41:02 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:02 INFO - 'USER': 'cltbld', 16:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:02 INFO - 'XPC_FLAGS': '0x0', 16:41:02 INFO - 'XPC_SERVICE_NAME': '0', 16:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:41:02 INFO - Cleaning up... 16:41:02 INFO - Return code: 0 16:41:02 INFO - Running post-action listener: _resource_record_post_action 16:41:02 INFO - Running post-action listener: _start_resource_monitoring 16:41:02 INFO - Starting resource monitoring. 16:41:02 INFO - ##### 16:41:02 INFO - ##### Finished create-virtualenv step (success) 16:41:02 INFO - ##### 16:41:02 INFO - ##### 16:41:02 INFO - ##### Running install step. 16:41:02 INFO - ##### 16:41:02 INFO - Running pre-action listener: _resource_record_pre_action 16:41:02 INFO - Running main action method: install 16:41:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:03 INFO - Reading from file tmpfile_stdout 16:41:03 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:03 INFO - Reading from file tmpfile_stdout 16:41:03 INFO - Output received: 16:41:03 INFO - Usage: mozinstall [options] installer 16:41:03 INFO - Options: 16:41:03 INFO - -h, --help show this help message and exit 16:41:03 INFO - -d DEST, --destination=DEST 16:41:03 INFO - Directory to install application into. [default: 16:41:03 INFO - "/builds/slave/test"] 16:41:03 INFO - --app=APP Application being installed. [default: firefox] 16:41:03 INFO - mkdir: /builds/slave/test/build/application 16:41:03 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'] 16:41:03 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 16:41:37 INFO - Reading from file tmpfile_stdout 16:41:37 INFO - Output received: 16:41:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:41:37 INFO - Running post-action listener: _resource_record_post_action 16:41:37 INFO - ##### 16:41:37 INFO - ##### Finished install step (success) 16:41:37 INFO - ##### 16:41:37 INFO - ##### 16:41:37 INFO - ##### Running run-tests step. 16:41:37 INFO - ##### 16:41:37 INFO - Running pre-action listener: _resource_record_pre_action 16:41:37 INFO - Running main action method: run_tests 16:41:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:41:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:41:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:41:37 INFO - 2016-07-01 16:41:37.436 screenresolution[1643:8353] starting screenresolution argv=screenresolution get 16:41:37 INFO - 2016-07-01 16:41:37.439 screenresolution[1643:8353] Display 0: 1600x1200x32@60 16:41:37 INFO - 2016-07-01 16:41:37.445 screenresolution[1644:8355] starting screenresolution argv=screenresolution list 16:41:37 INFO - Available Modes on Display 0 16:41:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:41:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:41:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:41:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:41:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:41:37 INFO - Intel Iris: 16:41:37 INFO - Chipset Model: Intel Iris 16:41:37 INFO - Type: GPU 16:41:37 INFO - Bus: Built-In 16:41:37 INFO - VRAM (Dynamic, Max): 1536 MB 16:41:37 INFO - Vendor: Intel (0x8086) 16:41:37 INFO - Device ID: 0x0a2e 16:41:37 INFO - Revision ID: 0x0009 16:41:37 INFO - Displays: 16:41:37 INFO - Display: 16:41:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:41:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:41:37 INFO - Display Serial Number: 1600x1200 60 16:41:37 INFO - Main Display: Yes 16:41:37 INFO - Mirror: Off 16:41:37 INFO - Online: Yes 16:41:37 INFO - Rotation: Supported 16:41:37 INFO - Return code: 0 16:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:41:37 INFO - Python 2.7.3 16:41:37 INFO - Return code: 0 16:41:37 INFO - grabbing minidump binary from tooltool 16:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:41:37 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 0x102bf2d30>, '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 0x102957db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102beab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:41:37 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 16:41:37 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 16:41:37 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 16:41:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:41:37 INFO - Return code: 0 16:41:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:41:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:41:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:41:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:41:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:41:37 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/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 16:41:37 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/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --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 16:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NfhuD30iM/Render', 16:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:37 INFO - 'HOME': '/Users/cltbld', 16:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:37 INFO - 'LOGNAME': 'cltbld', 16:41:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:41:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:37 INFO - 'MOZ_NO_REMOTE': '1', 16:41:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:41:37 INFO - 'NO_EM_RESTART': '1', 16:41:37 INFO - 'PAGER': '/bin/cat', 16:41:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:37 INFO - 'PWD': '/builds/slave/test', 16:41:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:37 INFO - 'SHELL': '/bin/bash', 16:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9bS7j3khRR/Listeners', 16:41:37 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:37 INFO - 'USER': 'cltbld', 16:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:37 INFO - 'XPC_FLAGS': '0x0', 16:41:37 INFO - 'XPC_SERVICE_NAME': '0', 16:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:37 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/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 16:41:38 INFO - mozversion application_buildid: 20160701144623 16:41:38 INFO - mozversion application_changeset: 9b5be9da64bafc31baba3bc81c5d42785081e3d9 16:41:38 INFO - mozversion application_display_name: Nightly 16:41:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:41:38 INFO - mozversion application_name: Firefox 16:41:38 INFO - mozversion application_remotingname: firefox 16:41:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:41:38 INFO - mozversion application_vendor: Mozilla 16:41:38 INFO - mozversion application_version: 50.0a1 16:41:38 INFO - mozversion platform_buildid: 20160701144623 16:41:38 INFO - mozversion platform_changeset: 9b5be9da64bafc31baba3bc81c5d42785081e3d9 16:41:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:41:38 INFO - mozversion platform_version: 50.0a1 16:41:38 INFO - using testdate: 1467416498 16:41:38 INFO - actual date: 1467416498 16:41:38 INFO - starting webserver on 'localhost:49271' 16:41:38 INFO - SUITE-START | Running 2 tests 16:41:38 INFO - TEST-START | dromaeo_css 16:41:38 INFO - operating with platform_type : mac_ 16:41:38 INFO - Initialising browser for dromaeo_css test... 16:41:38 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpVCTpB7/profile http://localhost:49271/getInfo.html) 16:41:40 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 16:41:40 INFO - PROCESS | 1654 | colorDepth:24 16:41:40 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 16:41:40 INFO - PROCESS | 1654 | __metrics 16:41:40 INFO - PROCESS | 1654 | 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" 16:41:40 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:41:41 INFO - TEST-INFO | 1654: exit 0 16:41:41 INFO - Browser initialized. 16:41:41 INFO - Running cycle 1/1 for dromaeo_css test... 16:41:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpVCTpB7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:41:53 INFO - PROCESS | 1657 | RSS: Main: 243572736 16:41:53 INFO - PROCESS | 1657 | 16:42:44 INFO - PROCESS | 1657 | 1467416564751 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] 16:44:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 16:44:56 INFO - PROCESS | 1657 | RSS: Main: 338993152 16:44:56 INFO - PROCESS | 1657 | 16:47:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 16:47:59 INFO - PROCESS | 1657 | RSS: Main: 381030400 16:47:59 INFO - PROCESS | 1657 | 16:51:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 16:51:02 INFO - PROCESS | 1657 | RSS: Main: 376410112 16:51:02 INFO - PROCESS | 1657 | 16:54:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 16:54:05 INFO - PROCESS | 1657 | RSS: Main: 371974144 16:54:05 INFO - PROCESS | 1657 | 16:57:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 16:57:09 INFO - PROCESS | 1657 | RSS: Main: 345141248 16:57:09 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 17:00:12 INFO - PROCESS | 1657 | RSS: Main: 382488576 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | __start_tp_report 17:00:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 17:00:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 17:00:12 INFO - PROCESS | 1657 | |i|pagename|runs| 17:00:12 INFO - PROCESS | 1657 | |0;dojo.html;3279.212988882668;3308.057203696947;3319.4191016572095;3344.866205351786;3370.7471939604534;5571.498565129137;5602;5660.3490598581175;5663.3204015518095;5708.400617935119;5021;5628.465295796893;5744.396838331967;5761.164631128494;5781.537477001841;5071.467495912932;5099.36257967754;5140.768665410047;5153.613478989072;5208.166693329068;5174.379074511064;5207.739613019353;5219.947800521984;5246.081935661263;5261.947380526203;5066.06277838601;5086.643934924557;5118.744062796883;5135.563477104451;5165.81919632813;5086.4659210783075;5104.464031276737;5120.974395128038;5237.895242095175;5246.396664383607;5109.20807274873;5134.922976155361;5149.639525233235;5154.561862241714;5195.662281951699;5556.555475561945;5620.325560932673;5736.082226843685;5741.19623252745;5743.885122297573;6066.785369686932;6147.938520614782;6151.96924015377;6184.07238914162;6196.504279657616;5402.756875940553;6180.381961803829;6244.906326405107;6252.687365631746;6254.468370188494;5604.775808967677;5696.287964004499;5763.5101016413655;5776.711164441761;5787;6259.812205633838;6261.029540421242;6266.530010249249;6268.968655156695;6282.837675030134;6121.095133350306;6133.846653833601;6136.846578835565;6140;6148.846278843064;6093.11649810775;6106.551676171891;6161.045038019114;6238.781642642576;6259.248890133122;6148.477379422709;6183.443490085914;6185.226846644169;6203.038529028008;6208.255009398811;6141.170941922784;6172.722227499773;6209.316975132731;6235.968820155961;6250.343713910065;4848.7090774553635;4897.534734200245;4908.435529914034;4922.089413458521;4976.950230497649;4381.605655491021;4423.071155057474;4444.933326000048;4458.509563947963;4462.598366147063;5695.40198279183;5701.059325211294;5718.571107166896;5721.342045664802;5782.277215348082;6113.938860611337;6207.037909124093;6256.061590761331;6258.499320054385;6267.5299352548745;5588.357338905997;5607.635503692247;5646.407127251579;5652.7173641318595;5656.943430565641;4669.995950870579;5345.812896548602;5353.277307563507;5391.622586418992;5394.056040193029;6250.218722659668;6253.96873015632;6257.8748425030335;6258.718357673915;6271.808356412267;6225.533085018551;6260.968695156495;6265.310815810349;6267.4045965633195;6275.215598050244;6187.566870319034;6221.626702397871;6246.250449945854;6249.50003999688;6260;6134.8159555213415;6139.539534534839;6140.140380346666;6146.477549408351;6156.445919867055;6082.817515474543;6095.969520152371;6182.505399568114;6184.979478386196;6203.96898015507;5881.176635271151;5943.881122377615;6001.669908155093;6020.367861374668;6054.091886217103;5905.763769449174;6057.909131362945;6067.150598916067;6076.8784624308155;6078.817635470943;5789.710514474344;5894.616849904743;5896.056630939029;5902.704864756831;5905.793297234576;4839.612830973584;6023.759049637965;6079.966405710951;6098.847528811815;6113.877722445615;5889.116632505158;5910.7931222407005;5962.135490353961;5972.790952316648;5973.940260597338 17:00:12 INFO - PROCESS | 1657 | |1;ext.html;495.3980913190474;501.41585053412774;538.3028977474171;540.3380858448401;545.6207935484837;29995.250118747015;31758.888438904643;40318.99202519938;41618.37572436409;41649;43295;43481.13037739243;43563.346549801696;43664.781676091574;43694;9559.522023898811;9739.318247722662;9745.220382369429;9749.02509749023;9766.755831104243;9652.276079294046;9734.707958761248;9748.220142388593;9764.072413120743;9785.217182625373;9674.661386851461;9729;9798.314118011742;9807.950960245225;9829.754256143618;9709.70870873875;9744.561494732754;9768.267379946461;9778.07108324708;9778.902210977905;9645.951770241103;9727.708168754949;9734.561944712505;9744.171745401649;9752.4636145012;41028.79485602583;41427.58572414267;41437.58562414368;41458.1708365831;41480.96297592554;3379.459286514146;3404.5063465797566;3433;3528.5412896323314;3605.6214097519905;31874.840625796955;32446.837765811255;32661.510077348623;32942.84700035487;33062.3387532248;3215.790973586733;3366.8316584170693;3373.4433818420175;3399.6770306820813;3410.471376936579;529485.3525732347;536522;536577;536670.3166484142;536775;62348.06477902835;63331;63396.68301658462;63570;63580.68209658922;58621.70689146527;65989.67005164943;66101.38138258205;66124.33875661278;66148;15956.202189890691;16137;16162.191890405433;16193.676126477405;16229.837701622833;12380.81428778569;12428.502859885684;12430.254184748945;12453.315067671365;12484.938780203784;17276.394361998566;19252.493334649724;19582.31461898827;19611.21555137807;19681.31115410954;6572;6823.522353435212;8035.477693949874;8168;8197.71308004217;896.171041786345;952.538019060755;961.5048250151178;962.9277804164716;1047.6490375724065;30097.548536771967;30380;30462;30724.79700187383;31947.201319966724;1066.1311031509294;1074.4949873559572;1093.0872721277663;1093.7593729379682;1114.331401159314;16020.519384418487;16053.357865685242;16072;16229.39053808899;16347.18264086791;652566;669491;675153;676148;676661;59450.70274648599;59795.10307345393;59984.70007650021;60060.39939600635;60220;1274.5029438518984;1380.7100508893045;1404.2767974493145;1415.812569580022;1428.1573871415915;26499.60250596204;30818.537721934637;32060.6793932067;32517;32587.511187331736;49247;49451.25823112728;50081.74959125181;50108;50168.74915625399;522802;525733;525886;525923;526947;24716.25851224394;26856.79144438466;27113.593296100968;27352.72647273502;27386.452270954072;48844.75577622089;48981;49273.50726492689;49416.752916235186;49995.75002124966;39405.802970984965;40464.98837528968;40508.59491405048;41588.79205603953;41666.37500437435;40212.59787402205;40585.79707101446;40770.184596307314;41171;41520;38827.80586097051;39287.803560982014;39357.80321098376;39415.408768867914;41247.381289280085;32723;33136;35082.94751157469;35637.82181089078;36249.275014499035;23846.16538421147;26067;26210.08264710726;26290.21129366122;26977.190684279503 17:00:12 INFO - PROCESS | 1657 | |2;jquery.html;359.9046252743022;380.4312552733663;387.53302270763714;388.2797410802961;412.6760493012985;15371.538853834389;15515;20613.587728245428;21049.368518944437;21073.683894741553;20066.899665501653;20520;20829.06269217885;21078.894605526988;21087.2619458319;5143.53708166265;5557.972210138943;5753.827385178431;6091.42131497507;6708.899366509506;6595.406413422791;6674.032265321525;6683.465322774191;6693.029510720942;6701.564398314094;6338.239411270654;6596.373344532261;6601.372869577406;6607.603543787388;6607.867842643144;6329.303776584572;6554.475641948657;6592.505562082839;6605.735770569171;6635.933640663604;5658.359075867993;5688.146777983295;5691.174779656926;5699.544036477113;5702.5723070769445;24989.6251556225;25058.624120638204;25122.748772512405;25400.36499087519;25811.09661161869;2179.5422961178174;2321;2863.040881304765;3067.5091985282247;3194.8402579870913;15576.98749581285;15601.45394911184;15605.297761600756;15660.530184094496;16006.439774607832;1865.7014877619652;1957.4910523263911;1964.2437661500426;1971.2213675598202;1994.4216177308563;105221.42167867412;111252.99999999919;118650;122166;122580.35486628755;10311;10523.894761052445;10647.307924985005;10746.516406761715;10912.945435272773;13039.804402933964;13147.276899770604;13149.934250328688;13170.47318107284;13170.736585268241;8184.140665230065;8350.582470876432;8359.247667709935;8418.873716894252;8438.873416898752;8333.541655208901;8344.457610255315;8369.790755231168;8515.616797244138;8531.65873365071;6405.775797847053;6490.837729056811;6490.935090649033;6526.836829079216;6592;1801.162459456333;1825.917833697487;1831.3864855273366;1832.5510249988838;1839.4021942868621;915.8871970555674;943.1747221181466;980.6028558433845;1001.5342865567546;1018.5416562546884;21282.680759788615;21326;21421;21491.140354385963;21506.032228549753;935.8689783430325;964.7009427077628;970.752458123174;977.6898955399656;978.2907392140642;11120.666380008613;11564.710882228012;11578.247413918069;11585.478653460446;11604.593839215755;554476;560746;561459;573389;604949;114635.70728585382;116715;117285;118553.40723296328;119516.40241798735;992.9106380425652;994.2642444591095;1011.5397494140295;1013.1843865688191;1013.6502906497246;18010.18954147093;18987.905060474608;19283.710744338565;19286.324978625682;19339.90330048341;51403.485965141364;52179.59525721717;52237;52909.7354513225;53094.73452632712;63396.366036339045;67875.3212467869;71515.64242178756;74242.62878685573;74456.88314675393;7591.658375373011;7619.847603047797;7824.4131690124605;7830.3735701144915;8072.556009419538;12158.635240942785;12229.449674764479;12416.875831241572;12507.249565026128;12529.436175372313;10748.247622666338;10768.730781730519;10789.892101078889;10828.133749300194;11080.224384293022;9061.22979546746;10198.235132364955;10710.892891071302;10769;10861.456927153453;10837.078848297668;10977.725556861142;11040.33757974524;11081.501332439899;11112.388818615054;7777.4166937478785;7779.105402878515;7809.765707028798;7810.179931107152;7837;8856.734297971072;10594.788104238027;10739.624113156002;10742.8388574173;10796.406197659204 17:00:12 INFO - PROCESS | 1657 | |3;mootools.html;1398.4056775870254;1542.2211783049559;1559.041189668354;1773.9911300443496;1919.635269298834;7805.297523222909;8238.629261683223;8243.340532757373;8256.752297431072;8550.230479256865;7592.27873352032;8576;8645.351598630112;8726.345524085704;8758.562071896411;2266.4447210433427;2343.0627748900406;2344.15610380263;2344.7186337639505;2347.0259842165483;2298.7816157465013;2324.941876453085;2333.4866329407505;2334.2763743239566;2337.4857531343077;2298.3449716830705;2314.594945884472;2327.5228578141523;2338.9649155262687;2339.1929784224417;1972.6843705007202;2295.0934380919443;2309.157157637467;2317.73346065203;2325.755795641467;2299.0803678528555;2306.7923886850263;2307.8730669813153;2308.0190918859585;2311.15642790382;8635.222829945273;8700.390972631918;8702.99915509718;8708.346873984476;8713.607887645008;4699.060187962421;5008.073506401291;5013.5989120870245;5210.53105220532;5231.60762942777;2957.866895989664;3421.794692318444;3478.9478157827652;3558.2349794793986;3560.928781424357;1047.4082143588848;1066.5840322274241;1079.1582565598846;1084.073117484555;1087.3638921231088;11125.054370378528;11193.384363859985;11293.830592541119;11311.604093856675;11343.773124537545;3601.9639803602154;3719.3119272934596;3763.41667041609;4502.4371953505815;4503.797329120198;8535.786605334917;8777.122287771172;8812.118788121137;8814.206721395105;8828.602712877935;4631.768411579407;5114.309568208244;5115.335006449212;5116.462771409023;5119.104156772624;3977.8265411703037;4052.8986775330854;4321.006168581181;4323.956760432356;4573.93139102907;3141.183292344007;3636.4908912752235;3712.5916149224104;3755.248950209969;3773.339665558516;1119.3004372267333;1144.9313041217554;1154.1632316570588;1156.2253290295523;1159.2580748321082;811.5212024905217;815.9311302194145;829.0863705926942;847.5592691800352;862.6376921692779;5405.21624364465;5724.885502289931;5899.970500147472;6066.908996365058;6077.20996270482;372.7558449215768;392.2018691961844;425.8935266183454;452.47964840433104;454.1575377674432;3870.903227419281;3920.764754114762;3979.383195604686;4016.196760647882;4044.3326851069833;8842.955785221033;10315.793684126276;10623.84064239037;10664.573417063386;10686.25196236256;10513.737156570995;10641.042306192325;10646.680599582025;10658.946705266424;10686;8134.105248422668;8151.83696326082;8153.959230203811;8244.422890397615;8244.422890397615;5802.4197580241635;5830.562707797017;5856.736446859816;5926.644401335933;5951.613145145551;9599.808003840024;9719.222462203148;9765.60937562518;9769.120779129913;9770.31607787448;9626.807463850544;9862.112409883144;10050.547725352231;10060.0945914868;10093.495325233856;6182.443580077815;6385.904211436925;6605.636689982097;6961.8955715665315;7004.3696067354185;8250.092489825995;8302.418830681794;8351.123132071045;8361.289290410139;8369.83260334802;7284.781456556099;7387.335139837441;7459.403247740276;7493.925060749323;7533.472656913963;7106.289371062852;7199;7217.458690598121;7330.340269375782;7359.521631094176;6811.625360605214;6850.109485766819;6865.656717164222;6868.965655171693;6877.518573699793;4734.195186818318;4879.634027448026;4941;4943.307936888767;4951.059298733373;4935.605151587936;5039.3448851649055;5060.291559181791;5087.160618498055;5162.045021671003 17:00:12 INFO - PROCESS | 1657 | |4;prototype.html;291.3677320215134;296.15300241309853;304.79426387188647;309.4399137561016;309.93801239752054;5219.504147106024;5280.841574752763;5284.709340986245;5306.23257487184;5359.104984517035;4000.9199816003734;4055.9391609125887;5108.872278193047;5224.503672151149;5314.963582101492;3409.925873349899;3445;3910.6284902934176;4041.494813148364;4060.2894493463673;4002.8198731057028;4023.9798801005954;4060.736052156615;4064.04494943688;4074.9812546863286;3144.8584813683437;3404.7062116395737;3551.3785087609695;3588.4437912123662;4024.9798751006206;3959.169194026285;3959.722819402643;4010.3382941814566;4012.1775036117524;4013.2776100301935;2820.858957052139;3008.353204061137;3310.6854848789567;3478.339115568034;3479.930401391983;4418.977905110486;4943;5150.871228219287;5166.5866730661455;5185.910958698678;3867.7099217558793;4493.573110554492;4555.954440455586;4589.770511474413;4610.239310513761;5644.294463192101;5652.660840349592;5654.491095801398;5667.801626943078;5668.404817494145;3100.364425292832;3268.7385009199443;3821.197548514815;3824.1778017726324;3831.252905683393;5085.389753229636;5447.6459030163305;5765.567582431292;5780.2774653167935;5808.477237048686;3747.887563373103;3807.1814559869763;3823;3827.78947157909;3829.5787463379006;3846.6922646188236;3873.748206366577;3882.4176373544196;3886.7084968627464;3893.766374017568;3836.9232615348096;4391.429114215131;4499.380223119674;4516.299973504112;4540.954590454053;5564.360098588633;5572.9411411831625;5579.6652200868075;5630.211770352154;5631.493165615114;1297.2929753284482;1318.7494376068516;1330.5542643214437;1331.7203387288585;1354.4717560151548;647.4917190005823;711.3810984443464;831.1314676163423;872.1932212703351;891.1845661219911;4694.906101877943;5064.822126485647;5096.796128154832;5105.8723531912;5106.974465127651;5623.578231632644;5628.437156284339;5629.4652008059165;5646.265985421869;5654.6324488908085;4102.774347410921;4212.662986961036;4375.5405682403525;4426.800793964279;5119.46245644202;2683.2486903667004;2986.940261194764;3026.5914101596445;3030.5605687175234;3034.7723920706035;5196.636235463481;5439.456054394529;5640.32316122068;5795.002172751267;5932.2584676915385;5226.738663066832;5560.721034162166;5847.035239185572;5919.141724449931;5943.167956486095;5396.784128634889;5842.941570584239;5880.353161152269;5949.732262048218;5976.503745308956;4737.573618374363;5435.10320797064;5752.254390128244;5755.683437410815;5790.147152911028;4671.579557839811;5215.504527069922;5613.803516876889;5638.900414419149;5660.7169641518585;5891.852703682443;5921.230240068763;5925.163199408197;5949.762009519571;5965.73154208053;3358.9496157558146;3709.9072523187137;3793.487879136281;3815.904602384963;3824.8278827452273;5413.485718856859;5671.347795003628;5699.124595275087;5718.771249149988;5719.198452487453;5187.974060129826;5569.888602228014;5604.579656525695;5628.634138780966;5669.007923613269;5442.809501667422;5477.165983811268;5579.804706835242;5580.469855363734;5606.177992152485;4910;4961.925571116509;5169.379674439091;5185.42960274377;5376.946230537645;4514.435695538058;5058.190689489664;5473.071906529987;5474.534664553557;5478.506934375925;4372.966570544712;4733.83431579893;5470.343558772973;5485.588580856532;5501.889962200654 17:00:12 INFO - PROCESS | 1657 | |5;yui.html;685.369460096711;755.5466719968016;758.5297115788212;765.9770206893795;797.4537441852333;13928.860711392883;14734.63163420915;14738.189399583018;14742.189179595118;14746.557603271918;14104.08323458977;14142.292885355717;14305.356258968346;14316;14353;697.91973922999;725.0936329588015;861.1775754154799;866.0084203586905;897.2059727141494;871.5990644303628;878.227160099115;882.6204731965253;883.2889523933231;884.4737381258174;940.1068984464752;940.2853831088388;940.7459985803829;941.8257622339889;945.6595625574788;711.281605578367;874.5627186406797;935.2798345274135;938.3056538161779;939.3893968920188;702.4099756204813;702.9753958611475;703.1281211298004;704.1936982155455;706.3713295167305;14322.925780566498;14363.138211707332;14390.28048597566;14400.783988240184;14414;13552.254625995567;13776.724465510733;13793.931030344884;13856.445742170301;13931.651708707363;10586.788264234749;12358.75282494354;12432.813507797391;12522.624321270376;12578.182418142791;4482.074190515522;4527.728336299832;4574.862754117382;4658.301254811771;4686.906261874743;98217.5089124557;100314.97165255088;100709;100782;104052.95947040508;10856.457177141112;10922.508487118099;11035.944820276009;11044.834327485094;11121.610743624096;21358.78641213599;21362.78637213608;21378.78621213799;21409.250676226256;21447.892760536408;7585.469017168745;7613.733519326908;7667.0416197975255;7847.019122609674;7887.566183859828;4279.850205242801;4321.092570560218;4343.457067866517;4373.059792144704;4505.819767209275;3396.4395874680895;3494.9126271842897;3495.8252087395526;3504.7021003215013;3506.088417011544;591.8757061017235;640.4940097323156;648.5751832549688;665.0987911380107;668.8595394967014;551.970574877858;558.9692566908777;635.7096529837235;636.3413866647998;709.7303024850523;8480.830383392298;8712.128787121237;8867.334949878656;9400.905990940004;9412.482313472825;479.5779713851788;532.7016870552503;569.5415190771386;638.575347393987;649.8667773106501;4302.591253830881;4338.76136812472;4454.777261136995;4579.335996280521;4630.6758526902795;109273.45363273133;123654;127905.72094279125;128673;146424.53575464108;71319;72559.63720181471;83807.5809620948;120432;134261;112928;114475.85524144986;116562;116646.00123992587;118564.40717796529;9530.332876698842;9563.282753793632;9843.458609776531;9883.456409897524;10000.749981250527;64656.676716616115;65856;66375.66812165908;66651.66674166598;67662.64674706577;57639.423605765085;57939;84119;103051;103163;13182.209067455984;13534.59396218115;13624.119560228715;13679.521216757366;13991.440342386597;15383.538493845203;15897.602559936095;16005.03969761818;16278.104704241381;16345.918270408572;12773.23360598367;13582.777550020546;13889.163878274107;14209;14615.488457903923;13850.515231966832;14325;14458.927705361826;14544.709105818036;14577.92711036438;11044.944775276072;12365.258084514959;13188.80216796768;14214.076085054437;14687.339069742102;11195.496202670736;11732.472038758448;11802.94098529502;11944.283342999448;11969.64091077269;8944.731658050268;9620.037996200324;11597.304161749984;11777.35224562623;11777.470013849568 17:00:12 INFO - PROCESS | 1657 | __end_tp_report 17:00:12 INFO - PROCESS | 1657 | __start_cc_report 17:00:12 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1202 17:00:12 INFO - PROCESS | 1657 | __end_cc_report 17:00:12 INFO - PROCESS | 1657 | __startTimestamp1467417612668__endTimestamp 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | ------- Summary: start ------- 17:00:12 INFO - PROCESS | 1657 | Number of tests: 6 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5690.30 Median:5902.70 stddev:644.11 (10.9%) stddev-sans-first:617.69 17:00:12 INFO - PROCESS | 1657 | Values: 3279.2 3308.1 3319.4 3344.9 3370.7 5571.5 5602.0 5660.3 5663.3 5708.4 5021.0 5628.5 5744.4 5761.2 5781.5 5071.5 5099.4 5140.8 5153.6 5208.2 5174.4 5207.7 5219.9 5246.1 5261.9 5066.1 5086.6 5118.7 5135.6 5165.8 5086.5 5104.5 5121.0 5237.9 5246.4 5109.2 5134.9 5149.6 5154.6 5195.7 5556.6 5620.3 5736.1 5741.2 5743.9 6066.8 6147.9 6152.0 6184.1 6196.5 5402.8 6180.4 6244.9 6252.7 6254.5 5604.8 5696.3 5763.5 5776.7 5787.0 6259.8 6261.0 6266.5 6269.0 6282.8 6121.1 6133.8 6136.8 6140.0 6148.8 6093.1 6106.6 6161.0 6238.8 6259.2 6148.5 6183.4 6185.2 6203.0 6208.3 6141.2 6172.7 6209.3 6236.0 6250.3 4848.7 4897.5 4908.4 4922.1 4977.0 4381.6 4423.1 4444.9 4458.5 4462.6 5695.4 5701.1 5718.6 5721.3 5782.3 6113.9 6207.0 6256.1 6258.5 6267.5 5588.4 5607.6 5646.4 5652.7 5656.9 4670.0 5345.8 5353.3 5391.6 5394.1 6250.2 6254.0 6257.9 6258.7 6271.8 6225.5 6261.0 6265.3 6267.4 6275.2 6187.6 6221.6 6246.3 6249.5 6260.0 6134.8 6139.5 6140.1 6146.5 6156.4 6082.8 6096.0 6182.5 6185.0 6204.0 5881.2 5943.9 6001.7 6020.4 6054.1 5905.8 6057.9 6067.2 6076.9 6078.8 5789.7 5894.6 5896.1 5902.7 5905.8 4839.6 6023.8 6080.0 6098.8 6113.9 5889.1 5910.8 5962.1 5972.8 5973.9 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:71468.97 Median:41500.48 stddev:155117.98 (373.8%) stddev-sans-first:155461.60 17:00:12 INFO - PROCESS | 1657 | Values: 495.4 501.4 538.3 540.3 545.6 29995.3 31758.9 40319.0 41618.4 41649.0 43295.0 43481.1 43563.3 43664.8 43694.0 9559.5 9739.3 9745.2 9749.0 9766.8 9652.3 9734.7 9748.2 9764.1 9785.2 9674.7 9729.0 9798.3 9808.0 9829.8 9709.7 9744.6 9768.3 9778.1 9778.9 9646.0 9727.7 9734.6 9744.2 9752.5 41028.8 41427.6 41437.6 41458.2 41481.0 3379.5 3404.5 3433.0 3528.5 3605.6 31874.8 32446.8 32661.5 32942.8 33062.3 3215.8 3366.8 3373.4 3399.7 3410.5 529485.4 536522.0 536577.0 536670.3 536775.0 62348.1 63331.0 63396.7 63570.0 63580.7 58621.7 65989.7 66101.4 66124.3 66148.0 15956.2 16137.0 16162.2 16193.7 16229.8 12380.8 12428.5 12430.3 12453.3 12484.9 17276.4 19252.5 19582.3 19611.2 19681.3 6572.0 6823.5 8035.5 8168.0 8197.7 896.2 952.5 961.5 962.9 1047.6 30097.5 30380.0 30462.0 30724.8 31947.2 1066.1 1074.5 1093.1 1093.8 1114.3 16020.5 16053.4 16072.0 16229.4 16347.2 652566.0 669491.0 675153.0 676148.0 676661.0 59450.7 59795.1 59984.7 60060.4 60220.0 1274.5 1380.7 1404.3 1415.8 1428.2 26499.6 30818.5 32060.7 32517.0 32587.5 49247.0 49451.3 50081.7 50108.0 50168.7 522802.0 525733.0 525886.0 525923.0 526947.0 24716.3 26856.8 27113.6 27352.7 27386.5 48844.8 48981.0 49273.5 49416.8 49995.8 39405.8 40465.0 40508.6 41588.8 41666.4 40212.6 40585.8 40770.2 41171.0 41520.0 38827.8 39287.8 39357.8 39415.4 41247.4 32723.0 33136.0 35082.9 35637.8 36249.3 23846.2 26067.0 26210.1 26290.2 26977.2 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33830.89 Median:25024.12 stddev:95158.07 (380.3%) stddev-sans-first:95391.82 17:00:12 INFO - PROCESS | 1657 | Values: 359.9 380.4 387.5 388.3 412.7 15371.5 15515.0 20613.6 21049.4 21073.7 20066.9 20520.0 20829.1 21078.9 21087.3 5143.5 5558.0 5753.8 6091.4 6708.9 6595.4 6674.0 6683.5 6693.0 6701.6 6338.2 6596.4 6601.4 6607.6 6607.9 6329.3 6554.5 6592.5 6605.7 6635.9 5658.4 5688.1 5691.2 5699.5 5702.6 24989.6 25058.6 25122.7 25400.4 25811.1 2179.5 2321.0 2863.0 3067.5 3194.8 15577.0 15601.5 15605.3 15660.5 16006.4 1865.7 1957.5 1964.2 1971.2 1994.4 105221.4 111253.0 118650.0 122166.0 122580.4 10311.0 10523.9 10647.3 10746.5 10912.9 13039.8 13147.3 13149.9 13170.5 13170.7 8184.1 8350.6 8359.2 8418.9 8438.9 8333.5 8344.5 8369.8 8515.6 8531.7 6405.8 6490.8 6490.9 6526.8 6592.0 1801.2 1825.9 1831.4 1832.6 1839.4 915.9 943.2 980.6 1001.5 1018.5 21282.7 21326.0 21421.0 21491.1 21506.0 935.9 964.7 970.8 977.7 978.3 11120.7 11564.7 11578.2 11585.5 11604.6 554476.0 560746.0 561459.0 573389.0 604949.0 114635.7 116715.0 117285.0 118553.4 119516.4 992.9 994.3 1011.5 1013.2 1013.7 18010.2 18987.9 19283.7 19286.3 19339.9 51403.5 52179.6 52237.0 52909.7 53094.7 63396.4 67875.3 71515.6 74242.6 74456.9 7591.7 7619.8 7824.4 7830.4 8072.6 12158.6 12229.4 12416.9 12507.2 12529.4 10748.2 10768.7 10789.9 10828.1 11080.2 9061.2 10198.2 10710.9 10769.0 10861.5 10837.1 10977.7 11040.3 11081.5 11112.4 7777.4 7779.1 7809.8 7810.2 7837.0 8856.7 10594.8 10739.6 10742.8 10796.4 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5504.12 Median:4907.62 stddev:3127.79 (63.7%) stddev-sans-first:3121.35 17:00:12 INFO - PROCESS | 1657 | Values: 1398.4 1542.2 1559.0 1774.0 1919.6 7805.3 8238.6 8243.3 8256.8 8550.2 7592.3 8576.0 8645.4 8726.3 8758.6 2266.4 2343.1 2344.2 2344.7 2347.0 2298.8 2324.9 2333.5 2334.3 2337.5 2298.3 2314.6 2327.5 2339.0 2339.2 1972.7 2295.1 2309.2 2317.7 2325.8 2299.1 2306.8 2307.9 2308.0 2311.2 8635.2 8700.4 8703.0 8708.3 8713.6 4699.1 5008.1 5013.6 5210.5 5231.6 2957.9 3421.8 3478.9 3558.2 3560.9 1047.4 1066.6 1079.2 1084.1 1087.4 11125.1 11193.4 11293.8 11311.6 11343.8 3602.0 3719.3 3763.4 4502.4 4503.8 8535.8 8777.1 8812.1 8814.2 8828.6 4631.8 5114.3 5115.3 5116.5 5119.1 3977.8 4052.9 4321.0 4324.0 4573.9 3141.2 3636.5 3712.6 3755.2 3773.3 1119.3 1144.9 1154.2 1156.2 1159.3 811.5 815.9 829.1 847.6 862.6 5405.2 5724.9 5900.0 6066.9 6077.2 372.8 392.2 425.9 452.5 454.2 3870.9 3920.8 3979.4 4016.2 4044.3 8843.0 10315.8 10623.8 10664.6 10686.3 10513.7 10641.0 10646.7 10658.9 10686.0 8134.1 8151.8 8154.0 8244.4 8244.4 5802.4 5830.6 5856.7 5926.6 5951.6 9599.8 9719.2 9765.6 9769.1 9770.3 9626.8 9862.1 10050.5 10060.1 10093.5 6182.4 6385.9 6605.6 6961.9 7004.4 8250.1 8302.4 8351.1 8361.3 8369.8 7284.8 7387.3 7459.4 7493.9 7533.5 7106.3 7199.0 7217.5 7330.3 7359.5 6811.6 6850.1 6865.7 6869.0 6877.5 4734.2 4879.6 4941.0 4943.3 4951.1 4935.6 5039.3 5060.3 5087.2 5162.0 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4462.57 Median:5106.42 stddev:1407.21 (27.6%) stddev-sans-first:1375.89 17:00:12 INFO - PROCESS | 1657 | Values: 291.4 296.2 304.8 309.4 309.9 5219.5 5280.8 5284.7 5306.2 5359.1 4000.9 4055.9 5108.9 5224.5 5315.0 3409.9 3445.0 3910.6 4041.5 4060.3 4002.8 4024.0 4060.7 4064.0 4075.0 3144.9 3404.7 3551.4 3588.4 4025.0 3959.2 3959.7 4010.3 4012.2 4013.3 2820.9 3008.4 3310.7 3478.3 3479.9 4419.0 4943.0 5150.9 5166.6 5185.9 3867.7 4493.6 4556.0 4589.8 4610.2 5644.3 5652.7 5654.5 5667.8 5668.4 3100.4 3268.7 3821.2 3824.2 3831.3 5085.4 5447.6 5765.6 5780.3 5808.5 3747.9 3807.2 3823.0 3827.8 3829.6 3846.7 3873.7 3882.4 3886.7 3893.8 3836.9 4391.4 4499.4 4516.3 4541.0 5564.4 5572.9 5579.7 5630.2 5631.5 1297.3 1318.7 1330.6 1331.7 1354.5 647.5 711.4 831.1 872.2 891.2 4694.9 5064.8 5096.8 5105.9 5107.0 5623.6 5628.4 5629.5 5646.3 5654.6 4102.8 4212.7 4375.5 4426.8 5119.5 2683.2 2986.9 3026.6 3030.6 3034.8 5196.6 5439.5 5640.3 5795.0 5932.3 5226.7 5560.7 5847.0 5919.1 5943.2 5396.8 5842.9 5880.4 5949.7 5976.5 4737.6 5435.1 5752.3 5755.7 5790.1 4671.6 5215.5 5613.8 5638.9 5660.7 5891.9 5921.2 5925.2 5949.8 5965.7 3358.9 3709.9 3793.5 3815.9 3824.8 5413.5 5671.3 5699.1 5718.8 5719.2 5188.0 5569.9 5604.6 5628.6 5669.0 5442.8 5477.2 5579.8 5580.5 5606.2 4910.0 4961.9 5169.4 5185.4 5376.9 4514.4 5058.2 5473.1 5474.5 5478.5 4373.0 4733.8 5470.3 5485.6 5501.9 17:00:12 INFO - PROCESS | 1657 | 17:00:12 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:23129.93 Median:3505.40 stddev:35507.62 (1012.9%) stddev-sans-first:35567.23 17:00:12 INFO - PROCESS | 1657 | Values: 685.4 755.5 758.5 766.0 797.5 13928.9 14734.6 14738.2 14742.2 14746.6 14104.1 14142.3 14305.4 14316.0 14353.0 697.9 725.1 861.2 866.0 897.2 871.6 878.2 882.6 883.3 884.5 940.1 940.3 940.7 941.8 945.7 711.3 874.6 935.3 938.3 939.4 702.4 703.0 703.1 704.2 706.4 14322.9 14363.1 14390.3 14400.8 14414.0 13552.3 13776.7 13793.9 13856.4 13931.7 10586.8 12358.8 12432.8 12522.6 12578.2 4482.1 4527.7 4574.9 4658.3 4686.9 98217.5 100315.0 100709.0 100782.0 104053.0 10856.5 10922.5 11035.9 11044.8 11121.6 21358.8 21362.8 21378.8 21409.3 21447.9 7585.5 7613.7 7667.0 7847.0 7887.6 4279.9 4321.1 4343.5 4373.1 4505.8 3396.4 3494.9 3495.8 3504.7 3506.1 591.9 640.5 648.6 665.1 668.9 552.0 559.0 635.7 636.3 709.7 8480.8 8712.1 8867.3 9400.9 9412.5 479.6 532.7 569.5 638.6 649.9 4302.6 4338.8 4454.8 4579.3 4630.7 109273.5 123654.0 127905.7 128673.0 146424.5 71319.0 72559.6 83807.6 120432.0 134261.0 112928.0 114475.9 116562.0 116646.0 118564.4 9530.3 9563.3 9843.5 9883.5 10000.7 64656.7 65856.0 66375.7 66651.7 67662.6 57639.4 57939.0 84119.0 103051.0 103163.0 13182.2 13534.6 13624.1 13679.5 13991.4 15383.5 15897.6 16005.0 16278.1 16345.9 12773.2 13582.8 13889.2 14209.0 14615.5 13850.5 14325.0 14458.9 14544.7 14577.9 11044.9 12365.3 13188.8 14214.1 14687.3 11195.5 11732.5 11802.9 11944.3 11969.6 8944.7 9620.0 11597.3 11777.4 11777.5 17:00:12 INFO - PROCESS | 1657 | -------- Summary: end -------- 17:00:12 INFO - PROCESS | 1657 | 17:00:13 INFO - TEST-INFO | 1657: exit 0 17:00:13 INFO - TEST-OK | dromaeo_css | took 1115293ms 17:00:13 INFO - TEST-START | kraken 17:00:13 INFO - operating with platform_type : mac_ 17:00:13 INFO - Initialising browser for kraken test... 17:00:13 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmps6uFEW/profile http://localhost:49271/getInfo.html) 17:00:15 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 17:00:15 INFO - PROCESS | 1662 | colorDepth:24 17:00:15 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 17:00:15 INFO - PROCESS | 1662 | __metrics 17:00:15 INFO - PROCESS | 1662 | 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" 17:00:15 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:00:16 INFO - TEST-INFO | 1662: exit 0 17:00:16 INFO - Browser initialized. 17:00:16 INFO - Running cycle 1/1 for kraken test... 17:00:16 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmps6uFEW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:00:28 INFO - PROCESS | 1663 | RSS: Main: 243560448 17:00:28 INFO - PROCESS | 1663 | 17:00:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 17:00:32 INFO - PROCESS | 1663 | RSS: Main: 294522880 17:00:32 INFO - PROCESS | 1663 | 17:00:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 17:00:35 INFO - PROCESS | 1663 | RSS: Main: 306618368 17:00:35 INFO - PROCESS | 1663 | 17:00:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 17:00:39 INFO - PROCESS | 1663 | RSS: Main: 393404416 17:00:39 INFO - PROCESS | 1663 | 17:00:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 17:00:42 INFO - PROCESS | 1663 | RSS: Main: 293535744 17:00:42 INFO - PROCESS | 1663 | 17:00:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:00:44 INFO - PROCESS | 1663 | RSS: Main: 297078784 17:00:44 INFO - PROCESS | 1663 | 17:00:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 17:00:48 INFO - PROCESS | 1663 | RSS: Main: 333283328 17:00:48 INFO - PROCESS | 1663 | 17:00:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 17:00:51 INFO - PROCESS | 1663 | RSS: Main: 336551936 17:00:51 INFO - PROCESS | 1663 | 17:00:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 17:00:55 INFO - PROCESS | 1663 | RSS: Main: 332828672 17:00:55 INFO - PROCESS | 1663 | 17:00:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:00:57 INFO - PROCESS | 1663 | RSS: Main: 313856000 17:00:57 INFO - PROCESS | 1663 | 17:00:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:00:59 INFO - PROCESS | 1663 | RSS: Main: 320851968 17:00:59 INFO - PROCESS | 1663 | 17:01:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:01:02 INFO - PROCESS | 1663 | RSS: Main: 318124032 17:01:02 INFO - PROCESS | 1663 | 17:01:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:01:06 INFO - PROCESS | 1663 | RSS: Main: 328491008 17:01:06 INFO - PROCESS | 1663 | 17:01:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:01:09 INFO - PROCESS | 1663 | RSS: Main: 330506240 17:01:09 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 17:01:11 INFO - PROCESS | 1663 | RSS: Main: 373796864 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | __start_tp_report 17:01:11 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 17:01:11 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 17:01:11 INFO - PROCESS | 1663 | |i|pagename|runs| 17:01:11 INFO - PROCESS | 1663 | |0;ai-astar;101;113;113;113;114;154;168;134;113;112 17:01:11 INFO - PROCESS | 1663 | |1;audio-beat-detection;146;108;116;199;140;122;128;106;147;140 17:01:11 INFO - PROCESS | 1663 | |2;audio-dft;184;184;187;185;183;181;181;182;184;184 17:01:11 INFO - PROCESS | 1663 | |3;audio-fft;79;74;57;85;57;56;64;62;94;57 17:01:11 INFO - PROCESS | 1663 | |4;audio-oscillator;84;97;92;70;71;71;98;71;68;102 17:01:11 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;83;133;84;86;83;90;84;86;83 17:01:11 INFO - PROCESS | 1663 | |6;imaging-darkroom;92;92;93;93;91;92;92;93;92;91 17:01:11 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;84;86;85;83;84;85;84;86;86 17:01:11 INFO - PROCESS | 1663 | |8;json-parse-financial;56;56;54;53;53;53;53;52;53;52 17:01:11 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;65;67;80;77;70;73;69;68;70;71 17:01:11 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;85;83;82;85;78;101;100;101;100;81 17:01:11 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;155;131;141;147;146;141;133;136;230;137 17:01:11 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;163;160;161;161;158;160;160;160;212;179 17:01:11 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;66;65;65;65;66;66;65;67;66;64 17:01:11 INFO - PROCESS | 1663 | __end_tp_report 17:01:11 INFO - PROCESS | 1663 | __start_cc_report 17:01:11 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1983 17:01:11 INFO - PROCESS | 1663 | __end_cc_report 17:01:11 INFO - PROCESS | 1663 | __startTimestamp1467417671858__endTimestamp 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | ------- Summary: start ------- 17:01:11 INFO - PROCESS | 1663 | Number of tests: 14 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:123.50 Median:113.50 stddev:21.58 (19.0%) stddev-sans-first:21.30 17:01:11 INFO - PROCESS | 1663 | Values: 101.0 113.0 113.0 113.0 114.0 154.0 168.0 134.0 113.0 112.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:140.00 stddev:27.00 (19.3%) stddev-sans-first:28.35 17:01:11 INFO - PROCESS | 1663 | Values: 146.0 108.0 116.0 199.0 140.0 122.0 128.0 106.0 147.0 140.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.94 17:01:11 INFO - PROCESS | 1663 | Values: 184.0 184.0 187.0 185.0 183.0 181.0 181.0 182.0 184.0 184.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:68.50 Median:69.00 stddev:13.66 (19.8%) stddev-sans-first:13.95 17:01:11 INFO - PROCESS | 1663 | Values: 79.0 74.0 57.0 85.0 57.0 56.0 64.0 62.0 94.0 57.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:77.50 stddev:13.69 (17.7%) stddev-sans-first:14.51 17:01:11 INFO - PROCESS | 1663 | Values: 84.0 97.0 92.0 70.0 71.0 71.0 98.0 71.0 68.0 102.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:85.00 stddev:15.31 (18.0%) stddev-sans-first:16.20 17:01:11 INFO - PROCESS | 1663 | Values: 87.0 83.0 133.0 84.0 86.0 83.0 90.0 84.0 86.0 83.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 17:01:11 INFO - PROCESS | 1663 | Values: 92.0 92.0 93.0 93.0 91.0 92.0 92.0 93.0 92.0 91.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.10 (1.3%) stddev-sans-first:1.09 17:01:11 INFO - PROCESS | 1663 | Values: 86.0 84.0 86.0 85.0 83.0 84.0 85.0 84.0 86.0 86.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:1.43 (2.7%) stddev-sans-first:1.20 17:01:11 INFO - PROCESS | 1663 | Values: 56.0 56.0 54.0 53.0 53.0 53.0 53.0 52.0 53.0 52.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.57 (6.5%) stddev-sans-first:4.30 17:01:11 INFO - PROCESS | 1663 | Values: 65.0 67.0 80.0 77.0 70.0 73.0 69.0 68.0 70.0 71.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:81.50 stddev:9.59 (11.8%) stddev-sans-first:10.03 17:01:11 INFO - PROCESS | 1663 | Values: 85.0 83.0 82.0 85.0 78.0 101.0 100.0 101.0 100.0 81.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:143.50 stddev:29.11 (20.3%) stddev-sans-first:30.81 17:01:11 INFO - PROCESS | 1663 | Values: 155.0 131.0 141.0 147.0 146.0 141.0 133.0 136.0 230.0 137.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:161.00 stddev:16.77 (10.4%) stddev-sans-first:17.72 17:01:11 INFO - PROCESS | 1663 | Values: 163.0 160.0 161.0 161.0 158.0 160.0 160.0 160.0 212.0 179.0 17:01:11 INFO - PROCESS | 1663 | 17:01:11 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 17:01:11 INFO - PROCESS | 1663 | Values: 66.0 65.0 65.0 65.0 66.0 66.0 65.0 67.0 66.0 64.0 17:01:11 INFO - PROCESS | 1663 | -------- Summary: end -------- 17:01:11 INFO - PROCESS | 1663 | 17:01:12 INFO - TEST-INFO | 1663: exit 0 17:01:12 INFO - TEST-OK | kraken | took 59332ms 17:01:12 INFO - SUITE-END | took 1174s 17:01:13 INFO - Completed test suite (00:19:34) 17:01:13 INFO - javascript benchmark 17:01:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.212988882668, 3308.057203696947, 3319.4191016572095, 3344.866205351786, 3370.7471939604534, 5571.498565129137, 5602.0, 5660.3490598581175, 5663.3204015518095, 5708.400617935119, 5021.0, 5628.465295796893, 5744.396838331967, 5761.164631128494, 5781.537477001841, 5071.467495912932, 5099.36257967754, 5140.768665410047, 5153.613478989072, 5208.166693329068, 5174.379074511064, 5207.739613019353, 5219.947800521984, 5246.081935661263, 5261.947380526203, 5066.06277838601, 5086.643934924557, 5118.744062796883, 5135.563477104451, 5165.81919632813, 5086.4659210783075, 5104.464031276737, 5120.974395128038, 5237.895242095175, 5246.396664383607, 5109.20807274873, 5134.922976155361, 5149.639525233235, 5154.561862241714, 5195.662281951699, 5556.555475561945, 5620.325560932673, 5736.082226843685, 5741.19623252745, 5743.885122297573, 6066.785369686932, 6147.938520614782, 6151.96924015377, 6184.07238914162, 6196.504279657616, 5402.756875940553, 6180.381961803829, 6244.906326405107, 6252.687365631746, 6254.468370188494, 5604.775808967677, 5696.287964004499, 5763.5101016413655, 5776.711164441761, 5787.0, 6259.812205633838, 6261.029540421242, 6266.530010249249, 6268.968655156695, 6282.837675030134, 6121.095133350306, 6133.846653833601, 6136.846578835565, 6140.0, 6148.846278843064, 6093.11649810775, 6106.551676171891, 6161.045038019114, 6238.781642642576, 6259.248890133122, 6148.477379422709, 6183.443490085914, 6185.226846644169, 6203.038529028008, 6208.255009398811, 6141.170941922784, 6172.722227499773, 6209.316975132731, 6235.968820155961, 6250.343713910065, 4848.7090774553635, 4897.534734200245, 4908.435529914034, 4922.089413458521, 4976.950230497649, 4381.605655491021, 4423.071155057474, 4444.933326000048, 4458.509563947963, 4462.598366147063, 5695.40198279183, 5701.059325211294, 5718.571107166896, 5721.342045664802, 5782.277215348082, 6113.938860611337, 6207.037909124093, 6256.061590761331, 6258.499320054385, 6267.5299352548745, 5588.357338905997, 5607.635503692247, 5646.407127251579, 5652.7173641318595, 5656.943430565641, 4669.995950870579, 5345.812896548602, 5353.277307563507, 5391.622586418992, 5394.056040193029, 6250.218722659668, 6253.96873015632, 6257.8748425030335, 6258.718357673915, 6271.808356412267, 6225.533085018551, 6260.968695156495, 6265.310815810349, 6267.4045965633195, 6275.215598050244, 6187.566870319034, 6221.626702397871, 6246.250449945854, 6249.50003999688, 6260.0, 6134.8159555213415, 6139.539534534839, 6140.140380346666, 6146.477549408351, 6156.445919867055, 6082.817515474543, 6095.969520152371, 6182.505399568114, 6184.979478386196, 6203.96898015507, 5881.176635271151, 5943.881122377615, 6001.669908155093, 6020.367861374668, 6054.091886217103, 5905.763769449174, 6057.909131362945, 6067.150598916067, 6076.8784624308155, 6078.817635470943, 5789.710514474344, 5894.616849904743, 5896.056630939029, 5902.704864756831, 5905.793297234576, 4839.612830973584, 6023.759049637965, 6079.966405710951, 6098.847528811815, 6113.877722445615, 5889.116632505158, 5910.7931222407005, 5962.135490353961, 5972.790952316648, 5973.940260597338], "value": 5648.995627103929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.3980913190474, 501.41585053412774, 538.3028977474171, 540.3380858448401, 545.6207935484837, 29995.250118747015, 31758.888438904643, 40318.99202519938, 41618.37572436409, 41649.0, 43295.0, 43481.13037739243, 43563.346549801696, 43664.781676091574, 43694.0, 9559.522023898811, 9739.318247722662, 9745.220382369429, 9749.02509749023, 9766.755831104243, 9652.276079294046, 9734.707958761248, 9748.220142388593, 9764.072413120743, 9785.217182625373, 9674.661386851461, 9729.0, 9798.314118011742, 9807.950960245225, 9829.754256143618, 9709.70870873875, 9744.561494732754, 9768.267379946461, 9778.07108324708, 9778.902210977905, 9645.951770241103, 9727.708168754949, 9734.561944712505, 9744.171745401649, 9752.4636145012, 41028.79485602583, 41427.58572414267, 41437.58562414368, 41458.1708365831, 41480.96297592554, 3379.459286514146, 3404.5063465797566, 3433.0, 3528.5412896323314, 3605.6214097519905, 31874.840625796955, 32446.837765811255, 32661.510077348623, 32942.84700035487, 33062.3387532248, 3215.790973586733, 3366.8316584170693, 3373.4433818420175, 3399.6770306820813, 3410.471376936579, 529485.3525732347, 536522.0, 536577.0, 536670.3166484142, 536775.0, 62348.06477902835, 63331.0, 63396.68301658462, 63570.0, 63580.68209658922, 58621.70689146527, 65989.67005164943, 66101.38138258205, 66124.33875661278, 66148.0, 15956.202189890691, 16137.0, 16162.191890405433, 16193.676126477405, 16229.837701622833, 12380.81428778569, 12428.502859885684, 12430.254184748945, 12453.315067671365, 12484.938780203784, 17276.394361998566, 19252.493334649724, 19582.31461898827, 19611.21555137807, 19681.31115410954, 6572.0, 6823.522353435212, 8035.477693949874, 8168.0, 8197.71308004217, 896.171041786345, 952.538019060755, 961.5048250151178, 962.9277804164716, 1047.6490375724065, 30097.548536771967, 30380.0, 30462.0, 30724.79700187383, 31947.201319966724, 1066.1311031509294, 1074.4949873559572, 1093.0872721277663, 1093.7593729379682, 1114.331401159314, 16020.519384418487, 16053.357865685242, 16072.0, 16229.39053808899, 16347.18264086791, 652566.0, 669491.0, 675153.0, 676148.0, 676661.0, 59450.70274648599, 59795.10307345393, 59984.70007650021, 60060.39939600635, 60220.0, 1274.5029438518984, 1380.7100508893045, 1404.2767974493145, 1415.812569580022, 1428.1573871415915, 26499.60250596204, 30818.537721934637, 32060.6793932067, 32517.0, 32587.511187331736, 49247.0, 49451.25823112728, 50081.74959125181, 50108.0, 50168.74915625399, 522802.0, 525733.0, 525886.0, 525923.0, 526947.0, 24716.25851224394, 26856.79144438466, 27113.593296100968, 27352.72647273502, 27386.452270954072, 48844.75577622089, 48981.0, 49273.50726492689, 49416.752916235186, 49995.75002124966, 39405.802970984965, 40464.98837528968, 40508.59491405048, 41588.79205603953, 41666.37500437435, 40212.59787402205, 40585.79707101446, 40770.184596307314, 41171.0, 41520.0, 38827.80586097051, 39287.803560982014, 39357.80321098376, 39415.408768867914, 41247.381289280085, 32723.0, 33136.0, 35082.94751157469, 35637.82181089078, 36249.275014499035, 23846.16538421147, 26067.0, 26210.08264710726, 26290.21129366122, 26977.190684279503], "value": 20373.704560750026, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.9046252743022, 380.4312552733663, 387.53302270763714, 388.2797410802961, 412.6760493012985, 15371.538853834389, 15515.0, 20613.587728245428, 21049.368518944437, 21073.683894741553, 20066.899665501653, 20520.0, 20829.06269217885, 21078.894605526988, 21087.2619458319, 5143.53708166265, 5557.972210138943, 5753.827385178431, 6091.42131497507, 6708.899366509506, 6595.406413422791, 6674.032265321525, 6683.465322774191, 6693.029510720942, 6701.564398314094, 6338.239411270654, 6596.373344532261, 6601.372869577406, 6607.603543787388, 6607.867842643144, 6329.303776584572, 6554.475641948657, 6592.505562082839, 6605.735770569171, 6635.933640663604, 5658.359075867993, 5688.146777983295, 5691.174779656926, 5699.544036477113, 5702.5723070769445, 24989.6251556225, 25058.624120638204, 25122.748772512405, 25400.36499087519, 25811.09661161869, 2179.5422961178174, 2321.0, 2863.040881304765, 3067.5091985282247, 3194.8402579870913, 15576.98749581285, 15601.45394911184, 15605.297761600756, 15660.530184094496, 16006.439774607832, 1865.7014877619652, 1957.4910523263911, 1964.2437661500426, 1971.2213675598202, 1994.4216177308563, 105221.42167867412, 111252.99999999919, 118650.0, 122166.0, 122580.35486628755, 10311.0, 10523.894761052445, 10647.307924985005, 10746.516406761715, 10912.945435272773, 13039.804402933964, 13147.276899770604, 13149.934250328688, 13170.47318107284, 13170.736585268241, 8184.140665230065, 8350.582470876432, 8359.247667709935, 8418.873716894252, 8438.873416898752, 8333.541655208901, 8344.457610255315, 8369.790755231168, 8515.616797244138, 8531.65873365071, 6405.775797847053, 6490.837729056811, 6490.935090649033, 6526.836829079216, 6592.0, 1801.162459456333, 1825.917833697487, 1831.3864855273366, 1832.5510249988838, 1839.4021942868621, 915.8871970555674, 943.1747221181466, 980.6028558433845, 1001.5342865567546, 1018.5416562546884, 21282.680759788615, 21326.0, 21421.0, 21491.140354385963, 21506.032228549753, 935.8689783430325, 964.7009427077628, 970.752458123174, 977.6898955399656, 978.2907392140642, 11120.666380008613, 11564.710882228012, 11578.247413918069, 11585.478653460446, 11604.593839215755, 554476.0, 560746.0, 561459.0, 573389.0, 604949.0, 114635.70728585382, 116715.0, 117285.0, 118553.40723296328, 119516.40241798735, 992.9106380425652, 994.2642444591095, 1011.5397494140295, 1013.1843865688191, 1013.6502906497246, 18010.18954147093, 18987.905060474608, 19283.710744338565, 19286.324978625682, 19339.90330048341, 51403.485965141364, 52179.59525721717, 52237.0, 52909.7354513225, 53094.73452632712, 63396.366036339045, 67875.3212467869, 71515.64242178756, 74242.62878685573, 74456.88314675393, 7591.658375373011, 7619.847603047797, 7824.4131690124605, 7830.3735701144915, 8072.556009419538, 12158.635240942785, 12229.449674764479, 12416.875831241572, 12507.249565026128, 12529.436175372313, 10748.247622666338, 10768.730781730519, 10789.892101078889, 10828.133749300194, 11080.224384293022, 9061.22979546746, 10198.235132364955, 10710.892891071302, 10769.0, 10861.456927153453, 10837.078848297668, 10977.725556861142, 11040.33757974524, 11081.501332439899, 11112.388818615054, 7777.4166937478785, 7779.105402878515, 7809.765707028798, 7810.179931107152, 7837.0, 8856.734297971072, 10594.788104238027, 10739.624113156002, 10742.8388574173, 10796.406197659204], "value": 9764.801789307787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.4056775870254, 1542.2211783049559, 1559.041189668354, 1773.9911300443496, 1919.635269298834, 7805.297523222909, 8238.629261683223, 8243.340532757373, 8256.752297431072, 8550.230479256865, 7592.27873352032, 8576.0, 8645.351598630112, 8726.345524085704, 8758.562071896411, 2266.4447210433427, 2343.0627748900406, 2344.15610380263, 2344.7186337639505, 2347.0259842165483, 2298.7816157465013, 2324.941876453085, 2333.4866329407505, 2334.2763743239566, 2337.4857531343077, 2298.3449716830705, 2314.594945884472, 2327.5228578141523, 2338.9649155262687, 2339.1929784224417, 1972.6843705007202, 2295.0934380919443, 2309.157157637467, 2317.73346065203, 2325.755795641467, 2299.0803678528555, 2306.7923886850263, 2307.8730669813153, 2308.0190918859585, 2311.15642790382, 8635.222829945273, 8700.390972631918, 8702.99915509718, 8708.346873984476, 8713.607887645008, 4699.060187962421, 5008.073506401291, 5013.5989120870245, 5210.53105220532, 5231.60762942777, 2957.866895989664, 3421.794692318444, 3478.9478157827652, 3558.2349794793986, 3560.928781424357, 1047.4082143588848, 1066.5840322274241, 1079.1582565598846, 1084.073117484555, 1087.3638921231088, 11125.054370378528, 11193.384363859985, 11293.830592541119, 11311.604093856675, 11343.773124537545, 3601.9639803602154, 3719.3119272934596, 3763.41667041609, 4502.4371953505815, 4503.797329120198, 8535.786605334917, 8777.122287771172, 8812.118788121137, 8814.206721395105, 8828.602712877935, 4631.768411579407, 5114.309568208244, 5115.335006449212, 5116.462771409023, 5119.104156772624, 3977.8265411703037, 4052.8986775330854, 4321.006168581181, 4323.956760432356, 4573.93139102907, 3141.183292344007, 3636.4908912752235, 3712.5916149224104, 3755.248950209969, 3773.339665558516, 1119.3004372267333, 1144.9313041217554, 1154.1632316570588, 1156.2253290295523, 1159.2580748321082, 811.5212024905217, 815.9311302194145, 829.0863705926942, 847.5592691800352, 862.6376921692779, 5405.21624364465, 5724.885502289931, 5899.970500147472, 6066.908996365058, 6077.20996270482, 372.7558449215768, 392.2018691961844, 425.8935266183454, 452.47964840433104, 454.1575377674432, 3870.903227419281, 3920.764754114762, 3979.383195604686, 4016.196760647882, 4044.3326851069833, 8842.955785221033, 10315.793684126276, 10623.84064239037, 10664.573417063386, 10686.25196236256, 10513.737156570995, 10641.042306192325, 10646.680599582025, 10658.946705266424, 10686.0, 8134.105248422668, 8151.83696326082, 8153.959230203811, 8244.422890397615, 8244.422890397615, 5802.4197580241635, 5830.562707797017, 5856.736446859816, 5926.644401335933, 5951.613145145551, 9599.808003840024, 9719.222462203148, 9765.60937562518, 9769.120779129913, 9770.31607787448, 9626.807463850544, 9862.112409883144, 10050.547725352231, 10060.0945914868, 10093.495325233856, 6182.443580077815, 6385.904211436925, 6605.636689982097, 6961.8955715665315, 7004.3696067354185, 8250.092489825995, 8302.418830681794, 8351.123132071045, 8361.289290410139, 8369.83260334802, 7284.781456556099, 7387.335139837441, 7459.403247740276, 7493.925060749323, 7533.472656913963, 7106.289371062852, 7199.0, 7217.458690598121, 7330.340269375782, 7359.521631094176, 6811.625360605214, 6850.109485766819, 6865.656717164222, 6868.965655171693, 6877.518573699793, 4734.195186818318, 4879.634027448026, 4941.0, 4943.307936888767, 4951.059298733373, 4935.605151587936, 5039.3448851649055, 5060.291559181791, 5087.160618498055, 5162.045021671003], "value": 4334.81583553863, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.3677320215134, 296.15300241309853, 304.79426387188647, 309.4399137561016, 309.93801239752054, 5219.504147106024, 5280.841574752763, 5284.709340986245, 5306.23257487184, 5359.104984517035, 4000.9199816003734, 4055.9391609125887, 5108.872278193047, 5224.503672151149, 5314.963582101492, 3409.925873349899, 3445.0, 3910.6284902934176, 4041.494813148364, 4060.2894493463673, 4002.8198731057028, 4023.9798801005954, 4060.736052156615, 4064.04494943688, 4074.9812546863286, 3144.8584813683437, 3404.7062116395737, 3551.3785087609695, 3588.4437912123662, 4024.9798751006206, 3959.169194026285, 3959.722819402643, 4010.3382941814566, 4012.1775036117524, 4013.2776100301935, 2820.858957052139, 3008.353204061137, 3310.6854848789567, 3478.339115568034, 3479.930401391983, 4418.977905110486, 4943.0, 5150.871228219287, 5166.5866730661455, 5185.910958698678, 3867.7099217558793, 4493.573110554492, 4555.954440455586, 4589.770511474413, 4610.239310513761, 5644.294463192101, 5652.660840349592, 5654.491095801398, 5667.801626943078, 5668.404817494145, 3100.364425292832, 3268.7385009199443, 3821.197548514815, 3824.1778017726324, 3831.252905683393, 5085.389753229636, 5447.6459030163305, 5765.567582431292, 5780.2774653167935, 5808.477237048686, 3747.887563373103, 3807.1814559869763, 3823.0, 3827.78947157909, 3829.5787463379006, 3846.6922646188236, 3873.748206366577, 3882.4176373544196, 3886.7084968627464, 3893.766374017568, 3836.9232615348096, 4391.429114215131, 4499.380223119674, 4516.299973504112, 4540.954590454053, 5564.360098588633, 5572.9411411831625, 5579.6652200868075, 5630.211770352154, 5631.493165615114, 1297.2929753284482, 1318.7494376068516, 1330.5542643214437, 1331.7203387288585, 1354.4717560151548, 647.4917190005823, 711.3810984443464, 831.1314676163423, 872.1932212703351, 891.1845661219911, 4694.906101877943, 5064.822126485647, 5096.796128154832, 5105.8723531912, 5106.974465127651, 5623.578231632644, 5628.437156284339, 5629.4652008059165, 5646.265985421869, 5654.6324488908085, 4102.774347410921, 4212.662986961036, 4375.5405682403525, 4426.800793964279, 5119.46245644202, 2683.2486903667004, 2986.940261194764, 3026.5914101596445, 3030.5605687175234, 3034.7723920706035, 5196.636235463481, 5439.456054394529, 5640.32316122068, 5795.002172751267, 5932.2584676915385, 5226.738663066832, 5560.721034162166, 5847.035239185572, 5919.141724449931, 5943.167956486095, 5396.784128634889, 5842.941570584239, 5880.353161152269, 5949.732262048218, 5976.503745308956, 4737.573618374363, 5435.10320797064, 5752.254390128244, 5755.683437410815, 5790.147152911028, 4671.579557839811, 5215.504527069922, 5613.803516876889, 5638.900414419149, 5660.7169641518585, 5891.852703682443, 5921.230240068763, 5925.163199408197, 5949.762009519571, 5965.73154208053, 3358.9496157558146, 3709.9072523187137, 3793.487879136281, 3815.904602384963, 3824.8278827452273, 5413.485718856859, 5671.347795003628, 5699.124595275087, 5718.771249149988, 5719.198452487453, 5187.974060129826, 5569.888602228014, 5604.579656525695, 5628.634138780966, 5669.007923613269, 5442.809501667422, 5477.165983811268, 5579.804706835242, 5580.469855363734, 5606.177992152485, 4910.0, 4961.925571116509, 5169.379674439091, 5185.42960274377, 5376.946230537645, 4514.435695538058, 5058.190689489664, 5473.071906529987, 5474.534664553557, 5478.506934375925, 4372.966570544712, 4733.83431579893, 5470.343558772973, 5485.588580856532, 5501.889962200654], "value": 4010.208133736948, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.369460096711, 755.5466719968016, 758.5297115788212, 765.9770206893795, 797.4537441852333, 13928.860711392883, 14734.63163420915, 14738.189399583018, 14742.189179595118, 14746.557603271918, 14104.08323458977, 14142.292885355717, 14305.356258968346, 14316.0, 14353.0, 697.91973922999, 725.0936329588015, 861.1775754154799, 866.0084203586905, 897.2059727141494, 871.5990644303628, 878.227160099115, 882.6204731965253, 883.2889523933231, 884.4737381258174, 940.1068984464752, 940.2853831088388, 940.7459985803829, 941.8257622339889, 945.6595625574788, 711.281605578367, 874.5627186406797, 935.2798345274135, 938.3056538161779, 939.3893968920188, 702.4099756204813, 702.9753958611475, 703.1281211298004, 704.1936982155455, 706.3713295167305, 14322.925780566498, 14363.138211707332, 14390.28048597566, 14400.783988240184, 14414.0, 13552.254625995567, 13776.724465510733, 13793.931030344884, 13856.445742170301, 13931.651708707363, 10586.788264234749, 12358.75282494354, 12432.813507797391, 12522.624321270376, 12578.182418142791, 4482.074190515522, 4527.728336299832, 4574.862754117382, 4658.301254811771, 4686.906261874743, 98217.5089124557, 100314.97165255088, 100709.0, 100782.0, 104052.95947040508, 10856.457177141112, 10922.508487118099, 11035.944820276009, 11044.834327485094, 11121.610743624096, 21358.78641213599, 21362.78637213608, 21378.78621213799, 21409.250676226256, 21447.892760536408, 7585.469017168745, 7613.733519326908, 7667.0416197975255, 7847.019122609674, 7887.566183859828, 4279.850205242801, 4321.092570560218, 4343.457067866517, 4373.059792144704, 4505.819767209275, 3396.4395874680895, 3494.9126271842897, 3495.8252087395526, 3504.7021003215013, 3506.088417011544, 591.8757061017235, 640.4940097323156, 648.5751832549688, 665.0987911380107, 668.8595394967014, 551.970574877858, 558.9692566908777, 635.7096529837235, 636.3413866647998, 709.7303024850523, 8480.830383392298, 8712.128787121237, 8867.334949878656, 9400.905990940004, 9412.482313472825, 479.5779713851788, 532.7016870552503, 569.5415190771386, 638.575347393987, 649.8667773106501, 4302.591253830881, 4338.76136812472, 4454.777261136995, 4579.335996280521, 4630.6758526902795, 109273.45363273133, 123654.0, 127905.72094279125, 128673.0, 146424.53575464108, 71319.0, 72559.63720181471, 83807.5809620948, 120432.0, 134261.0, 112928.0, 114475.85524144986, 116562.0, 116646.00123992587, 118564.40717796529, 9530.332876698842, 9563.282753793632, 9843.458609776531, 9883.456409897524, 10000.749981250527, 64656.676716616115, 65856.0, 66375.66812165908, 66651.66674166598, 67662.64674706577, 57639.423605765085, 57939.0, 84119.0, 103051.0, 103163.0, 13182.209067455984, 13534.59396218115, 13624.119560228715, 13679.521216757366, 13991.440342386597, 15383.538493845203, 15897.602559936095, 16005.03969761818, 16278.104704241381, 16345.918270408572, 12773.23360598367, 13582.777550020546, 13889.163878274107, 14209.0, 14615.488457903923, 13850.515231966832, 14325.0, 14458.927705361826, 14544.709105818036, 14577.92711036438, 11044.944775276072, 12365.258084514959, 13188.80216796768, 14214.076085054437, 14687.339069742102, 11195.496202670736, 11732.472038758448, 11802.94098529502, 11944.283342999448, 11969.64091077269, 8944.731658050268, 9620.037996200324, 11597.304161749984, 11777.35224562623, 11777.470013849568], "value": 7736.666884362168, "unit": "score"}], "name": "dromaeo_css", "value": 7298.6252552760125, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 114.0, 154.0, 168.0, 134.0, 113.0, 112.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 116.0, 199.0, 140.0, 122.0, 128.0, 106.0, 147.0, 140.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 187.0, 185.0, 183.0, 181.0, 181.0, 182.0, 184.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 85.0, 57.0, 56.0, 64.0, 62.0, 94.0, 57.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 97.0, 92.0, 70.0, 71.0, 71.0, 98.0, 71.0, 68.0, 102.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 133.0, 84.0, 86.0, 83.0, 90.0, 84.0, 86.0, 83.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 91.0, 92.0, 92.0, 93.0, 92.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 85.0, 83.0, 84.0, 85.0, 84.0, 86.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 53.0, 53.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 80.0, 77.0, 70.0, 73.0, 69.0, 68.0, 70.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 85.0, 78.0, 101.0, 100.0, 101.0, 100.0, 81.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 131.0, 141.0, 147.0, 146.0, 141.0, 133.0, 136.0, 230.0, 137.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 161.0, 158.0, 160.0, 160.0, 160.0, 212.0, 179.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 67.0, 66.0, 64.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1456.7, "alertThreshold": 2.0}]} 17:01:13 INFO - Return code: 0 17:01:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:01:13 INFO - # TBPL SUCCESS # 17:01:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:01:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:01:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:01:13 INFO - 2016-07-01 17:01:13.176 screenresolution[1668:10376] starting screenresolution argv=screenresolution get 17:01:13 INFO - 2016-07-01 17:01:13.178 screenresolution[1668:10376] Display 0: 1600x1200x32@60 17:01:13 INFO - 2016-07-01 17:01:13.184 screenresolution[1669:10379] starting screenresolution argv=screenresolution list 17:01:13 INFO - Available Modes on Display 0 17:01:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:01:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:01:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:01:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:01:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:01:13 INFO - Intel Iris: 17:01:13 INFO - Chipset Model: Intel Iris 17:01:13 INFO - Type: GPU 17:01:13 INFO - Bus: Built-In 17:01:13 INFO - VRAM (Dynamic, Max): 1536 MB 17:01:13 INFO - Vendor: Intel (0x8086) 17:01:13 INFO - Device ID: 0x0a2e 17:01:13 INFO - Revision ID: 0x0009 17:01:13 INFO - Displays: 17:01:13 INFO - Display: 17:01:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:01:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:01:13 INFO - Display Serial Number: 1600x1200 60 17:01:13 INFO - Main Display: Yes 17:01:13 INFO - Mirror: Off 17:01:13 INFO - Online: Yes 17:01:13 INFO - Rotation: Supported 17:01:13 INFO - Return code: 0 17:01:13 INFO - Running post-action listener: _resource_record_post_action 17:01:13 INFO - ##### 17:01:13 INFO - ##### Finished run-tests step (success) 17:01:13 INFO - ##### 17:01:13 INFO - Running post-run listener: _resource_record_post_run 17:01:13 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31403520; Write bytes: 704417280; Read time: 318; Write time: 1879 17:01:13 INFO - TinderboxPrint: CPU usage
53.5% 17:01:13 INFO - TinderboxPrint: I/O read bytes / time
31,403,520 / 318 17:01:13 INFO - TinderboxPrint: I/O write bytes / time
704,417,280 / 1,879 17:01:13 INFO - TinderboxPrint: CPU idle
2,253.3 (46.5%) 17:01:13 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 17:01:13 INFO - TinderboxPrint: CPU user
1,620.1 (33.5%) 17:01:13 INFO - TinderboxPrint: Swap in / out
434,634,752 / 0 17:01:13 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396270080; Write bytes: 390624768; Read time: 28865; Write time: 749 17:01:13 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29523456; Write bytes: 293939200; Read time: 298; Write time: 1088 17:01:13 INFO - Running post-run listener: _upload_blobber_files 17:01:13 INFO - Blob upload gear active. 17:01:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:01:13 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 17:01:13 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'] 17:01:13 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 17:01:14 INFO - (blobuploader) - INFO - Open directory for files ... 17:01:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:01:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:01:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:01:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:15 INFO - (blobuploader) - INFO - Done attempting. 17:01:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:01:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:01:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:01:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:16 INFO - (blobuploader) - INFO - Done attempting. 17:01:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:01:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:01:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:01:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:18 INFO - (blobuploader) - INFO - Done attempting. 17:01:18 INFO - (blobuploader) - INFO - Iteration through files over. 17:01:18 INFO - Return code: 0 17:01:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:01:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:01:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f090f2c9e79360766953d8a0b5282eec1efd8ab9d404f5efd284ead608ac5d3c7906fa7acb299e6ecf8810373cc62e4fe48ff31f9bc43faf0a42327cedad5914", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf082106ada0a5b9313c077a72423b07340d40dee14a90553b1d227c43652036898086a9e7695a13636a837ce4b6040f6a4ffb6565aa5ee94270c5bf2ec09744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5cc28585e048a45ddb078d7f4893895bb36a06b300e3220078eb767d8875767b5a8043d1e3f309995f992e8021170984a7f243c8be591487fa3451bd4cac44d3"} 17:01:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:01:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:01:18 INFO - Contents: 17:01:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f090f2c9e79360766953d8a0b5282eec1efd8ab9d404f5efd284ead608ac5d3c7906fa7acb299e6ecf8810373cc62e4fe48ff31f9bc43faf0a42327cedad5914", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf082106ada0a5b9313c077a72423b07340d40dee14a90553b1d227c43652036898086a9e7695a13636a837ce4b6040f6a4ffb6565aa5ee94270c5bf2ec09744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5cc28585e048a45ddb078d7f4893895bb36a06b300e3220078eb767d8875767b5a8043d1e3f309995f992e8021170984a7f243c8be591487fa3451bd4cac44d3"} 17:01:18 INFO - Running post-run listener: copy_logs_to_upload_dir 17:01:18 INFO - Copying logs to upload dir... 17:01:18 INFO - mkdir: /builds/slave/test/build/upload/logs 17:01:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.264067 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-01 17:01:18.688582) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:01:18.692118) ========= 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.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/f090f2c9e79360766953d8a0b5282eec1efd8ab9d404f5efd284ead608ac5d3c7906fa7acb299e6ecf8810373cc62e4fe48ff31f9bc43faf0a42327cedad5914", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf082106ada0a5b9313c077a72423b07340d40dee14a90553b1d227c43652036898086a9e7695a13636a837ce4b6040f6a4ffb6565aa5ee94270c5bf2ec09744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5cc28585e048a45ddb078d7f4893895bb36a06b300e3220078eb767d8875767b5a8043d1e3f309995f992e8021170984a7f243c8be591487fa3451bd4cac44d3"} build_url:https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/TxNBLTdGRvm1hMM6UjbUiA/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/f090f2c9e79360766953d8a0b5282eec1efd8ab9d404f5efd284ead608ac5d3c7906fa7acb299e6ecf8810373cc62e4fe48ff31f9bc43faf0a42327cedad5914", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf082106ada0a5b9313c077a72423b07340d40dee14a90553b1d227c43652036898086a9e7695a13636a837ce4b6040f6a4ffb6565aa5ee94270c5bf2ec09744", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5cc28585e048a45ddb078d7f4893895bb36a06b300e3220078eb767d8875767b5a8043d1e3f309995f992e8021170984a7f243c8be591487fa3451bd4cac44d3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:01:18.731289) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:01:18.732154) ========= 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.5NfhuD30iM/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.9bS7j3khRR/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005026 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:01:18.784198) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:01:18.784950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:01:18.785665) ========= ========= Total master_lag: 0.18 =========