builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0072 starttime: 1468754344.42 results: success (0) buildid: 20160717013351 builduid: c716241ec02443febeec2c20cbdeab0a revision: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.427851) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.428359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.428635) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.485526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.485793) ========= 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.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-17 04:19:04-- 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% 10.3M=0.001s 2016-07-17 04:19:04 (10.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.670267) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.670557) ========= 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.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.020852 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:04.719175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 04:19:04.719489) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 --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 e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-17 04:19:04,793 truncating revision to first 12 chars 2016-07-17 04:19:04,793 Setting DEBUG logging. 2016-07-17 04:19:04,794 attempt 1/10 2016-07-17 04:19:04,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e7a27a7538b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 04:19:06,354 unpacking tar archive at: fx-team-e7a27a7538b2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.820967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 04:19:06.562526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:06.562866) ========= script_repo_revision: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:06.563291) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:06.563592) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:19:06.585571) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-17 04:19:06.585836) ========= /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.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:06 INFO - MultiFileLogger online at 20160717 04:19:06 in /builds/slave/test 04:19:06 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 04:19:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:19:06 INFO - {'append_to_log': False, 04:19:06 INFO - 'base_work_dir': '/builds/slave/test', 04:19:06 INFO - 'blob_upload_branch': 'Fx-Team', 04:19:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:19:06 INFO - 'branch': 'Fx-Team', 04:19:06 INFO - 'buildbot_json_path': 'buildprops.json', 04:19:06 INFO - 'config_files': ('talos/mac_config.py',), 04:19:06 INFO - 'default_actions': ('clobber', 04:19:06 INFO - 'read-buildbot-config', 04:19:06 INFO - 'download-and-extract', 04:19:06 INFO - 'populate-webroot', 04:19:06 INFO - 'create-virtualenv', 04:19:06 INFO - 'install', 04:19:06 INFO - 'run-tests'), 04:19:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:19:06 INFO - 'download_minidump_stackwalk': True, 04:19:06 INFO - 'download_symbols': 'ondemand', 04:19:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:19:06 INFO - 'tooltool.py': '/tools/tooltool.py', 04:19:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:19:06 INFO - '/tools/misc-python/virtualenv.py')}, 04:19:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:19:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:19:06 INFO - 'installer_path': 'installer.exe', 04:19:06 INFO - 'log_level': 'info', 04:19:06 INFO - 'log_name': 'talos', 04:19:06 INFO - 'log_to_console': True, 04:19:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:19:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:19:06 INFO - 'opt_config_files': (), 04:19:06 INFO - 'pip_index': False, 04:19:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:19:06 INFO - 'cmd': ('bash', 04:19:06 INFO - '-c', 04:19:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:19:06 INFO - 'enabled': True, 04:19:06 INFO - 'halt_on_failure': False, 04:19:06 INFO - 'name': 'check_screen_resolution'},), 04:19:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:19:06 INFO - 'cmd': ('bash', 04:19:06 INFO - '-c', 04:19:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:19:06 INFO - 'enabled': True, 04:19:06 INFO - 'halt_on_failure': False, 04:19:06 INFO - 'name': 'check_screen_resolution'},), 04:19:06 INFO - 'run_cmd_checks_enabled': True, 04:19:06 INFO - 'sps_profile': False, 04:19:06 INFO - 'sps_profile_interval': 0, 04:19:06 INFO - 'suite': 'dromaeojs', 04:19:06 INFO - 'system_bits': '32', 04:19:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:19:06 INFO - 'title': 't-yosemite-r7-0072', 04:19:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:19:06 INFO - 'use_talos_json': True, 04:19:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:19:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:19:06 INFO - 'work_dir': 'build'} 04:19:06 INFO - ##### 04:19:06 INFO - ##### Running clobber step. 04:19:06 INFO - ##### 04:19:06 INFO - Running pre-action listener: _resource_record_pre_action 04:19:06 INFO - Running main action method: clobber 04:19:06 INFO - rmtree: /builds/slave/test/build 04:19:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:19:08 INFO - Running post-action listener: _resource_record_post_action 04:19:08 INFO - ##### 04:19:08 INFO - ##### Finished clobber step (success) 04:19:08 INFO - ##### 04:19:08 INFO - ##### 04:19:08 INFO - ##### Running read-buildbot-config step. 04:19:08 INFO - ##### 04:19:08 INFO - Running pre-action listener: _resource_record_pre_action 04:19:08 INFO - Running main action method: read_buildbot_config 04:19:08 INFO - Using buildbot properties: 04:19:08 INFO - { 04:19:08 INFO - "project": "", 04:19:08 INFO - "product": "firefox", 04:19:08 INFO - "script_repo_revision": "production", 04:19:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:19:08 INFO - "repository": "", 04:19:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:19:08 INFO - "buildid": "20160717013351", 04:19:08 INFO - "pgo_build": "False", 04:19:08 INFO - "basedir": "/builds/slave/test", 04:19:08 INFO - "buildnumber": 892, 04:19:08 INFO - "slavename": "t-yosemite-r7-0072", 04:19:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:19:08 INFO - "platform": "yosemite_r7", 04:19:08 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 04:19:08 INFO - "branch": "fx-team", 04:19:08 INFO - "repo_path": "integration/fx-team", 04:19:08 INFO - "revision": "e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8", 04:19:08 INFO - "stage_platform": "macosx64", 04:19:08 INFO - "builduid": "c716241ec02443febeec2c20cbdeab0a", 04:19:08 INFO - "slavebuilddir": "test" 04:19:08 INFO - } 04:19:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:19:08 INFO - Running post-action listener: _resource_record_post_action 04:19:08 INFO - ##### 04:19:08 INFO - ##### Finished read-buildbot-config step (success) 04:19:08 INFO - ##### 04:19:08 INFO - ##### 04:19:08 INFO - ##### Running download-and-extract step. 04:19:08 INFO - ##### 04:19:08 INFO - Running pre-action listener: _resource_record_pre_action 04:19:08 INFO - Running main action method: download_and_extract 04:19:08 INFO - mkdir: /builds/slave/test/build/tests 04:19:08 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')]} 04:19:08 INFO - https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:19:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:19:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:19:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:12 INFO - Downloaded 1199 bytes. 04:19:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:19:12 INFO - Using the following test package requirements: 04:19:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:19:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:19:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:19:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'jsshell-mac.zip'], 04:19:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:19:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:19:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:19:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:19:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:19:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:19:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:19:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:12 INFO - https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:19:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:19:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:19:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:13 INFO - Downloaded 18835897 bytes. 04:19: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'] 04:19: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 04:19:17 INFO - Return code: 0 04:19:17 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 04:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:17 INFO - https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:19:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:19:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:19:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:20 INFO - Downloaded 18835897 bytes. 04:19:20 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'] 04:19:20 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 04:19:23 INFO - Return code: 0 04:19:23 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')]} 04:19:23 INFO - https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:19:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:19:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:19:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:24 INFO - Downloaded 14403317 bytes. 04:19:24 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'] 04:19:24 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 04:19:25 INFO - Return code: 0 04:19:25 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')]} 04:19:25 INFO - https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:19:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:19:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:19:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U8qYx2jWTQm-V13EnniI9w/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 04:19:27 INFO - Downloaded 112756204 bytes. 04:19:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:19:27 INFO - mkdir: /builds/slave/test/properties 04:19:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:19:27 INFO - Writing to file /builds/slave/test/properties/build_url 04:19:27 INFO - Contents: 04:19:27 INFO - build_url:https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:19:29 INFO - Running post-action listener: _resource_record_post_action 04:19:29 INFO - Running post-action listener: set_extra_try_arguments 04:19:29 INFO - ##### 04:19:29 INFO - ##### Finished download-and-extract step (success) 04:19:29 INFO - ##### 04:19:29 INFO - ##### 04:19:29 INFO - ##### Running populate-webroot step. 04:19:29 INFO - ##### 04:19:29 INFO - Running pre-action listener: _resource_record_pre_action 04:19:29 INFO - Running main action method: populate_webroot 04:19:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:19:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:19:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:19:29 INFO - '--tppagecycles', 04:19:29 INFO - '7'], 04:19:29 INFO - 'tests': ['tsvgm']}}, 04:19:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:19:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:19:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:19:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:19:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['damp', 'tps']}, 04:19:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'tests': ['damp', 'tps']}, 04:19:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['dromaeo_dom']}, 04:19:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:19:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['basic_compositor_video']}, 04:19:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:19:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'other-e10s': {'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['a11yr', 04:19:29 INFO - 'ts_paint', 04:19:29 INFO - 'tpaint', 04:19:29 INFO - 'sessionrestore', 04:19:29 INFO - 'sessionrestore_no_auto_restore', 04:19:29 INFO - 'tabpaint']}, 04:19:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['tsvgx', 04:19:29 INFO - 'tsvgr_opacity', 04:19:29 INFO - 'tart', 04:19:29 INFO - 'tscrollx', 04:19:29 INFO - 'cart']}, 04:19:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:19:29 INFO - 'tsvgr_opacity', 04:19:29 INFO - 'tart', 04:19:29 INFO - 'tscrollx', 04:19:29 INFO - 'cart']}, 04:19:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'talos_options': ['--disable-e10s'], 04:19:29 INFO - 'tests': ['tp5o']}, 04:19:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'tests': ['tp5o']}, 04:19:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'talos_options': ['--disable-e10s', 04:19:29 INFO - '--xperf_path', 04:19:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:29 INFO - 'tests': ['tp5n']}, 04:19:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:19:29 INFO - 'talos_options': ['--xperf_path', 04:19:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:29 INFO - 'tests': ['tp5n']}}, 04:19:29 INFO - 'talos.zip': {'path': '', 04:19:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:19:29 INFO - Running post-action listener: _resource_record_post_action 04:19:29 INFO - ##### 04:19:29 INFO - ##### Finished populate-webroot step (success) 04:19:29 INFO - ##### 04:19:29 INFO - ##### 04:19:29 INFO - ##### Running create-virtualenv step. 04:19:29 INFO - ##### 04:19:29 INFO - Running pre-action listener: _resource_record_pre_action 04:19:29 INFO - Running main action method: create_virtualenv 04:19:29 INFO - Creating virtualenv /builds/slave/test/build/venv 04:19:29 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 04:19:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:19:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:19:29 INFO - Using real prefix '/tools/python27' 04:19:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:19:30 INFO - Installing distribute.............................................................................................................................................................................................done. 04:19:32 INFO - Installing pip.................done. 04:19:32 INFO - Return code: 0 04:19:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:32 INFO - 'HOME': '/Users/cltbld', 04:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:32 INFO - 'LOGNAME': 'cltbld', 04:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:32 INFO - 'MOZ_NO_REMOTE': '1', 04:19:32 INFO - 'NO_EM_RESTART': '1', 04:19:32 INFO - 'PAGER': '/bin/cat', 04:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:32 INFO - 'PWD': '/builds/slave/test', 04:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:32 INFO - 'SHELL': '/bin/bash', 04:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:32 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:32 INFO - 'USER': 'cltbld', 04:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:32 INFO - 'XPC_FLAGS': '0x0', 04:19:32 INFO - 'XPC_SERVICE_NAME': '0', 04:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:33 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 04:19:33 INFO - Cleaning up... 04:19:33 INFO - Return code: 0 04:19:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:19:33 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')]} 04:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:33 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')]} 04:19:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:33 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:33 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 04:19:33 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 04:19:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:33 INFO - 'HOME': '/Users/cltbld', 04:19:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:33 INFO - 'LOGNAME': 'cltbld', 04:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:33 INFO - 'MOZ_NO_REMOTE': '1', 04:19:33 INFO - 'NO_EM_RESTART': '1', 04:19:33 INFO - 'PAGER': '/bin/cat', 04:19:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:33 INFO - 'PWD': '/builds/slave/test', 04:19:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:33 INFO - 'SHELL': '/bin/bash', 04:19:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:33 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:33 INFO - 'USER': 'cltbld', 04:19:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:33 INFO - 'XPC_FLAGS': '0x0', 04:19:33 INFO - 'XPC_SERVICE_NAME': '0', 04:19:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:33 INFO - Downloading/unpacking psutil>=3.1.1 04:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:19:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:36 INFO - Installing collected packages: psutil 04:19:36 INFO - Running setup.py install for psutil 04:19:36 INFO - building 'psutil._psutil_osx' extension 04:19:36 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 04:19:36 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 04:19:36 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 04:19:36 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 04:19:36 INFO - building 'psutil._psutil_posix' extension 04:19:36 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 04:19:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:19:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:19:36 INFO - ^ 04:19:36 INFO - 1 warning generated. 04:19:36 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 04:19:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:36 INFO - Successfully installed psutil 04:19:36 INFO - Cleaning up... 04:19:36 INFO - Return code: 0 04:19:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19: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')]} 04:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:19: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 mozsystemmonitor==0.3 04:19:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:36 INFO - 'HOME': '/Users/cltbld', 04:19:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:36 INFO - 'LOGNAME': 'cltbld', 04:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:36 INFO - 'MOZ_NO_REMOTE': '1', 04:19:36 INFO - 'NO_EM_RESTART': '1', 04:19:36 INFO - 'PAGER': '/bin/cat', 04:19:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:36 INFO - 'PWD': '/builds/slave/test', 04:19:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:36 INFO - 'SHELL': '/bin/bash', 04:19:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:36 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:36 INFO - 'USER': 'cltbld', 04:19:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:36 INFO - 'XPC_FLAGS': '0x0', 04:19:36 INFO - 'XPC_SERVICE_NAME': '0', 04:19:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:19:39 INFO - Installing collected packages: mozsystemmonitor 04:19:39 INFO - Running setup.py install for mozsystemmonitor 04:19:39 INFO - Successfully installed mozsystemmonitor 04:19:39 INFO - Cleaning up... 04:19:40 INFO - Return code: 0 04:19:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:19:40 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')]} 04:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:40 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')]} 04:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:40 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:40 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 04:19:40 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 04:19:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:40 INFO - 'HOME': '/Users/cltbld', 04:19:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:40 INFO - 'LOGNAME': 'cltbld', 04:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:40 INFO - 'MOZ_NO_REMOTE': '1', 04:19:40 INFO - 'NO_EM_RESTART': '1', 04:19:40 INFO - 'PAGER': '/bin/cat', 04:19:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:40 INFO - 'PWD': '/builds/slave/test', 04:19:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:40 INFO - 'SHELL': '/bin/bash', 04:19:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:40 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:40 INFO - 'USER': 'cltbld', 04:19:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:40 INFO - 'XPC_FLAGS': '0x0', 04:19:40 INFO - 'XPC_SERVICE_NAME': '0', 04:19:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:40 INFO - Downloading/unpacking jsonschema==2.5.1 04:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:19:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:19:42 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 04:19:42 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 04:19:42 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 04:19:42 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 04:19:42 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 04:19:42 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 04:19:43 INFO - Downloading functools32-3.2.3-2.tar.gz 04:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:19:43 INFO - warning: no files found matching '*.txt' 04:19:43 INFO - no previously-included directories found matching 'build' 04:19:43 INFO - no previously-included directories found matching 'dist' 04:19:43 INFO - no previously-included directories found matching '.git*' 04:19:43 INFO - Installing collected packages: jsonschema, functools32 04:19:43 INFO - Running setup.py install for functools32 04:19:43 INFO - warning: no files found matching '*.txt' 04:19:43 INFO - no previously-included directories found matching 'build' 04:19:43 INFO - no previously-included directories found matching 'dist' 04:19:43 INFO - no previously-included directories found matching '.git*' 04:19:43 INFO - Successfully installed jsonschema functools32 04:19:43 INFO - Cleaning up... 04:19:43 INFO - Return code: 0 04:19:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:19:43 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')]} 04:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:43 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')]} 04:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:43 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:43 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 04:19:43 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 04:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:43 INFO - 'HOME': '/Users/cltbld', 04:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:43 INFO - 'LOGNAME': 'cltbld', 04:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:43 INFO - 'MOZ_NO_REMOTE': '1', 04:19:43 INFO - 'NO_EM_RESTART': '1', 04:19:43 INFO - 'PAGER': '/bin/cat', 04:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:43 INFO - 'PWD': '/builds/slave/test', 04:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:43 INFO - 'SHELL': '/bin/bash', 04:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:43 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:43 INFO - 'USER': 'cltbld', 04:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:43 INFO - 'XPC_FLAGS': '0x0', 04:19:43 INFO - 'XPC_SERVICE_NAME': '0', 04:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:43 INFO - Downloading/unpacking blobuploader==1.2.4 04:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:46 INFO - Downloading blobuploader-1.2.4.tar.gz 04:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:19:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:19:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19: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 04:19:47 INFO - Downloading docopt-0.6.1.tar.gz 04:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:19:47 INFO - Installing collected packages: blobuploader, requests, docopt 04:19:47 INFO - Running setup.py install for blobuploader 04:19:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:19:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:19:47 INFO - Running setup.py install for requests 04:19:48 INFO - Running setup.py install for docopt 04:19:48 INFO - Successfully installed blobuploader requests docopt 04:19:48 INFO - Cleaning up... 04:19:48 INFO - Return code: 0 04:19:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:48 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:48 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 04:19:48 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 04:19:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:48 INFO - 'HOME': '/Users/cltbld', 04:19:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:48 INFO - 'LOGNAME': 'cltbld', 04:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:48 INFO - 'MOZ_NO_REMOTE': '1', 04:19:48 INFO - 'NO_EM_RESTART': '1', 04:19:48 INFO - 'PAGER': '/bin/cat', 04:19:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:48 INFO - 'PWD': '/builds/slave/test', 04:19:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:48 INFO - 'SHELL': '/bin/bash', 04:19:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:48 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:48 INFO - 'USER': 'cltbld', 04:19:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:48 INFO - 'XPC_FLAGS': '0x0', 04:19:48 INFO - 'XPC_SERVICE_NAME': '0', 04:19:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:48 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GiQoHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:48 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-nQgEzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:48 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fOdYNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-g_DF2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-RUDOcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-1_syFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ocPDxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-DM0E9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-HQcf3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-DYGyrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-CDwUki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-sAANlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:49 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vKrs4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:50 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-_rchfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:50 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bcR9wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:50 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-5fBWwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:50 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-wvMV8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:19:50 INFO - Running setup.py install for manifestparser 04:19:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:19:50 INFO - Running setup.py install for mozcrash 04:19:50 INFO - Running setup.py install for mozdebug 04:19:50 INFO - Running setup.py install for mozdevice 04:19:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Running setup.py install for mozfile 04:19:51 INFO - Running setup.py install for mozhttpd 04:19:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Running setup.py install for mozinfo 04:19:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Running setup.py install for mozInstall 04:19:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Running setup.py install for mozleak 04:19:51 INFO - Running setup.py install for mozlog 04:19:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Running setup.py install for moznetwork 04:19:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:19:52 INFO - Running setup.py install for mozprocess 04:19:52 INFO - Running setup.py install for mozprofile 04:19:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:19:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:19:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:19:52 INFO - Running setup.py install for mozrunner 04:19:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:19:52 INFO - Running setup.py install for mozscreenshot 04:19:52 INFO - Running setup.py install for moztest 04:19:52 INFO - Running setup.py install for mozversion 04:19:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:19:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:19:53 INFO - Cleaning up... 04:19:53 INFO - Return code: 0 04:19:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:53 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:53 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 04:19:53 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 04:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:53 INFO - 'HOME': '/Users/cltbld', 04:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:53 INFO - 'LOGNAME': 'cltbld', 04:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:53 INFO - 'MOZ_NO_REMOTE': '1', 04:19:53 INFO - 'NO_EM_RESTART': '1', 04:19:53 INFO - 'PAGER': '/bin/cat', 04:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:53 INFO - 'PWD': '/builds/slave/test', 04:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:53 INFO - 'SHELL': '/bin/bash', 04:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:53 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:53 INFO - 'USER': 'cltbld', 04:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:53 INFO - 'XPC_FLAGS': '0x0', 04:19:53 INFO - 'XPC_SERVICE_NAME': '0', 04:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-o8Brlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-gLH70U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Udnvmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-r5I2_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-5Dv42Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-aHdF2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:53 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)) 04:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-VmSjju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vGzAzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-P_Kd5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-G3nClX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fOZfco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bFK9JK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-C5XNBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-mO6KNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-M0DVcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:54 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)) 04:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:54 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-IbAL4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:55 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)) 04:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:55 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-LXiQW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:55 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)) 04:19:55 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)) 04:19:55 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)) 04:19:55 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)) 04:19:55 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)) 04:19:55 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)) 04:19:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:19:55 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 04:19:55 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 04:19:55 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 04:19:55 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 04:19:55 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 04:19:55 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 04:19:57 INFO - Downloading blessings-1.6.tar.gz 04:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:19:57 INFO - Installing collected packages: blessings 04:19:57 INFO - Running setup.py install for blessings 04:19:57 INFO - Successfully installed blessings 04:19:57 INFO - Cleaning up... 04:19:58 INFO - Return code: 0 04:19:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:19:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:58 INFO - Reading from file tmpfile_stdout 04:19:58 INFO - Current package versions: 04:19:58 INFO - blessings == 1.6 04:19:58 INFO - blobuploader == 1.2.4 04:19:58 INFO - docopt == 0.6.1 04:19:58 INFO - functools32 == 3.2.3-2 04:19:58 INFO - jsonschema == 2.5.1 04:19:58 INFO - manifestparser == 1.1 04:19:58 INFO - mozInstall == 1.12 04:19:58 INFO - mozcrash == 0.17 04:19:58 INFO - mozdebug == 0.1 04:19:58 INFO - mozdevice == 0.48 04:19:58 INFO - mozfile == 1.2 04:19:58 INFO - mozhttpd == 0.7 04:19:58 INFO - mozinfo == 0.9 04:19:58 INFO - mozleak == 0.1 04:19:58 INFO - mozlog == 3.2 04:19:58 INFO - moznetwork == 0.27 04:19:58 INFO - mozprocess == 0.23 04:19:58 INFO - mozprofile == 0.28 04:19:58 INFO - mozrunner == 6.12 04:19:58 INFO - mozscreenshot == 0.1 04:19:58 INFO - mozsystemmonitor == 0.3 04:19:58 INFO - moztest == 0.7 04:19:58 INFO - mozversion == 1.4 04:19:58 INFO - psutil == 3.1.1 04:19:58 INFO - requests == 1.2.3 04:19:58 INFO - wsgiref == 0.1.2 04:19:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:58 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')]} 04:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:58 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')]} 04:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:58 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:58 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 04:19:58 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 04:19:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:58 INFO - 'HOME': '/Users/cltbld', 04:19:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:58 INFO - 'LOGNAME': 'cltbld', 04:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:58 INFO - 'MOZ_NO_REMOTE': '1', 04:19:58 INFO - 'NO_EM_RESTART': '1', 04:19:58 INFO - 'PAGER': '/bin/cat', 04:19:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:58 INFO - 'PWD': '/builds/slave/test', 04:19:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:58 INFO - 'SHELL': '/bin/bash', 04:19:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:58 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:58 INFO - 'USER': 'cltbld', 04:19:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:58 INFO - 'XPC_FLAGS': '0x0', 04:19:58 INFO - 'XPC_SERVICE_NAME': '0', 04:19:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 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)) 04:19:58 INFO - Cleaning up... 04:19:58 INFO - Return code: 0 04:19:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:19:58 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')]} 04:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:58 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')]} 04:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:58 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 0x10faa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea7948dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, '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.QNtxXSrPz2/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.PebV2lHRRx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 04:19:58 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 04:19:58 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 04:19:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:19:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:58 INFO - 'HOME': '/Users/cltbld', 04:19:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:58 INFO - 'LOGNAME': 'cltbld', 04:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:58 INFO - 'MOZ_NO_REMOTE': '1', 04:19:58 INFO - 'NO_EM_RESTART': '1', 04:19:58 INFO - 'PAGER': '/bin/cat', 04:19:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:58 INFO - 'PWD': '/builds/slave/test', 04:19:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:58 INFO - 'SHELL': '/bin/bash', 04:19:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:19:58 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:19:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:58 INFO - 'USER': 'cltbld', 04:19:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:58 INFO - 'XPC_FLAGS': '0x0', 04:19:58 INFO - 'XPC_SERVICE_NAME': '0', 04:19:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:19:58 INFO - Cleaning up... 04:19:58 INFO - Return code: 0 04:19:58 INFO - Running post-action listener: _resource_record_post_action 04:19:58 INFO - Running post-action listener: _start_resource_monitoring 04:19:58 INFO - Starting resource monitoring. 04:19:58 INFO - ##### 04:19:58 INFO - ##### Finished create-virtualenv step (success) 04:19:58 INFO - ##### 04:19:58 INFO - ##### 04:19:58 INFO - ##### Running install step. 04:19:58 INFO - ##### 04:19:58 INFO - Running pre-action listener: _resource_record_pre_action 04:19:58 INFO - Running main action method: install 04:19:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:59 INFO - Reading from file tmpfile_stdout 04:19:59 INFO - Detecting whether we're running mozinstall >=1.0... 04:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:19:59 INFO - Reading from file tmpfile_stdout 04:19:59 INFO - Output received: 04:19:59 INFO - Usage: mozinstall [options] installer 04:19:59 INFO - Options: 04:19:59 INFO - -h, --help show this help message and exit 04:19:59 INFO - -d DEST, --destination=DEST 04:19:59 INFO - Directory to install application into. [default: 04:19:59 INFO - "/builds/slave/test"] 04:19:59 INFO - --app=APP Application being installed. [default: firefox] 04:19:59 INFO - mkdir: /builds/slave/test/build/application 04:19:59 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'] 04:19:59 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 04:20:33 INFO - Reading from file tmpfile_stdout 04:20:33 INFO - Output received: 04:20:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:20:33 INFO - Running post-action listener: _resource_record_post_action 04:20:33 INFO - ##### 04:20:33 INFO - ##### Finished install step (success) 04:20:33 INFO - ##### 04:20:33 INFO - ##### 04:20:33 INFO - ##### Running run-tests step. 04:20:33 INFO - ##### 04:20:33 INFO - Running pre-action listener: _resource_record_pre_action 04:20:33 INFO - Running main action method: run_tests 04:20:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:20:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:20:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:20:33 INFO - 2016-07-17 04:20:33.615 screenresolution[1911:19807] starting screenresolution argv=screenresolution get 04:20:33 INFO - 2016-07-17 04:20:33.617 screenresolution[1911:19807] Display 0: 1600x1200x32@60 04:20:33 INFO - 2016-07-17 04:20:33.624 screenresolution[1912:19809] starting screenresolution argv=screenresolution list 04:20:33 INFO - Available Modes on Display 0 04:20:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:20:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:20:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:20:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:20:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:20:33 INFO - Intel Iris: 04:20:33 INFO - Chipset Model: Intel Iris 04:20:33 INFO - Type: GPU 04:20:33 INFO - Bus: Built-In 04:20:33 INFO - VRAM (Dynamic, Max): 1536 MB 04:20:33 INFO - Vendor: Intel (0x8086) 04:20:33 INFO - Device ID: 0x0a2e 04:20:33 INFO - Revision ID: 0x0009 04:20:33 INFO - Displays: 04:20:33 INFO - Display: 04:20:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:20:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:20:33 INFO - Display Serial Number: 1600x1200 60 04:20:33 INFO - Main Display: Yes 04:20:33 INFO - Mirror: Off 04:20:33 INFO - Online: Yes 04:20:33 INFO - Rotation: Supported 04:20:33 INFO - Return code: 0 04:20:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:20:33 INFO - Python 2.7.3 04:20:33 INFO - Return code: 0 04:20:33 INFO - grabbing minidump binary from tooltool 04:20:33 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')]} 04:20:33 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 0x10fa04d30>, '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 0x10f769db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:20:33 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 04:20:33 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 04:20:33 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 04:20:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:20:33 INFO - Return code: 0 04:20:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:20:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:20:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:20:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:20:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:20:33 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/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 04:20:33 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/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 04:20:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QNtxXSrPz2/Render', 04:20:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:33 INFO - 'HOME': '/Users/cltbld', 04:20:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:33 INFO - 'LOGNAME': 'cltbld', 04:20:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:20:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:33 INFO - 'MOZ_NO_REMOTE': '1', 04:20:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:20:33 INFO - 'NO_EM_RESTART': '1', 04:20:33 INFO - 'PAGER': '/bin/cat', 04:20:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:33 INFO - 'PWD': '/builds/slave/test', 04:20:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:20:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:33 INFO - 'SHELL': '/bin/bash', 04:20:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PebV2lHRRx/Listeners', 04:20:33 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 04:20:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:33 INFO - 'USER': 'cltbld', 04:20:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:33 INFO - 'XPC_FLAGS': '0x0', 04:20:33 INFO - 'XPC_SERVICE_NAME': '0', 04:20:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:33 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/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 04:20:34 INFO - mozversion application_buildid: 20160717013351 04:20:34 INFO - mozversion application_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 04:20:34 INFO - mozversion application_display_name: Nightly 04:20:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:20:34 INFO - mozversion application_name: Firefox 04:20:34 INFO - mozversion application_remotingname: firefox 04:20:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:20:34 INFO - mozversion application_vendor: Mozilla 04:20:34 INFO - mozversion application_version: 50.0a1 04:20:34 INFO - mozversion platform_buildid: 20160717013351 04:20:34 INFO - mozversion platform_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 04:20:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:20:34 INFO - mozversion platform_version: 50.0a1 04:20:34 INFO - using testdate: 1468754434 04:20:34 INFO - actual date: 1468754434 04:20:34 INFO - starting webserver on 'localhost:55985' 04:20:34 INFO - SUITE-START | Running 2 tests 04:20:34 INFO - TEST-START | dromaeo_css 04:20:34 INFO - operating with platform_type : mac_ 04:20:34 INFO - Initialising browser for dromaeo_css test... 04:20:34 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpDPJyJ2/profile http://localhost:55985/getInfo.html) 04:20:36 INFO - PROCESS | 1922 | JavaScript error: , line 0: uncaught exception: undefined 04:20:36 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 04:20:36 INFO - PROCESS | 1922 | colorDepth:24 04:20:36 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 04:20:36 INFO - PROCESS | 1922 | __metrics 04:20:36 INFO - PROCESS | 1922 | 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" 04:20:36 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:20:37 INFO - TEST-INFO | 1922: exit 0 04:20:37 INFO - Browser initialized. 04:20:37 INFO - Running cycle 1/1 for dromaeo_css test... 04:20:37 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpDPJyJ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:49 INFO - PROCESS | 1923 | RSS: Main: 245071872 04:20:49 INFO - PROCESS | 1923 | 04:21:40 INFO - PROCESS | 1923 | 1468754500545 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] 04:23:51 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55985/tests/dromaeo/cssquery-ext.html) 04:23:52 INFO - PROCESS | 1923 | RSS: Main: 346632192 04:23:52 INFO - PROCESS | 1923 | 04:26:55 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-ext.html (next: http://localhost:55985/tests/dromaeo/cssquery-jquery.html) 04:26:56 INFO - PROCESS | 1923 | RSS: Main: 375435264 04:26:56 INFO - PROCESS | 1923 | 04:29:58 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55985/tests/dromaeo/cssquery-mootools.html) 04:29:59 INFO - PROCESS | 1923 | RSS: Main: 369336320 04:29:59 INFO - PROCESS | 1923 | 04:33:01 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55985/tests/dromaeo/cssquery-prototype.html) 04:33:02 INFO - PROCESS | 1923 | RSS: Main: 371085312 04:33:02 INFO - PROCESS | 1923 | 04:36:05 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55985/tests/dromaeo/cssquery-yui.html) 04:36:06 INFO - PROCESS | 1923 | RSS: Main: 350560256 04:36:06 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-yui.html (next: http://localhost:55985/tests/dromaeo/cssquery-dojo.html) 04:39:09 INFO - PROCESS | 1923 | RSS: Main: 434126848 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | __start_tp_report 04:39:09 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 04:39:09 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 04:39:09 INFO - PROCESS | 1923 | |i|pagename|runs| 04:39:09 INFO - PROCESS | 1923 | |0;dojo.html;3346.498025296206;3354.832258387081;3366.7811592246517;3396.0660818274987;3429.1427143214196;5538.252335934648;5628.549716022719;5650.773969041238;5741.339745929215;5750.137479378091;5356.946430535693;5423.783048678058;5631.971840140804;5675.574331925101;5771.942280577193;5124.692518448886;5127.256547800566;5179.352580927384;5189.065968125736;5300.708461034642;5331.866703332428;5334.199870019509;5357.4374690657505;5367.570594352462;5384.515393614574;5146.845594632167;5181.4300426953005;5195.844124676266;5241.292425522543;5262.605304602132;5181.404138524081;5232.215167724834;5237.580993520509;5238.554722848562;5247.816326428595;5120.667156634807;5132.486751324875;5147.382314122329;5172.844814655567;5205.089109405877;5699.971500142514;5711.228984087136;5798.565107616945;5799.9130013049835;5817.883642327172;6090.989973308798;6101.913673707472;6260.123582698426;6265.8120256392385;6291.591046582003;6186.226721659793;6221.751129954841;6260;6260.499160067229;6298.118263443122;5865.149553314788;5875.559333050039;5885.529157667419;5886.058230683113;5907.793227237026;5300.204969254565;6201.441870231701;6261.373862613702;6261.843453913598;6323.5889667172405;6174.938250617526;6186.845328866724;6195.907061394083;6203.875922481525;6210.4100110489435;6308.243010838729;6327.145835312174;6330.968345158245;6337.9683101585115;6358.713857876407;6099.237595300588;6104.20645316106;6260.812175634738;6260.968695156586;6282.748690052438;6171.197744293213;6176.289726681399;6237.625742455467;6292.874142517216;6312.589681670768;4956.925646115311;4962.230854217602;4963.677360971526;4964.60283177345;4997.5002499749735;4427.247367947457;4441.822327106945;4484.955150448453;4518.864434066983;4559.042601053749;5680.034394153004;5697.857553561112;5756.194132821408;5771.220885180531;5827.184194212814;6284.874302514016;6318.778842740482;6333.778317758856;6352.809415717536;6362.745490180341;5583.302087239095;5644.322681278273;5687.630304030225;5709.486146246858;5803.738831752581;5447.264619276347;5448.101063324586;5451.018816613047;5454.836354909359;5463.071277882769;6118.877622447437;6329.9050514243245;6332.936670633327;6337.049442583557;6345.175127233522;6151.292601350723;6203.627782333075;6238.376162383907;6317.431431171217;6332.873342533216;5626.971865140648;6088.51291896656;6295.433410993032;6331.525135614939;6338.461230795434;6129.7969628740475;6200.06998950143;6200.56596038273;6234.064890266496;6244.094606281973;6124.411449640054;6206.906896396648;6247.781327653511;6323.241211054703;6336.968315158395;4702.858914232578;6103.542234332354;6155.322914479314;6169.722362493609;6170.475509581556;6075.1798507200965;6112.755489780537;6117.84705382369;6125.663088530174;6129.049997250433;5788.768449261982;5881.441263080001;5899.38056504078;5999;6005.849853753691;6152.753889844356;6163.38366163398;6175.536834737323;6176.289726681489;6183.5671502994355;5981.252343457068;5985.730642121028;5986;5999.610025348338;6000.219971403864 04:39:09 INFO - PROCESS | 1923 | |1;ext.html;499.69768290184436;512.1600575056907;538.6148903533972;541.2963147907719;550.975186153754;30682.693173068263;32181.678183218162;32306.838465807698;32372.352552948956;42411.363829542584;42441.787791061004;45096.09807803842;45147;45149.871253218684;45239.321410178876;8915.465072095694;9481;9656;9858.753531161707;9900.603975840957;8733.12668733128;8905.287576993825;9675.225981921429;9807.950960245225;11791.528338866436;11720.355380454072;12094.971927386183;12101;12101.152919295653;12103.636890893287;11966.401679915969;12036.879631203663;12040.458179381949;12050.15648904577;12062.69843253917;8681.652733890636;8870;8872.55637218143;9033.81932361349;11971.521139154424;41578.168436631095;41947.161056779;42080;44186.33720494195;44223.33664995027;2982.3438843454405;3400.574928133983;3692.778433294011;3704.2035962267973;3860.3823388258024;31271.350660247335;32024.5196322053;32453.513197302058;33070;33181;3323.80057196569;3325.057324268016;3336.8832090876704;3398.4052790761516;3453.8445769940163;485025;487179;487278;488104;488330;63001.05498417527;63672;63713;63737.36262637407;63809.04286435707;68591.65704171447;68663.31336686669;68694;68724.96912546316;68759.65620171867;16968.830311696973;17175.656486870193;17208.05355705448;17217.91391043037;17256.65486690259;11947.163698541019;12154.635360939186;12158.392080395945;12272.956798672034;12904;18719.625607488048;18919.905400472908;19239.228624170257;19375.51561210981;19439.319623813382;6622.10601568782;7516.098068231738;7696.846063078707;7731.806704832424;7751.418643601753;865.8311629232239;893.6246776353947;974.6296407365297;1038.2835843268122;1038.8026275007724;30886.073417797503;31011.84494077515;31105.066847994596;31127.533087003714;32139;1083.7724077943562;1086.9293495922739;1116.1126904111252;1130.5477808876515;1185.3006726031683;15744.763828542582;16100.597485062734;16293;16566.58583535397;16645.83354166467;680960;683823;684989;685134;685322;54309;58438.12342814776;61620;62027.689861551306;62341.064884026775;1246.4141853328922;1387.9791803122962;1398.5245016694373;1410.4569740649717;1431.2056808470982;31118.844405777825;32808;32850.34299313952;32983.34033319272;34433.48349774806;54231;54938.450615494934;55051.724741376034;55061.31978781688;55193;613968.7904681523;644433;645351;645849;646338;45968.770156149;46232.61744431584;46233.53766462292;46965.53034469612;48743;50582;50750.492495076054;51235.975280494924;51496.74251628868;51825.740871295406;42150.57849421589;42379;42559.57440425556;43090.138197236505;43492.347614786435;39388.803055984536;41999.79000105102;42399.304027839775;43354.349684754125;43369.566304337815;40542;40932;40949.590504095766;41322.79338603408;41798.58201417947;29588.852055739582;35771.46342804808;35844;36115;36755.08112297214;26640.60039099454;26731.33171670646;26763.06329278466;26915.06031588111;27022.864885675444 04:39:09 INFO - PROCESS | 1923 | |2;jquery.html;359.0413595699482;384.63843986652546;393.43149149478995;395.5154935204374;415.4495293735799;15701.371945122195;16973.74539381909;20244.29144979926;21077.894610526924;21084.683729744065;20719.482012949684;21000.894995525003;21053.578928421422;21062.262820801276;21079.262225822098;5737.512311453532;5922.082077278029;6026.578139530213;6138.969305153468;6187.752489900398;5942.435468630495;5965.299889531484;6046.21399218101;6048.848778780543;6051.515878729691;5852.912206316909;5998.6400815950965;6009.278886533601;6213.633000739853;6214.57064875079;5266.499682530153;6122.877542449126;6152.446279834814;6186.917289474347;6201.720922558496;4823.421189457253;6063.302720187191;6066.120412540201;6068.787592434288;6069.757209711606;13149.605511834565;13445.798313025312;19008.714869276973;26050.86974565134;26090.73909260902;2160.967585486219;2229.331200639818;2314.2478694424212;2332.9300120996227;2357.5049239659693;9356.672516461958;9914.851277230848;10029.398236105784;10205;10468.790624187473;1852.3516769130697;1885.6700077486385;1906.9141888615045;1920.059171006197;1925.911859799209;110750.44624776905;125100;128318;129531;131580;11258.831117533244;11328.94335528317;11373.886261137448;11385.772284554263;11477.426128693698;14377.78433323501;14526.564203073925;14537.418503259963;14556.781648275284;14581.052231604912;8469;8487.363447741443;8507.53208573522;8545.786355341043;8641.30869530436;8504.681774324867;8508.787715194094;8617.267532259828;8630.00754913193;8732.257758090507;4423.203823311835;4491.011977364975;4512.819487220474;4539.727616342964;4574.977125114419;1921.9039048047541;1924.6246981838408;1926.9036548172535;1929.8166674165932;1941.8834869907569;720.2762703502714;934.2759361494896;934.8270569944582;938.990610093904;965.666844938495;21230.575388492143;21741.78258217429;21765.347039588836;21934.89032554827;23072.4231894204;934.359963425056;954.7851733359938;994.4083270454068;1032.5972870580479;1080.7244152741;10993.175511836644;11503.424828758529;11536.019438347666;11559.30644161353;11581.76836463266;555744;562179;563279;590696;618649.9067504724;133386;139390;139991.30004350116;141751;144544.27727861292;982.9950850245846;984.1978787288338;1003.4581326083979;1004.8794144702537;1006.2101250518308;19263.325783597506;19373.90313048482;19402.514937126685;19442.513937151685;19550.90224548868;38861.80569097136;57328.71335643295;57986.13020804607;58583.414165857794;59297.81404371802;80977;83522;84183.57908210665;84478.30281581052;85058.14941850503;8000.119986801564;8053.275205231557;8177.836443271221;8348.6243119061;8610;12483.93758031204;13169.604911852659;13210.27343496117;13274;13332.266725329811;11171.944140279247;11244.775104497701;11331.980121789078;11525.308481491138;11533.192676512563;10018;11100.389478578756;11192.664220073411;11509.712257193638;11586.420678966186;11737.295762254293;11743.11926605491;11853.822192666945;11871.347075910908;11925.522979080739;7027.508074434741;7396.519226250277;7438.144613369532;8251.917480825115;8496.150384961702;8081.797955051171;9791.657291994747;11245.550177993116;11678.357690327113;11802.704932376759 04:39:09 INFO - PROCESS | 1923 | |3;mootools.html;1545.8917875748696;1592.0925072708558;1797.7932537758154;1933.82595566399;1937.205745644287;8379.036410812758;8692.521911294876;8862.601182946766;8879.60041798119;8884.378093533454;7809.6485658145375;8062.959685201566;8314.168583141698;8527.019392769833;8662.566871656423;2355.6819829323035;2383.725871524771;2387.4747555537756;2388.283514945518;2388.8327817052814;2331.591971405006;2340.5786958347576;2368.0054377161546;2374.2402431222017;2375.441771183779;1770.876038677293;2082.760482544504;2164.0153730052866;2169.8155656769113;2171.185805323004;1857.5727582655998;2029.2593203480772;2351.2005917987794;2354.846934949223;2362.5274945011065;1946.902654867265;2145.592337455894;2374.1927744566756;2380.0598763488324;2392.6889504364494;7355.668994895243;8687.956560217222;8866.73399798007;8882.822343553158;8885.42244754089;5205.55752761012;5369.5167434931045;5371.382291036542;5373.865653358658;5384.434634363413;3540.840662170183;3583.068402215417;3659;3665.688416484602;3683.797391143486;1073.5169173871789;1082.5453309609982;1097.3032124600857;1103.3545375955075;1112.1547623805966;11607.593734219261;11630.88369116315;11649.767004659943;11649.883501164964;11682.182247242696;4468.151051300242;4686.27362758773;4772.997670489167;4787.138315103245;4793.640476964241;9854.556544955494;9883.604655813688;9883.604655813688;9905.603775848886;9908.950455247677;5351.759170837244;5355.839324820184;5356.250124982505;5357.946420535744;5365.3293338332705;4725.5510726480925;4892.779824907888;4901;4904.60763138948;4930.211166213388;3714.517112775376;3729.627037296303;3759.586445490994;3762.830672619739;3821.828017739153;1141.2068612314363;1142.4801715219555;1142.6000899685043;1146.8165093584987;1148.534843388429;605.5216379060581;812.6586833529932;837.7601150297472;954.6467806911487;984.5028260728286;5961.165436838846;6027.969860150672;6030.125631783455;6148.9692551537855;6156.138140660221;484.0657530965271;496.5282981167905;497.0035079665248;502.4272329544322;651.3519048546726;4043.636072753466;4208.284591619432;4242.915141697149;4309.569043095666;4317.762523061262;10651.09465695409;10729.409882456539;10753.354798711945;10876.510557024953;10885.727856803644;10310.175185985103;10758.892411075945;10808.78382432347;10815.242932994772;10837.241393102408;8073.15231900642;8133.308668763221;8309.709160179365;8312.625931832961;8318.584070796556;5978.461938425563;6094.664793436404;6194.4115309045565;6262.405071518198;6275.058741188858;9292.210162136294;9787.265955053257;10094.293399462262;10117.19062475015;10242.743931401774;10125.898741012496;10201.897981020094;10392.948035259775;10403.895961040293;10413.427261500387;7486.5508069516;7521.849563008818;7637.312641862259;7643.656035478306;7644.15914249421;8175.427720059776;8231.176882311722;8314;8325.125861784667;8337.583120844054;6959;7097.964510177416;7337.853242935004;7498.512596681198;7647.158812530728;6989.615571143636;7480.2145774695055;7533.2466753324225;7605.923940760522;7636.465447418849;7059.18819335783;7062.258462861531;7130.893036604351;7138.571685698875;7143.178534468602;4904.6566740327835;5046.747662616928;5060.544550990428;5069.264956581202;5205.58355331565;5045.394552653705;5053.140966035856;5105;5119.974400127976;5280.524752772269 04:39:09 INFO - PROCESS | 1923 | |4;prototype.html;282.5338191983227;290.32933922638705;314.11889649533066;320.74661017795955;322.79502515902396;5573.916391254129;5615.63498372606;5618.943810561904;5641.887162256752;5650.350209725882;4497.707649002812;5410.621256512046;5557.6109672322955;5560.110382338827;5560.86097847554;3275.950860737091;3980.781057041854;4141.855035073773;4217.051163488221;4236.512801027875;3532.364174448598;3740.5137332146783;3796.4684944107707;4186.204621121992;4187;3183.7771356005087;3381.9830900845463;4134.751914885116;4159.9792001040105;4166.583341665824;3938.5470670872787;4043.8382464701376;4053.1488387438526;4156.875293741163;4180.28935081037;3575.8390872410546;3592.2276710507367;3594.748367614268;3599.4623790227824;3615.5842078161086;5414.566834653218;5416.891662166735;5421.213923981;5439.619226654136;5448.972755136199;3664.9450258246147;4156.8129434175535;4480.238359478896;4767.880802979918;4795.640326975491;5304.496072873071;5629.8311050668135;5706.3722990471015;5712;5783.537317014629;3928.4696565963513;3936.114374265796;3948.1511475032717;3950.6246906543834;3956.604339566049;5278.838655495823;5799.9130013049835;5998.25021872266;6021.976264035125;6147.661878596676;3361.8655253789575;3887.872516970104;3913.3934240192816;3926.7447615904875;3991.381335892941;3352.7485438591716;3836.271108489378;4050.858219962287;4051.2505186540584;4055.53361363447;4129.876103716893;4450.265706158448;4548.6133678637025;4693.882652933704;4732.574068333797;4998.300917504507;5608.747606357723;5765.971170144123;5775.249217601769;5777.94222057774;1342.5166939901628;1371;1371.1978492581952;1373.409433943414;1374.9550341740348;688.0985908459934;757.9886301705479;787.9645415956296;849.2526576612665;850.4557083466586;4353.695241333076;4576.558384108995;4797.912907885081;5445.3045742357;5457.717436402521;5176.611754118381;5934.465898069195;5939.673317967553;5964.836856812966;5971.402859713994;4684.789184486706;4982.729404002029;5416.295881535375;5456.263404440349;5482.698451585201;2848;2947.469455498031;3071.2475443516482;3071.293602471444;3116.8597413116463;5079.907819818683;5607.355154157324;5947.1376650385455;6151.938480615226;6212.565120441616;4880.8529995451045;5998.030058755176;6071.787487438007;6122.663253521099;6127.938720612826;5303.36359636846;5368;6041.456268935817;6137.478314343331;6148.754049837957;5303.549198318186;5536.7231638418725;5897.5281977440845;5982.491488223375;5998.19114862057;5797.84043191355;5831.679257640871;5836.620619659709;5846.532277417881;5866.6186697864505;5101.591872650105;5423.376311724202;5870.207521984477;6144.050855589253;6178.382161783966;3598.6221446747713;3732.1602639405914;3915.0995271088377;3923.843046278117;3933.9409908851962;5403.51438338353;5532.640378375393;5874.385898275226;5883.793087942142;5893.233879595625;4623.884402889955;5241;5752.504348869408;5754.798582049608;5761.308642962704;5485.945140548703;5718.656880587178;5734.885302294014;5735.4551317624755;5754.683492407958;5184.766685499086;5270.920936186037;5323.840284791462;5609.579281553982;5631.802886898939;5563.304586926634;5644.379118297066;5729.799457018984;5744.281964754406;5784.587093614788;5526.784107496344;5588.580856435702;5608.803691870765;5628.690422026828;5636.520895723909 04:39:09 INFO - PROCESS | 1923 | |5;yui.html;746.7722344684872;760.6082867323325;813.5118928642815;826.987595186072;854.8758382726714;15500.922495387522;16107.677846443064;16209.513714588566;16210.108444035573;16241.512754617366;14791.778123328158;15168.089914605103;15181.392744290215;15194.088354698699;15205.391784328642;711.7046425733314;738.2728012907282;881.1761003461768;889.9644014239423;903.941243819153;886.2953951608487;912.069688917304;912.2246090822781;918.1874041473277;924.0436148586215;799.8800179972992;963.6964356227801;964.6334392930677;965.2326400511575;965.9275554333418;744.0587656614388;922.2944447497603;954.2938225712992;955.3217215776772;958.8417911044669;705.3898377903076;706.4666177036356;708.9822754431129;709.2092317066433;716.201435399531;15046;15073.924630376887;15082.321295541728;15095.094294342374;15105.169215693133;11181.608643697513;14034.298285085806;14124.505642302573;14230.786538201935;14452.494162704255;12930.935345323307;12984.740305193938;13044.217346959118;13052.629473905197;13087;3933.606639336072;4566.783853192696;4745.810167593292;4764;4817;88333;112652.99999999919;112929;113023;113133;11022.614208502664;11031.338119712844;11239.775204495865;11272.436378181223;11278.661640150809;21863.78136218649;22057.77942220557;22089.779102208773;22090.558188836134;22300.442488937908;7828.921710782934;7889.684412623431;7896.802579935433;8021.879671804928;8041.678332866737;3357.815320157366;4325.4593175853015;4388.802503887333;4406.074724307867;4583.175028494903;3441.174118211611;3568.411212149966;3940.9802950985063;4138.379243113497;4138.716997730653;725.5429079679768;735.9705611775469;746.4438992950287;750.5384188724005;751.3688501658633;526.9973650131725;541.8184908055846;671.9899201511981;686.5777546808677;696.4393663101253;7576.962115189389;7894.644740986554;8134.308583770446;9501;9620.470874101991;629.1475051305507;650.2781912077685;664.833791552112;694.8506071194718;699.1435491522844;4624.699394539344;4824.421069471686;4837.637177211724;4870.218613180376;4879.2925025871045;142926;166644.16677916775;170793;172505;179919;91993.54003229941;99023;148597;150398;151351;99443.50278248562;101212.98787012183;117292;127148;133184;9857.211423086279;9906.455144967096;10044;10131.949340253252;10152.593896244363;64191;64435.489515735324;64829;66487;67156.328436715;98728.51907221541;101657;102468.46297305397;102540;106840;13325.267110309027;13408.798868016793;13527.458901643824;13767.724645507235;14016;15026.17356045428;15131.621709457351;15243.923780381027;15304.387824486896;15540.922295388451;14063.507777227747;14098.295085245492;14207.140767044766;14314;14706.55880323592;14100.78848817248;14203.64490887736;14374.568762937128;14583.781243281148;14987.475438359605;13816.585502434944;14099.436022559394;14240.715185696436;14327.140371577738;14556.781648275497;9371.531423428665;11290.887091128983;11491.597794077166;11588.884111159117;11975.461104250504;10119.331268366304;10817.675469735921;11019.559217631206;11445.542178312775;11700.531978721096 04:39:09 INFO - PROCESS | 1923 | __end_tp_report 04:39:09 INFO - PROCESS | 1923 | __start_cc_report 04:39:09 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,1161 04:39:09 INFO - PROCESS | 1923 | __end_cc_report 04:39:09 INFO - PROCESS | 1923 | __startTimestamp1468755549336__endTimestamp 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | ------- Summary: start ------- 04:39:09 INFO - PROCESS | 1923 | Number of tests: 6 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5749.11 Median:5985.73 stddev:640.46 (10.7%) stddev-sans-first:614.07 04:39:09 INFO - PROCESS | 1923 | Values: 3346.5 3354.8 3366.8 3396.1 3429.1 5538.3 5628.5 5650.8 5741.3 5750.1 5356.9 5423.8 5632.0 5675.6 5771.9 5124.7 5127.3 5179.4 5189.1 5300.7 5331.9 5334.2 5357.4 5367.6 5384.5 5146.8 5181.4 5195.8 5241.3 5262.6 5181.4 5232.2 5237.6 5238.6 5247.8 5120.7 5132.5 5147.4 5172.8 5205.1 5700.0 5711.2 5798.6 5799.9 5817.9 6091.0 6101.9 6260.1 6265.8 6291.6 6186.2 6221.8 6260.0 6260.5 6298.1 5865.1 5875.6 5885.5 5886.1 5907.8 5300.2 6201.4 6261.4 6261.8 6323.6 6174.9 6186.8 6195.9 6203.9 6210.4 6308.2 6327.1 6331.0 6338.0 6358.7 6099.2 6104.2 6260.8 6261.0 6282.7 6171.2 6176.3 6237.6 6292.9 6312.6 4956.9 4962.2 4963.7 4964.6 4997.5 4427.2 4441.8 4485.0 4518.9 4559.0 5680.0 5697.9 5756.2 5771.2 5827.2 6284.9 6318.8 6333.8 6352.8 6362.7 5583.3 5644.3 5687.6 5709.5 5803.7 5447.3 5448.1 5451.0 5454.8 5463.1 6118.9 6329.9 6332.9 6337.0 6345.2 6151.3 6203.6 6238.4 6317.4 6332.9 5627.0 6088.5 6295.4 6331.5 6338.5 6129.8 6200.1 6200.6 6234.1 6244.1 6124.4 6206.9 6247.8 6323.2 6337.0 4702.9 6103.5 6155.3 6169.7 6170.5 6075.2 6112.8 6117.8 6125.7 6129.0 5788.8 5881.4 5899.4 5999.0 6005.8 6152.8 6163.4 6175.5 6176.3 6183.6 5981.3 5985.7 5986.0 5999.6 6000.2 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:74914.15 Median:41973.48 stddev:162829.92 (387.9%) stddev-sans-first:163190.84 04:39:09 INFO - PROCESS | 1923 | Values: 499.7 512.2 538.6 541.3 551.0 30682.7 32181.7 32306.8 32372.4 42411.4 42441.8 45096.1 45147.0 45149.9 45239.3 8915.5 9481.0 9656.0 9858.8 9900.6 8733.1 8905.3 9675.2 9808.0 11791.5 11720.4 12095.0 12101.0 12101.2 12103.6 11966.4 12036.9 12040.5 12050.2 12062.7 8681.7 8870.0 8872.6 9033.8 11971.5 41578.2 41947.2 42080.0 44186.3 44223.3 2982.3 3400.6 3692.8 3704.2 3860.4 31271.4 32024.5 32453.5 33070.0 33181.0 3323.8 3325.1 3336.9 3398.4 3453.8 485025.0 487179.0 487278.0 488104.0 488330.0 63001.1 63672.0 63713.0 63737.4 63809.0 68591.7 68663.3 68694.0 68725.0 68759.7 16968.8 17175.7 17208.1 17217.9 17256.7 11947.2 12154.6 12158.4 12273.0 12904.0 18719.6 18919.9 19239.2 19375.5 19439.3 6622.1 7516.1 7696.8 7731.8 7751.4 865.8 893.6 974.6 1038.3 1038.8 30886.1 31011.8 31105.1 31127.5 32139.0 1083.8 1086.9 1116.1 1130.5 1185.3 15744.8 16100.6 16293.0 16566.6 16645.8 680960.0 683823.0 684989.0 685134.0 685322.0 54309.0 58438.1 61620.0 62027.7 62341.1 1246.4 1388.0 1398.5 1410.5 1431.2 31118.8 32808.0 32850.3 32983.3 34433.5 54231.0 54938.5 55051.7 55061.3 55193.0 613968.8 644433.0 645351.0 645849.0 646338.0 45968.8 46232.6 46233.5 46965.5 48743.0 50582.0 50750.5 51236.0 51496.7 51825.7 42150.6 42379.0 42559.6 43090.1 43492.3 39388.8 41999.8 42399.3 43354.3 43369.6 40542.0 40932.0 40949.6 41322.8 41798.6 29588.9 35771.5 35844.0 36115.0 36755.1 26640.6 26731.3 26763.1 26915.1 27022.9 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:35110.96 Median:12693.34 stddev:97378.80 (767.2%) stddev-sans-first:97617.02 04:39:09 INFO - PROCESS | 1923 | Values: 359.0 384.6 393.4 395.5 415.4 15701.4 16973.7 20244.3 21077.9 21084.7 20719.5 21000.9 21053.6 21062.3 21079.3 5737.5 5922.1 6026.6 6139.0 6187.8 5942.4 5965.3 6046.2 6048.8 6051.5 5852.9 5998.6 6009.3 6213.6 6214.6 5266.5 6122.9 6152.4 6186.9 6201.7 4823.4 6063.3 6066.1 6068.8 6069.8 13149.6 13445.8 19008.7 26050.9 26090.7 2161.0 2229.3 2314.2 2332.9 2357.5 9356.7 9914.9 10029.4 10205.0 10468.8 1852.4 1885.7 1906.9 1920.1 1925.9 110750.4 125100.0 128318.0 129531.0 131580.0 11258.8 11328.9 11373.9 11385.8 11477.4 14377.8 14526.6 14537.4 14556.8 14581.1 8469.0 8487.4 8507.5 8545.8 8641.3 8504.7 8508.8 8617.3 8630.0 8732.3 4423.2 4491.0 4512.8 4539.7 4575.0 1921.9 1924.6 1926.9 1929.8 1941.9 720.3 934.3 934.8 939.0 965.7 21230.6 21741.8 21765.3 21934.9 23072.4 934.4 954.8 994.4 1032.6 1080.7 10993.2 11503.4 11536.0 11559.3 11581.8 555744.0 562179.0 563279.0 590696.0 618649.9 133386.0 139390.0 139991.3 141751.0 144544.3 983.0 984.2 1003.5 1004.9 1006.2 19263.3 19373.9 19402.5 19442.5 19550.9 38861.8 57328.7 57986.1 58583.4 59297.8 80977.0 83522.0 84183.6 84478.3 85058.1 8000.1 8053.3 8177.8 8348.6 8610.0 12483.9 13169.6 13210.3 13274.0 13332.3 11171.9 11244.8 11332.0 11525.3 11533.2 10018.0 11100.4 11192.7 11509.7 11586.4 11737.3 11743.1 11853.8 11871.3 11925.5 7027.5 7396.5 7438.1 8251.9 8496.2 8081.8 9791.7 11245.6 11678.4 11802.7 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:5712.12 Median:496.77 stddev:3225.88 (649.4%) stddev-sans-first:3219.73 04:39:09 INFO - PROCESS | 1923 | Values: 1545.9 1592.1 1797.8 1933.8 1937.2 8379.0 8692.5 8862.6 8879.6 8884.4 7809.6 8063.0 8314.2 8527.0 8662.6 2355.7 2383.7 2387.5 2388.3 2388.8 2331.6 2340.6 2368.0 2374.2 2375.4 1770.9 2082.8 2164.0 2169.8 2171.2 1857.6 2029.3 2351.2 2354.8 2362.5 1946.9 2145.6 2374.2 2380.1 2392.7 7355.7 8688.0 8866.7 8882.8 8885.4 5205.6 5369.5 5371.4 5373.9 5384.4 3540.8 3583.1 3659.0 3665.7 3683.8 1073.5 1082.5 1097.3 1103.4 1112.2 11607.6 11630.9 11649.8 11649.9 11682.2 4468.2 4686.3 4773.0 4787.1 4793.6 9854.6 9883.6 9883.6 9905.6 9909.0 5351.8 5355.8 5356.3 5357.9 5365.3 4725.6 4892.8 4901.0 4904.6 4930.2 3714.5 3729.6 3759.6 3762.8 3821.8 1141.2 1142.5 1142.6 1146.8 1148.5 605.5 812.7 837.8 954.6 984.5 5961.2 6028.0 6030.1 6149.0 6156.1 484.1 496.5 497.0 502.4 651.4 4043.6 4208.3 4242.9 4309.6 4317.8 10651.1 10729.4 10753.4 10876.5 10885.7 10310.2 10758.9 10808.8 10815.2 10837.2 8073.2 8133.3 8309.7 8312.6 8318.6 5978.5 6094.7 6194.4 6262.4 6275.1 9292.2 9787.3 10094.3 10117.2 10242.7 10125.9 10201.9 10392.9 10403.9 10413.4 7486.6 7521.8 7637.3 7643.7 7644.2 8175.4 8231.2 8314.0 8325.1 8337.6 6959.0 7098.0 7337.9 7498.5 7647.2 6989.6 7480.2 7533.2 7605.9 7636.5 7059.2 7062.3 7130.9 7138.6 7143.2 4904.7 5046.7 5060.5 5069.3 5205.6 5045.4 5053.1 5105.0 5120.0 5280.5 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4632.88 Median:5385.76 stddev:1455.93 (27.0%) stddev-sans-first:1422.92 04:39:09 INFO - PROCESS | 1923 | Values: 282.5 290.3 314.1 320.7 322.8 5573.9 5615.6 5618.9 5641.9 5650.4 4497.7 5410.6 5557.6 5560.1 5560.9 3276.0 3980.8 4141.9 4217.1 4236.5 3532.4 3740.5 3796.5 4186.2 4187.0 3183.8 3382.0 4134.8 4160.0 4166.6 3938.5 4043.8 4053.1 4156.9 4180.3 3575.8 3592.2 3594.7 3599.5 3615.6 5414.6 5416.9 5421.2 5439.6 5449.0 3664.9 4156.8 4480.2 4767.9 4795.6 5304.5 5629.8 5706.4 5712.0 5783.5 3928.5 3936.1 3948.2 3950.6 3956.6 5278.8 5799.9 5998.3 6022.0 6147.7 3361.9 3887.9 3913.4 3926.7 3991.4 3352.7 3836.3 4050.9 4051.3 4055.5 4129.9 4450.3 4548.6 4693.9 4732.6 4998.3 5608.7 5766.0 5775.2 5777.9 1342.5 1371.0 1371.2 1373.4 1375.0 688.1 758.0 788.0 849.3 850.5 4353.7 4576.6 4797.9 5445.3 5457.7 5176.6 5934.5 5939.7 5964.8 5971.4 4684.8 4982.7 5416.3 5456.3 5482.7 2848.0 2947.5 3071.2 3071.3 3116.9 5079.9 5607.4 5947.1 6151.9 6212.6 4880.9 5998.0 6071.8 6122.7 6127.9 5303.4 5368.0 6041.5 6137.5 6148.8 5303.5 5536.7 5897.5 5982.5 5998.2 5797.8 5831.7 5836.6 5846.5 5866.6 5101.6 5423.4 5870.2 6144.1 6178.4 3598.6 3732.2 3915.1 3923.8 3933.9 5403.5 5532.6 5874.4 5883.8 5893.2 4623.9 5241.0 5752.5 5754.8 5761.3 5485.9 5718.7 5734.9 5735.5 5754.7 5184.8 5270.9 5323.8 5609.6 5631.8 5563.3 5644.4 5729.8 5744.3 5784.6 5526.8 5588.6 5608.8 5628.7 5636.5 04:39:09 INFO - PROCESS | 1923 | 04:39:09 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:26041.42 Median:22195.50 stddev:42297.26 (190.6%) stddev-sans-first:42373.28 04:39:09 INFO - PROCESS | 1923 | Values: 746.8 760.6 813.5 827.0 854.9 15500.9 16107.7 16209.5 16210.1 16241.5 14791.8 15168.1 15181.4 15194.1 15205.4 711.7 738.3 881.2 890.0 903.9 886.3 912.1 912.2 918.2 924.0 799.9 963.7 964.6 965.2 965.9 744.1 922.3 954.3 955.3 958.8 705.4 706.5 709.0 709.2 716.2 15046.0 15073.9 15082.3 15095.1 15105.2 11181.6 14034.3 14124.5 14230.8 14452.5 12930.9 12984.7 13044.2 13052.6 13087.0 3933.6 4566.8 4745.8 4764.0 4817.0 88333.0 112653.0 112929.0 113023.0 113133.0 11022.6 11031.3 11239.8 11272.4 11278.7 21863.8 22057.8 22089.8 22090.6 22300.4 7828.9 7889.7 7896.8 8021.9 8041.7 3357.8 4325.5 4388.8 4406.1 4583.2 3441.2 3568.4 3941.0 4138.4 4138.7 725.5 736.0 746.4 750.5 751.4 527.0 541.8 672.0 686.6 696.4 7577.0 7894.6 8134.3 9501.0 9620.5 629.1 650.3 664.8 694.9 699.1 4624.7 4824.4 4837.6 4870.2 4879.3 142926.0 166644.2 170793.0 172505.0 179919.0 91993.5 99023.0 148597.0 150398.0 151351.0 99443.5 101213.0 117292.0 127148.0 133184.0 9857.2 9906.5 10044.0 10131.9 10152.6 64191.0 64435.5 64829.0 66487.0 67156.3 98728.5 101657.0 102468.5 102540.0 106840.0 13325.3 13408.8 13527.5 13767.7 14016.0 15026.2 15131.6 15243.9 15304.4 15540.9 14063.5 14098.3 14207.1 14314.0 14706.6 14100.8 14203.6 14374.6 14583.8 14987.5 13816.6 14099.4 14240.7 14327.1 14556.8 9371.5 11290.9 11491.6 11588.9 11975.5 10119.3 10817.7 11019.6 11445.5 11700.5 04:39:09 INFO - PROCESS | 1923 | -------- Summary: end -------- 04:39:09 INFO - PROCESS | 1923 | 04:39:10 INFO - TEST-INFO | 1923: exit 0 04:39:10 INFO - TEST-OK | dromaeo_css | took 1115841ms 04:39:10 INFO - TEST-START | kraken 04:39:10 INFO - operating with platform_type : mac_ 04:39:10 INFO - Initialising browser for kraken test... 04:39:10 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmprBuREw/profile http://localhost:55985/getInfo.html) 04:39:12 INFO - PROCESS | 1927 | JavaScript error: , line 0: uncaught exception: undefined 04:39:12 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 04:39:12 INFO - PROCESS | 1927 | colorDepth:24 04:39:12 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 04:39:12 INFO - PROCESS | 1927 | __metrics 04:39:12 INFO - PROCESS | 1927 | 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" 04:39:12 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:39:13 INFO - TEST-INFO | 1927: exit 0 04:39:13 INFO - Browser initialized. 04:39:13 INFO - Running cycle 1/1 for kraken test... 04:39:13 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmprBuREw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:39:24 INFO - PROCESS | 1928 | RSS: Main: 245129216 04:39:24 INFO - PROCESS | 1928 | 04:39:27 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-beat-detection) 04:39:28 INFO - PROCESS | 1928 | RSS: Main: 294965248 04:39:28 INFO - PROCESS | 1928 | 04:39:31 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-dft) 04:39:31 INFO - PROCESS | 1928 | RSS: Main: 305389568 04:39:31 INFO - PROCESS | 1928 | 04:39:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-fft) 04:39:35 INFO - PROCESS | 1928 | RSS: Main: 389599232 04:39:35 INFO - PROCESS | 1928 | 04:39:37 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-oscillator) 04:39:38 INFO - PROCESS | 1928 | RSS: Main: 292663296 04:39:38 INFO - PROCESS | 1928 | 04:39:40 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:39:40 INFO - PROCESS | 1928 | RSS: Main: 296861696 04:39:40 INFO - PROCESS | 1928 | 04:39:43 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-darkroom) 04:39:44 INFO - PROCESS | 1928 | RSS: Main: 333410304 04:39:44 INFO - PROCESS | 1928 | 04:39:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-desaturate) 04:39:47 INFO - PROCESS | 1928 | RSS: Main: 336781312 04:39:47 INFO - PROCESS | 1928 | 04:39:50 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55985/tests/kraken/driver.html?testName=json-parse-financial) 04:39:51 INFO - PROCESS | 1928 | RSS: Main: 334016512 04:39:51 INFO - PROCESS | 1928 | 04:39:52 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:39:53 INFO - PROCESS | 1928 | RSS: Main: 312176640 04:39:53 INFO - PROCESS | 1928 | 04:39:55 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:39:56 INFO - PROCESS | 1928 | RSS: Main: 318881792 04:39:56 INFO - PROCESS | 1928 | 04:39:58 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:39:59 INFO - PROCESS | 1928 | RSS: Main: 319639552 04:39:59 INFO - PROCESS | 1928 | 04:40:02 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:40:02 INFO - PROCESS | 1928 | RSS: Main: 326799360 04:40:02 INFO - PROCESS | 1928 | 04:40:05 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:40:06 INFO - PROCESS | 1928 | RSS: Main: 329560064 04:40:06 INFO - PROCESS | 1928 | 04:40:07 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55985/tests/kraken/driver.html?testName=ai-astar) 04:40:08 INFO - PROCESS | 1928 | RSS: Main: 372817920 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | __start_tp_report 04:40:08 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 04:40:08 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 04:40:08 INFO - PROCESS | 1928 | |i|pagename|runs| 04:40:08 INFO - PROCESS | 1928 | |0;ai-astar;103;115;112;115;116;115;114;113;112;113 04:40:08 INFO - PROCESS | 1928 | |1;audio-beat-detection;142;108;105;160;106;106;130;105;139;106 04:40:08 INFO - PROCESS | 1928 | |2;audio-dft;183;184;182;184;183;184;184;181;182;182 04:40:08 INFO - PROCESS | 1928 | |3;audio-fft;79;57;57;108;57;56;63;63;84;57 04:40:08 INFO - PROCESS | 1928 | |4;audio-oscillator;82;69;90;72;69;67;96;69;68;101 04:40:08 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;92;129;89;86;93;86;89;87;88;85 04:40:08 INFO - PROCESS | 1928 | |6;imaging-darkroom;95;94;94;95;95;94;99;95;95;94 04:40:08 INFO - PROCESS | 1928 | |7;imaging-desaturate;125;142;97;87;87;87;87;88;92;85 04:40:08 INFO - PROCESS | 1928 | |8;json-parse-financial;54;54;54;52;54;54;54;55;53;52 04:40:08 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;67;68;75;78;76;73;72;71;71;97 04:40:08 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;84;84;83;84;82;84;81;86;79;91 04:40:08 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;136;124;125;140;144;124;141;136;228;152 04:40:08 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;166;171;171;172;169;171;171;170;171;169 04:40:08 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;67;67;68;69;69;67;75;84;84;85 04:40:08 INFO - PROCESS | 1928 | __end_tp_report 04:40:08 INFO - PROCESS | 1928 | __start_cc_report 04:40:08 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,2151 04:40:08 INFO - PROCESS | 1928 | __end_cc_report 04:40:08 INFO - PROCESS | 1928 | __startTimestamp1468755608235__endTimestamp 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | ------- Summary: start ------- 04:40:08 INFO - PROCESS | 1928 | Number of tests: 14 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.50 stddev:3.71 (3.2%) stddev-sans-first:1.45 04:40:08 INFO - PROCESS | 1928 | Values: 103.0 115.0 112.0 115.0 116.0 115.0 114.0 113.0 112.0 113.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:119.00 stddev:20.34 (17.1%) stddev-sans-first:20.06 04:40:08 INFO - PROCESS | 1928 | Values: 142.0 108.0 105.0 160.0 106.0 106.0 130.0 105.0 139.0 106.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.10 (0.6%) stddev-sans-first:1.17 04:40:08 INFO - PROCESS | 1928 | Values: 183.0 184.0 182.0 184.0 183.0 184.0 184.0 181.0 182.0 182.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:68.10 Median:60.00 stddev:17.18 (28.6%) stddev-sans-first:17.76 04:40:08 INFO - PROCESS | 1928 | Values: 79.0 57.0 57.0 108.0 57.0 56.0 63.0 63.0 84.0 57.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:70.50 stddev:12.96 (18.4%) stddev-sans-first:13.68 04:40:08 INFO - PROCESS | 1928 | Values: 82.0 69.0 90.0 72.0 69.0 67.0 96.0 69.0 68.0 101.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:88.50 stddev:13.12 (14.8%) stddev-sans-first:13.91 04:40:08 INFO - PROCESS | 1928 | Values: 92.0 129.0 89.0 86.0 93.0 86.0 89.0 87.0 88.0 85.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.49 (1.6%) stddev-sans-first:1.58 04:40:08 INFO - PROCESS | 1928 | Values: 95.0 94.0 94.0 95.0 95.0 94.0 99.0 95.0 95.0 94.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:87.00 stddev:19.59 (22.5%) stddev-sans-first:18.12 04:40:08 INFO - PROCESS | 1928 | Values: 125.0 142.0 97.0 87.0 87.0 87.0 87.0 88.0 92.0 85.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 04:40:08 INFO - PROCESS | 1928 | Values: 54.0 54.0 54.0 52.0 54.0 54.0 54.0 55.0 53.0 52.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:74.00 stddev:8.51 (11.5%) stddev-sans-first:8.54 04:40:08 INFO - PROCESS | 1928 | Values: 67.0 68.0 75.0 78.0 76.0 73.0 72.0 71.0 71.0 97.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:3.19 (3.8%) stddev-sans-first:3.38 04:40:08 INFO - PROCESS | 1928 | Values: 84.0 84.0 83.0 84.0 82.0 84.0 81.0 86.0 79.0 91.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:140.50 stddev:30.59 (21.8%) stddev-sans-first:32.28 04:40:08 INFO - PROCESS | 1928 | Values: 136.0 124.0 125.0 140.0 144.0 124.0 141.0 136.0 228.0 152.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:171.00 stddev:1.73 (1.0%) stddev-sans-first:1.01 04:40:08 INFO - PROCESS | 1928 | Values: 166.0 171.0 171.0 172.0 169.0 171.0 171.0 170.0 171.0 169.0 04:40:08 INFO - PROCESS | 1928 | 04:40:08 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:72.00 stddev:7.84 (10.9%) stddev-sans-first:7.95 04:40:08 INFO - PROCESS | 1928 | Values: 67.0 67.0 68.0 69.0 69.0 67.0 75.0 84.0 84.0 85.0 04:40:08 INFO - PROCESS | 1928 | -------- Summary: end -------- 04:40:08 INFO - PROCESS | 1928 | 04:40:09 INFO - TEST-INFO | 1928: exit 0 04:40:09 INFO - TEST-OK | kraken | took 59109ms 04:40:09 INFO - SUITE-END | took 1174s 04:40:09 INFO - Completed test suite (00:19:35) 04:40:09 INFO - javascript benchmark 04:40:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.498025296206, 3354.832258387081, 3366.7811592246517, 3396.0660818274987, 3429.1427143214196, 5538.252335934648, 5628.549716022719, 5650.773969041238, 5741.339745929215, 5750.137479378091, 5356.946430535693, 5423.783048678058, 5631.971840140804, 5675.574331925101, 5771.942280577193, 5124.692518448886, 5127.256547800566, 5179.352580927384, 5189.065968125736, 5300.708461034642, 5331.866703332428, 5334.199870019509, 5357.4374690657505, 5367.570594352462, 5384.515393614574, 5146.845594632167, 5181.4300426953005, 5195.844124676266, 5241.292425522543, 5262.605304602132, 5181.404138524081, 5232.215167724834, 5237.580993520509, 5238.554722848562, 5247.816326428595, 5120.667156634807, 5132.486751324875, 5147.382314122329, 5172.844814655567, 5205.089109405877, 5699.971500142514, 5711.228984087136, 5798.565107616945, 5799.9130013049835, 5817.883642327172, 6090.989973308798, 6101.913673707472, 6260.123582698426, 6265.8120256392385, 6291.591046582003, 6186.226721659793, 6221.751129954841, 6260.0, 6260.499160067229, 6298.118263443122, 5865.149553314788, 5875.559333050039, 5885.529157667419, 5886.058230683113, 5907.793227237026, 5300.204969254565, 6201.441870231701, 6261.373862613702, 6261.843453913598, 6323.5889667172405, 6174.938250617526, 6186.845328866724, 6195.907061394083, 6203.875922481525, 6210.4100110489435, 6308.243010838729, 6327.145835312174, 6330.968345158245, 6337.9683101585115, 6358.713857876407, 6099.237595300588, 6104.20645316106, 6260.812175634738, 6260.968695156586, 6282.748690052438, 6171.197744293213, 6176.289726681399, 6237.625742455467, 6292.874142517216, 6312.589681670768, 4956.925646115311, 4962.230854217602, 4963.677360971526, 4964.60283177345, 4997.5002499749735, 4427.247367947457, 4441.822327106945, 4484.955150448453, 4518.864434066983, 4559.042601053749, 5680.034394153004, 5697.857553561112, 5756.194132821408, 5771.220885180531, 5827.184194212814, 6284.874302514016, 6318.778842740482, 6333.778317758856, 6352.809415717536, 6362.745490180341, 5583.302087239095, 5644.322681278273, 5687.630304030225, 5709.486146246858, 5803.738831752581, 5447.264619276347, 5448.101063324586, 5451.018816613047, 5454.836354909359, 5463.071277882769, 6118.877622447437, 6329.9050514243245, 6332.936670633327, 6337.049442583557, 6345.175127233522, 6151.292601350723, 6203.627782333075, 6238.376162383907, 6317.431431171217, 6332.873342533216, 5626.971865140648, 6088.51291896656, 6295.433410993032, 6331.525135614939, 6338.461230795434, 6129.7969628740475, 6200.06998950143, 6200.56596038273, 6234.064890266496, 6244.094606281973, 6124.411449640054, 6206.906896396648, 6247.781327653511, 6323.241211054703, 6336.968315158395, 4702.858914232578, 6103.542234332354, 6155.322914479314, 6169.722362493609, 6170.475509581556, 6075.1798507200965, 6112.755489780537, 6117.84705382369, 6125.663088530174, 6129.049997250433, 5788.768449261982, 5881.441263080001, 5899.38056504078, 5999.0, 6005.849853753691, 6152.753889844356, 6163.38366163398, 6175.536834737323, 6176.289726681489, 6183.5671502994355, 5981.252343457068, 5985.730642121028, 5986.0, 5999.610025348338, 6000.219971403864], "value": 5708.881847827003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.69768290184436, 512.1600575056907, 538.6148903533972, 541.2963147907719, 550.975186153754, 30682.693173068263, 32181.678183218162, 32306.838465807698, 32372.352552948956, 42411.363829542584, 42441.787791061004, 45096.09807803842, 45147.0, 45149.871253218684, 45239.321410178876, 8915.465072095694, 9481.0, 9656.0, 9858.753531161707, 9900.603975840957, 8733.12668733128, 8905.287576993825, 9675.225981921429, 9807.950960245225, 11791.528338866436, 11720.355380454072, 12094.971927386183, 12101.0, 12101.152919295653, 12103.636890893287, 11966.401679915969, 12036.879631203663, 12040.458179381949, 12050.15648904577, 12062.69843253917, 8681.652733890636, 8870.0, 8872.55637218143, 9033.81932361349, 11971.521139154424, 41578.168436631095, 41947.161056779, 42080.0, 44186.33720494195, 44223.33664995027, 2982.3438843454405, 3400.574928133983, 3692.778433294011, 3704.2035962267973, 3860.3823388258024, 31271.350660247335, 32024.5196322053, 32453.513197302058, 33070.0, 33181.0, 3323.80057196569, 3325.057324268016, 3336.8832090876704, 3398.4052790761516, 3453.8445769940163, 485025.0, 487179.0, 487278.0, 488104.0, 488330.0, 63001.05498417527, 63672.0, 63713.0, 63737.36262637407, 63809.04286435707, 68591.65704171447, 68663.31336686669, 68694.0, 68724.96912546316, 68759.65620171867, 16968.830311696973, 17175.656486870193, 17208.05355705448, 17217.91391043037, 17256.65486690259, 11947.163698541019, 12154.635360939186, 12158.392080395945, 12272.956798672034, 12904.0, 18719.625607488048, 18919.905400472908, 19239.228624170257, 19375.51561210981, 19439.319623813382, 6622.10601568782, 7516.098068231738, 7696.846063078707, 7731.806704832424, 7751.418643601753, 865.8311629232239, 893.6246776353947, 974.6296407365297, 1038.2835843268122, 1038.8026275007724, 30886.073417797503, 31011.84494077515, 31105.066847994596, 31127.533087003714, 32139.0, 1083.7724077943562, 1086.9293495922739, 1116.1126904111252, 1130.5477808876515, 1185.3006726031683, 15744.763828542582, 16100.597485062734, 16293.0, 16566.58583535397, 16645.83354166467, 680960.0, 683823.0, 684989.0, 685134.0, 685322.0, 54309.0, 58438.12342814776, 61620.0, 62027.689861551306, 62341.064884026775, 1246.4141853328922, 1387.9791803122962, 1398.5245016694373, 1410.4569740649717, 1431.2056808470982, 31118.844405777825, 32808.0, 32850.34299313952, 32983.34033319272, 34433.48349774806, 54231.0, 54938.450615494934, 55051.724741376034, 55061.31978781688, 55193.0, 613968.7904681523, 644433.0, 645351.0, 645849.0, 646338.0, 45968.770156149, 46232.61744431584, 46233.53766462292, 46965.53034469612, 48743.0, 50582.0, 50750.492495076054, 51235.975280494924, 51496.74251628868, 51825.740871295406, 42150.57849421589, 42379.0, 42559.57440425556, 43090.138197236505, 43492.347614786435, 39388.803055984536, 41999.79000105102, 42399.304027839775, 43354.349684754125, 43369.566304337815, 40542.0, 40932.0, 40949.590504095766, 41322.79338603408, 41798.58201417947, 29588.852055739582, 35771.46342804808, 35844.0, 36115.0, 36755.08112297214, 26640.60039099454, 26731.33171670646, 26763.06329278466, 26915.06031588111, 27022.864885675444], "value": 21280.32343534903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.0413595699482, 384.63843986652546, 393.43149149478995, 395.5154935204374, 415.4495293735799, 15701.371945122195, 16973.74539381909, 20244.29144979926, 21077.894610526924, 21084.683729744065, 20719.482012949684, 21000.894995525003, 21053.578928421422, 21062.262820801276, 21079.262225822098, 5737.512311453532, 5922.082077278029, 6026.578139530213, 6138.969305153468, 6187.752489900398, 5942.435468630495, 5965.299889531484, 6046.21399218101, 6048.848778780543, 6051.515878729691, 5852.912206316909, 5998.6400815950965, 6009.278886533601, 6213.633000739853, 6214.57064875079, 5266.499682530153, 6122.877542449126, 6152.446279834814, 6186.917289474347, 6201.720922558496, 4823.421189457253, 6063.302720187191, 6066.120412540201, 6068.787592434288, 6069.757209711606, 13149.605511834565, 13445.798313025312, 19008.714869276973, 26050.86974565134, 26090.73909260902, 2160.967585486219, 2229.331200639818, 2314.2478694424212, 2332.9300120996227, 2357.5049239659693, 9356.672516461958, 9914.851277230848, 10029.398236105784, 10205.0, 10468.790624187473, 1852.3516769130697, 1885.6700077486385, 1906.9141888615045, 1920.059171006197, 1925.911859799209, 110750.44624776905, 125100.0, 128318.0, 129531.0, 131580.0, 11258.831117533244, 11328.94335528317, 11373.886261137448, 11385.772284554263, 11477.426128693698, 14377.78433323501, 14526.564203073925, 14537.418503259963, 14556.781648275284, 14581.052231604912, 8469.0, 8487.363447741443, 8507.53208573522, 8545.786355341043, 8641.30869530436, 8504.681774324867, 8508.787715194094, 8617.267532259828, 8630.00754913193, 8732.257758090507, 4423.203823311835, 4491.011977364975, 4512.819487220474, 4539.727616342964, 4574.977125114419, 1921.9039048047541, 1924.6246981838408, 1926.9036548172535, 1929.8166674165932, 1941.8834869907569, 720.2762703502714, 934.2759361494896, 934.8270569944582, 938.990610093904, 965.666844938495, 21230.575388492143, 21741.78258217429, 21765.347039588836, 21934.89032554827, 23072.4231894204, 934.359963425056, 954.7851733359938, 994.4083270454068, 1032.5972870580479, 1080.7244152741, 10993.175511836644, 11503.424828758529, 11536.019438347666, 11559.30644161353, 11581.76836463266, 555744.0, 562179.0, 563279.0, 590696.0, 618649.9067504724, 133386.0, 139390.0, 139991.30004350116, 141751.0, 144544.27727861292, 982.9950850245846, 984.1978787288338, 1003.4581326083979, 1004.8794144702537, 1006.2101250518308, 19263.325783597506, 19373.90313048482, 19402.514937126685, 19442.513937151685, 19550.90224548868, 38861.80569097136, 57328.71335643295, 57986.13020804607, 58583.414165857794, 59297.81404371802, 80977.0, 83522.0, 84183.57908210665, 84478.30281581052, 85058.14941850503, 8000.119986801564, 8053.275205231557, 8177.836443271221, 8348.6243119061, 8610.0, 12483.93758031204, 13169.604911852659, 13210.27343496117, 13274.0, 13332.266725329811, 11171.944140279247, 11244.775104497701, 11331.980121789078, 11525.308481491138, 11533.192676512563, 10018.0, 11100.389478578756, 11192.664220073411, 11509.712257193638, 11586.420678966186, 11737.295762254293, 11743.11926605491, 11853.822192666945, 11871.347075910908, 11925.522979080739, 7027.508074434741, 7396.519226250277, 7438.144613369532, 8251.917480825115, 8496.150384961702, 8081.797955051171, 9791.657291994747, 11245.550177993116, 11678.357690327113, 11802.704932376759], "value": 9649.784461320012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.8917875748696, 1592.0925072708558, 1797.7932537758154, 1933.82595566399, 1937.205745644287, 8379.036410812758, 8692.521911294876, 8862.601182946766, 8879.60041798119, 8884.378093533454, 7809.6485658145375, 8062.959685201566, 8314.168583141698, 8527.019392769833, 8662.566871656423, 2355.6819829323035, 2383.725871524771, 2387.4747555537756, 2388.283514945518, 2388.8327817052814, 2331.591971405006, 2340.5786958347576, 2368.0054377161546, 2374.2402431222017, 2375.441771183779, 1770.876038677293, 2082.760482544504, 2164.0153730052866, 2169.8155656769113, 2171.185805323004, 1857.5727582655998, 2029.2593203480772, 2351.2005917987794, 2354.846934949223, 2362.5274945011065, 1946.902654867265, 2145.592337455894, 2374.1927744566756, 2380.0598763488324, 2392.6889504364494, 7355.668994895243, 8687.956560217222, 8866.73399798007, 8882.822343553158, 8885.42244754089, 5205.55752761012, 5369.5167434931045, 5371.382291036542, 5373.865653358658, 5384.434634363413, 3540.840662170183, 3583.068402215417, 3659.0, 3665.688416484602, 3683.797391143486, 1073.5169173871789, 1082.5453309609982, 1097.3032124600857, 1103.3545375955075, 1112.1547623805966, 11607.593734219261, 11630.88369116315, 11649.767004659943, 11649.883501164964, 11682.182247242696, 4468.151051300242, 4686.27362758773, 4772.997670489167, 4787.138315103245, 4793.640476964241, 9854.556544955494, 9883.604655813688, 9883.604655813688, 9905.603775848886, 9908.950455247677, 5351.759170837244, 5355.839324820184, 5356.250124982505, 5357.946420535744, 5365.3293338332705, 4725.5510726480925, 4892.779824907888, 4901.0, 4904.60763138948, 4930.211166213388, 3714.517112775376, 3729.627037296303, 3759.586445490994, 3762.830672619739, 3821.828017739153, 1141.2068612314363, 1142.4801715219555, 1142.6000899685043, 1146.8165093584987, 1148.534843388429, 605.5216379060581, 812.6586833529932, 837.7601150297472, 954.6467806911487, 984.5028260728286, 5961.165436838846, 6027.969860150672, 6030.125631783455, 6148.9692551537855, 6156.138140660221, 484.0657530965271, 496.5282981167905, 497.0035079665248, 502.4272329544322, 651.3519048546726, 4043.636072753466, 4208.284591619432, 4242.915141697149, 4309.569043095666, 4317.762523061262, 10651.09465695409, 10729.409882456539, 10753.354798711945, 10876.510557024953, 10885.727856803644, 10310.175185985103, 10758.892411075945, 10808.78382432347, 10815.242932994772, 10837.241393102408, 8073.15231900642, 8133.308668763221, 8309.709160179365, 8312.625931832961, 8318.584070796556, 5978.461938425563, 6094.664793436404, 6194.4115309045565, 6262.405071518198, 6275.058741188858, 9292.210162136294, 9787.265955053257, 10094.293399462262, 10117.19062475015, 10242.743931401774, 10125.898741012496, 10201.897981020094, 10392.948035259775, 10403.895961040293, 10413.427261500387, 7486.5508069516, 7521.849563008818, 7637.312641862259, 7643.656035478306, 7644.15914249421, 8175.427720059776, 8231.176882311722, 8314.0, 8325.125861784667, 8337.583120844054, 6959.0, 7097.964510177416, 7337.853242935004, 7498.512596681198, 7647.158812530728, 6989.615571143636, 7480.2145774695055, 7533.2466753324225, 7605.923940760522, 7636.465447418849, 7059.18819335783, 7062.258462861531, 7130.893036604351, 7138.571685698875, 7143.178534468602, 4904.6566740327835, 5046.747662616928, 5060.544550990428, 5069.264956581202, 5205.58355331565, 5045.394552653705, 5053.140966035856, 5105.0, 5119.974400127976, 5280.524752772269], "value": 4505.324746765889, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.5338191983227, 290.32933922638705, 314.11889649533066, 320.74661017795955, 322.79502515902396, 5573.916391254129, 5615.63498372606, 5618.943810561904, 5641.887162256752, 5650.350209725882, 4497.707649002812, 5410.621256512046, 5557.6109672322955, 5560.110382338827, 5560.86097847554, 3275.950860737091, 3980.781057041854, 4141.855035073773, 4217.051163488221, 4236.512801027875, 3532.364174448598, 3740.5137332146783, 3796.4684944107707, 4186.204621121992, 4187.0, 3183.7771356005087, 3381.9830900845463, 4134.751914885116, 4159.9792001040105, 4166.583341665824, 3938.5470670872787, 4043.8382464701376, 4053.1488387438526, 4156.875293741163, 4180.28935081037, 3575.8390872410546, 3592.2276710507367, 3594.748367614268, 3599.4623790227824, 3615.5842078161086, 5414.566834653218, 5416.891662166735, 5421.213923981, 5439.619226654136, 5448.972755136199, 3664.9450258246147, 4156.8129434175535, 4480.238359478896, 4767.880802979918, 4795.640326975491, 5304.496072873071, 5629.8311050668135, 5706.3722990471015, 5712.0, 5783.537317014629, 3928.4696565963513, 3936.114374265796, 3948.1511475032717, 3950.6246906543834, 3956.604339566049, 5278.838655495823, 5799.9130013049835, 5998.25021872266, 6021.976264035125, 6147.661878596676, 3361.8655253789575, 3887.872516970104, 3913.3934240192816, 3926.7447615904875, 3991.381335892941, 3352.7485438591716, 3836.271108489378, 4050.858219962287, 4051.2505186540584, 4055.53361363447, 4129.876103716893, 4450.265706158448, 4548.6133678637025, 4693.882652933704, 4732.574068333797, 4998.300917504507, 5608.747606357723, 5765.971170144123, 5775.249217601769, 5777.94222057774, 1342.5166939901628, 1371.0, 1371.1978492581952, 1373.409433943414, 1374.9550341740348, 688.0985908459934, 757.9886301705479, 787.9645415956296, 849.2526576612665, 850.4557083466586, 4353.695241333076, 4576.558384108995, 4797.912907885081, 5445.3045742357, 5457.717436402521, 5176.611754118381, 5934.465898069195, 5939.673317967553, 5964.836856812966, 5971.402859713994, 4684.789184486706, 4982.729404002029, 5416.295881535375, 5456.263404440349, 5482.698451585201, 2848.0, 2947.469455498031, 3071.2475443516482, 3071.293602471444, 3116.8597413116463, 5079.907819818683, 5607.355154157324, 5947.1376650385455, 6151.938480615226, 6212.565120441616, 4880.8529995451045, 5998.030058755176, 6071.787487438007, 6122.663253521099, 6127.938720612826, 5303.36359636846, 5368.0, 6041.456268935817, 6137.478314343331, 6148.754049837957, 5303.549198318186, 5536.7231638418725, 5897.5281977440845, 5982.491488223375, 5998.19114862057, 5797.84043191355, 5831.679257640871, 5836.620619659709, 5846.532277417881, 5866.6186697864505, 5101.591872650105, 5423.376311724202, 5870.207521984477, 6144.050855589253, 6178.382161783966, 3598.6221446747713, 3732.1602639405914, 3915.0995271088377, 3923.843046278117, 3933.9409908851962, 5403.51438338353, 5532.640378375393, 5874.385898275226, 5883.793087942142, 5893.233879595625, 4623.884402889955, 5241.0, 5752.504348869408, 5754.798582049608, 5761.308642962704, 5485.945140548703, 5718.656880587178, 5734.885302294014, 5735.4551317624755, 5754.683492407958, 5184.766685499086, 5270.920936186037, 5323.840284791462, 5609.579281553982, 5631.802886898939, 5563.304586926634, 5644.379118297066, 5729.799457018984, 5744.281964754406, 5784.587093614788, 5526.784107496344, 5588.580856435702, 5608.803691870765, 5628.690422026828, 5636.520895723909], "value": 4158.556903996756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.7722344684872, 760.6082867323325, 813.5118928642815, 826.987595186072, 854.8758382726714, 15500.922495387522, 16107.677846443064, 16209.513714588566, 16210.108444035573, 16241.512754617366, 14791.778123328158, 15168.089914605103, 15181.392744290215, 15194.088354698699, 15205.391784328642, 711.7046425733314, 738.2728012907282, 881.1761003461768, 889.9644014239423, 903.941243819153, 886.2953951608487, 912.069688917304, 912.2246090822781, 918.1874041473277, 924.0436148586215, 799.8800179972992, 963.6964356227801, 964.6334392930677, 965.2326400511575, 965.9275554333418, 744.0587656614388, 922.2944447497603, 954.2938225712992, 955.3217215776772, 958.8417911044669, 705.3898377903076, 706.4666177036356, 708.9822754431129, 709.2092317066433, 716.201435399531, 15046.0, 15073.924630376887, 15082.321295541728, 15095.094294342374, 15105.169215693133, 11181.608643697513, 14034.298285085806, 14124.505642302573, 14230.786538201935, 14452.494162704255, 12930.935345323307, 12984.740305193938, 13044.217346959118, 13052.629473905197, 13087.0, 3933.606639336072, 4566.783853192696, 4745.810167593292, 4764.0, 4817.0, 88333.0, 112652.99999999919, 112929.0, 113023.0, 113133.0, 11022.614208502664, 11031.338119712844, 11239.775204495865, 11272.436378181223, 11278.661640150809, 21863.78136218649, 22057.77942220557, 22089.779102208773, 22090.558188836134, 22300.442488937908, 7828.921710782934, 7889.684412623431, 7896.802579935433, 8021.879671804928, 8041.678332866737, 3357.815320157366, 4325.4593175853015, 4388.802503887333, 4406.074724307867, 4583.175028494903, 3441.174118211611, 3568.411212149966, 3940.9802950985063, 4138.379243113497, 4138.716997730653, 725.5429079679768, 735.9705611775469, 746.4438992950287, 750.5384188724005, 751.3688501658633, 526.9973650131725, 541.8184908055846, 671.9899201511981, 686.5777546808677, 696.4393663101253, 7576.962115189389, 7894.644740986554, 8134.308583770446, 9501.0, 9620.470874101991, 629.1475051305507, 650.2781912077685, 664.833791552112, 694.8506071194718, 699.1435491522844, 4624.699394539344, 4824.421069471686, 4837.637177211724, 4870.218613180376, 4879.2925025871045, 142926.0, 166644.16677916775, 170793.0, 172505.0, 179919.0, 91993.54003229941, 99023.0, 148597.0, 150398.0, 151351.0, 99443.50278248562, 101212.98787012183, 117292.0, 127148.0, 133184.0, 9857.211423086279, 9906.455144967096, 10044.0, 10131.949340253252, 10152.593896244363, 64191.0, 64435.489515735324, 64829.0, 66487.0, 67156.328436715, 98728.51907221541, 101657.0, 102468.46297305397, 102540.0, 106840.0, 13325.267110309027, 13408.798868016793, 13527.458901643824, 13767.724645507235, 14016.0, 15026.17356045428, 15131.621709457351, 15243.923780381027, 15304.387824486896, 15540.922295388451, 14063.507777227747, 14098.295085245492, 14207.140767044766, 14314.0, 14706.55880323592, 14100.78848817248, 14203.64490887736, 14374.568762937128, 14583.781243281148, 14987.475438359605, 13816.585502434944, 14099.436022559394, 14240.715185696436, 14327.140371577738, 14556.781648275497, 9371.531423428665, 11290.887091128983, 11491.597794077166, 11588.884111159117, 11975.461104250504, 10119.331268366304, 10817.675469735921, 11019.559217631206, 11445.542178312775, 11700.531978721096], "value": 8130.449306822126, "unit": "score"}], "name": "dromaeo_css", "value": 7504.350537738632, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 112.0, 115.0, 116.0, 115.0, 114.0, 113.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 105.0, 160.0, 106.0, 106.0, 130.0, 105.0, 139.0, 106.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 182.0, 184.0, 183.0, 184.0, 184.0, 181.0, 182.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 57.0, 108.0, 57.0, 56.0, 63.0, 63.0, 84.0, 57.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 90.0, 72.0, 69.0, 67.0, 96.0, 69.0, 68.0, 101.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 129.0, 89.0, 86.0, 93.0, 86.0, 89.0, 87.0, 88.0, 85.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 99.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 142.0, 97.0, 87.0, 87.0, 87.0, 87.0, 88.0, 92.0, 85.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 52.0, 54.0, 54.0, 54.0, 55.0, 53.0, 52.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 75.0, 78.0, 76.0, 73.0, 72.0, 71.0, 71.0, 97.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 82.0, 84.0, 81.0, 86.0, 79.0, 91.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 124.0, 125.0, 140.0, 144.0, 124.0, 141.0, 136.0, 228.0, 152.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 171.0, 172.0, 169.0, 171.0, 171.0, 170.0, 171.0, 169.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 69.0, 69.0, 67.0, 75.0, 84.0, 84.0, 85.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1448.6999999999998, "alertThreshold": 2.0}]} 04:40:09 INFO - Return code: 0 04:40:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:40:09 INFO - # TBPL SUCCESS # 04:40:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:09 INFO - 2016-07-17 04:40:09.528 screenresolution[1932:21637] starting screenresolution argv=screenresolution get 04:40:09 INFO - 2016-07-17 04:40:09.531 screenresolution[1932:21637] Display 0: 1600x1200x32@60 04:40:09 INFO - 2016-07-17 04:40:09.537 screenresolution[1933:21640] starting screenresolution argv=screenresolution list 04:40:09 INFO - Available Modes on Display 0 04:40:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:09 INFO - Intel Iris: 04:40:09 INFO - Chipset Model: Intel Iris 04:40:09 INFO - Type: GPU 04:40:09 INFO - Bus: Built-In 04:40:09 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:09 INFO - Vendor: Intel (0x8086) 04:40:09 INFO - Device ID: 0x0a2e 04:40:09 INFO - Revision ID: 0x0009 04:40:09 INFO - Displays: 04:40:09 INFO - Display: 04:40:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:09 INFO - Display Serial Number: 1600x1200 60 04:40:09 INFO - Main Display: Yes 04:40:09 INFO - Mirror: Off 04:40:09 INFO - Online: Yes 04:40:09 INFO - Rotation: Supported 04:40:09 INFO - Return code: 0 04:40:09 INFO - Running post-action listener: _resource_record_post_action 04:40:09 INFO - ##### 04:40:09 INFO - ##### Finished run-tests step (success) 04:40:09 INFO - ##### 04:40:09 INFO - Running post-run listener: _resource_record_post_run 04:40:09 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 1015808; Write bytes: 701977600; Read time: 10; Write time: 2031 04:40:09 INFO - TinderboxPrint: CPU usage
53.3% 04:40:09 INFO - TinderboxPrint: I/O read bytes / time
1,015,808 / 10 04:40:09 INFO - TinderboxPrint: I/O write bytes / time
701,977,600 / 2,031 04:40:09 INFO - TinderboxPrint: CPU idle
2,259.9 (46.7%) 04:40:09 INFO - TinderboxPrint: CPU system
962.9 (19.9%) 04:40:09 INFO - TinderboxPrint: CPU user
1,618.9 (33.4%) 04:40:09 INFO - TinderboxPrint: Swap in / out
407,871,488 / 0 04:40:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394469888; Write bytes: 389206528; Read time: 29258; Write time: 757 04:40:09 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 1015808; Write bytes: 290947584; Read time: 10; Write time: 1230 04:40:10 INFO - Running post-run listener: _upload_blobber_files 04:40:10 INFO - Blob upload gear active. 04:40:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:40:10 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 04:40:10 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'] 04:40:10 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 04:40:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:40:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:40:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:12 INFO - (blobuploader) - WARNING - File uploaded to blobserver but failed uploading to Amazon S3. 04:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:12 INFO - (blobuploader) - INFO - Done attempting. 04:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:13 INFO - (blobuploader) - INFO - Done attempting. 04:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:16 INFO - (blobuploader) - INFO - Done attempting. 04:40:16 INFO - (blobuploader) - INFO - Iteration through files over. 04:40:16 INFO - Return code: 0 04:40:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:40:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:40:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9cee54ec6ad7c0a7964e9113b8fc8345d82e5a651cafb2f43e69af046c9ab60b61fda70fb065a26864f76f2213bbb8efb12d3b201198a6d98ba70a720baab0c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ca2dc58b01464c9673e448b12e12fbdefa8e4be9ea65b47f23f23c4786942205879c96f793c94d551b73f7b95195ddd2f395fe433f69a2c9cfc1f7aacc4649b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/139050c0ddf1480ef98b7b4046fc07da2afc3cc427d3a8b74a62652a24ed811ff14a3c9bfa8bc5d1c7c9de500f8e17b6f651117c50b79bd7342c26ded0f36619"} 04:40:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:40:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:40:16 INFO - Contents: 04:40:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9cee54ec6ad7c0a7964e9113b8fc8345d82e5a651cafb2f43e69af046c9ab60b61fda70fb065a26864f76f2213bbb8efb12d3b201198a6d98ba70a720baab0c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ca2dc58b01464c9673e448b12e12fbdefa8e4be9ea65b47f23f23c4786942205879c96f793c94d551b73f7b95195ddd2f395fe433f69a2c9cfc1f7aacc4649b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/139050c0ddf1480ef98b7b4046fc07da2afc3cc427d3a8b74a62652a24ed811ff14a3c9bfa8bc5d1c7c9de500f8e17b6f651117c50b79bd7342c26ded0f36619"} 04:40:16 INFO - Running post-run listener: copy_logs_to_upload_dir 04:40:16 INFO - Copying logs to upload dir... 04:40:16 INFO - mkdir: /builds/slave/test/build/upload/logs 04:40:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.491214 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-17 04:40:16.118528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:40:16.121783) ========= 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.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/9cee54ec6ad7c0a7964e9113b8fc8345d82e5a651cafb2f43e69af046c9ab60b61fda70fb065a26864f76f2213bbb8efb12d3b201198a6d98ba70a720baab0c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ca2dc58b01464c9673e448b12e12fbdefa8e4be9ea65b47f23f23c4786942205879c96f793c94d551b73f7b95195ddd2f395fe433f69a2c9cfc1f7aacc4649b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/139050c0ddf1480ef98b7b4046fc07da2afc3cc427d3a8b74a62652a24ed811ff14a3c9bfa8bc5d1c7c9de500f8e17b6f651117c50b79bd7342c26ded0f36619"} build_url:https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/U8qYx2jWTQm-V13EnniI9w/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/9cee54ec6ad7c0a7964e9113b8fc8345d82e5a651cafb2f43e69af046c9ab60b61fda70fb065a26864f76f2213bbb8efb12d3b201198a6d98ba70a720baab0c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ca2dc58b01464c9673e448b12e12fbdefa8e4be9ea65b47f23f23c4786942205879c96f793c94d551b73f7b95195ddd2f395fe433f69a2c9cfc1f7aacc4649b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/139050c0ddf1480ef98b7b4046fc07da2afc3cc427d3a8b74a62652a24ed811ff14a3c9bfa8bc5d1c7c9de500f8e17b6f651117c50b79bd7342c26ded0f36619"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:40:16.157409) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:40:16.158064) ========= 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.QNtxXSrPz2/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.PebV2lHRRx/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005138 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:40:16.208209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:40:16.208554) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:40:16.208893) ========= ========= Total master_lag: 0.18 =========