builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0050 starttime: 1456257745.61 results: success (0) buildid: 20160223101806 builduid: 475bdb51340848eda33b0b765bd87f03 revision: 5edc2330c4cf536785b4019e5443f58e4b307c34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:25.613769) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:25.614208) ========= ========= Started set props: basedir (results: 0, elapsed: 2 secs) (at 2016-02-23 12:02:25.614493) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005865 basedir: '/builds/slave/test' ========= master_lag: 2.66 ========= ========= Finished set props: basedir (results: 0, elapsed: 2 secs) (at 2016-02-23 12:02:28.276506) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-02-23 12:02:28.276811) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-02-23 12:02:31.097743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.098188) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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.005453 ========= master_lag: 0.51 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.615344) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.615652) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.616008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.616278) ========= 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.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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-02-23 12:02:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 72.4M=0s 2016-02-23 12:02:31 (72.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180841 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.815729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.816069) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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.019251 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:31.864719) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-23 12:02:31.866108) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5edc2330c4cf536785b4019e5443f58e4b307c34 --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 5edc2330c4cf536785b4019e5443f58e4b307c34 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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-02-23 12:02:31,946 truncating revision to first 12 chars 2016-02-23 12:02:31,946 Setting DEBUG logging. 2016-02-23 12:02:31,946 attempt 1/10 2016-02-23 12:02:31,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5edc2330c4cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-23 12:02:33,059 unpacking tar archive at: fx-team-5edc2330c4cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.362130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-23 12:02:33.247459) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:33.247831) ========= echo 5edc2330c4cf536785b4019e5443f58e4b307c34 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'5edc2330c4cf536785b4019e5443f58e4b307c34'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 5edc2330c4cf536785b4019e5443f58e4b307c34 program finished with exit code 0 elapsedTime=0.005658 script_repo_revision: '5edc2330c4cf536785b4019e5443f58e4b307c34' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:33.299567) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:33.299871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-23 12:02:33.312771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-02-23 12:02:33.313090) ========= /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.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:02:33 INFO - MultiFileLogger online at 20160223 12:02:33 in /builds/slave/test 12:02:33 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 12:02:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:02:33 INFO - {'append_to_log': False, 12:02:33 INFO - 'base_work_dir': '/builds/slave/test', 12:02:33 INFO - 'blob_upload_branch': 'Fx-Team', 12:02:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:02:33 INFO - 'branch': 'Fx-Team', 12:02:33 INFO - 'buildbot_json_path': 'buildprops.json', 12:02:33 INFO - 'config_files': ('talos/mac_config.py',), 12:02:33 INFO - 'default_actions': ('clobber', 12:02:33 INFO - 'read-buildbot-config', 12:02:33 INFO - 'download-and-extract', 12:02:33 INFO - 'populate-webroot', 12:02:33 INFO - 'create-virtualenv', 12:02:33 INFO - 'install', 12:02:33 INFO - 'run-tests'), 12:02:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:02:33 INFO - 'download_minidump_stackwalk': True, 12:02:33 INFO - 'download_symbols': 'ondemand', 12:02:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:02:33 INFO - 'tooltool.py': '/tools/tooltool.py', 12:02:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:02:33 INFO - '/tools/misc-python/virtualenv.py')}, 12:02:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:02:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:02:33 INFO - 'installer_path': 'installer.exe', 12:02:33 INFO - 'log_level': 'info', 12:02:33 INFO - 'log_name': 'talos', 12:02:33 INFO - 'log_to_console': True, 12:02:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:02:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:02:33 INFO - 'opt_config_files': (), 12:02:33 INFO - 'pip_index': False, 12:02:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:02:33 INFO - 'cmd': ('bash', 12:02:33 INFO - '-c', 12:02:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:02:33 INFO - 'enabled': True, 12:02:33 INFO - 'halt_on_failure': False, 12:02:33 INFO - 'name': 'check_screen_resolution'},), 12:02:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:02:33 INFO - 'cmd': ('bash', 12:02:33 INFO - '-c', 12:02:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:02:33 INFO - 'enabled': True, 12:02:33 INFO - 'halt_on_failure': False, 12:02:33 INFO - 'name': 'check_screen_resolution'},), 12:02:33 INFO - 'run_cmd_checks_enabled': True, 12:02:33 INFO - 'sps_profile': False, 12:02:33 INFO - 'sps_profile_interval': 0, 12:02:33 INFO - 'suite': 'dromaeojs', 12:02:33 INFO - 'system_bits': '32', 12:02:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:02:33 INFO - 'title': 't-yosemite-r7-0050', 12:02:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:02:33 INFO - 'use_talos_json': True, 12:02:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:02:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:02:33 INFO - 'work_dir': 'build'} 12:02:33 INFO - ##### 12:02:33 INFO - ##### Running clobber step. 12:02:33 INFO - ##### 12:02:33 INFO - Running pre-action listener: _resource_record_pre_action 12:02:33 INFO - Running main action method: clobber 12:02:33 INFO - rmtree: /builds/slave/test/build 12:02:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:02:35 INFO - Running post-action listener: _resource_record_post_action 12:02:35 INFO - ##### 12:02:35 INFO - ##### Running read-buildbot-config step. 12:02:35 INFO - ##### 12:02:35 INFO - Running pre-action listener: _resource_record_pre_action 12:02:35 INFO - Running main action method: read_buildbot_config 12:02:35 INFO - Using buildbot properties: 12:02:35 INFO - { 12:02:35 INFO - "project": "", 12:02:35 INFO - "product": "firefox", 12:02:35 INFO - "script_repo_revision": "production", 12:02:35 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:02:35 INFO - "repository": "", 12:02:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:02:35 INFO - "buildid": "20160223101806", 12:02:35 INFO - "pgo_build": "False", 12:02:35 INFO - "basedir": "/builds/slave/test", 12:02:35 INFO - "buildnumber": 416, 12:02:35 INFO - "slavename": "t-yosemite-r7-0050", 12:02:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:02:35 INFO - "platform": "yosemite_r7", 12:02:35 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:02:35 INFO - "branch": "fx-team", 12:02:35 INFO - "repo_path": "integration/fx-team", 12:02:35 INFO - "revision": "5edc2330c4cf536785b4019e5443f58e4b307c34", 12:02:35 INFO - "stage_platform": "macosx64", 12:02:35 INFO - "builduid": "475bdb51340848eda33b0b765bd87f03", 12:02:35 INFO - "slavebuilddir": "test" 12:02:35 INFO - } 12:02:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 12:02:35 INFO - Running post-action listener: _resource_record_post_action 12:02:35 INFO - ##### 12:02:35 INFO - ##### Running download-and-extract step. 12:02:35 INFO - ##### 12:02:35 INFO - Running pre-action listener: _resource_record_pre_action 12:02:35 INFO - Running main action method: download_and_extract 12:02:35 INFO - mkdir: /builds/slave/test/build/tests 12:02:35 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')]} 12:02:35 INFO - https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:02:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/test_packages.json 12:02:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/test_packages.json 12:02:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:02:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:02:37 INFO - Downloaded 1277 bytes. 12:02:37 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:02:37 INFO - Using the following test package requirements: 12:02:37 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 12:02:37 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 12:02:37 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 12:02:37 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'jsshell-mac.zip'], 12:02:37 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 12:02:37 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 12:02:37 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 12:02:37 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 12:02:37 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 12:02:37 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 12:02:37 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 12:02:37 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 12:02:37 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:37 INFO - https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:02:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:02:39 INFO - Downloaded 17825656 bytes. 12:02:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:02:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:02:42 INFO - Return code: 0 12:02:42 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:42 INFO - https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:02:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 12:02:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:02:43 INFO - Downloaded 17825656 bytes. 12:02:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:02:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:02:47 INFO - Return code: 0 12:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:47 INFO - https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:02:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 12:02:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 12:02:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 12:02:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:02:48 INFO - Downloaded 10707015 bytes. 12:02:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:02:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:02:48 INFO - Return code: 0 12:02: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')]} 12:02:48 INFO - https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:02:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 12:02:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 12:02:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 12:02:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 12:02:51 INFO - Downloaded 111445866 bytes. 12:02:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 12:02:51 INFO - mkdir: /builds/slave/test/properties 12:02:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:02:51 INFO - Writing to file /builds/slave/test/properties/build_url 12:02:51 INFO - Contents: 12:02:51 INFO - build_url:https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 12:02:52 INFO - Running post-action listener: _resource_record_post_action 12:02:52 INFO - Running post-action listener: set_extra_try_arguments 12:02:52 INFO - ##### 12:02:52 INFO - ##### Running populate-webroot step. 12:02:52 INFO - ##### 12:02:52 INFO - Running pre-action listener: _resource_record_pre_action 12:02:52 INFO - Running main action method: populate_webroot 12:02:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:02:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:02:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:02:52 INFO - '--tppagecycles', 12:02:52 INFO - '7'], 12:02:52 INFO - 'tests': ['tsvgm']}}, 12:02:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:02:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:02:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 12:02:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:02:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'tests': ['damp', 'tps']}, 12:02:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['damp', 'tps']}, 12:02:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:02:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:02:52 INFO - 'other': {'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'other_l64': {'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'other_nol64': {'tests': ['a11yr', 12:02:52 INFO - 'ts_paint', 12:02:52 INFO - 'tpaint', 12:02:52 INFO - 'sessionrestore', 12:02:52 INFO - 'sessionrestore_no_auto_restore']}, 12:02:52 INFO - 'svgr': {'tests': ['tsvgx', 12:02:52 INFO - 'tsvgr_opacity', 12:02:52 INFO - 'tart', 12:02:52 INFO - 'tscrollx', 12:02:52 INFO - 'cart']}, 12:02:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['tsvgx', 12:02:52 INFO - 'tsvgr_opacity', 12:02:52 INFO - 'tart', 12:02:52 INFO - 'tscrollx', 12:02:52 INFO - 'cart']}, 12:02:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'tests': ['tp5o']}, 12:02:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'talos_options': ['--e10s'], 12:02:52 INFO - 'tests': ['tp5o']}, 12:02:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'talos_options': ['--xperf_path', 12:02:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:52 INFO - 'tests': ['tp5n']}, 12:02:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:52 INFO - 'talos_options': ['--e10s', 12:02:52 INFO - '--xperf_path', 12:02:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:52 INFO - 'tests': ['tp5n']}}, 12:02:52 INFO - 'talos.zip': {'path': '', 12:02:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:02:52 INFO - Running post-action listener: _resource_record_post_action 12:02:52 INFO - ##### 12:02:52 INFO - ##### Running create-virtualenv step. 12:02:52 INFO - ##### 12:02:52 INFO - Running pre-action listener: _resource_record_pre_action 12:02:52 INFO - Running main action method: create_virtualenv 12:02:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:02:52 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 12:02:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:02:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:02:52 INFO - Using real prefix '/tools/python27' 12:02:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:02:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:02:55 INFO - Installing pip.................done. 12:02:55 INFO - Return code: 0 12:02:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:02:55 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')]} 12:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:55 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')]} 12:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:55 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 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:02:55 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 12:02:55 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 12:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:55 INFO - 'HOME': '/Users/cltbld', 12:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:55 INFO - 'LOGNAME': 'cltbld', 12:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:55 INFO - 'MOZ_NO_REMOTE': '1', 12:02:55 INFO - 'NO_EM_RESTART': '1', 12:02:55 INFO - 'PAGER': '/bin/cat', 12:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:55 INFO - 'PWD': '/builds/slave/test', 12:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:55 INFO - 'SHELL': '/bin/bash', 12:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:02:55 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:55 INFO - 'USER': 'cltbld', 12:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:55 INFO - 'XPC_FLAGS': '0x0', 12:02:55 INFO - 'XPC_SERVICE_NAME': '0', 12:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:56 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 12:02:56 INFO - Cleaning up... 12:02:56 INFO - Return code: 0 12:02:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:56 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:02:56 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>=0.7.1'] in /builds/slave/test/build 12:02:56 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>=0.7.1 12:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:56 INFO - 'HOME': '/Users/cltbld', 12:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:56 INFO - 'LOGNAME': 'cltbld', 12:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:56 INFO - 'MOZ_NO_REMOTE': '1', 12:02:56 INFO - 'NO_EM_RESTART': '1', 12:02:56 INFO - 'PAGER': '/bin/cat', 12:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:56 INFO - 'PWD': '/builds/slave/test', 12:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:56 INFO - 'SHELL': '/bin/bash', 12:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:02:56 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:56 INFO - 'USER': 'cltbld', 12:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:56 INFO - 'XPC_FLAGS': '0x0', 12:02:56 INFO - 'XPC_SERVICE_NAME': '0', 12:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:56 INFO - Downloading/unpacking psutil>=0.7.1 12:02:56 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 12:02:56 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 12:02:56 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 12:02:56 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 12:02:56 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 12:02:56 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 12:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:02:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:59 INFO - Installing collected packages: psutil 12:02:59 INFO - Running setup.py install for psutil 12:02:59 INFO - building 'psutil._psutil_osx' extension 12:02:59 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 12:02:59 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 12:02:59 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 12:02:59 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 12:02:59 INFO - building 'psutil._psutil_posix' extension 12:02:59 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 12:02:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:02:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:02:59 INFO - ^ 12:03:00 INFO - 1 warning generated. 12:03:00 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 12:03:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:00 INFO - Successfully installed psutil 12:03:00 INFO - Cleaning up... 12:03:00 INFO - Return code: 0 12:03:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:03:00 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')]} 12:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:00 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')]} 12:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:00 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:00 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.0.0'] in /builds/slave/test/build 12:03:00 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.0.0 12:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:00 INFO - 'HOME': '/Users/cltbld', 12:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:00 INFO - 'LOGNAME': 'cltbld', 12:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:00 INFO - 'MOZ_NO_REMOTE': '1', 12:03:00 INFO - 'NO_EM_RESTART': '1', 12:03:00 INFO - 'PAGER': '/bin/cat', 12:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:00 INFO - 'PWD': '/builds/slave/test', 12:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:00 INFO - 'SHELL': '/bin/bash', 12:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:00 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:00 INFO - 'USER': 'cltbld', 12:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:00 INFO - 'XPC_FLAGS': '0x0', 12:03:00 INFO - 'XPC_SERVICE_NAME': '0', 12:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:03:00 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 12:03:00 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 12:03:00 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 12:03:00 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 12:03:00 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 12:03:00 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 12:03:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:03:02 INFO - Installing collected packages: mozsystemmonitor 12:03:02 INFO - Running setup.py install for mozsystemmonitor 12:03:03 INFO - Successfully installed mozsystemmonitor 12:03:03 INFO - Cleaning up... 12:03:03 INFO - Return code: 0 12:03:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:03:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:03 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 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:03 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 12:03:03 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 12:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:03 INFO - 'HOME': '/Users/cltbld', 12:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:03 INFO - 'LOGNAME': 'cltbld', 12:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:03 INFO - 'MOZ_NO_REMOTE': '1', 12:03:03 INFO - 'NO_EM_RESTART': '1', 12:03:03 INFO - 'PAGER': '/bin/cat', 12:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:03 INFO - 'PWD': '/builds/slave/test', 12:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:03 INFO - 'SHELL': '/bin/bash', 12:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:03 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:03 INFO - 'USER': 'cltbld', 12:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:03 INFO - 'XPC_FLAGS': '0x0', 12:03:03 INFO - 'XPC_SERVICE_NAME': '0', 12:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:03 INFO - Downloading/unpacking blobuploader==1.2.4 12:03:03 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 12:03:03 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 12:03:03 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 12:03:03 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 12:03:03 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 12:03:03 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 12:03:05 INFO - Downloading blobuploader-1.2.4.tar.gz 12:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:03:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:03:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:03:06 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 12:03:06 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 12:03:06 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 12:03:06 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 12:03:06 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 12:03:06 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 12:03:06 INFO - Downloading docopt-0.6.1.tar.gz 12:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:03:06 INFO - Installing collected packages: blobuploader, requests, docopt 12:03:06 INFO - Running setup.py install for blobuploader 12:03:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:03:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:03:07 INFO - Running setup.py install for requests 12:03:07 INFO - Running setup.py install for docopt 12:03:07 INFO - Successfully installed blobuploader requests docopt 12:03:07 INFO - Cleaning up... 12:03:07 INFO - Return code: 0 12:03:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:07 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')]} 12:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03: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')]} 12:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:08 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 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:08 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 12:03:08 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 12:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:08 INFO - 'HOME': '/Users/cltbld', 12:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:08 INFO - 'LOGNAME': 'cltbld', 12:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:08 INFO - 'MOZ_NO_REMOTE': '1', 12:03:08 INFO - 'NO_EM_RESTART': '1', 12:03:08 INFO - 'PAGER': '/bin/cat', 12:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:08 INFO - 'PWD': '/builds/slave/test', 12:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:08 INFO - 'SHELL': '/bin/bash', 12:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:08 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:08 INFO - 'USER': 'cltbld', 12:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:08 INFO - 'XPC_FLAGS': '0x0', 12:03:08 INFO - 'XPC_SERVICE_NAME': '0', 12:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:08 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-kOfvCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:08 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-dFiRiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:08 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-xVc0tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:08 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-LPTLJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:08 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-3biuSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-UHD2uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-xVX_9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-A5Vu8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-AmVRnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-HI5tiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-QMidNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-2c9kML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-Fsq0Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:09 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-T2FSU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:10 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-yxoHAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:10 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-WQVRt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:10 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-v93w4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:03:10 INFO - Running setup.py install for manifestparser 12:03:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:03:10 INFO - Running setup.py install for mozcrash 12:03:10 INFO - Running setup.py install for mozdebug 12:03:10 INFO - Running setup.py install for mozdevice 12:03:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:03:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:03:10 INFO - Running setup.py install for mozfile 12:03:11 INFO - Running setup.py install for mozhttpd 12:03:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Running setup.py install for mozinfo 12:03:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Running setup.py install for mozInstall 12:03:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Running setup.py install for mozleak 12:03:11 INFO - Running setup.py install for mozlog 12:03:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:03:11 INFO - Running setup.py install for moznetwork 12:03:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Running setup.py install for mozprocess 12:03:12 INFO - Running setup.py install for mozprofile 12:03:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Running setup.py install for mozrunner 12:03:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Running setup.py install for mozscreenshot 12:03:12 INFO - Running setup.py install for moztest 12:03:12 INFO - Running setup.py install for mozversion 12:03:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:03:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:03:12 INFO - Cleaning up... 12:03:12 INFO - Return code: 0 12:03:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03: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')]} 12:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:12 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 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:12 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 12:03:12 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 12:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:12 INFO - 'HOME': '/Users/cltbld', 12:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:12 INFO - 'LOGNAME': 'cltbld', 12:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:12 INFO - 'MOZ_NO_REMOTE': '1', 12:03:12 INFO - 'NO_EM_RESTART': '1', 12:03:12 INFO - 'PAGER': '/bin/cat', 12:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:12 INFO - 'PWD': '/builds/slave/test', 12:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:12 INFO - 'SHELL': '/bin/bash', 12:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:12 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:12 INFO - 'USER': 'cltbld', 12:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:12 INFO - 'XPC_FLAGS': '0x0', 12:03:12 INFO - 'XPC_SERVICE_NAME': '0', 12:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-ESGHzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-_NJKjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-fZ7fS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-nG9eO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-cDkSYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-SiCteA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-tK2OMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:13 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)) 12:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:13 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-REpv8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-4dc5z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-wcLo0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-iMhT7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-WIVt7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-xTdd20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-2CUUPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-nzcebs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-Z7mEpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:14 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)) 12:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:14 INFO - Running setup.py (path:/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/pip-Ixw1tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:15 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)) 12:03:15 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:15 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:15 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)) 12:03:15 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)) 12:03:15 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)) 12:03:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:17 INFO - Downloading blessings-1.6.tar.gz 12:03:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:03:17 INFO - Installing collected packages: blessings 12:03:17 INFO - Running setup.py install for blessings 12:03:17 INFO - Successfully installed blessings 12:03:17 INFO - Cleaning up... 12:03:17 INFO - Return code: 0 12:03:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:03:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:18 INFO - Reading from file tmpfile_stdout 12:03:18 INFO - Current package versions: 12:03:18 INFO - blessings == 1.6 12:03:18 INFO - blobuploader == 1.2.4 12:03:18 INFO - docopt == 0.6.1 12:03:18 INFO - manifestparser == 1.1 12:03:18 INFO - mozInstall == 1.12 12:03:18 INFO - mozcrash == 0.16 12:03:18 INFO - mozdebug == 0.1 12:03:18 INFO - mozdevice == 0.48 12:03:18 INFO - mozfile == 1.2 12:03:18 INFO - mozhttpd == 0.7 12:03:18 INFO - mozinfo == 0.9 12:03:18 INFO - mozleak == 0.1 12:03:18 INFO - mozlog == 3.1 12:03:18 INFO - moznetwork == 0.27 12:03:18 INFO - mozprocess == 0.22 12:03:18 INFO - mozprofile == 0.28 12:03:18 INFO - mozrunner == 6.11 12:03:18 INFO - mozscreenshot == 0.1 12:03:18 INFO - mozsystemmonitor == 0.0 12:03:18 INFO - moztest == 0.7 12:03:18 INFO - mozversion == 1.4 12:03:18 INFO - psutil == 3.1.1 12:03:18 INFO - requests == 1.2.3 12:03:18 INFO - wsgiref == 0.1.2 12:03:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:18 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 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:18 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 12:03:18 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 12:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:18 INFO - 'HOME': '/Users/cltbld', 12:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:18 INFO - 'LOGNAME': 'cltbld', 12:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:18 INFO - 'MOZ_NO_REMOTE': '1', 12:03:18 INFO - 'NO_EM_RESTART': '1', 12:03:18 INFO - 'PAGER': '/bin/cat', 12:03:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:18 INFO - 'PWD': '/builds/slave/test', 12:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:18 INFO - 'SHELL': '/bin/bash', 12:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:18 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:18 INFO - 'USER': 'cltbld', 12:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:18 INFO - 'XPC_FLAGS': '0x0', 12:03:18 INFO - 'XPC_SERVICE_NAME': '0', 12:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 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)) 12:03:18 INFO - Cleaning up... 12:03:18 INFO - Return code: 0 12:03:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:18 INFO - 'HOME': '/Users/cltbld', 12:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:18 INFO - 'LOGNAME': 'cltbld', 12:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:18 INFO - 'MOZ_NO_REMOTE': '1', 12:03:18 INFO - 'NO_EM_RESTART': '1', 12:03:18 INFO - 'PAGER': '/bin/cat', 12:03:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:18 INFO - 'PWD': '/builds/slave/test', 12:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:18 INFO - 'SHELL': '/bin/bash', 12:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:18 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:18 INFO - 'USER': 'cltbld', 12:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:18 INFO - 'XPC_FLAGS': '0x0', 12:03:18 INFO - 'XPC_SERVICE_NAME': '0', 12:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:18 INFO - Downloading/unpacking jsonschema 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:03:20 INFO - Downloading/unpacking functools32 (from jsonschema) 12:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:21 INFO - Downloading functools32-3.2.3-2.tar.gz 12:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:03:21 INFO - warning: no files found matching '*.txt' 12:03:21 INFO - no previously-included directories found matching 'build' 12:03:21 INFO - no previously-included directories found matching 'dist' 12:03:21 INFO - no previously-included directories found matching '.git*' 12:03:21 INFO - Installing collected packages: jsonschema, functools32 12:03:21 INFO - Running setup.py install for functools32 12:03:21 INFO - warning: no files found matching '*.txt' 12:03:21 INFO - no previously-included directories found matching 'build' 12:03:21 INFO - no previously-included directories found matching 'dist' 12:03:21 INFO - no previously-included directories found matching '.git*' 12:03:21 INFO - Successfully installed jsonschema functools32 12:03:21 INFO - Cleaning up... 12:03:21 INFO - Return code: 0 12:03:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5014c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd58b4a3df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, '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.gjTtVodU77/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.r8Uf8V50H4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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 12:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:21 INFO - 'HOME': '/Users/cltbld', 12:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:21 INFO - 'LOGNAME': 'cltbld', 12:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:21 INFO - 'MOZ_NO_REMOTE': '1', 12:03:21 INFO - 'NO_EM_RESTART': '1', 12:03:21 INFO - 'PAGER': '/bin/cat', 12:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:21 INFO - 'PWD': '/builds/slave/test', 12:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:21 INFO - 'SHELL': '/bin/bash', 12:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:03:21 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:21 INFO - 'USER': 'cltbld', 12:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:21 INFO - 'XPC_FLAGS': '0x0', 12:03:21 INFO - 'XPC_SERVICE_NAME': '0', 12:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:21 INFO - Downloading/unpacking flake8 12:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:03:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:03:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:03:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:03:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:03:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:03:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:03:25 INFO - no previously-included directories found matching 'docs/_build' 12:03:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:03:25 INFO - Running setup.py install for pep8 12:03:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:03:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:03:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:03:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:03:25 INFO - no previously-included directories found matching 'docs/_build' 12:03:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:03:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:03:25 INFO - Cleaning up... 12:03:25 INFO - Return code: 0 12:03:25 INFO - Running post-action listener: _resource_record_post_action 12:03:25 INFO - Running post-action listener: _start_resource_monitoring 12:03:25 INFO - Starting resource monitoring. 12:03:25 INFO - ##### 12:03:25 INFO - ##### Running install step. 12:03:25 INFO - ##### 12:03:25 INFO - Running pre-action listener: _resource_record_pre_action 12:03:25 INFO - Running main action method: install 12:03:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:26 INFO - Reading from file tmpfile_stdout 12:03:26 INFO - Detecting whether we're running mozinstall >=1.0... 12:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:03:26 INFO - Reading from file tmpfile_stdout 12:03:26 INFO - Output received: 12:03:26 INFO - Usage: mozinstall [options] installer 12:03:26 INFO - Options: 12:03:26 INFO - -h, --help show this help message and exit 12:03:26 INFO - -d DEST, --destination=DEST 12:03:26 INFO - Directory to install application into. [default: 12:03:26 INFO - "/builds/slave/test"] 12:03:26 INFO - --app=APP Application being installed. [default: firefox] 12:03:26 INFO - mkdir: /builds/slave/test/build/application 12:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:04:00 INFO - Reading from file tmpfile_stdout 12:04:00 INFO - Output received: 12:04:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:04:00 INFO - Running post-action listener: _resource_record_post_action 12:04:00 INFO - ##### 12:04:00 INFO - ##### Running run-tests step. 12:04:00 INFO - ##### 12:04:00 INFO - Running pre-action listener: _resource_record_pre_action 12:04:00 INFO - Running main action method: run_tests 12:04:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:04:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:04:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:04:00 INFO - 2016-02-23 12:04:00.328 screenresolution[2890:27151] starting screenresolution argv=screenresolution get 12:04:00 INFO - 2016-02-23 12:04:00.331 screenresolution[2890:27151] Display 0: 1600x1200x32@60 12:04:00 INFO - 2016-02-23 12:04:00.337 screenresolution[2891:27153] starting screenresolution argv=screenresolution list 12:04:00 INFO - Available Modes on Display 0 12:04:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:04:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:04:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:04:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:04:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:04:00 INFO - Intel Iris: 12:04:00 INFO - Chipset Model: Intel Iris 12:04:00 INFO - Type: GPU 12:04:00 INFO - Bus: Built-In 12:04:00 INFO - VRAM (Dynamic, Max): 1536 MB 12:04:00 INFO - Vendor: Intel (0x8086) 12:04:00 INFO - Device ID: 0x0a2e 12:04:00 INFO - Revision ID: 0x0009 12:04:00 INFO - Displays: 12:04:00 INFO - Display: 12:04:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:04:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:04:00 INFO - Display Serial Number: 1600x1200 60 12:04:00 INFO - Main Display: Yes 12:04:00 INFO - Mirror: Off 12:04:00 INFO - Online: Yes 12:04:00 INFO - Rotation: Supported 12:04:00 INFO - Return code: 0 12:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:04:00 INFO - Python 2.7.3 12:04:00 INFO - Return code: 0 12:04:00 INFO - grabbing minidump binary from tooltool 12:04:00 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')]} 12:04:00 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39dd30>, '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 0x10a1c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a394b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:04:00 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 12:04:00 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 12:04:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:04:00 INFO - Return code: 0 12:04:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:04:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:04:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:04:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:04:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:04:01 INFO - Return code: 0 12:04:01 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/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0050', '--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 12:04:01 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/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0050 --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 12:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjTtVodU77/Render', 12:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:01 INFO - 'HOME': '/Users/cltbld', 12:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:01 INFO - 'LOGNAME': 'cltbld', 12:04:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:04:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:01 INFO - 'MOZ_NO_REMOTE': '1', 12:04:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:04:01 INFO - 'NO_EM_RESTART': '1', 12:04:01 INFO - 'PAGER': '/bin/cat', 12:04:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:01 INFO - 'PWD': '/builds/slave/test', 12:04:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:01 INFO - 'SHELL': '/bin/bash', 12:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8Uf8V50H4/Listeners', 12:04:01 INFO - 'TMPDIR': '/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/', 12:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:01 INFO - 'USER': 'cltbld', 12:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:01 INFO - 'XPC_FLAGS': '0x0', 12:04:01 INFO - 'XPC_SERVICE_NAME': '0', 12:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:01 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/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0050', '--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 12:04:02 INFO - mozversion application_buildid: 20160223101806 12:04:02 INFO - mozversion application_changeset: 5edc2330c4cf536785b4019e5443f58e4b307c34 12:04:02 INFO - mozversion application_display_name: Nightly 12:04:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:04:02 INFO - mozversion application_name: Firefox 12:04:02 INFO - mozversion application_remotingname: firefox 12:04:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:04:02 INFO - mozversion application_vendor: Mozilla 12:04:02 INFO - mozversion application_version: 47.0a1 12:04:02 INFO - mozversion platform_buildid: 20160223101806 12:04:02 INFO - mozversion platform_changeset: 5edc2330c4cf536785b4019e5443f58e4b307c34 12:04:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:04:02 INFO - mozversion platform_version: 47.0a1 12:04:02 INFO - using testdate: 1456257842 12:04:02 INFO - actual date: 1456257842 12:04:02 INFO - starting webserver on 'localhost:51042' 12:04:02 INFO - SUITE-START | Running 2 tests 12:04:02 INFO - TEST-START | dromaeo_css 12:04:02 INFO - operating with platform_type : mac_ 12:04:02 INFO - Initialising browser for dromaeo_css test... 12:04:02 INFO - TEST-INFO | started process 2909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/tmpfnz7wA/profile http://localhost:51042/getInfo.html) 12:04:05 INFO - PROCESS | 2909 | __metrics Screen width/height:1600/1200 12:04:05 INFO - PROCESS | 2909 | colorDepth:24 12:04:05 INFO - PROCESS | 2909 | Browser inner width/height: 1024/689 12:04:05 INFO - PROCESS | 2909 | __metrics 12:04:05 INFO - PROCESS | 2909 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:04:05 INFO - PROCESS | 2909 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:04:05 INFO - TEST-INFO | 2909: exit 0 12:04:05 INFO - Browser initialized. 12:04:05 INFO - Running cycle 1/1 for dromaeo_css test... 12:04:05 INFO - TEST-INFO | started process 2912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/tmpfnz7wA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:17 INFO - PROCESS | 2912 | RSS: Main: 244224000 12:04:17 INFO - PROCESS | 2912 | 12:05:09 INFO - PROCESS | 2912 | 1456257909241 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] 12:07:19 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51042/tests/dromaeo/cssquery-ext.html) 12:07:20 INFO - PROCESS | 2912 | RSS: Main: 320999424 12:07:20 INFO - PROCESS | 2912 | 12:10:22 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-ext.html (next: http://localhost:51042/tests/dromaeo/cssquery-jquery.html) 12:10:23 INFO - PROCESS | 2912 | RSS: Main: 374935552 12:10:23 INFO - PROCESS | 2912 | 12:13:25 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51042/tests/dromaeo/cssquery-mootools.html) 12:13:26 INFO - PROCESS | 2912 | RSS: Main: 344780800 12:13:26 INFO - PROCESS | 2912 | 12:16:28 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51042/tests/dromaeo/cssquery-prototype.html) 12:16:28 INFO - PROCESS | 2912 | RSS: Main: 333111296 12:16:28 INFO - PROCESS | 2912 | 12:19:31 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51042/tests/dromaeo/cssquery-yui.html) 12:19:32 INFO - PROCESS | 2912 | RSS: Main: 336519168 12:19:32 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | Cycle 1(1): loaded http://localhost:51042/tests/dromaeo/cssquery-yui.html (next: http://localhost:51042/tests/dromaeo/cssquery-dojo.html) 12:22:35 INFO - PROCESS | 2912 | RSS: Main: 390135808 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | __start_tp_report 12:22:35 INFO - PROCESS | 2912 | _x_x_mozilla_page_load 12:22:35 INFO - PROCESS | 2912 | _x_x_mozilla_page_load_details 12:22:35 INFO - PROCESS | 2912 | |i|pagename|runs| 12:22:35 INFO - PROCESS | 2912 | |0;dojo.html;3348.6651334866515;3439.9140021499443;3451.9827400863;3462.7402944779146;3501.789892606444;5512.283403157584;5561.888762224753;5566.610337276392;5580.190872323511;5593.888122237558;5553.611247212697;5577.665340079598;5581.581381396401;5679.375268720438;5705.629134106281;4994;5013.849584512462;5044.344235249412;5062.569681577071;5130.204818253177;5087.771050302746;5097.2608971699265;5128.8461346159675;5169.663971841837;5200.8699782505555;4996.500349965011;5103.974480127613;5104;5106.310648062501;5109.872253193663;5082;5090.032893750175;5091.363579552556;5097.745112744385;5103.948960510422;4998.800047998076;5045.697258164522;5049.091163590552;5058.266551350071;5061.9876024795185;4749.0027094310235;5303.23103150045;5564.693941833198;5575.637583557096;5576.498115169654;6045.33501314855;6061.302950160743;6062.242219722535;6080.695965201722;6082.878342433127;5998.340182579913;6149.2620885494025;6149.907751383733;6152.907706384408;6165.815025549241;5497.532709719679;5577.107662773978;5623.606347555673;5693.516051135617;5700.971495142498;6282.685865706696;6304.747810087637;6319.55763096588;6344.175257216532;6344.49244060474;5971.0744834550715;5977.521798256129;5982.700864956735;6002.459778619946;6008.429199226067;4939.851804445872;6119.418655227746;6143.38566143391;6195.721192546346;6197.25632924052;6024.789132380346;6025.171339563863;6035.818925432244;6046.153538504613;6102;6052.364501727343;6072.483838873745;6092.1775560299675;6117.051856962178;6139.969300153471;4716.834910778106;4783.784729687172;4783.808647654055;4784.760761961888;4818.301346304766;4273.893152671146;4299.806508707115;4304.160688665743;4348;4356.716813407118;5587.3015873015875;5650.124230744241;5653.349864765522;5663.433656634304;5681.431856814368;5139.871503212449;6182.041783523561;6200.503959683215;6213.689315534205;6230.158928544679;5626.887462250732;5680.488756011979;5684.261046063985;5710.343310519261;5716.085426331767;4631.282151266491;4970.782158371222;5151.9484805152215;5250.291210686585;5321.707306098125;6233.875322493524;6255.186825712628;6264.906026409516;6268.216472940882;6277.1525844011385;6181.2790431555095;6197.81406557804;6211.875762484816;6213.347598502092;6223.159873417031;5503.477169668875;6200.472959798468;6222.12890195364;6270.278917924497;6272;6030.125631783455;6037.728302226323;6038.275406951194;6039.214902062704;6041.848953776191;6042.244719410074;6080.2399700037495;6153.230846144232;6164.321924588381;6184.876302474016;5933.1396947443245;5942.376050514634;5945.910811337747;5951.077582974646;5979.162917191509;5861.589688721749;5895.174675545512;5919.733611987384;5925.111233315038;5928.822135335947;5677.1768093627015;5786.681732504752;5799.652020878761;5800.50695690871;5908.497777688945;5761.740721667619;5924.585279030426;5926.081457374114;5926.555508336805;5958.136070269873;4447.577480139382;5733.140028995684;5819.15622234782;5827.912581311199;5831.1836342912875 12:22:35 INFO - PROCESS | 2912 | |1;ext.html;452.38249789037974;585.8652509922717;602.866610771749;608.9177960975269;633.6609913696172;29134.708652913465;29627.259318517048;37489.81255093724;37862.43206351907;37903;40751.59248407515;41086.38370424446;41087;41110;41159.79420102903;8515.23362897339;9417.905820941805;9636.614535418576;9639.277054220962;9690.370125941827;11419.029382502456;11675.182737208399;11682.824757628643;11689.59086431975;11690.941545292262;11373.431328433586;11477.655670329903;11480;11499.827502587468;11501.827472587918;10172.287939844251;10799.136069114491;11507.021903138244;11507.942460287644;11509.884901150965;11354.71613209668;11501.712457188554;11540.422978851024;11541.711457213552;11545.249558778652;39212.411813822815;39495.40756888649;39501.012474688076;39617.60382396168;39687.40468892969;3170.3342298117423;3398.898033059012;3469.1500582357485;3676.8161591920298;3730.8321125549414;28224.71775282262;28429.85785071082;28695.9921218151;28954;29312.41375172506;2759.8344099354103;2813.8452385118812;2818.25316291183;2860.942781144386;2911.7525010374143;492905;502558.4104556323;503329;503467;503905;44519;44851;44881;44999.55000450019;65938.34061659322;43857.78071109624;43910.34134487985;44066;44069.55930440719;44269.33595996063;14997.850021499864;15095.4716584919;15118;15168.696626067416;15184.316705748268;11089.27919685218;11230.606928757454;12097.516099355928;12126.333051682066;12249.571265005681;14060.789088163685;14094.788578171336;14161.29193540319;14180.14919104857;14263.857361426253;6155;6225.750969961151;6273.592216505913;6279.403456671609;6285.340039295899;712.9661990114355;867.626920424211;984.5077461269366;995.7759504111661;1012.6101450941441;27254.046108386112;29314.560281595794;30840.845795770878;31011.379772404878;31482.685173148435;1030.3766221436074;1092.126298960828;1154.3362566524281;1166.7141550320057;1168.8422063021553;14967.850321496864;15055.397784088515;15095;15331;15975.680486390207;503069;626263;626929;630255;632601;57683.8463230733;58489.12266316009;58586.70706646525;58700.41299587035;58780.82438351209;1411.2097225553723;1419.971600567983;1438.6475313548037;1444.2129039673403;1449.9637509062359;29281.828726850687;29404.11787646374;29824.70175298306;29959.400811984073;30659;51893.22160167676;51995.960080798926;52582.94834103373;52640.7367963173;52793.47206527885;395602;402809;440060;441528;506886;40178.39732403958;40997;41516.962075947136;41544.1691166169;41611.37582936319;43472.69645901263;43505;43521.78239108784;44698.10603787971;44821.32768008548;30675.84662076675;36388.454173186896;37432.625673744005;38201.23597528089;38507.037324067125;30108.247293816952;34732;35388.46917296191;35468.7929612322;35613;34300.97097087091;34978.825105874304;35066.649333506335;35198.824005879804;35223.94328170159;29762;31582;31582.894598688934;31670;31704.524432133996;23466.882665586563;24625.63061554114;25026.874865625556;25627;25851.353716157246 12:22:35 INFO - PROCESS | 2912 | |2;jquery.html;323.7733586489457;356.2092155414979;358.8797752752826;359.97300202484803;364.92883887641887;19464.513387165327;19890.303839365624;20738.274160404388;20742.585148297025;20824.375268741944;11784.7643047139;19245.903770481127;21051.2632057878;21068.57862842746;21081.367558973256;5299.54953828924;5418.593605479585;5487.341519017724;5489.176623506466;5492.0663487207075;5012.147934851066;5389.83830485086;5450.645708028986;5461.726913654321;5972.104184372335;2530.367408147963;5924.526037916957;5955.047192449209;5973.4026597340135;5979.850503737398;4241.533431322568;5275.86810329741;5791.507721843648;5940.821775346746;6031.366706495799;5218.843434696965;5231.398389185254;5235.397929238148;5239.318888544503;5241.0828105081455;13277.269750163738;13578.456861725519;13761.931190344085;13850.93074534631;13862.861371386358;2039.479932617173;2139.7860213978634;2150.5698860228017;2321.655337267495;2665.8533780642056;9786.41281523111;9839.606415743434;10111.49442527878;10364.585416583328;10425.322354046963;1759.2435252841274;1818.1909050472545;1826.9951526660284;1842.9631407371912;1852.3239017758556;83827.5808620953;94469;102953.48523257411;103429.482852586;103573;10341.482925853828;10697.946510267398;10849.240553161202;10863.728406789893;11062.22564420499;13387.464501419834;13402.329883505785;13481.191128532319;13536.052476326562;13567.86432135666;8510.617022234015;8554.315654747605;8560.101189375151;8645.221930026328;8685.478871267744;8717.346199035097;8720.389572729975;8745.868811967826;8799.692010779592;8834.337424693174;4454.955450445454;4517.2094883395275;4525.818967241339;4561.133384656904;4567.977160114245;1871.3637363296546;1915.530694979711;1930.7393501877348;1937.3703546347494;1963.9803601964084;886.2998231397253;977;1001.4441984698417;1012.9819531370925;1041.6874937518714;17731.074372728614;17838.01890895987;20355.69466458004;20728;20751.377458676263;839.2740279658174;888.8089060851947;896;915.5651065743851;947.7488465556632;10706.839397409045;11204.887951120547;11236.269642473213;11237.044851187577;11256.15578831591;456686.44910979085;463566.68216658704;613887.2675935467;614521;615508;102797;110278;110738;111870;114526;993.9453330066771;1016.5679586175994;1027.0602398805158;1030.314840630987;1030.3302853145515;18780.90609546998;19255.422337329903;19278.614427711647;19580.804191957897;19733.506662333555;35819.64180358163;39657.80171099126;54015.72992135014;56359.30364727687;56926;77385.45229095499;78313.60843195747;80680;81379.96550086295;82167;7078.752243671447;7875.566843823644;7929.563873986986;8211.95894020526;8327.375446841632;11515.827262591236;11717.472713728073;13320.733585328433;13493.923374571117;13612.387442565307;11297.209195356247;11741.354225517678;11914.10644201671;11963;11985.520579177084;8374.83250335002;11614.825777613174;11762.176647634584;11802.940985295361;11920;6112.235970503687;8844.778880528038;10651.201159912882;11866.762664746484;12014.579489717818;8160;8239.835203296021;8359.749207523784;8402.41183117176;8409.62156702962;8740.694075707319;9831.410115393099;11003.834942475709;11821.468033938321;12001.699957500783 12:22:35 INFO - PROCESS | 2912 | |3;mootools.html;1317.7562151002062;1381.0539780250535;1511.9092854428734;1526.4581073718825;1875.2967637136067;7791.259830316118;8432.83134337313;8592.527410992392;8594.312455003601;8607.311415086793;8345.95827020864;8563.929508811398;8572.142785721424;8573.056963733985;8583.52790596517;2124.989375053123;2179.6512557990723;2179.901904414305;2402.6516155157537;2421.8183636227263;2375.6674065630827;2442.096424323003;2443.364725171459;2446.425090103833;2447.119037146626;2360.433495960974;2387.438951846315;2390.1873363056557;2418.032786885251;2420.104561312317;2389.7251816041203;2404.362843846382;2406.049610403898;2409.2170044735444;2428.247243354548;2388.3551441110976;2412.16780210827;2412.421018955438;2417.818663600219;2425.1027119965547;8612.18184272493;8662.350323725746;8670.176333248395;8679.609417576225;8699.608517616722;5224.660397074216;5361.276227709248;5367.892642147135;5375.623706340558;5424;3133.8746450141966;3557.0573797943307;3598.874039408608;3685.4840322354894;3719.609441008682;1034.5758239122;1053.4100903494068;1067.450263114489;1073.3613499967585;1084.9692791847697;11670.883291167149;11836.99385552229;11844.644660660195;11851.992580630656;11874.228175168586;4818.10864989978;4822.132016237111;4864.440589332272;4875.219964805614;4906.705597664151;10096.444695541815;10097.848532272023;10097.99020097999;10102.040306170902;10107.241956853266;4515.706479078849;5386.730663466811;5396.811111611153;5425.403205647375;5438.320209973754;4292.720973136736;4436.955630443719;4709.458412282563;4881.780319885614;4934.309196712463;3843.673287770515;3866.6906647468136;3867.303885300673;3913.099987003005;3932.46911666927;1092.251807511857;1280.1998750780763;1287.4850059976084;1291.799771035491;1297.6690943809267;1327.4889167670517;1362.3392654562529;1378.2281922123443;1381.1781989716103;1399.7270532246318;6279.7488100476385;8068.3948703847445;8074.677012919417;8074.959625201836;8079.47483413588;504.2461520027582;510.3875349580444;519.6674128557726;521.88518525925;521.9608529360237;5063.215201643751;6109.053096769919;6164.229471316085;6182.1345011698395;6197.752089916353;10032.247581431422;10202.081812636898;10273.845892311621;10302.742431439125;10373.896261037444;9974.850377244347;10191.745206369751;10237.692869213786;10238.53926573306;10244.948775256225;8059.597020149086;8189.549574773445;8239.134890836376;8283.08886022537;8289.295409890106;7968.721094761656;7998.9200108000505;8042.798930026796;8101;8144.714934977247;9546.713598592054;9547.904520954702;9602.66390676323;9618.182454491447;9647.9035209647;9268.907310926805;9562.95218523903;9741.902580974383;9881.654142104991;10060.496975151067;6704.128463299739;6895.275996020547;7162.391196748335;7317.451191160578;7475.476716629783;8105;8154.673813047411;8160.755177344689;8165.755027349189;8172.101068882299;7131.179914309921;7222.566646001257;7242.710291588278;7365.226651201547;7370.926290737239;7161.9641901790155;7185.245549217257;7202.567845929261;7203.099612548432;7270;7570.129435115033;7650.961745191238;7729.227077292226;7740.961295193488;7750.883736743841;6948.652567371712;6963.2340442552295;7024.5434046786795;7030.648467576703;7041;6936.34104760047;7084.36240738336;7091.11361079865;7093.751718690028;7122.893156602759 12:22:35 INFO - PROCESS | 2912 | |4;prototype.html;316.8067478837909;320.64248363075166;327.46459538654295;327.9744358294733;330.92388750587367;5141.203113517401;5308.787648494061;6565.901511477332;6580.638064906429;6613.636250006248;6462.676866156685;6483.1571895653615;6538.836529086775;6562.901556476657;6578.901316480256;4889.633277504201;4907.582855457291;4929.285253638221;4930.457649658535;4931.235658472924;4875.70745755254;4884.951150488485;4933.062718083553;4947.282644016634;4973.204287314031;4490.7081039732475;4605.746683932382;4729.95940893003;4905.117078925792;4911.386076740408;3865.922681546381;4735.573798358164;4785.617150627942;4795.400574928134;4800.351952486404;4101.138760860223;4114.362273847559;4121.361189015707;4121.464209652756;4127.33962565988;5945.346011938684;6096.786612468542;6101.267847858241;6165.84585385369;6231.906521402183;5696.2594862668;5707.286589176353;5707.343655479632;5711.343195532525;5727.083666613322;7020.754273600451;7029.156501219835;7030.226675065739;7039.436845052434;7051.753188638373;4646.4424269087585;4650.279206722963;4656.604188643969;4659.18464268755;4662.6269898408045;5555.416681248451;7182.604956727378;7247.528910620793;7404.629768511553;7426.212631557891;4711.976440117777;4758.2386818108935;4761.642876784185;4762.166620841408;4762.357081793983;4821.397325334333;4832.275158726219;4838.588719958843;4850.53919877611;4854.514548545118;4550.88622784433;5636.802711905064;5653.491185793298;5669.829905102853;5712.857178570569;5713.314402271754;7076.433885289164;7079.327463890922;7088.326608972139;7094;1552.6661767719768;1617.3207252953787;1621.8378162183924;1659.2118743596695;1677.5554478063323;1029.4441001858913;1029.7888932768765;1034.343192073039;1043.5095505112586;1044.8693913260843;6533.954567269214;6583.80248592543;6595.901061484082;6612.537122401482;6640.800775976729;7277;7285.7814265572115;7287.526310789888;7300.853982920312;7330.560166390033;6592.9340706593275;6599.91101468262;6610.669466526654;6612.8346791330605;6623.933760662428;2944.823310601371;3826.4642949987024;3837.6162383761402;3871.4773505576386;3882.941755873664;7479.476436649491;7743.728969486154;7759.495632783851;7767.262110099531;7845.646945887449;7568.243175682498;7655.540667559964;7655.923440765521;7660.540367577852;7671.53970761756;7807.609619519001;7822.569758663328;7826.804329891685;7840.3335716464735;7850.097238817609;7128;7431.219721929119;7433.368163706145;7439.590822504814;7459.9929009585185;7250.927490725026;7287.307705767944;7291.963540182265;7300.853982920205;7303;7813.960930195312;7823.178566250462;7840.568768717775;7849.646765895434;7852.882206766789;4317.978410107929;4437.689361744647;4535.886602834956;4600.769961501978;4656.464506581787;7261.092363454568;7296.233895440902;7298.781036568911;7300.598467084149;7320.853582928418;7028.8945665814035;7076.681549330396;7080.079589653312;7081.575105493687;7085.610291433814;6197.977333740063;6678.7328506859185;7062.788116356517;7069.081019467436;7076.25699301566;6544.574602650812;6885.862282754417;6895.172779213613;6904.585724856524;6911.550749201286;6972.895406568804;7039.753608623673;7052.612106333996;7057.941308803721;7069.399101076466;6895.931040689729;6897.89653155193;6903.551269167488;6914.688839002358;6921.065656136358 12:22:35 INFO - PROCESS | 2912 | |5;yui.html;596.2606368103552;703.1456780012286;733.8312188196714;735.4925101679837;790.7034861926774;14808.333624986875;15026.549203523899;15042;15065.096094234339;15065.92467037666;13638.318084095781;13644;13656.112352697068;13668.111572747765;13670.658233544167;692.3976140757522;714.9821254468628;841.726438907353;872.2367928062945;872.8472517309477;860.2429861721677;871.8997315308757;875.4134729731095;875.9430637008605;881.5107615273521;921.4010892919589;923.7736754495158;925.3476299209075;926.5089502563652;926.666400095965;724.971001159953;901.6934242357597;921.5392303848109;922.0410772796283;922.0548937339214;689.4312192441206;689.8171984424132;689.9517033807635;690.002945743401;691.4399336537421;14555.981081324311;14652.12087274767;14652.780208296885;14660.266986650731;14685.412583496647;12475.812862807064;12641.936790316082;12710.872891271061;12771.808422873664;12816.423260953186;10112.595496180144;10127.848082278771;10158.542865571068;10175.440350780704;10185.796284074351;3517;3547.255076433952;3552.289542091592;3552.502649629054;3552.502649629054;76938;78135.8279625806;78747.60626196889;79331.60334198292;82983;9380.493287975753;9499.335046546676;9505.239580833517;9543.713688589354;9763.023697630322;20021;20068.498287542927;20097.59804803896;20116.29592964239;20117.49706257355;7417.258274172647;7633;7647.273509016634;7659.348955338859;7746.961265193638;4120.21715873989;4121.072758629344;4153.6677065834665;4162.1259535497275;4172.415861779354;3460.584729832386;3526.982365088158;3527.241643046706;3530.1704099536587;3533;557.5734563059262;629.1632129268063;652.9902051469137;660.9239937407164;674.8987651852261;587.8853623543368;595.5146555557207;627.438442593876;629.4020680353682;639.2456900857034;8160.5103693778565;8243.876341854877;8313.709020184264;8474.533900635524;8547.57262136903;533.9893202135936;577.6158854361802;608.9269287685506;621.7854840080165;630.961549854846;3752.4746535485056;3966.900827479278;4008.6318343642406;4030.657667649801;4116.814743336557;91495.17009659903;92301;93825;93876.53061734648;93952.78613780302;57350;76973.46053078907;78483.21516784873;79009.60495197488;79232.6038369816;68576.97134542886;69036.96444553336;69533;70755.6462217696;72471;7931.682732690628;7999.040115186215;8064.112947575644;8158.877616835871;8170.509769413854;41495;46581.30128048014;47774.52225477701;50811.475655730384;52601;65528.34471655222;66781;70755.29244707487;71879.92180117198;72830.63584682043;11176.888231117584;11256.324620522795;11371.54513819438;11373.829392558953;11667.299962002307;12254.20347677398;12422.36024844702;12599.18105323151;12669;12836.614901552968;9538.523073846418;11470.082393408675;11972.700682482659;12017.21888077307;12121.636350909486;11627.418629068681;11743.412829358327;11815.82276265874;11987.34069626179;12146.696332591757;11389.487473063566;11416.486258118239;11426.714332141762;11700.765984680429;12359.69100772488;9484.95257523708;9676;9836.803263934538;10042;10901.182411319025;8687.66599647539;9341.532923353941;9375.953120234355;10042.598296068076;10260 12:22:35 INFO - PROCESS | 2912 | __end_tp_report 12:22:35 INFO - PROCESS | 2912 | __start_cc_report 12:22:35 INFO - PROCESS | 2912 | _x_x_mozilla_cycle_collect,810 12:22:35 INFO - PROCESS | 2912 | __end_cc_report 12:22:35 INFO - PROCESS | 2912 | __startTimestamp1456258955399__endTimestamp 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | ------- Summary: start ------- 12:22:35 INFO - PROCESS | 2912 | Number of tests: 6 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#0] dojo.html Cycles:165 Average:5601.41 Median:5786.68 stddev:639.89 (11.1%) stddev-sans-first:616.97 12:22:35 INFO - PROCESS | 2912 | Values: 3348.7 3439.9 3452.0 3462.7 3501.8 5512.3 5561.9 5566.6 5580.2 5593.9 5553.6 5577.7 5581.6 5679.4 5705.6 4994.0 5013.8 5044.3 5062.6 5130.2 5087.8 5097.3 5128.8 5169.7 5200.9 4996.5 5104.0 5104.0 5106.3 5109.9 5082.0 5090.0 5091.4 5097.7 5103.9 4998.8 5045.7 5049.1 5058.3 5062.0 4749.0 5303.2 5564.7 5575.6 5576.5 6045.3 6061.3 6062.2 6080.7 6082.9 5998.3 6149.3 6149.9 6152.9 6165.8 5497.5 5577.1 5623.6 5693.5 5701.0 6282.7 6304.7 6319.6 6344.2 6344.5 5971.1 5977.5 5982.7 6002.5 6008.4 4939.9 6119.4 6143.4 6195.7 6197.3 6024.8 6025.2 6035.8 6046.2 6102.0 6052.4 6072.5 6092.2 6117.1 6140.0 4716.8 4783.8 4783.8 4784.8 4818.3 4273.9 4299.8 4304.2 4348.0 4356.7 5587.3 5650.1 5653.3 5663.4 5681.4 5139.9 6182.0 6200.5 6213.7 6230.2 5626.9 5680.5 5684.3 5710.3 5716.1 4631.3 4970.8 5151.9 5250.3 5321.7 6233.9 6255.2 6264.9 6268.2 6277.2 6181.3 6197.8 6211.9 6213.3 6223.2 5503.5 6200.5 6222.1 6270.3 6272.0 6030.1 6037.7 6038.3 6039.2 6041.8 6042.2 6080.2 6153.2 6164.3 6184.9 5933.1 5942.4 5945.9 5951.1 5979.2 5861.6 5895.2 5919.7 5925.1 5928.8 5677.2 5786.7 5799.7 5800.5 5908.5 5761.7 5924.6 5926.1 5926.6 5958.1 4447.6 5733.1 5819.2 5827.9 5831.2 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#1] ext.html Cycles:180 Average:64572.66 Median:35022.74 stddev:138750.86 (396.2%) stddev-sans-first:139056.57 12:22:35 INFO - PROCESS | 2912 | Values: 452.4 585.9 602.9 608.9 633.7 29134.7 29627.3 37489.8 37862.4 37903.0 40751.6 41086.4 41087.0 41110.0 41159.8 8515.2 9417.9 9636.6 9639.3 9690.4 11419.0 11675.2 11682.8 11689.6 11690.9 11373.4 11477.7 11480.0 11499.8 11501.8 10172.3 10799.1 11507.0 11507.9 11509.9 11354.7 11501.7 11540.4 11541.7 11545.2 39212.4 39495.4 39501.0 39617.6 39687.4 3170.3 3398.9 3469.2 3676.8 3730.8 28224.7 28429.9 28696.0 28954.0 29312.4 2759.8 2813.8 2818.3 2860.9 2911.8 492905.0 502558.4 503329.0 503467.0 503905.0 44519.0 44851.0 44881.0 44999.6 65938.3 43857.8 43910.3 44066.0 44069.6 44269.3 14997.9 15095.5 15118.0 15168.7 15184.3 11089.3 11230.6 12097.5 12126.3 12249.6 14060.8 14094.8 14161.3 14180.1 14263.9 6155.0 6225.8 6273.6 6279.4 6285.3 713.0 867.6 984.5 995.8 1012.6 27254.0 29314.6 30840.8 31011.4 31482.7 1030.4 1092.1 1154.3 1166.7 1168.8 14967.9 15055.4 15095.0 15331.0 15975.7 503069.0 626263.0 626929.0 630255.0 632601.0 57683.8 58489.1 58586.7 58700.4 58780.8 1411.2 1420.0 1438.6 1444.2 1450.0 29281.8 29404.1 29824.7 29959.4 30659.0 51893.2 51996.0 52582.9 52640.7 52793.5 395602.0 402809.0 440060.0 441528.0 506886.0 40178.4 40997.0 41517.0 41544.2 41611.4 43472.7 43505.0 43521.8 44698.1 44821.3 30675.8 36388.5 37432.6 38201.2 38507.0 30108.2 34732.0 35388.5 35468.8 35613.0 34301.0 34978.8 35066.6 35198.8 35223.9 29762.0 31582.0 31582.9 31670.0 31704.5 23466.9 24625.6 25026.9 25627.0 25851.4 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#2] jquery.html Cycles:180 Average:32126.99 Median:2236.11 stddev:92772.10 (4148.8%) stddev-sans-first:93001.61 12:22:35 INFO - PROCESS | 2912 | Values: 323.8 356.2 358.9 360.0 364.9 19464.5 19890.3 20738.3 20742.6 20824.4 11784.8 19245.9 21051.3 21068.6 21081.4 5299.5 5418.6 5487.3 5489.2 5492.1 5012.1 5389.8 5450.6 5461.7 5972.1 2530.4 5924.5 5955.0 5973.4 5979.9 4241.5 5275.9 5791.5 5940.8 6031.4 5218.8 5231.4 5235.4 5239.3 5241.1 13277.3 13578.5 13761.9 13850.9 13862.9 2039.5 2139.8 2150.6 2321.7 2665.9 9786.4 9839.6 10111.5 10364.6 10425.3 1759.2 1818.2 1827.0 1843.0 1852.3 83827.6 94469.0 102953.5 103429.5 103573.0 10341.5 10697.9 10849.2 10863.7 11062.2 13387.5 13402.3 13481.2 13536.1 13567.9 8510.6 8554.3 8560.1 8645.2 8685.5 8717.3 8720.4 8745.9 8799.7 8834.3 4455.0 4517.2 4525.8 4561.1 4568.0 1871.4 1915.5 1930.7 1937.4 1964.0 886.3 977.0 1001.4 1013.0 1041.7 17731.1 17838.0 20355.7 20728.0 20751.4 839.3 888.8 896.0 915.6 947.7 10706.8 11204.9 11236.3 11237.0 11256.2 456686.4 463566.7 613887.3 614521.0 615508.0 102797.0 110278.0 110738.0 111870.0 114526.0 993.9 1016.6 1027.1 1030.3 1030.3 18780.9 19255.4 19278.6 19580.8 19733.5 35819.6 39657.8 54015.7 56359.3 56926.0 77385.5 78313.6 80680.0 81380.0 82167.0 7078.8 7875.6 7929.6 8212.0 8327.4 11515.8 11717.5 13320.7 13493.9 13612.4 11297.2 11741.4 11914.1 11963.0 11985.5 8374.8 11614.8 11762.2 11802.9 11920.0 6112.2 8844.8 10651.2 11866.8 12014.6 8160.0 8239.8 8359.7 8402.4 8409.6 8740.7 9831.4 11003.8 11821.5 12001.7 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#3] mootools.html Cycles:180 Average:5938.24 Median:521.92 stddev:3161.16 (605.7%) stddev-sans-first:3150.95 12:22:35 INFO - PROCESS | 2912 | Values: 1317.8 1381.1 1511.9 1526.5 1875.3 7791.3 8432.8 8592.5 8594.3 8607.3 8346.0 8563.9 8572.1 8573.1 8583.5 2125.0 2179.7 2179.9 2402.7 2421.8 2375.7 2442.1 2443.4 2446.4 2447.1 2360.4 2387.4 2390.2 2418.0 2420.1 2389.7 2404.4 2406.0 2409.2 2428.2 2388.4 2412.2 2412.4 2417.8 2425.1 8612.2 8662.4 8670.2 8679.6 8699.6 5224.7 5361.3 5367.9 5375.6 5424.0 3133.9 3557.1 3598.9 3685.5 3719.6 1034.6 1053.4 1067.5 1073.4 1085.0 11670.9 11837.0 11844.6 11852.0 11874.2 4818.1 4822.1 4864.4 4875.2 4906.7 10096.4 10097.8 10098.0 10102.0 10107.2 4515.7 5386.7 5396.8 5425.4 5438.3 4292.7 4437.0 4709.5 4881.8 4934.3 3843.7 3866.7 3867.3 3913.1 3932.5 1092.3 1280.2 1287.5 1291.8 1297.7 1327.5 1362.3 1378.2 1381.2 1399.7 6279.7 8068.4 8074.7 8075.0 8079.5 504.2 510.4 519.7 521.9 522.0 5063.2 6109.1 6164.2 6182.1 6197.8 10032.2 10202.1 10273.8 10302.7 10373.9 9974.9 10191.7 10237.7 10238.5 10244.9 8059.6 8189.5 8239.1 8283.1 8289.3 7968.7 7998.9 8042.8 8101.0 8144.7 9546.7 9547.9 9602.7 9618.2 9647.9 9268.9 9563.0 9741.9 9881.7 10060.5 6704.1 6895.3 7162.4 7317.5 7475.5 8105.0 8154.7 8160.8 8165.8 8172.1 7131.2 7222.6 7242.7 7365.2 7370.9 7162.0 7185.2 7202.6 7203.1 7270.0 7570.1 7651.0 7729.2 7741.0 7750.9 6948.7 6963.2 7024.5 7030.6 7041.0 6936.3 7084.4 7091.1 7093.8 7122.9 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#4] prototype.html Cycles:180 Average:5763.40 Median:6564.40 stddev:1872.16 (28.5%) stddev-sans-first:1832.23 12:22:35 INFO - PROCESS | 2912 | Values: 316.8 320.6 327.5 328.0 330.9 5141.2 5308.8 6565.9 6580.6 6613.6 6462.7 6483.2 6538.8 6562.9 6578.9 4889.6 4907.6 4929.3 4930.5 4931.2 4875.7 4885.0 4933.1 4947.3 4973.2 4490.7 4605.7 4730.0 4905.1 4911.4 3865.9 4735.6 4785.6 4795.4 4800.4 4101.1 4114.4 4121.4 4121.5 4127.3 5945.3 6096.8 6101.3 6165.8 6231.9 5696.3 5707.3 5707.3 5711.3 5727.1 7020.8 7029.2 7030.2 7039.4 7051.8 4646.4 4650.3 4656.6 4659.2 4662.6 5555.4 7182.6 7247.5 7404.6 7426.2 4712.0 4758.2 4761.6 4762.2 4762.4 4821.4 4832.3 4838.6 4850.5 4854.5 4550.9 5636.8 5653.5 5669.8 5712.9 5713.3 7076.4 7079.3 7088.3 7094.0 1552.7 1617.3 1621.8 1659.2 1677.6 1029.4 1029.8 1034.3 1043.5 1044.9 6534.0 6583.8 6595.9 6612.5 6640.8 7277.0 7285.8 7287.5 7300.9 7330.6 6592.9 6599.9 6610.7 6612.8 6623.9 2944.8 3826.5 3837.6 3871.5 3882.9 7479.5 7743.7 7759.5 7767.3 7845.6 7568.2 7655.5 7655.9 7660.5 7671.5 7807.6 7822.6 7826.8 7840.3 7850.1 7128.0 7431.2 7433.4 7439.6 7460.0 7250.9 7287.3 7292.0 7300.9 7303.0 7814.0 7823.2 7840.6 7849.6 7852.9 4318.0 4437.7 4535.9 4600.8 4656.5 7261.1 7296.2 7298.8 7300.6 7320.9 7028.9 7076.7 7080.1 7081.6 7085.6 6198.0 6678.7 7062.8 7069.1 7076.3 6544.6 6885.9 6895.2 6904.6 6911.6 6972.9 7039.8 7052.6 7057.9 7069.4 6895.9 6897.9 6903.6 6914.7 6921.1 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - PROCESS | 2912 | [#5] yui.html Cycles:180 Average:18169.58 Median:615.36 stddev:25474.18 (4139.7%) stddev-sans-first:25511.46 12:22:35 INFO - PROCESS | 2912 | Values: 596.3 703.1 733.8 735.5 790.7 14808.3 15026.5 15042.0 15065.1 15065.9 13638.3 13644.0 13656.1 13668.1 13670.7 692.4 715.0 841.7 872.2 872.8 860.2 871.9 875.4 875.9 881.5 921.4 923.8 925.3 926.5 926.7 725.0 901.7 921.5 922.0 922.1 689.4 689.8 690.0 690.0 691.4 14556.0 14652.1 14652.8 14660.3 14685.4 12475.8 12641.9 12710.9 12771.8 12816.4 10112.6 10127.8 10158.5 10175.4 10185.8 3517.0 3547.3 3552.3 3552.5 3552.5 76938.0 78135.8 78747.6 79331.6 82983.0 9380.5 9499.3 9505.2 9543.7 9763.0 20021.0 20068.5 20097.6 20116.3 20117.5 7417.3 7633.0 7647.3 7659.3 7747.0 4120.2 4121.1 4153.7 4162.1 4172.4 3460.6 3527.0 3527.2 3530.2 3533.0 557.6 629.2 653.0 660.9 674.9 587.9 595.5 627.4 629.4 639.2 8160.5 8243.9 8313.7 8474.5 8547.6 534.0 577.6 608.9 621.8 631.0 3752.5 3966.9 4008.6 4030.7 4116.8 91495.2 92301.0 93825.0 93876.5 93952.8 57350.0 76973.5 78483.2 79009.6 79232.6 68577.0 69037.0 69533.0 70755.6 72471.0 7931.7 7999.0 8064.1 8158.9 8170.5 41495.0 46581.3 47774.5 50811.5 52601.0 65528.3 66781.0 70755.3 71879.9 72830.6 11176.9 11256.3 11371.5 11373.8 11667.3 12254.2 12422.4 12599.2 12669.0 12836.6 9538.5 11470.1 11972.7 12017.2 12121.6 11627.4 11743.4 11815.8 11987.3 12146.7 11389.5 11416.5 11426.7 11700.8 12359.7 9485.0 9676.0 9836.8 10042.0 10901.2 8687.7 9341.5 9376.0 10042.6 10260.0 12:22:35 INFO - PROCESS | 2912 | -------- Summary: end -------- 12:22:35 INFO - PROCESS | 2912 | 12:22:35 INFO - TEST-INFO | 2912: exit 0 12:22:35 INFO - TEST-OK | dromaeo_css | took 1113309ms 12:22:35 INFO - TEST-START | kraken 12:22:35 INFO - operating with platform_type : mac_ 12:22:35 INFO - Initialising browser for kraken test... 12:22:35 INFO - TEST-INFO | started process 2915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/tmpJwsI1F/profile http://localhost:51042/getInfo.html) 12:22:38 INFO - PROCESS | 2915 | __metrics Screen width/height:1600/1200 12:22:38 INFO - PROCESS | 2915 | colorDepth:24 12:22:38 INFO - PROCESS | 2915 | Browser inner width/height: 1024/689 12:22:38 INFO - PROCESS | 2915 | __metrics 12:22:38 INFO - PROCESS | 2915 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:38 INFO - PROCESS | 2915 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:38 INFO - TEST-INFO | 2915: exit 0 12:22:38 INFO - Browser initialized. 12:22:38 INFO - Running cycle 1/1 for kraken test... 12:22:38 INFO - TEST-INFO | started process 2917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/T/tmpJwsI1F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:50 INFO - PROCESS | 2917 | RSS: Main: 242794496 12:22:50 INFO - PROCESS | 2917 | 12:22:53 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51042/tests/kraken/driver.html?testName=audio-beat-detection) 12:22:53 INFO - PROCESS | 2917 | RSS: Main: 289492992 12:22:53 INFO - PROCESS | 2917 | 12:22:56 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51042/tests/kraken/driver.html?testName=audio-dft) 12:22:57 INFO - PROCESS | 2917 | RSS: Main: 300367872 12:22:57 INFO - PROCESS | 2917 | 12:23:00 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51042/tests/kraken/driver.html?testName=audio-fft) 12:23:00 INFO - PROCESS | 2917 | RSS: Main: 284504064 12:23:00 INFO - PROCESS | 2917 | 12:23:02 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51042/tests/kraken/driver.html?testName=audio-oscillator) 12:23:03 INFO - PROCESS | 2917 | RSS: Main: 286490624 12:23:03 INFO - PROCESS | 2917 | 12:23:05 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51042/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:23:06 INFO - PROCESS | 2917 | RSS: Main: 288366592 12:23:06 INFO - PROCESS | 2917 | 12:23:08 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51042/tests/kraken/driver.html?testName=imaging-darkroom) 12:23:09 INFO - PROCESS | 2917 | RSS: Main: 287494144 12:23:09 INFO - PROCESS | 2917 | 12:23:12 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51042/tests/kraken/driver.html?testName=imaging-desaturate) 12:23:12 INFO - PROCESS | 2917 | RSS: Main: 290111488 12:23:12 INFO - PROCESS | 2917 | 12:23:16 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51042/tests/kraken/driver.html?testName=json-parse-financial) 12:23:16 INFO - PROCESS | 2917 | RSS: Main: 288780288 12:23:16 INFO - PROCESS | 2917 | 12:23:18 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51042/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:23:18 INFO - PROCESS | 2917 | RSS: Main: 325406720 12:23:18 INFO - PROCESS | 2917 | 12:23:20 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:23:21 INFO - PROCESS | 2917 | RSS: Main: 324698112 12:23:21 INFO - PROCESS | 2917 | 12:23:23 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:23:24 INFO - PROCESS | 2917 | RSS: Main: 332537856 12:23:24 INFO - PROCESS | 2917 | 12:23:26 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:23:27 INFO - PROCESS | 2917 | RSS: Main: 333475840 12:23:27 INFO - PROCESS | 2917 | 12:23:29 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:23:30 INFO - PROCESS | 2917 | RSS: Main: 332455936 12:23:30 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | Cycle 1(1): loaded http://localhost:51042/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51042/tests/kraken/driver.html?testName=ai-astar) 12:23:32 INFO - PROCESS | 2917 | RSS: Main: 393895936 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | __start_tp_report 12:23:32 INFO - PROCESS | 2917 | _x_x_mozilla_page_load 12:23:32 INFO - PROCESS | 2917 | _x_x_mozilla_page_load_details 12:23:32 INFO - PROCESS | 2917 | |i|pagename|runs| 12:23:32 INFO - PROCESS | 2917 | |0;ai-astar;111;108;109;110;112;109;116;110;109;109 12:23:32 INFO - PROCESS | 2917 | |1;audio-beat-detection;159;121;147;185;142;146;141;122;162;120 12:23:32 INFO - PROCESS | 2917 | |2;audio-dft;186;184;186;187;186;184;185;187;185;182 12:23:32 INFO - PROCESS | 2917 | |3;audio-fft;71;76;87;82;55;56;58;78;77;57 12:23:32 INFO - PROCESS | 2917 | |4;audio-oscillator;97;136;168;107;78;80;112;78;78;113 12:23:32 INFO - PROCESS | 2917 | |5;imaging-gaussian-blur;89;92;86;91;86;91;85;91;85;92 12:23:32 INFO - PROCESS | 2917 | |6;imaging-darkroom;94;94;93;95;94;94;94;93;95;93 12:23:32 INFO - PROCESS | 2917 | |7;imaging-desaturate;89;96;142;134;87;88;134;135;142;85 12:23:32 INFO - PROCESS | 2917 | |8;json-parse-financial;54;53;52;54;72;83;84;92;54;54 12:23:32 INFO - PROCESS | 2917 | |9;json-stringify-tinderbox;62;65;75;77;70;68;69;68;69;69 12:23:32 INFO - PROCESS | 2917 | |10;stanford-crypto-aes;81;78;78;94;108;109;84;82;85;84 12:23:32 INFO - PROCESS | 2917 | |11;stanford-crypto-ccm;130;136;130;107;128;119;130;121;125;132 12:23:32 INFO - PROCESS | 2917 | |12;stanford-crypto-pbkdf2;138;127;138;170;166;137;135;137;133;137 12:23:32 INFO - PROCESS | 2917 | |13;stanford-crypto-sha256-iterative;66;67;68;67;67;66;68;84;96;91 12:23:32 INFO - PROCESS | 2917 | __end_tp_report 12:23:32 INFO - PROCESS | 2917 | __start_cc_report 12:23:32 INFO - PROCESS | 2917 | _x_x_mozilla_cycle_collect,1109 12:23:32 INFO - PROCESS | 2917 | __end_cc_report 12:23:32 INFO - PROCESS | 2917 | __startTimestamp1456259012356__endTimestamp 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | ------- Summary: start ------- 12:23:32 INFO - PROCESS | 2917 | Number of tests: 14 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:2.31 (2.1%) stddev-sans-first:2.44 12:23:32 INFO - PROCESS | 2917 | Values: 111.0 108.0 109.0 110.0 112.0 109.0 116.0 110.0 109.0 109.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:146.50 stddev:20.66 (14.1%) stddev-sans-first:21.24 12:23:32 INFO - PROCESS | 2917 | Values: 159.0 121.0 147.0 185.0 142.0 146.0 141.0 122.0 162.0 120.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#2] audio-dft Cycles:10 Average:185.20 Median:186.00 stddev:1.55 (0.8%) stddev-sans-first:1.62 12:23:32 INFO - PROCESS | 2917 | Values: 186.0 184.0 186.0 187.0 186.0 184.0 185.0 187.0 185.0 182.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#3] audio-fft Cycles:10 Average:69.70 Median:76.50 stddev:12.09 (15.8%) stddev-sans-first:12.82 12:23:32 INFO - PROCESS | 2917 | Values: 71.0 76.0 87.0 82.0 55.0 56.0 58.0 78.0 77.0 57.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:78.00 stddev:29.67 (38.0%) stddev-sans-first:31.34 12:23:32 INFO - PROCESS | 2917 | Values: 97.0 136.0 168.0 107.0 78.0 80.0 112.0 78.0 78.0 113.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:91.00 stddev:2.97 (3.3%) stddev-sans-first:3.15 12:23:32 INFO - PROCESS | 2917 | Values: 89.0 92.0 86.0 91.0 86.0 91.0 85.0 91.0 85.0 92.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 12:23:32 INFO - PROCESS | 2917 | Values: 94.0 94.0 93.0 95.0 94.0 94.0 94.0 93.0 95.0 93.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#7] imaging-desaturate Cycles:10 Average:113.20 Median:86.00 stddev:25.81 (30.0%) stddev-sans-first:25.85 12:23:32 INFO - PROCESS | 2917 | Values: 89.0 96.0 142.0 134.0 87.0 88.0 134.0 135.0 142.0 85.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:63.00 stddev:15.85 (25.2%) stddev-sans-first:16.28 12:23:32 INFO - PROCESS | 2917 | Values: 54.0 53.0 52.0 54.0 72.0 83.0 84.0 92.0 54.0 54.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:4.32 (6.3%) stddev-sans-first:3.71 12:23:32 INFO - PROCESS | 2917 | Values: 62.0 65.0 75.0 77.0 70.0 68.0 69.0 68.0 69.0 69.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:83.00 stddev:11.56 (13.9%) stddev-sans-first:11.95 12:23:32 INFO - PROCESS | 2917 | Values: 81.0 78.0 78.0 94.0 108.0 109.0 84.0 82.0 85.0 84.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:130.00 stddev:8.32 (6.4%) stddev-sans-first:8.69 12:23:32 INFO - PROCESS | 2917 | Values: 130.0 136.0 130.0 107.0 128.0 119.0 130.0 121.0 125.0 132.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.80 Median:137.50 stddev:14.23 (10.3%) stddev-sans-first:15.02 12:23:32 INFO - PROCESS | 2917 | Values: 138.0 127.0 138.0 170.0 166.0 137.0 135.0 137.0 133.0 137.0 12:23:32 INFO - PROCESS | 2917 | 12:23:32 INFO - PROCESS | 2917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:68.00 stddev:11.64 (17.1%) stddev-sans-first:11.98 12:23:32 INFO - PROCESS | 2917 | Values: 66.0 67.0 68.0 67.0 67.0 66.0 68.0 84.0 96.0 91.0 12:23:32 INFO - PROCESS | 2917 | -------- Summary: end -------- 12:23:32 INFO - PROCESS | 2917 | 12:23:33 INFO - TEST-INFO | 2917: exit 0 12:23:33 INFO - TEST-OK | kraken | took 57159ms 12:23:33 INFO - SUITE-END | took 1170s 12:23:33 INFO - Completed test suite (00:19:30) 12:23:33 INFO - Working with test: dromaeo_css 12:23:33 INFO - Generating results file: dromaeo_css 12:23:33 INFO - Working with test: kraken 12:23:33 INFO - Generating results file: kraken 12:23:33 INFO - javascript benchmark 12:23:33 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:23:33 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 12:23:33 INFO - process_Request line: dromaeo_css 7407.49 graph.html#tests=[[72,64,59]] 12:23:33 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:23:33 INFO - process_Request line: kraken 1474.60 graph.html#tests=[[232,64,59]] 12:23:33 INFO - RETURN: dromaeo_css: 7407.49 12:23:33 INFO - RETURN: kraken: 1474.60 12:23:33 INFO - javascript benchmark 12:23:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3348.6651334866515, 3439.9140021499443, 3451.9827400863, 3462.7402944779146, 3501.789892606444, 5512.283403157584, 5561.888762224753, 5566.610337276392, 5580.190872323511, 5593.888122237558, 5553.611247212697, 5577.665340079598, 5581.581381396401, 5679.375268720438, 5705.629134106281, 4994.0, 5013.849584512462, 5044.344235249412, 5062.569681577071, 5130.204818253177, 5087.771050302746, 5097.2608971699265, 5128.8461346159675, 5169.663971841837, 5200.8699782505555, 4996.500349965011, 5103.974480127613, 5104.0, 5106.310648062501, 5109.872253193663, 5082.0, 5090.032893750175, 5091.363579552556, 5097.745112744385, 5103.948960510422, 4998.800047998076, 5045.697258164522, 5049.091163590552, 5058.266551350071, 5061.9876024795185, 4749.0027094310235, 5303.23103150045, 5564.693941833198, 5575.637583557096, 5576.498115169654, 6045.33501314855, 6061.302950160743, 6062.242219722535, 6080.695965201722, 6082.878342433127, 5998.340182579913, 6149.2620885494025, 6149.907751383733, 6152.907706384408, 6165.815025549241, 5497.532709719679, 5577.107662773978, 5623.606347555673, 5693.516051135617, 5700.971495142498, 6282.685865706696, 6304.747810087637, 6319.55763096588, 6344.175257216532, 6344.49244060474, 5971.0744834550715, 5977.521798256129, 5982.700864956735, 6002.459778619946, 6008.429199226067, 4939.851804445872, 6119.418655227746, 6143.38566143391, 6195.721192546346, 6197.25632924052, 6024.789132380346, 6025.171339563863, 6035.818925432244, 6046.153538504613, 6102.0, 6052.364501727343, 6072.483838873745, 6092.1775560299675, 6117.051856962178, 6139.969300153471, 4716.834910778106, 4783.784729687172, 4783.808647654055, 4784.760761961888, 4818.301346304766, 4273.893152671146, 4299.806508707115, 4304.160688665743, 4348.0, 4356.716813407118, 5587.3015873015875, 5650.124230744241, 5653.349864765522, 5663.433656634304, 5681.431856814368, 5139.871503212449, 6182.041783523561, 6200.503959683215, 6213.689315534205, 6230.158928544679, 5626.887462250732, 5680.488756011979, 5684.261046063985, 5710.343310519261, 5716.085426331767, 4631.282151266491, 4970.782158371222, 5151.9484805152215, 5250.291210686585, 5321.707306098125, 6233.875322493524, 6255.186825712628, 6264.906026409516, 6268.216472940882, 6277.1525844011385, 6181.2790431555095, 6197.81406557804, 6211.875762484816, 6213.347598502092, 6223.159873417031, 5503.477169668875, 6200.472959798468, 6222.12890195364, 6270.278917924497, 6272.0, 6030.125631783455, 6037.728302226323, 6038.275406951194, 6039.214902062704, 6041.848953776191, 6042.244719410074, 6080.2399700037495, 6153.230846144232, 6164.321924588381, 6184.876302474016, 5933.1396947443245, 5942.376050514634, 5945.910811337747, 5951.077582974646, 5979.162917191509, 5861.589688721749, 5895.174675545512, 5919.733611987384, 5925.111233315038, 5928.822135335947, 5677.1768093627015, 5786.681732504752, 5799.652020878761, 5800.50695690871, 5908.497777688945, 5761.740721667619, 5924.585279030426, 5926.081457374114, 5926.555508336805, 5958.136070269873, 4447.577480139382, 5733.140028995684, 5819.15622234782, 5827.912581311199, 5831.1836342912875], "unit": "score", "name": "dojo.html", "value": 5562.596678834495}, {"lowerIsBetter": false, "replicates": [452.38249789037974, 585.8652509922717, 602.866610771749, 608.9177960975269, 633.6609913696172, 29134.708652913465, 29627.259318517048, 37489.81255093724, 37862.43206351907, 37903.0, 40751.59248407515, 41086.38370424446, 41087.0, 41110.0, 41159.79420102903, 8515.23362897339, 9417.905820941805, 9636.614535418576, 9639.277054220962, 9690.370125941827, 11419.029382502456, 11675.182737208399, 11682.824757628643, 11689.59086431975, 11690.941545292262, 11373.431328433586, 11477.655670329903, 11480.0, 11499.827502587468, 11501.827472587918, 10172.287939844251, 10799.136069114491, 11507.021903138244, 11507.942460287644, 11509.884901150965, 11354.71613209668, 11501.712457188554, 11540.422978851024, 11541.711457213552, 11545.249558778652, 39212.411813822815, 39495.40756888649, 39501.012474688076, 39617.60382396168, 39687.40468892969, 3170.3342298117423, 3398.898033059012, 3469.1500582357485, 3676.8161591920298, 3730.8321125549414, 28224.71775282262, 28429.85785071082, 28695.9921218151, 28954.0, 29312.41375172506, 2759.8344099354103, 2813.8452385118812, 2818.25316291183, 2860.942781144386, 2911.7525010374143, 492905.0, 502558.4104556323, 503329.0, 503467.0, 503905.0, 44519.0, 44851.0, 44881.0, 44999.55000450019, 65938.34061659322, 43857.78071109624, 43910.34134487985, 44066.0, 44069.55930440719, 44269.33595996063, 14997.850021499864, 15095.4716584919, 15118.0, 15168.696626067416, 15184.316705748268, 11089.27919685218, 11230.606928757454, 12097.516099355928, 12126.333051682066, 12249.571265005681, 14060.789088163685, 14094.788578171336, 14161.29193540319, 14180.14919104857, 14263.857361426253, 6155.0, 6225.750969961151, 6273.592216505913, 6279.403456671609, 6285.340039295899, 712.9661990114355, 867.626920424211, 984.5077461269366, 995.7759504111661, 1012.6101450941441, 27254.046108386112, 29314.560281595794, 30840.845795770878, 31011.379772404878, 31482.685173148435, 1030.3766221436074, 1092.126298960828, 1154.3362566524281, 1166.7141550320057, 1168.8422063021553, 14967.850321496864, 15055.397784088515, 15095.0, 15331.0, 15975.680486390207, 503069.0, 626263.0, 626929.0, 630255.0, 632601.0, 57683.8463230733, 58489.12266316009, 58586.70706646525, 58700.41299587035, 58780.82438351209, 1411.2097225553723, 1419.971600567983, 1438.6475313548037, 1444.2129039673403, 1449.9637509062359, 29281.828726850687, 29404.11787646374, 29824.70175298306, 29959.400811984073, 30659.0, 51893.22160167676, 51995.960080798926, 52582.94834103373, 52640.7367963173, 52793.47206527885, 395602.0, 402809.0, 440060.0, 441528.0, 506886.0, 40178.39732403958, 40997.0, 41516.962075947136, 41544.1691166169, 41611.37582936319, 43472.69645901263, 43505.0, 43521.78239108784, 44698.10603787971, 44821.32768008548, 30675.84662076675, 36388.454173186896, 37432.625673744005, 38201.23597528089, 38507.037324067125, 30108.247293816952, 34732.0, 35388.46917296191, 35468.7929612322, 35613.0, 34300.97097087091, 34978.825105874304, 35066.649333506335, 35198.824005879804, 35223.94328170159, 29762.0, 31582.0, 31582.894598688934, 31670.0, 31704.524432133996, 23466.882665586563, 24625.63061554114, 25026.874865625556, 25627.0, 25851.353716157246], "unit": "score", "name": "ext.html", "value": 19441.448605768925}, {"lowerIsBetter": false, "replicates": [323.7733586489457, 356.2092155414979, 358.8797752752826, 359.97300202484803, 364.92883887641887, 19464.513387165327, 19890.303839365624, 20738.274160404388, 20742.585148297025, 20824.375268741944, 11784.7643047139, 19245.903770481127, 21051.2632057878, 21068.57862842746, 21081.367558973256, 5299.54953828924, 5418.593605479585, 5487.341519017724, 5489.176623506466, 5492.0663487207075, 5012.147934851066, 5389.83830485086, 5450.645708028986, 5461.726913654321, 5972.104184372335, 2530.367408147963, 5924.526037916957, 5955.047192449209, 5973.4026597340135, 5979.850503737398, 4241.533431322568, 5275.86810329741, 5791.507721843648, 5940.821775346746, 6031.366706495799, 5218.843434696965, 5231.398389185254, 5235.397929238148, 5239.318888544503, 5241.0828105081455, 13277.269750163738, 13578.456861725519, 13761.931190344085, 13850.93074534631, 13862.861371386358, 2039.479932617173, 2139.7860213978634, 2150.5698860228017, 2321.655337267495, 2665.8533780642056, 9786.41281523111, 9839.606415743434, 10111.49442527878, 10364.585416583328, 10425.322354046963, 1759.2435252841274, 1818.1909050472545, 1826.9951526660284, 1842.9631407371912, 1852.3239017758556, 83827.5808620953, 94469.0, 102953.48523257411, 103429.482852586, 103573.0, 10341.482925853828, 10697.946510267398, 10849.240553161202, 10863.728406789893, 11062.22564420499, 13387.464501419834, 13402.329883505785, 13481.191128532319, 13536.052476326562, 13567.86432135666, 8510.617022234015, 8554.315654747605, 8560.101189375151, 8645.221930026328, 8685.478871267744, 8717.346199035097, 8720.389572729975, 8745.868811967826, 8799.692010779592, 8834.337424693174, 4454.955450445454, 4517.2094883395275, 4525.818967241339, 4561.133384656904, 4567.977160114245, 1871.3637363296546, 1915.530694979711, 1930.7393501877348, 1937.3703546347494, 1963.9803601964084, 886.2998231397253, 977.0, 1001.4441984698417, 1012.9819531370925, 1041.6874937518714, 17731.074372728614, 17838.01890895987, 20355.69466458004, 20728.0, 20751.377458676263, 839.2740279658174, 888.8089060851947, 896.0, 915.5651065743851, 947.7488465556632, 10706.839397409045, 11204.887951120547, 11236.269642473213, 11237.044851187577, 11256.15578831591, 456686.44910979085, 463566.68216658704, 613887.2675935467, 614521.0, 615508.0, 102797.0, 110278.0, 110738.0, 111870.0, 114526.0, 993.9453330066771, 1016.5679586175994, 1027.0602398805158, 1030.314840630987, 1030.3302853145515, 18780.90609546998, 19255.422337329903, 19278.614427711647, 19580.804191957897, 19733.506662333555, 35819.64180358163, 39657.80171099126, 54015.72992135014, 56359.30364727687, 56926.0, 77385.45229095499, 78313.60843195747, 80680.0, 81379.96550086295, 82167.0, 7078.752243671447, 7875.566843823644, 7929.563873986986, 8211.95894020526, 8327.375446841632, 11515.827262591236, 11717.472713728073, 13320.733585328433, 13493.923374571117, 13612.387442565307, 11297.209195356247, 11741.354225517678, 11914.10644201671, 11963.0, 11985.520579177084, 8374.83250335002, 11614.825777613174, 11762.176647634584, 11802.940985295361, 11920.0, 6112.235970503687, 8844.778880528038, 10651.201159912882, 11866.762664746484, 12014.579489717818, 8160.0, 8239.835203296021, 8359.749207523784, 8402.41183117176, 8409.62156702962, 8740.694075707319, 9831.410115393099, 11003.834942475709, 11821.468033938321, 12001.699957500783], "unit": "score", "name": "jquery.html", "value": 9095.790888263085}, {"lowerIsBetter": false, "replicates": [1317.7562151002062, 1381.0539780250535, 1511.9092854428734, 1526.4581073718825, 1875.2967637136067, 7791.259830316118, 8432.83134337313, 8592.527410992392, 8594.312455003601, 8607.311415086793, 8345.95827020864, 8563.929508811398, 8572.142785721424, 8573.056963733985, 8583.52790596517, 2124.989375053123, 2179.6512557990723, 2179.901904414305, 2402.6516155157537, 2421.8183636227263, 2375.6674065630827, 2442.096424323003, 2443.364725171459, 2446.425090103833, 2447.119037146626, 2360.433495960974, 2387.438951846315, 2390.1873363056557, 2418.032786885251, 2420.104561312317, 2389.7251816041203, 2404.362843846382, 2406.049610403898, 2409.2170044735444, 2428.247243354548, 2388.3551441110976, 2412.16780210827, 2412.421018955438, 2417.818663600219, 2425.1027119965547, 8612.18184272493, 8662.350323725746, 8670.176333248395, 8679.609417576225, 8699.608517616722, 5224.660397074216, 5361.276227709248, 5367.892642147135, 5375.623706340558, 5424.0, 3133.8746450141966, 3557.0573797943307, 3598.874039408608, 3685.4840322354894, 3719.609441008682, 1034.5758239122, 1053.4100903494068, 1067.450263114489, 1073.3613499967585, 1084.9692791847697, 11670.883291167149, 11836.99385552229, 11844.644660660195, 11851.992580630656, 11874.228175168586, 4818.10864989978, 4822.132016237111, 4864.440589332272, 4875.219964805614, 4906.705597664151, 10096.444695541815, 10097.848532272023, 10097.99020097999, 10102.040306170902, 10107.241956853266, 4515.706479078849, 5386.730663466811, 5396.811111611153, 5425.403205647375, 5438.320209973754, 4292.720973136736, 4436.955630443719, 4709.458412282563, 4881.780319885614, 4934.309196712463, 3843.673287770515, 3866.6906647468136, 3867.303885300673, 3913.099987003005, 3932.46911666927, 1092.251807511857, 1280.1998750780763, 1287.4850059976084, 1291.799771035491, 1297.6690943809267, 1327.4889167670517, 1362.3392654562529, 1378.2281922123443, 1381.1781989716103, 1399.7270532246318, 6279.7488100476385, 8068.3948703847445, 8074.677012919417, 8074.959625201836, 8079.47483413588, 504.2461520027582, 510.3875349580444, 519.6674128557726, 521.88518525925, 521.9608529360237, 5063.215201643751, 6109.053096769919, 6164.229471316085, 6182.1345011698395, 6197.752089916353, 10032.247581431422, 10202.081812636898, 10273.845892311621, 10302.742431439125, 10373.896261037444, 9974.850377244347, 10191.745206369751, 10237.692869213786, 10238.53926573306, 10244.948775256225, 8059.597020149086, 8189.549574773445, 8239.134890836376, 8283.08886022537, 8289.295409890106, 7968.721094761656, 7998.9200108000505, 8042.798930026796, 8101.0, 8144.714934977247, 9546.713598592054, 9547.904520954702, 9602.66390676323, 9618.182454491447, 9647.9035209647, 9268.907310926805, 9562.95218523903, 9741.902580974383, 9881.654142104991, 10060.496975151067, 6704.128463299739, 6895.275996020547, 7162.391196748335, 7317.451191160578, 7475.476716629783, 8105.0, 8154.673813047411, 8160.755177344689, 8165.755027349189, 8172.101068882299, 7131.179914309921, 7222.566646001257, 7242.710291588278, 7365.226651201547, 7370.926290737239, 7161.9641901790155, 7185.245549217257, 7202.567845929261, 7203.099612548432, 7270.0, 7570.129435115033, 7650.961745191238, 7729.227077292226, 7740.961295193488, 7750.883736743841, 6948.652567371712, 6963.2340442552295, 7024.5434046786795, 7030.648467576703, 7041.0, 6936.34104760047, 7084.36240738336, 7091.11361079865, 7093.751718690028, 7122.893156602759], "unit": "score", "name": "mootools.html", "value": 4755.639223287515}, {"lowerIsBetter": false, "replicates": [316.8067478837909, 320.64248363075166, 327.46459538654295, 327.9744358294733, 330.92388750587367, 5141.203113517401, 5308.787648494061, 6565.901511477332, 6580.638064906429, 6613.636250006248, 6462.676866156685, 6483.1571895653615, 6538.836529086775, 6562.901556476657, 6578.901316480256, 4889.633277504201, 4907.582855457291, 4929.285253638221, 4930.457649658535, 4931.235658472924, 4875.70745755254, 4884.951150488485, 4933.062718083553, 4947.282644016634, 4973.204287314031, 4490.7081039732475, 4605.746683932382, 4729.95940893003, 4905.117078925792, 4911.386076740408, 3865.922681546381, 4735.573798358164, 4785.617150627942, 4795.400574928134, 4800.351952486404, 4101.138760860223, 4114.362273847559, 4121.361189015707, 4121.464209652756, 4127.33962565988, 5945.346011938684, 6096.786612468542, 6101.267847858241, 6165.84585385369, 6231.906521402183, 5696.2594862668, 5707.286589176353, 5707.343655479632, 5711.343195532525, 5727.083666613322, 7020.754273600451, 7029.156501219835, 7030.226675065739, 7039.436845052434, 7051.753188638373, 4646.4424269087585, 4650.279206722963, 4656.604188643969, 4659.18464268755, 4662.6269898408045, 5555.416681248451, 7182.604956727378, 7247.528910620793, 7404.629768511553, 7426.212631557891, 4711.976440117777, 4758.2386818108935, 4761.642876784185, 4762.166620841408, 4762.357081793983, 4821.397325334333, 4832.275158726219, 4838.588719958843, 4850.53919877611, 4854.514548545118, 4550.88622784433, 5636.802711905064, 5653.491185793298, 5669.829905102853, 5712.857178570569, 5713.314402271754, 7076.433885289164, 7079.327463890922, 7088.326608972139, 7094.0, 1552.6661767719768, 1617.3207252953787, 1621.8378162183924, 1659.2118743596695, 1677.5554478063323, 1029.4441001858913, 1029.7888932768765, 1034.343192073039, 1043.5095505112586, 1044.8693913260843, 6533.954567269214, 6583.80248592543, 6595.901061484082, 6612.537122401482, 6640.800775976729, 7277.0, 7285.7814265572115, 7287.526310789888, 7300.853982920312, 7330.560166390033, 6592.9340706593275, 6599.91101468262, 6610.669466526654, 6612.8346791330605, 6623.933760662428, 2944.823310601371, 3826.4642949987024, 3837.6162383761402, 3871.4773505576386, 3882.941755873664, 7479.476436649491, 7743.728969486154, 7759.495632783851, 7767.262110099531, 7845.646945887449, 7568.243175682498, 7655.540667559964, 7655.923440765521, 7660.540367577852, 7671.53970761756, 7807.609619519001, 7822.569758663328, 7826.804329891685, 7840.3335716464735, 7850.097238817609, 7128.0, 7431.219721929119, 7433.368163706145, 7439.590822504814, 7459.9929009585185, 7250.927490725026, 7287.307705767944, 7291.963540182265, 7300.853982920205, 7303.0, 7813.960930195312, 7823.178566250462, 7840.568768717775, 7849.646765895434, 7852.882206766789, 4317.978410107929, 4437.689361744647, 4535.886602834956, 4600.769961501978, 4656.464506581787, 7261.092363454568, 7296.233895440902, 7298.781036568911, 7300.598467084149, 7320.853582928418, 7028.8945665814035, 7076.681549330396, 7080.079589653312, 7081.575105493687, 7085.610291433814, 6197.977333740063, 6678.7328506859185, 7062.788116356517, 7069.081019467436, 7076.25699301566, 6544.574602650812, 6885.862282754417, 6895.172779213613, 6904.585724856524, 6911.550749201286, 6972.895406568804, 7039.753608623673, 7052.612106333996, 7057.941308803721, 7069.399101076466, 6895.931040689729, 6897.89653155193, 6903.551269167488, 6914.688839002358, 6921.065656136358], "unit": "score", "name": "prototype.html", "value": 5135.180865483936}, {"lowerIsBetter": false, "replicates": [596.2606368103552, 703.1456780012286, 733.8312188196714, 735.4925101679837, 790.7034861926774, 14808.333624986875, 15026.549203523899, 15042.0, 15065.096094234339, 15065.92467037666, 13638.318084095781, 13644.0, 13656.112352697068, 13668.111572747765, 13670.658233544167, 692.3976140757522, 714.9821254468628, 841.726438907353, 872.2367928062945, 872.8472517309477, 860.2429861721677, 871.8997315308757, 875.4134729731095, 875.9430637008605, 881.5107615273521, 921.4010892919589, 923.7736754495158, 925.3476299209075, 926.5089502563652, 926.666400095965, 724.971001159953, 901.6934242357597, 921.5392303848109, 922.0410772796283, 922.0548937339214, 689.4312192441206, 689.8171984424132, 689.9517033807635, 690.002945743401, 691.4399336537421, 14555.981081324311, 14652.12087274767, 14652.780208296885, 14660.266986650731, 14685.412583496647, 12475.812862807064, 12641.936790316082, 12710.872891271061, 12771.808422873664, 12816.423260953186, 10112.595496180144, 10127.848082278771, 10158.542865571068, 10175.440350780704, 10185.796284074351, 3517.0, 3547.255076433952, 3552.289542091592, 3552.502649629054, 3552.502649629054, 76938.0, 78135.8279625806, 78747.60626196889, 79331.60334198292, 82983.0, 9380.493287975753, 9499.335046546676, 9505.239580833517, 9543.713688589354, 9763.023697630322, 20021.0, 20068.498287542927, 20097.59804803896, 20116.29592964239, 20117.49706257355, 7417.258274172647, 7633.0, 7647.273509016634, 7659.348955338859, 7746.961265193638, 4120.21715873989, 4121.072758629344, 4153.6677065834665, 4162.1259535497275, 4172.415861779354, 3460.584729832386, 3526.982365088158, 3527.241643046706, 3530.1704099536587, 3533.0, 557.5734563059262, 629.1632129268063, 652.9902051469137, 660.9239937407164, 674.8987651852261, 587.8853623543368, 595.5146555557207, 627.438442593876, 629.4020680353682, 639.2456900857034, 8160.5103693778565, 8243.876341854877, 8313.709020184264, 8474.533900635524, 8547.57262136903, 533.9893202135936, 577.6158854361802, 608.9269287685506, 621.7854840080165, 630.961549854846, 3752.4746535485056, 3966.900827479278, 4008.6318343642406, 4030.657667649801, 4116.814743336557, 91495.17009659903, 92301.0, 93825.0, 93876.53061734648, 93952.78613780302, 57350.0, 76973.46053078907, 78483.21516784873, 79009.60495197488, 79232.6038369816, 68576.97134542886, 69036.96444553336, 69533.0, 70755.6462217696, 72471.0, 7931.682732690628, 7999.040115186215, 8064.112947575644, 8158.877616835871, 8170.509769413854, 41495.0, 46581.30128048014, 47774.52225477701, 50811.475655730384, 52601.0, 65528.34471655222, 66781.0, 70755.29244707487, 71879.92180117198, 72830.63584682043, 11176.888231117584, 11256.324620522795, 11371.54513819438, 11373.829392558953, 11667.299962002307, 12254.20347677398, 12422.36024844702, 12599.18105323151, 12669.0, 12836.614901552968, 9538.523073846418, 11470.082393408675, 11972.700682482659, 12017.21888077307, 12121.636350909486, 11627.418629068681, 11743.412829358327, 11815.82276265874, 11987.34069626179, 12146.696332591757, 11389.487473063566, 11416.486258118239, 11426.714332141762, 11700.765984680429, 12359.69100772488, 9484.95257523708, 9676.0, 9836.803263934538, 10042.0, 10901.182411319025, 8687.66599647539, 9341.532923353941, 9375.953120234355, 10042.598296068076, 10260.0], "unit": "score", "name": "yui.html", "value": 6876.700060683797}], "name": "dromaeo_css", "value": 7407.491051263069}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 109.0, 110.0, 112.0, 109.0, 116.0, 110.0, 109.0, 109.0], "unit": "score", "name": "ai-astar", "value": 110.3}, {"lowerIsBetter": true, "replicates": [159.0, 121.0, 147.0, 185.0, 142.0, 146.0, 141.0, 122.0, 162.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 144.5}, {"lowerIsBetter": true, "replicates": [186.0, 184.0, 186.0, 187.0, 186.0, 184.0, 185.0, 187.0, 185.0, 182.0], "unit": "score", "name": "audio-dft", "value": 185.2}, {"lowerIsBetter": true, "replicates": [71.0, 76.0, 87.0, 82.0, 55.0, 56.0, 58.0, 78.0, 77.0, 57.0], "unit": "score", "name": "audio-fft", "value": 69.7}, {"lowerIsBetter": true, "replicates": [97.0, 136.0, 168.0, 107.0, 78.0, 80.0, 112.0, 78.0, 78.0, 113.0], "unit": "score", "name": "audio-oscillator", "value": 104.7}, {"lowerIsBetter": true, "replicates": [89.0, 92.0, 86.0, 91.0, 86.0, 91.0, 85.0, 91.0, 85.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.8}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 93.0, 95.0, 94.0, 94.0, 94.0, 93.0, 95.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.9}, {"lowerIsBetter": true, "replicates": [89.0, 96.0, 142.0, 134.0, 87.0, 88.0, 134.0, 135.0, 142.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 113.2}, {"lowerIsBetter": true, "replicates": [54.0, 53.0, 52.0, 54.0, 72.0, 83.0, 84.0, 92.0, 54.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 65.2}, {"lowerIsBetter": true, "replicates": [62.0, 65.0, 75.0, 77.0, 70.0, 68.0, 69.0, 68.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [81.0, 78.0, 78.0, 94.0, 108.0, 109.0, 84.0, 82.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.3}, {"lowerIsBetter": true, "replicates": [130.0, 136.0, 130.0, 107.0, 128.0, 119.0, 130.0, 121.0, 125.0, 132.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.8}, {"lowerIsBetter": true, "replicates": [138.0, 127.0, 138.0, 170.0, 166.0, 137.0, 135.0, 137.0, 133.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 141.8}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 67.0, 67.0, 66.0, 68.0, 84.0, 96.0, 91.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 74.0}], "name": "kraken", "value": 1474.6}]} 12:23:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1474.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7407.49"}}} 12:23:33 INFO - Return code: 0 12:23:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:23:34 INFO - # TBPL SUCCESS # 12:23:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:34 INFO - 2016-02-23 12:23:34.025 screenresolution[2921:29059] starting screenresolution argv=screenresolution get 12:23:34 INFO - 2016-02-23 12:23:34.028 screenresolution[2921:29059] Display 0: 1600x1200x32@60 12:23:34 INFO - 2016-02-23 12:23:34.033 screenresolution[2922:29062] starting screenresolution argv=screenresolution list 12:23:34 INFO - Available Modes on Display 0 12:23:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:34 INFO - Intel Iris: 12:23:34 INFO - Chipset Model: Intel Iris 12:23:34 INFO - Type: GPU 12:23:34 INFO - Bus: Built-In 12:23:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:34 INFO - Vendor: Intel (0x8086) 12:23:34 INFO - Device ID: 0x0a2e 12:23:34 INFO - Revision ID: 0x0009 12:23:34 INFO - Displays: 12:23:34 INFO - Display: 12:23:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:34 INFO - Display Serial Number: 1600x1200 60 12:23:34 INFO - Main Display: Yes 12:23:34 INFO - Mirror: Off 12:23:34 INFO - Online: Yes 12:23:34 INFO - Rotation: Supported 12:23:34 INFO - Return code: 0 12:23:34 INFO - Running post-action listener: _resource_record_post_action 12:23:34 INFO - Running post-run listener: _resource_record_post_run 12:23:35 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 196608; Write bytes: 705945600; Read time: 2; Write time: 2286 12:23:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397212160; Write bytes: 391042560; Read time: 29716; Write time: 696 12:23:35 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 196608; Write bytes: 299149824; Read time: 2; Write time: 1548 12:23:35 INFO - Running post-run listener: _upload_blobber_files 12:23:35 INFO - Blob upload gear active. 12:23:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:23:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:23:35 INFO - (blobuploader) - INFO - Open directory for files ... 12:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:36 INFO - (blobuploader) - INFO - Done attempting. 12:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:37 INFO - (blobuploader) - INFO - Done attempting. 12:23:37 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:37 INFO - Return code: 0 12:23:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e805c8b451eb0a4abb8c3c9b13cb48f3ee7c7eaed67c20dd2e211c0cd4726c7d01e2cb68c66fe662334815de408054c67292df297110979149cd1ba3f09950f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4442510f99501ffd9f787ff95a8c0e52e059f202ab3e6e84c0834ea82616b72917e3f7bb55d8b463d44c03627058c8bd2ca9a291a2d8bd5065b6eee2eab0312"} 12:23:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:37 INFO - Contents: 12:23:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e805c8b451eb0a4abb8c3c9b13cb48f3ee7c7eaed67c20dd2e211c0cd4726c7d01e2cb68c66fe662334815de408054c67292df297110979149cd1ba3f09950f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4442510f99501ffd9f787ff95a8c0e52e059f202ab3e6e84c0834ea82616b72917e3f7bb55d8b463d44c03627058c8bd2ca9a291a2d8bd5065b6eee2eab0312"} 12:23:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:37 INFO - Copying logs to upload dir... 12:23:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.231099 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-02-23 12:23:37.573650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-23 12:23:37.577370) ========= 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.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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/3e805c8b451eb0a4abb8c3c9b13cb48f3ee7c7eaed67c20dd2e211c0cd4726c7d01e2cb68c66fe662334815de408054c67292df297110979149cd1ba3f09950f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4442510f99501ffd9f787ff95a8c0e52e059f202ab3e6e84c0834ea82616b72917e3f7bb55d8b463d44c03627058c8bd2ca9a291a2d8bd5065b6eee2eab0312"} build_url:https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011716 build_url: 'https://queue.taskcluster.net/v1/task/IFeIhZHDQ3m-ZVKQvGYaKA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e805c8b451eb0a4abb8c3c9b13cb48f3ee7c7eaed67c20dd2e211c0cd4726c7d01e2cb68c66fe662334815de408054c67292df297110979149cd1ba3f09950f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a4442510f99501ffd9f787ff95a8c0e52e059f202ab3e6e84c0834ea82616b72917e3f7bb55d8b463d44c03627058c8bd2ca9a291a2d8bd5065b6eee2eab0312"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-23 12:23:37.608079) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:23:37.608396) ========= 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.gjTtVodU77/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.r8Uf8V50H4/Listeners TMPDIR=/var/folders/mg/4p66s20s2m19br0bc1hxf60m00000w/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.005419 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-23 12:23:37.660162) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-23 12:23:37.660472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-23 12:23:37.661456) ========= ========= Total master_lag: 3.38 =========