builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0161 starttime: 1455713942.4 results: success (0) buildid: 20160217032051 builduid: 42ad477cacad453a8c2cd5bce5bb2f6b revision: 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.395501) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.395885) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.396182) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.006007 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.429172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.429453) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.505545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.505822) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005441 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.565049) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.565310) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.565634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.565915) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-17 04:59:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.6M=0.001s 2016-02-17 04:59:02 (11.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.198206 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.789281) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.789558) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.019245 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:02.837285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 04:59:02.837586) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d --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 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-17 04:59:02,835 truncating revision to first 12 chars 2016-02-17 04:59:02,835 Setting DEBUG logging. 2016-02-17 04:59:02,835 attempt 1/10 2016-02-17 04:59:02,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/937e039f8b8c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-17 04:59:03,902 unpacking tar archive at: fx-team-937e039f8b8c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.312437 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 04:59:04.167201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:04.167519) ========= echo 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d program finished with exit code 0 elapsedTime=0.004889 script_repo_revision: '937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:04.222075) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:04.222350) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 04:59:04.240254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-02-17 04:59:04.240520) ========= /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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:59:04 INFO - MultiFileLogger online at 20160217 04:59:04 in /builds/slave/test 04:59:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:59:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:04 INFO - {'append_to_log': False, 04:59:04 INFO - 'base_work_dir': '/builds/slave/test', 04:59:04 INFO - 'blob_upload_branch': 'Fx-Team', 04:59:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:04 INFO - 'branch': 'Fx-Team', 04:59:04 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:04 INFO - 'config_files': ('talos/mac_config.py',), 04:59:04 INFO - 'default_actions': ('clobber', 04:59:04 INFO - 'read-buildbot-config', 04:59:04 INFO - 'download-and-extract', 04:59:04 INFO - 'populate-webroot', 04:59:04 INFO - 'create-virtualenv', 04:59:04 INFO - 'install', 04:59:04 INFO - 'run-tests'), 04:59:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:04 INFO - 'download_minidump_stackwalk': True, 04:59:04 INFO - 'download_symbols': 'ondemand', 04:59:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:04 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:04 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:04 INFO - 'installer_path': 'installer.exe', 04:59:04 INFO - 'log_level': 'info', 04:59:04 INFO - 'log_name': 'talos', 04:59:04 INFO - 'log_to_console': True, 04:59:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:04 INFO - 'opt_config_files': (), 04:59:04 INFO - 'pip_index': False, 04:59:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:04 INFO - 'cmd': ('bash', 04:59:04 INFO - '-c', 04:59:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:04 INFO - 'enabled': True, 04:59:04 INFO - 'halt_on_failure': False, 04:59:04 INFO - 'name': 'check_screen_resolution'},), 04:59:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:04 INFO - 'cmd': ('bash', 04:59:04 INFO - '-c', 04:59:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:04 INFO - 'enabled': True, 04:59:04 INFO - 'halt_on_failure': False, 04:59:04 INFO - 'name': 'check_screen_resolution'},), 04:59:04 INFO - 'run_cmd_checks_enabled': True, 04:59:04 INFO - 'sps_profile': False, 04:59:04 INFO - 'sps_profile_interval': 0, 04:59:04 INFO - 'suite': 'dromaeojs', 04:59:04 INFO - 'system_bits': '32', 04:59:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:04 INFO - 'title': 't-yosemite-r7-0161', 04:59:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:04 INFO - 'use_talos_json': True, 04:59:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:04 INFO - 'work_dir': 'build'} 04:59:04 INFO - ##### 04:59:04 INFO - ##### Running clobber step. 04:59:04 INFO - ##### 04:59:04 INFO - Running pre-action listener: _resource_record_pre_action 04:59:04 INFO - Running main action method: clobber 04:59:04 INFO - rmtree: /builds/slave/test/build 04:59:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:05 INFO - Running post-action listener: _resource_record_post_action 04:59:05 INFO - ##### 04:59:05 INFO - ##### Running read-buildbot-config step. 04:59:05 INFO - ##### 04:59:05 INFO - Running pre-action listener: _resource_record_pre_action 04:59:05 INFO - Running main action method: read_buildbot_config 04:59:05 INFO - Using buildbot properties: 04:59:05 INFO - { 04:59:05 INFO - "project": "", 04:59:05 INFO - "product": "firefox", 04:59:05 INFO - "script_repo_revision": "production", 04:59:05 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:59:05 INFO - "repository": "", 04:59:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:59:05 INFO - "buildid": "20160217032051", 04:59:05 INFO - "pgo_build": "False", 04:59:05 INFO - "basedir": "/builds/slave/test", 04:59:05 INFO - "buildnumber": 374, 04:59:05 INFO - "slavename": "t-yosemite-r7-0161", 04:59:05 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:59:05 INFO - "platform": "yosemite_r7", 04:59:05 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:59:05 INFO - "branch": "fx-team", 04:59:05 INFO - "repo_path": "integration/fx-team", 04:59:05 INFO - "revision": "937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d", 04:59:05 INFO - "stage_platform": "macosx64", 04:59:05 INFO - "builduid": "42ad477cacad453a8c2cd5bce5bb2f6b", 04:59:05 INFO - "slavebuilddir": "test" 04:59:05 INFO - } 04:59:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 04:59:05 INFO - Running post-action listener: _resource_record_post_action 04:59:05 INFO - ##### 04:59:05 INFO - ##### Running download-and-extract step. 04:59:05 INFO - ##### 04:59:05 INFO - Running pre-action listener: _resource_record_pre_action 04:59:05 INFO - Running main action method: download_and_extract 04:59:05 INFO - mkdir: /builds/slave/test/build/tests 04:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:05 INFO - https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:59:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/test_packages.json 04:59:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/test_packages.json 04:59:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:59:07 INFO - Downloaded 1277 bytes. 04:59:07 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:59:07 INFO - Using the following test package requirements: 04:59:07 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:59:07 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:07 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 04:59:07 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'jsshell-mac.zip'], 04:59:07 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 04:59:07 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:59:07 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 04:59:07 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 04:59:07 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 04:59:07 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:59:07 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:59:07 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:07 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59: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')]} 04:59:07 INFO - https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:59:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:59:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:08 INFO - Downloaded 17806595 bytes. 04:59:08 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'] 04:59:08 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 04:59:11 INFO - Return code: 0 04:59:11 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 04:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:11 INFO - https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:59:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:59:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:15 INFO - Downloaded 17806595 bytes. 04:59:15 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'] 04:59:15 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 04:59:19 INFO - Return code: 0 04:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:19 INFO - https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:59:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 04:59:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 04:59:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:21 INFO - Downloaded 10707071 bytes. 04:59:21 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'] 04:59:21 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 04:59:21 INFO - Return code: 0 04:59: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')]} 04:59:21 INFO - https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:59:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 04:59:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 04:59:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 04:59:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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 04:59:26 INFO - Downloaded 111231923 bytes. 04:59:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 04:59:26 INFO - mkdir: /builds/slave/test/properties 04:59:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:59:26 INFO - Writing to file /builds/slave/test/properties/build_url 04:59:26 INFO - Contents: 04:59:26 INFO - build_url:https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 04:59:27 INFO - Running post-action listener: _resource_record_post_action 04:59:27 INFO - Running post-action listener: set_extra_try_arguments 04:59:27 INFO - ##### 04:59:27 INFO - ##### Running populate-webroot step. 04:59:27 INFO - ##### 04:59:27 INFO - Running pre-action listener: _resource_record_pre_action 04:59:27 INFO - Running main action method: populate_webroot 04:59:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:59:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:59:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:59:27 INFO - '--tppagecycles', 04:59:27 INFO - '7'], 04:59:27 INFO - 'tests': ['tsvgm']}}, 04:59:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:59:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:59:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:59:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:59:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'tests': ['damp', 'tps']}, 04:59:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['damp', 'tps']}, 04:59:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:59:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:59:27 INFO - 'other': {'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'other_l64': {'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'other_nol64': {'tests': ['a11yr', 04:59:27 INFO - 'ts_paint', 04:59:27 INFO - 'tpaint', 04:59:27 INFO - 'sessionrestore', 04:59:27 INFO - 'sessionrestore_no_auto_restore']}, 04:59:27 INFO - 'svgr': {'tests': ['tsvgx', 04:59:27 INFO - 'tsvgr_opacity', 04:59:27 INFO - 'tart', 04:59:27 INFO - 'tscrollx', 04:59:27 INFO - 'cart']}, 04:59:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['tsvgx', 04:59:27 INFO - 'tsvgr_opacity', 04:59:27 INFO - 'tart', 04:59:27 INFO - 'tscrollx', 04:59:27 INFO - 'cart']}, 04:59:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'tests': ['tp5o']}, 04:59:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'talos_options': ['--e10s'], 04:59:27 INFO - 'tests': ['tp5o']}, 04:59:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'talos_options': ['--xperf_path', 04:59:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:27 INFO - 'tests': ['tp5n']}, 04:59:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:27 INFO - 'talos_options': ['--e10s', 04:59:27 INFO - '--xperf_path', 04:59:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:27 INFO - 'tests': ['tp5n']}}, 04:59:27 INFO - 'talos.zip': {'path': '', 04:59:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:59:27 INFO - Running post-action listener: _resource_record_post_action 04:59:27 INFO - ##### 04:59:27 INFO - ##### Running create-virtualenv step. 04:59:27 INFO - ##### 04:59:27 INFO - Running pre-action listener: _resource_record_pre_action 04:59:27 INFO - Running main action method: create_virtualenv 04:59:27 INFO - Creating virtualenv /builds/slave/test/build/venv 04:59:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:59:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:59:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:59:27 INFO - Using real prefix '/tools/python27' 04:59:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:59:28 INFO - Installing distribute.............................................................................................................................................................................................done. 04:59:31 INFO - Installing pip.................done. 04:59:31 INFO - Return code: 0 04:59:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:31 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:59:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:31 INFO - 'HOME': '/Users/cltbld', 04:59:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:31 INFO - 'LOGNAME': 'cltbld', 04:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:31 INFO - 'MOZ_NO_REMOTE': '1', 04:59:31 INFO - 'NO_EM_RESTART': '1', 04:59:31 INFO - 'PAGER': '/bin/cat', 04:59:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:31 INFO - 'PWD': '/builds/slave/test', 04:59:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:31 INFO - 'SHELL': '/bin/bash', 04:59:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:31 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:31 INFO - 'USER': 'cltbld', 04:59:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:31 INFO - 'XPC_FLAGS': '0x0', 04:59:31 INFO - 'XPC_SERVICE_NAME': '0', 04:59:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:59:31 INFO - Cleaning up... 04:59:31 INFO - Return code: 0 04:59:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:31 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:31 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 04:59:31 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 04:59:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:31 INFO - 'HOME': '/Users/cltbld', 04:59:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:31 INFO - 'LOGNAME': 'cltbld', 04:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:31 INFO - 'MOZ_NO_REMOTE': '1', 04:59:31 INFO - 'NO_EM_RESTART': '1', 04:59:31 INFO - 'PAGER': '/bin/cat', 04:59:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:31 INFO - 'PWD': '/builds/slave/test', 04:59:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:31 INFO - 'SHELL': '/bin/bash', 04:59:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:31 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:31 INFO - 'USER': 'cltbld', 04:59:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:31 INFO - 'XPC_FLAGS': '0x0', 04:59:31 INFO - 'XPC_SERVICE_NAME': '0', 04:59:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:32 INFO - Downloading/unpacking psutil>=0.7.1 04:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:59:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:59:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:59:35 INFO - Installing collected packages: psutil 04:59:35 INFO - Running setup.py install for psutil 04:59:35 INFO - building 'psutil._psutil_osx' extension 04:59:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:59:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:59:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:59:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:59:35 INFO - building 'psutil._psutil_posix' extension 04:59:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:59:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:59:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:59:35 INFO - ^ 04:59:36 INFO - 1 warning generated. 04:59:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:59:36 INFO - Successfully installed psutil 04:59:36 INFO - Cleaning up... 04:59:36 INFO - Return code: 0 04:59:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:36 INFO - 'HOME': '/Users/cltbld', 04:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:36 INFO - 'LOGNAME': 'cltbld', 04:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:36 INFO - 'MOZ_NO_REMOTE': '1', 04:59:36 INFO - 'NO_EM_RESTART': '1', 04:59:36 INFO - 'PAGER': '/bin/cat', 04:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:36 INFO - 'PWD': '/builds/slave/test', 04:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:36 INFO - 'SHELL': '/bin/bash', 04:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:36 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:36 INFO - 'USER': 'cltbld', 04:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:36 INFO - 'XPC_FLAGS': '0x0', 04:59:36 INFO - 'XPC_SERVICE_NAME': '0', 04:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:59:38 INFO - Installing collected packages: mozsystemmonitor 04:59:38 INFO - Running setup.py install for mozsystemmonitor 04:59:39 INFO - Successfully installed mozsystemmonitor 04:59:39 INFO - Cleaning up... 04:59:39 INFO - Return code: 0 04:59:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:39 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:59:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:39 INFO - 'HOME': '/Users/cltbld', 04:59:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:39 INFO - 'LOGNAME': 'cltbld', 04:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:39 INFO - 'MOZ_NO_REMOTE': '1', 04:59:39 INFO - 'NO_EM_RESTART': '1', 04:59:39 INFO - 'PAGER': '/bin/cat', 04:59:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:39 INFO - 'PWD': '/builds/slave/test', 04:59:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:39 INFO - 'SHELL': '/bin/bash', 04:59:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:39 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:39 INFO - 'USER': 'cltbld', 04:59:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:39 INFO - 'XPC_FLAGS': '0x0', 04:59:39 INFO - 'XPC_SERVICE_NAME': '0', 04:59:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:39 INFO - Downloading/unpacking blobuploader==1.2.4 04:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:41 INFO - Downloading blobuploader-1.2.4.tar.gz 04:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:59:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:59:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:42 INFO - Downloading docopt-0.6.1.tar.gz 04:59:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:59:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:59:43 INFO - Running setup.py install for blobuploader 04:59:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:59:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:59:43 INFO - Running setup.py install for requests 04:59:43 INFO - Running setup.py install for docopt 04:59:43 INFO - Successfully installed blobuploader requests docopt 04:59:43 INFO - Cleaning up... 04:59:43 INFO - Return code: 0 04:59:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:43 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:43 INFO - 'HOME': '/Users/cltbld', 04:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:43 INFO - 'LOGNAME': 'cltbld', 04:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:43 INFO - 'MOZ_NO_REMOTE': '1', 04:59:43 INFO - 'NO_EM_RESTART': '1', 04:59:43 INFO - 'PAGER': '/bin/cat', 04:59:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:43 INFO - 'PWD': '/builds/slave/test', 04:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:43 INFO - 'SHELL': '/bin/bash', 04:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:43 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:43 INFO - 'USER': 'cltbld', 04:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:43 INFO - 'XPC_FLAGS': '0x0', 04:59:43 INFO - 'XPC_SERVICE_NAME': '0', 04:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-rjGotw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-8c5lDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-aRQQ9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-t1kPcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Mi6HUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-536EpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-aJRe7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-s4zd3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:59:44 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-5jlj1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-yUYzuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-zs9Ied-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-cPpI5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-_zXipo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CP8owS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Gv8oPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-OT8oUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:59:45 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-V_fIph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:59:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:59:45 INFO - Running setup.py install for manifestparser 04:59:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:59:46 INFO - Running setup.py install for mozcrash 04:59:46 INFO - Running setup.py install for mozdebug 04:59:46 INFO - Running setup.py install for mozdevice 04:59:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:59:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:59:46 INFO - Running setup.py install for mozfile 04:59:46 INFO - Running setup.py install for mozhttpd 04:59:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:59:46 INFO - Running setup.py install for mozinfo 04:59:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:59:46 INFO - Running setup.py install for mozInstall 04:59:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Running setup.py install for mozleak 04:59:47 INFO - Running setup.py install for mozlog 04:59:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Running setup.py install for moznetwork 04:59:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Running setup.py install for mozprocess 04:59:47 INFO - Running setup.py install for mozprofile 04:59:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:59:47 INFO - Running setup.py install for mozrunner 04:59:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:59:48 INFO - Running setup.py install for mozscreenshot 04:59:48 INFO - Running setup.py install for moztest 04:59:48 INFO - Running setup.py install for mozversion 04:59:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:59:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:59:48 INFO - Cleaning up... 04:59:48 INFO - Return code: 0 04:59:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:48 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:48 INFO - 'HOME': '/Users/cltbld', 04:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:48 INFO - 'LOGNAME': 'cltbld', 04:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:48 INFO - 'MOZ_NO_REMOTE': '1', 04:59:48 INFO - 'NO_EM_RESTART': '1', 04:59:48 INFO - 'PAGER': '/bin/cat', 04:59:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:48 INFO - 'PWD': '/builds/slave/test', 04:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:48 INFO - 'SHELL': '/bin/bash', 04:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:48 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:48 INFO - 'USER': 'cltbld', 04:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:48 INFO - 'XPC_FLAGS': '0x0', 04:59:48 INFO - 'XPC_SERVICE_NAME': '0', 04:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:59:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-HDU8GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:59:48 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-JJShH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:59:49 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)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Gm0LoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-KrQPtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-DYgMvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-bPhaRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-fNll1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-xi59s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-wMJLmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Xlkfpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:59:49 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)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-AaIXn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:59:49 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-_IR20X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:59:50 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)) 04:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:59:50 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Dz6Orh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:59:50 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-5Wy8ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:59:50 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)) 04:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:50 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-XqmzKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:59:50 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-kwY4XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:59:50 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-t82VUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:59:50 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)) 04:59:50 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)) 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:52 INFO - Downloading blessings-1.6.tar.gz 04:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:59:52 INFO - Installing collected packages: blessings 04:59:52 INFO - Running setup.py install for blessings 04:59:53 INFO - Successfully installed blessings 04:59:53 INFO - Cleaning up... 04:59:53 INFO - Return code: 0 04:59:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:59:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:59:53 INFO - Reading from file tmpfile_stdout 04:59:53 INFO - Current package versions: 04:59:53 INFO - blessings == 1.6 04:59:53 INFO - blobuploader == 1.2.4 04:59:53 INFO - docopt == 0.6.1 04:59:53 INFO - manifestparser == 1.1 04:59:53 INFO - mozInstall == 1.12 04:59:53 INFO - mozcrash == 0.16 04:59:53 INFO - mozdebug == 0.1 04:59:53 INFO - mozdevice == 0.48 04:59:53 INFO - mozfile == 1.2 04:59:53 INFO - mozhttpd == 0.7 04:59:53 INFO - mozinfo == 0.9 04:59:53 INFO - mozleak == 0.1 04:59:53 INFO - mozlog == 3.1 04:59:53 INFO - moznetwork == 0.27 04:59:53 INFO - mozprocess == 0.22 04:59:53 INFO - mozprofile == 0.28 04:59:53 INFO - mozrunner == 6.11 04:59:53 INFO - mozscreenshot == 0.1 04:59:53 INFO - mozsystemmonitor == 0.0 04:59:53 INFO - moztest == 0.7 04:59:53 INFO - mozversion == 1.4 04:59:53 INFO - psutil == 3.1.1 04:59:53 INFO - requests == 1.2.3 04:59:53 INFO - wsgiref == 0.1.2 04:59:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:53 INFO - 'HOME': '/Users/cltbld', 04:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:53 INFO - 'LOGNAME': 'cltbld', 04:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:53 INFO - 'MOZ_NO_REMOTE': '1', 04:59:53 INFO - 'NO_EM_RESTART': '1', 04:59:53 INFO - 'PAGER': '/bin/cat', 04:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:53 INFO - 'PWD': '/builds/slave/test', 04:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:53 INFO - 'SHELL': '/bin/bash', 04:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:53 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:53 INFO - 'USER': 'cltbld', 04:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:53 INFO - 'XPC_FLAGS': '0x0', 04:59:53 INFO - 'XPC_SERVICE_NAME': '0', 04:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:59:53 INFO - Cleaning up... 04:59:53 INFO - Return code: 0 04:59:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:53 INFO - 'HOME': '/Users/cltbld', 04:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:53 INFO - 'LOGNAME': 'cltbld', 04:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:53 INFO - 'MOZ_NO_REMOTE': '1', 04:59:53 INFO - 'NO_EM_RESTART': '1', 04:59:53 INFO - 'PAGER': '/bin/cat', 04:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:53 INFO - 'PWD': '/builds/slave/test', 04:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:53 INFO - 'SHELL': '/bin/bash', 04:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:53 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:53 INFO - 'USER': 'cltbld', 04:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:53 INFO - 'XPC_FLAGS': '0x0', 04:59:53 INFO - 'XPC_SERVICE_NAME': '0', 04:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:54 INFO - Downloading/unpacking jsonschema 04:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:59:56 INFO - Downloading/unpacking functools32 (from jsonschema) 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59:56 INFO - Downloading functools32-3.2.3-2.tar.gz 04:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:59:56 INFO - warning: no files found matching '*.txt' 04:59:56 INFO - no previously-included directories found matching 'build' 04:59:56 INFO - no previously-included directories found matching 'dist' 04:59:56 INFO - no previously-included directories found matching '.git*' 04:59:56 INFO - Installing collected packages: jsonschema, functools32 04:59:56 INFO - Running setup.py install for functools32 04:59:57 INFO - warning: no files found matching '*.txt' 04:59:57 INFO - no previously-included directories found matching 'build' 04:59:57 INFO - no previously-included directories found matching 'dist' 04:59:57 INFO - no previously-included directories found matching '.git*' 04:59:57 INFO - Successfully installed jsonschema functools32 04:59:57 INFO - Cleaning up... 04:59:57 INFO - Return code: 0 04:59:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:57 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 0x101585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10158d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90924b7060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, '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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:57 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 04:59:57 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 04:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 04:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:57 INFO - 'HOME': '/Users/cltbld', 04:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:57 INFO - 'LOGNAME': 'cltbld', 04:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:57 INFO - 'MOZ_NO_REMOTE': '1', 04:59:57 INFO - 'NO_EM_RESTART': '1', 04:59:57 INFO - 'PAGER': '/bin/cat', 04:59:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:57 INFO - 'PWD': '/builds/slave/test', 04:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:57 INFO - 'SHELL': '/bin/bash', 04:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 04:59:57 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 04:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:57 INFO - 'USER': 'cltbld', 04:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:57 INFO - 'XPC_FLAGS': '0x0', 04:59:57 INFO - 'XPC_SERVICE_NAME': '0', 04:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:57 INFO - Downloading/unpacking flake8 04:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:59:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:00:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:00:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:00:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:00:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:00:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:00:00 INFO - no previously-included directories found matching 'docs/_build' 05:00:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:00:01 INFO - Running setup.py install for pep8 05:00:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:00:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:00:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:00:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:00:01 INFO - no previously-included directories found matching 'docs/_build' 05:00:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:00:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:00:01 INFO - Cleaning up... 05:00:01 INFO - Return code: 0 05:00:01 INFO - Running post-action listener: _resource_record_post_action 05:00:01 INFO - Running post-action listener: _start_resource_monitoring 05:00:01 INFO - Starting resource monitoring. 05:00:01 INFO - ##### 05:00:01 INFO - ##### Running install step. 05:00:01 INFO - ##### 05:00:01 INFO - Running pre-action listener: _resource_record_pre_action 05:00:01 INFO - Running main action method: install 05:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:01 INFO - Reading from file tmpfile_stdout 05:00:01 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:01 INFO - Reading from file tmpfile_stdout 05:00:01 INFO - Output received: 05:00:01 INFO - Usage: mozinstall [options] installer 05:00:01 INFO - Options: 05:00:01 INFO - -h, --help show this help message and exit 05:00:01 INFO - -d DEST, --destination=DEST 05:00:01 INFO - Directory to install application into. [default: 05:00:01 INFO - "/builds/slave/test"] 05:00:01 INFO - --app=APP Application being installed. [default: firefox] 05:00:01 INFO - mkdir: /builds/slave/test/build/application 05:00:01 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'] 05:00:01 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 05:00:35 INFO - Reading from file tmpfile_stdout 05:00:35 INFO - Output received: 05:00:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:00:35 INFO - Running post-action listener: _resource_record_post_action 05:00:35 INFO - ##### 05:00:35 INFO - ##### Running run-tests step. 05:00:35 INFO - ##### 05:00:35 INFO - Running pre-action listener: _resource_record_pre_action 05:00:35 INFO - Running main action method: run_tests 05:00:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:00:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:00:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:00:35 INFO - 2016-02-17 05:00:35.767 screenresolution[2726:24035] starting screenresolution argv=screenresolution get 05:00:35 INFO - 2016-02-17 05:00:35.769 screenresolution[2726:24035] Display 0: 1600x1200x32@60 05:00:35 INFO - 2016-02-17 05:00:35.775 screenresolution[2727:24037] starting screenresolution argv=screenresolution list 05:00:35 INFO - Available Modes on Display 0 05:00:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:00:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:00:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:00:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:00:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:00:36 INFO - Intel Iris: 05:00:36 INFO - Chipset Model: Intel Iris 05:00:36 INFO - Type: GPU 05:00:36 INFO - Bus: Built-In 05:00:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:00:36 INFO - Vendor: Intel (0x8086) 05:00:36 INFO - Device ID: 0x0a2e 05:00:36 INFO - Revision ID: 0x0009 05:00:36 INFO - Displays: 05:00:36 INFO - Display: 05:00:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:00:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:00:36 INFO - Display Serial Number: 1600x1200 60 05:00:36 INFO - Main Display: Yes 05:00:36 INFO - Mirror: Off 05:00:36 INFO - Online: Yes 05:00:36 INFO - Rotation: Supported 05:00:36 INFO - Return code: 0 05:00:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:00:36 INFO - Python 2.7.3 05:00:36 INFO - Return code: 0 05:00:36 INFO - grabbing minidump binary from tooltool 05:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:36 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 0x101429d30>, '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 0x10124ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101420b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:00:36 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 05:00:36 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 05:00:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:00:36 INFO - Return code: 0 05:00:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:00:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:00:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:00:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:00:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:00:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:00:37 INFO - Return code: 0 05:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 05:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 05:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8bnKgaybDw/Render', 05:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:37 INFO - 'HOME': '/Users/cltbld', 05:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:37 INFO - 'LOGNAME': 'cltbld', 05:00:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:00:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:37 INFO - 'MOZ_NO_REMOTE': '1', 05:00:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:00:37 INFO - 'NO_EM_RESTART': '1', 05:00:37 INFO - 'PAGER': '/bin/cat', 05:00:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:37 INFO - 'PWD': '/builds/slave/test', 05:00:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:37 INFO - 'SHELL': '/bin/bash', 05:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JMjjtx0MQ1/Listeners', 05:00:37 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 05:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:37 INFO - 'USER': 'cltbld', 05:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:37 INFO - 'XPC_FLAGS': '0x0', 05:00:37 INFO - 'XPC_SERVICE_NAME': '0', 05:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 05:00:37 INFO - mozversion application_buildid: 20160217032051 05:00:37 INFO - mozversion application_changeset: 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d 05:00:37 INFO - mozversion application_display_name: Nightly 05:00:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:00:37 INFO - mozversion application_name: Firefox 05:00:37 INFO - mozversion application_remotingname: firefox 05:00:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:00:37 INFO - mozversion application_vendor: Mozilla 05:00:37 INFO - mozversion application_version: 47.0a1 05:00:37 INFO - mozversion platform_buildid: 20160217032051 05:00:37 INFO - mozversion platform_changeset: 937e039f8b8c14ed1b8f6a4a045ce847dbf82f0d 05:00:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:00:37 INFO - mozversion platform_version: 47.0a1 05:00:37 INFO - using testdate: 1455714037 05:00:37 INFO - actual date: 1455714037 05:00:37 INFO - starting webserver on 'localhost:53581' 05:00:37 INFO - SUITE-START | Running 2 tests 05:00:37 INFO - TEST-START | dromaeo_css 05:00:37 INFO - operating with platform_type : mac_ 05:00:37 INFO - Initialising browser for dromaeo_css test... 05:00:37 INFO - TEST-INFO | started process 2745 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpR6aAN5/profile http://localhost:53581/getInfo.html) 05:00:39 INFO - PROCESS | 2745 | __metrics Screen width/height:1600/1200 05:00:39 INFO - PROCESS | 2745 | colorDepth:24 05:00:39 INFO - PROCESS | 2745 | Browser inner width/height: 1024/689 05:00:39 INFO - PROCESS | 2745 | __metrics 05:00:39 INFO - PROCESS | 2745 | 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" 05:00:39 INFO - PROCESS | 2745 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:00:40 INFO - TEST-INFO | 2745: exit 0 05:00:40 INFO - Browser initialized. 05:00:40 INFO - Running cycle 1/1 for dromaeo_css test... 05:00:40 INFO - TEST-INFO | started process 2748 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpR6aAN5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:00:52 INFO - PROCESS | 2748 | RSS: Main: 244342784 05:00:52 INFO - PROCESS | 2748 | 05:01:44 INFO - PROCESS | 2748 | 1455714104227 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] 05:03:52 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53581/tests/dromaeo/cssquery-ext.html) 05:03:54 INFO - PROCESS | 2748 | RSS: Main: 435204096 05:03:54 INFO - PROCESS | 2748 | 05:06:57 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-ext.html (next: http://localhost:53581/tests/dromaeo/cssquery-jquery.html) 05:06:58 INFO - PROCESS | 2748 | RSS: Main: 387747840 05:06:58 INFO - PROCESS | 2748 | 05:10:00 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53581/tests/dromaeo/cssquery-mootools.html) 05:10:00 INFO - PROCESS | 2748 | RSS: Main: 378048512 05:10:00 INFO - PROCESS | 2748 | 05:13:03 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53581/tests/dromaeo/cssquery-prototype.html) 05:13:03 INFO - PROCESS | 2748 | RSS: Main: 345026560 05:13:03 INFO - PROCESS | 2748 | 05:16:06 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53581/tests/dromaeo/cssquery-yui.html) 05:16:07 INFO - PROCESS | 2748 | RSS: Main: 344190976 05:16:07 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | Cycle 1(1): loaded http://localhost:53581/tests/dromaeo/cssquery-yui.html (next: http://localhost:53581/tests/dromaeo/cssquery-dojo.html) 05:19:10 INFO - PROCESS | 2748 | RSS: Main: 436015104 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | __start_tp_report 05:19:10 INFO - PROCESS | 2748 | _x_x_mozilla_page_load 05:19:10 INFO - PROCESS | 2748 | _x_x_mozilla_page_load_details 05:19:10 INFO - PROCESS | 2748 | |i|pagename|runs| 05:19:10 INFO - PROCESS | 2748 | |0;dojo.html;3320.252943087805;3376.848041838117;3400;3420.2817408344245;3445.638207988161;5429.321334833146;5442.319710036246;5462.836114916549;5498.917516237259;5504.587155963299;5484.670919744818;5522.834314970547;5563.165525171226;5615.971920140393;5624.240727501786;4919.188333924916;4957.925631115545;4983.7508124593805;5005.2492126181;5121.001404726061;5044.268581055745;5052.166392545226;5068.594512439015;5104.948950510485;5109.974450127763;4551.203539380628;4715.221988371914;4919.975400123012;5007.649464537484;5068.898622027539;4977.228529577885;5008.649394542384;5012.147934851084;5023.296738456619;5053.343065401511;4939.481354457766;4985.576226020793;4986.950130498685;5000.749962501896;5013.448520662724;5301;5335.6798592084215;5389.649672771257;5432.619716619799;5436.483534064258;5084.949150508484;5979.730912108922;6043.546733994924;6086.421789929949;6093.451589356979;6099.9085013724825;6130.908036379458;6166.260048794128;6172.722227499773;6181.258249010148;5607.523360514361;5626.1842032905815;5634.408387119375;5641;5707.571932105108;6081.665508397081;6091.193004372268;6172.105044768484;6226.782062627786;6238.376162383725;5983.521318294526;5993.700314984233;5999.070144127667;6013.037913933749;6054.8183554493435;6127.969360153171;6159.414855588712;6160.784372546939;6161;6168.845778855564;5923.851903702442;5993.460588547052;6070.878582428415;6073.392660733891;6134.141220229171;6020.367861374581;6110.938890611126;6131.908021379683;6138.741557980603;6149.985252433388;4824.855254342376;4836.347093142451;4839.41926968766;4841.636877234221;4855.975720121377;4232.830686772495;4241.703080784316;4279.679024073144;4342.848000319974;4348.956510434855;5552.3892371839065;5579.4978451938705;5580.414056524007;5585.636933599384;5660;6022.939770602326;6102.877942441215;6152.907706384318;6157.199564056644;6165.84585385369;5518.53092487135;5578.497935185853;5606.355269144019;5623;5667.801626943037;5221.895562088737;5238.869028274247;5243.4232234454175;5247.370315562157;5264.447233040552;6114.084479019511;6172.290186628506;6172.9074063889975;6223.595466294676;6227.875442491125;6057.57596968208;6150;6151.200343955257;6151.6001459904965;6153.738483610939;5766.170271146665;6218.502519798315;6223.875522489434;6239.032949892774;6242.500599952084;5815.244018277597;5995.400459953969;5997.850053748692;6079.817605471843;6143.938560614337;5888.411158884077;6098.390160984043;6118.923762351665;6128.908066379097;6136.457808108435;5958.731857066356;5966.940330596639;5974.731137098754;5980.162777211107;5998.310194327708;5820.912686309794;5869.765209391577;5914.142449344907;5925.229720136354;5926.407359264039;5788.421157884178;5788.450097240755;5795.710214489343;5803.8549036274435;5804.622699524517;5886.793962211302;5886.882262354643;5925.822225333247;5926.585139040226;5948.167256583995;5724.627899186539;5805;5811.4479124483105;5815.04051831443;5907.70461476933 05:19:10 INFO - PROCESS | 2748 | |1;ext.html;442.371831998562;470.367355906306;485.0033181811376;586.0564491169217;587.6709042935955;30201;30751.692483075163;38108.85673429798;38808.80595597025;38837;41126;41598.79200603993;41787.74636760902;41853.953651158736;41895.162096758046;9603.66387176449;9852.704418867446;9854.162396196332;9860.30977831552;9866.605335786597;9735.805283894319;9898.059684330014;9917.553710083064;9919;9924.45415502147;9846.556904939294;9934.751631209241;9935.354201976885;9944;9958.854731705838;9839.409635421898;9915.157211636983;9925.454100024495;9928;9936.105750482491;9656.179224765903;9807.411555306633;9810.411375317504;9816.705498835046;9831;40209.79895100535;40441.59558404437;40475;40479.988000299934;40498.39252411187;3112.693236414324;3150.952735708966;3195.6484786673445;3285.901422957315;3515.4199557073052;28791.85604071987;29195.708042919723;29626.851865740748;29887;30139.246518836986;2719.3881376690283;2789.191134570972;2811.1847564206355;2852.443773464175;2854.957175642367;485686.57156714343;488736.99999999645;488741;489083;489091;61654;61989.38010619836;62216.75566488645;62242;62338.68830655908;61713;62003.6899815498;62004;62006.68996655078;62045;16100.275487603087;16756.245968931427;16793.99236045841;16798.83201167997;16811.243494042796;12887.226766394046;12899.419526121346;12917.418716157796;12925.095243333064;12933.805992910115;19102.71345929812;19109.331173408864;19766.60466790685;19806.009699514965;19806.108725107406;6519.608823470607;7732;7735.381169506426;7758.3017528422715;7822.4524283299625;970.45169479244;978.2760757039881;990.3612170150357;1085.7285678580356;1092.306385445248;29000.564991525145;29251.707482925325;29324.413511729646;29991.850040749658;30044.54933176004;979.2459805949379;1048.7745134796055;1102.6306187427142;1110.0675432636624;1206.258151236983;13764.724705505834;13815.792763108562;14062.789058164135;14973.700525989638;15592.220388980506;243590;292155;295764;295904;296890;57661.8467630645;57821;57920;58015.70992145012;58099.709501453064;1177.3936422742295;1397.196611948134;1421.9431222750975;1421.992890035564;1424.6509605146805;30103.096907092822;30282.84858575693;30320.696793031788;30670.84664576663;30753.538696919117;50437;50782.74608626933;50885.74557127191;51002;51004.74497627488;382480;383596;383757;384350;384950;30879.073627791204;30993.53509697311;31361;31547.842260788548;31637.36725265528;44649.776751116035;44767.10465790731;45151.54848451474;45695;45914.08171836611;36064.81967590145;36969.6303036977;38232.426513601764;38236;38504.422433662956;36511.26977460489;36566.817165914;37175.442368363954;37512.812435937645;37617.62382376141;36399.090022749646;36986.8150659245;37115.8144209288;37516.43725344067;37562.06094847542;27346.726532734418;28559.571606426336;31705.04884853458;32838.507422388204;33451;23724.762752372255;24009.399765006016;24463.75536244686;24589.584732900545;24765.504689906462 05:19:10 INFO - PROCESS | 2748 | |2;jquery.html;347.74093300491137;364.74285628631816;370.59975226755097;371.9330520506308;376.87374729465625;14788.408463661453;18422.723659145107;19169.233230670772;19431.708524372145;19462.416127516197;19240.3265885694;19983.00084995748;19991.800081999176;20004.69992950107;20009;4138.896527586804;4323.286657701476;5173.560247378959;5654;6160.938390616081;6334.208223972004;6380.7447702092095;6380.936190638104;6416.230052393696;6429.710663020175;5877.70611469427;6040.667763273018;6242.8439289017915;6348.111264422984;6363.300036995926;6167.074938759177;6347;6352.047192921053;6354.364563543655;6392.264889537716;5432.239486471897;5497.340319161726;5499.670019798785;5507.972460137674;5509.752061157257;24166.637500437508;24242.636360454606;24304.878475607686;24418.901149448317;24584.385390365205;2153.9246126385656;2155.1056311630655;2160.5786871560053;2252.8986195621073;2422.4186195313173;8849.469031858109;10092.646757363531;13641.113327633771;14459.638509037359;14719;1657.5773177839694;1723.3020626646228;1766.9823301767076;1780.866435017366;1795.1742198588665;95714.52142739312;100435.9956400434;100989.99010009879;101196.49401752945;102168;10077.29458937882;10216.693499195037;10228.232882533839;10279.023492768176;10371.222158338154;12402.503899843905;12438.875611243771;12444.315562644142;12446.875531244752;12458.75082498345;8007.239312265326;8103.351731861437;8123.512589244664;8182.7545173644885;8205.71280005197;7393.149787774367;8424.115467875907;8447.915520844836;8506.659733610586;8514.787130321667;3919.4904662393706;4097.549269580344;4107.876763697094;4262.42457268271;4400.647948164139;1668.9833101669071;1791.4894255137224;1819.0904547726136;1875.559243577758;1885.3118611706627;799.7121036426998;895.632790555869;898.3756289378949;974.337450533644;1014.9441780702132;18270.26918923255;18662.253509859453;19007.619847603248;19011.524711882314;19070.04649767506;812.273015650989;932.2914584915513;956.8899576548647;960.8325884050912;982.7690492734201;8817.57127603312;9533.713988580354;10942.17933654979;10948.233623646427;10956;745510;749450;760790;762398;763789;67068.99396509056;67500;93365.5331723337;107398.46300768446;111848.44075779569;1111.4998250787178;1123.4270522033657;1138.527511082908;1139;1139.4530625299903;16955.830441695423;18207.724034495688;18257.81742182561;18423;18598.349057782914;49079.75460122676;49508.50491495039;50954.49045509497;50980.235296469844;53321.93356132778;75715.62142189255;77077;77697;77774.83337749825;78308;6134.294556125924;7120.501564890408;7552.6223688816435;7767.262110099531;7846.843063138591;10778.622748203776;12312.81530777057;12482.75034499323;12573.497060117494;12820.423080961566;7647.38820894316;10526.31578947366;10573.207009474474;10635.308704934154;10880.727981800519;8839.911600883908;9359.298052646214;9701.417914925127;9834.01659834011;10677.679669609925;10717.14262858985;10842;11014.339139651647;11138.554457821596;11286.604968826052;7508.43686723487;7689.923100769144;7692;7748;7791.0650721913735;8963.462192268486;10314.690559283234;10632.893671063499;10860.674179774303;10874.619388321371 05:19:10 INFO - PROCESS | 2748 | |3;mootools.html;1387.458891032497;1524.5273965070826;1548.4967385599675;1775.7425173349864;1929.6816025355818;7902.367810575154;8478.957605211981;8671.69649062283;8672.52301123438;8674.34942379321;8453.830923381529;8517.361197910166;8601.526916019631;8603.010653774818;8641.65433382664;2168.143583284602;2226.3988723044768;2454.177850420107;2478.2565230430887;2479.219046000513;2243.147603910512;2246.5731511012937;2251.6172250717336;2255.334676270496;2427.514497100578;2402.7957623602015;2404.1224952892157;2409.6144616861307;2410.409449684821;2420.3465064432594;2368.348704106376;2379.2267513058237;2424.733279339271;2425.1390756281444;2425.1390756281617;2120.4486833423266;2181.8909054547366;2183.825293976478;2184.3119417383473;2413.9396515087265;7924.366050715987;8315.459495132858;8507.276881465083;8509.61706723199;8524.275436587897;5224.582033437316;5321.9201711973965;5357.357117145967;5358.2498450217;5358.4373640767935;3567.9821600892087;3619.5475565554307;3684.0237337105686;3693.1874987502706;3713.888583342477;1010.989010989011;1022.3814592171703;1034.9120324772402;1071.507106730897;1073.6564299424188;11497.482613282338;11542.82685759706;11551.53793848245;11560.132990025697;11583.826242606368;4823.27650852368;4841.491643377394;4872.731999740048;4894.535019173173;4905.018996200774;10163.898361016296;10176.796464070678;10180.440075795903;10183.592656293813;10187.745306367251;4292.785360731951;5458.536024437888;5482.698451585121;5492.862678433071;5497.39528651848;4930.926036109461;4938.25926111079;4938.777755000961;4938.9012219755405;4944.233643785219;3363.226457914693;3575.748488029169;3685.6867166291167;3772.6227377262053;3806.71449641278;1166.3759888459804;1168.8714241433436;1174.448009435564;1177.6996865799167;1182.2729021651621;1262.9179103358251;1308.9803652945213;1327.1042046618493;1355.105630284008;1357.7895426208954;7894.486858354188;7980.600969951479;8016.118226995026;8021.639026243716;8116.026076870813;1029.9691009269734;1196.4316949449117;1203.0976767424431;1217.634709587138;1225.3628113380992;5886.175935368966;6057.212562366953;6159.168512250879;6246.781362652377;6265.310815810257;8237.958810205912;10599.258051936291;10613.469326533797;10636.46817659114;10643.52104155315;9860.654877079267;10330.586776528855;10360.274780765274;10396.22028347877;10428.843567346496;7823.804404889924;8104.756857294291;8155.510669359857;8222.054463736627;8247.546384948766;7988.162722573725;8066.757997259857;8079.515229086273;8093.190680932095;8100.351971842357;9097.909020909707;9334.673286434941;9357.672481463389;9390.483523406005;9532.618695252113;9650.662226822027;9724.076212759777;9891.950540247253;10044.246681499062;10050;7313.378362839218;7357;7397.630118493946;7513.2111128330735;7522.736704215326;6493.448056915026;8072.515649061075;8128.674853005814;8159.18408159203;8160.183981601793;6472.902906456313;7604.5437273763755;7608.8478230436185;7753.883691744515;7782.49413788102;7404.851902962018;7409.37020353276;7493.363064139391;7642.426817988562;7661.808454788675;7623.275788800055;7675.347595454449;7737.071551413641;7737.3423259021365;7745.7676269712;6944.201416836926;6995.370416662321;6996.755113571001;7009.684564194726;7010.50926435165;7038.014677945194;7109.502334836512;7111.182214045451;7115.928840711526;7117.537360071578 05:19:10 INFO - PROCESS | 2748 | |4;prototype.html;313.1857171354479;317.71881884532195;325.94621887388587;326.653435552762;329.31831109603115;5913.11303304504;6313.242410910697;6526.641034743087;6590.044443555687;6608.339166083395;6299.2440907091095;6454;6469.967650161754;6501.414872661459;6514.1205937198465;4368.344748287766;4686.859394218179;4839.419269687624;4853.56317931387;4864.197407427788;4579.610733087691;4656.371389862376;4711.104890070893;4817.9759101204445;4873.951260487403;3835.4055121456217;4759.309900064489;4760.738159401231;4768.141734487791;4781.689190202643;4245.363195520666;4464.843730469418;4751.881202969919;4890.046440944017;4918.9508104919205;4143.1713657268365;4146.460960075171;4146.481689788777;4147.3778933159965;4149.128682976579;5990.460858522711;6214.844628884269;6215.223097112861;6221.222347206599;6265.498760099181;4609.562091601292;5736.082226843727;5740.339860916007;5744.569157313176;5752.568557358173;5958.493528050078;6154.199954005995;6554.836129096763;7017.543859649107;7019.789406317818;4021.4973128358956;4447.3996010538485;4590.887843496072;4664.673472856902;4683.531646835323;7438.181800001996;7444.367228785505;7448.1434635016585;7457.813554661123;7470.393865318928;3695.2978934002454;4524.88687782808;4752.548507891814;4752.667313288037;4760.809567617326;4803.831865884677;4816.397950256218;4824.758762061883;4828.855134345975;4831.598836337493;4610.861674149781;5678.971605141948;5703.572232082611;5717.256756621696;5731.570132240098;7044.859102817915;7056.92943070573;7057.89413158803;7065.858682826315;7091.149062112477;1638.5493989153078;1666.899986000844;1667.1080971680092;1667.816540180579;1672.7741754862939;893.9731808045769;1054.383185836279;1082.783443311341;1084.3168803653648;1094.8686157660973;6544.28412208928;6678.09845670838;6728.831779205559;6737.663116844138;6744.055832183498;6874.621895795779;6983.127109111361;7184.281571842774;7195.280471952868;7268.527545709512;5639.943600563942;6329.905051424233;6503.154589903377;6505.707243174069;6515.250746164157;3835.7890316032326;3869.5356557212754;3870.3613903706137;3873.5932727063814;3874.96125038746;6161.322254551996;7681.539107653559;7706.691732330756;7710.845783084419;7711.190325015905;6076.605020673642;7425.62871856405;7586.924130758732;7625.923740762632;7648.546776112521;7713.151553329241;7717.2668596483245;7744.225577442324;7747.535147891032;7760.961195193988;7302.744403945836;7313.341799238094;7366.742164024234;7368.499962517762;7377.741779037708;6516.761815255087;6916.827079323057;7204.243554426919;7220.602866842374;7235.384992275505;6988.231294557493;7677.155512893689;7688.384929205762;7719.150893401609;7724.459287849797;4558.954410455853;4619.284010978304;4625.676202665781;4631.2126938421225;4640.675152739276;6960.825979350557;7138.607376594337;7149.177844547944;7213;7252.963735181291;7026.578405295699;7033.156021277479;7037.26108758573;7039.8944015840825;7048.436125110082;6851.177858656993;6974.61639609806;6981;7022.964885175541;7033.788986330418;5618.69097199658;6966.338197871194;6972;6974.093367862146;6976.965115174392;5523.475269849358;6757.729690812509;6961.443084553325;7125.5368401053765;7146.249643787535;7008.894866577108;7038.753643622448;7131.964340178266;7171.462140339391;7175.677094530863 05:19:10 INFO - PROCESS | 2748 | |5;yui.html;665.4111111666175;725.0610459455007;730.4813582356527;745.191467258025;771.9035120609924;15067.849321506781;15292.694146117072;15298.69402611947;15309.77035344468;15326.693466130699;13899.861001389983;13976.440942362293;13992.790108148385;15596;16005.039697618151;858.8282343531288;873.3929918706501;875.0680525240598;875.671623141322;875.8905136857893;690.9136357955256;713.4043074033189;863.7236084452946;867.2671592504355;882.2368651116769;903.850864607339;915.1077699243218;916.225789208118;917.0874979395511;918.5177781664646;718.2063819479505;911.3074063711593;911.4531281231241;912.9087091290884;913.0093848174705;546.89335579562;667.8363800868818;678.3792829560995;679.5277282288811;679.7450955891541;15024.849751502454;15123.01700389471;15135;15143;15212.543623691306;12214.938925305316;12242.81635775464;12280.631581052583;12615.621531354074;12927.935360323232;8380.622871970776;10356.223283253787;10384.844227336596;10412.739681507946;11051.336919784839;3481.860725570974;3537.7877327360443;3622.7645203061716;3671.541057367829;3679.779213247187;87525;87695.99999999936;88339;88467.67298490528;88554;8874.645014199488;8886.022537520868;9148.771280717901;9168.404053736485;9520.619175233052;21138.89430552868;21140.682889756667;21145.154193832383;21145.942702864795;21150.048247828883;7354;7435.553866767903;7452.850942981218;7478.2521747824785;7589;3951.3480275754755;4086.734362266443;4159.292920203573;4226.429432026637;4314.2958531380555;3568.750187486851;3630.49173115764;3638.526991491089;3646.3983442732183;3647.015305867401;602.3645054467491;632.2570979099631;640.7276907314466;650.4601181019742;653.5686446945089;526.2816255810867;569.971501424927;574.5461085742301;634.0236036503733;640.7501074580917;7419.703211871572;8712.433691810138;9134.680286190085;9335.439873607469;9365.139593401804;590.3771521045338;592.1887014789679;599.1821164111044;627.4039662320813;648.2674577727138;3839.4432807242792;3876.1278712290073;3908.179282350681;4019.3569028955226;4025.899352516152;86996.1300386988;92830;93091.53454232686;93237.5338123305;93268.60097098548;77197;77212.61393693108;77427.8385824213;77938.22061779423;78237;63861.680691597176;65971.34028659748;66756.6662166686;66774.33225667778;66774.99837502441;7773;7788.805279868048;7912.248336408032;7991.679894903834;8045;47758.283625746335;47823.52176478191;48711.26933096077;48878;50158.498415016846;71263.28736712567;71504.77695065134;72456.63771681108;73023;73206.63396682983;11036.944815275872;11110.555577776799;11237.55049797999;11252.493637786156;11508.42457877086;12339.62981110568;12388.318642474751;12437;12561.183523070971;12717.173383730027;11195.440227988405;11412.771744565229;11442.885571144181;11551.537938482366;12417.875821242033;11221;11376;11474.598389056342;11821.822672659744;11918.225315354473;11046.917023607994;11295.717607059889;11330.206885517935;11709.941450293034;11741.236819606698;9659.565319560777;9726.319157658896;9932.354396964174;9992.150667193078;10201.717595366546;7410.592417417094;8785.25325347327;8786.868196977179;9127.269818414643;9466.574004169966 05:19:10 INFO - PROCESS | 2748 | __end_tp_report 05:19:10 INFO - PROCESS | 2748 | __start_cc_report 05:19:10 INFO - PROCESS | 2748 | _x_x_mozilla_cycle_collect,1826 05:19:10 INFO - PROCESS | 2748 | __end_cc_report 05:19:10 INFO - PROCESS | 2748 | __startTimestamp1455715150637__endTimestamp 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | ------- Summary: start ------- 05:19:10 INFO - PROCESS | 2748 | Number of tests: 6 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#0] dojo.html Cycles:165 Average:5589.76 Median:5804.62 stddev:633.58 (10.9%) stddev-sans-first:610.00 05:19:10 INFO - PROCESS | 2748 | Values: 3320.3 3376.8 3400.0 3420.3 3445.6 5429.3 5442.3 5462.8 5498.9 5504.6 5484.7 5522.8 5563.2 5616.0 5624.2 4919.2 4957.9 4983.8 5005.2 5121.0 5044.3 5052.2 5068.6 5104.9 5110.0 4551.2 4715.2 4920.0 5007.6 5068.9 4977.2 5008.6 5012.1 5023.3 5053.3 4939.5 4985.6 4987.0 5000.7 5013.4 5301.0 5335.7 5389.6 5432.6 5436.5 5084.9 5979.7 6043.5 6086.4 6093.5 6099.9 6130.9 6166.3 6172.7 6181.3 5607.5 5626.2 5634.4 5641.0 5707.6 6081.7 6091.2 6172.1 6226.8 6238.4 5983.5 5993.7 5999.1 6013.0 6054.8 6128.0 6159.4 6160.8 6161.0 6168.8 5923.9 5993.5 6070.9 6073.4 6134.1 6020.4 6110.9 6131.9 6138.7 6150.0 4824.9 4836.3 4839.4 4841.6 4856.0 4232.8 4241.7 4279.7 4342.8 4349.0 5552.4 5579.5 5580.4 5585.6 5660.0 6022.9 6102.9 6152.9 6157.2 6165.8 5518.5 5578.5 5606.4 5623.0 5667.8 5221.9 5238.9 5243.4 5247.4 5264.4 6114.1 6172.3 6172.9 6223.6 6227.9 6057.6 6150.0 6151.2 6151.6 6153.7 5766.2 6218.5 6223.9 6239.0 6242.5 5815.2 5995.4 5997.9 6079.8 6143.9 5888.4 6098.4 6118.9 6128.9 6136.5 5958.7 5966.9 5974.7 5980.2 5998.3 5820.9 5869.8 5914.1 5925.2 5926.4 5788.4 5788.5 5795.7 5803.9 5804.6 5886.8 5886.9 5925.8 5926.6 5948.2 5724.6 5805.0 5811.4 5815.0 5907.7 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#1] ext.html Cycles:180 Average:54733.74 Median:38170.64 stddev:104470.75 (273.7%) stddev-sans-first:104684.29 05:19:10 INFO - PROCESS | 2748 | Values: 442.4 470.4 485.0 586.1 587.7 30201.0 30751.7 38108.9 38808.8 38837.0 41126.0 41598.8 41787.7 41854.0 41895.2 9603.7 9852.7 9854.2 9860.3 9866.6 9735.8 9898.1 9917.6 9919.0 9924.5 9846.6 9934.8 9935.4 9944.0 9958.9 9839.4 9915.2 9925.5 9928.0 9936.1 9656.2 9807.4 9810.4 9816.7 9831.0 40209.8 40441.6 40475.0 40480.0 40498.4 3112.7 3151.0 3195.6 3285.9 3515.4 28791.9 29195.7 29626.9 29887.0 30139.2 2719.4 2789.2 2811.2 2852.4 2855.0 485686.6 488737.0 488741.0 489083.0 489091.0 61654.0 61989.4 62216.8 62242.0 62338.7 61713.0 62003.7 62004.0 62006.7 62045.0 16100.3 16756.2 16794.0 16798.8 16811.2 12887.2 12899.4 12917.4 12925.1 12933.8 19102.7 19109.3 19766.6 19806.0 19806.1 6519.6 7732.0 7735.4 7758.3 7822.5 970.5 978.3 990.4 1085.7 1092.3 29000.6 29251.7 29324.4 29991.9 30044.5 979.2 1048.8 1102.6 1110.1 1206.3 13764.7 13815.8 14062.8 14973.7 15592.2 243590.0 292155.0 295764.0 295904.0 296890.0 57661.8 57821.0 57920.0 58015.7 58099.7 1177.4 1397.2 1421.9 1422.0 1424.7 30103.1 30282.8 30320.7 30670.8 30753.5 50437.0 50782.7 50885.7 51002.0 51004.7 382480.0 383596.0 383757.0 384350.0 384950.0 30879.1 30993.5 31361.0 31547.8 31637.4 44649.8 44767.1 45151.5 45695.0 45914.1 36064.8 36969.6 38232.4 38236.0 38504.4 36511.3 36566.8 37175.4 37512.8 37617.6 36399.1 36986.8 37115.8 37516.4 37562.1 27346.7 28559.6 31705.0 32838.5 33451.0 23724.8 24009.4 24463.8 24589.6 24765.5 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#2] jquery.html Cycles:180 Average:37387.95 Median:371.27 stddev:124213.38 (33456.7%) stddev-sans-first:124530.69 05:19:10 INFO - PROCESS | 2748 | Values: 347.7 364.7 370.6 371.9 376.9 14788.4 18422.7 19169.2 19431.7 19462.4 19240.3 19983.0 19991.8 20004.7 20009.0 4138.9 4323.3 5173.6 5654.0 6160.9 6334.2 6380.7 6380.9 6416.2 6429.7 5877.7 6040.7 6242.8 6348.1 6363.3 6167.1 6347.0 6352.0 6354.4 6392.3 5432.2 5497.3 5499.7 5508.0 5509.8 24166.6 24242.6 24304.9 24418.9 24584.4 2153.9 2155.1 2160.6 2252.9 2422.4 8849.5 10092.6 13641.1 14459.6 14719.0 1657.6 1723.3 1767.0 1780.9 1795.2 95714.5 100436.0 100990.0 101196.5 102168.0 10077.3 10216.7 10228.2 10279.0 10371.2 12402.5 12438.9 12444.3 12446.9 12458.8 8007.2 8103.4 8123.5 8182.8 8205.7 7393.1 8424.1 8447.9 8506.7 8514.8 3919.5 4097.5 4107.9 4262.4 4400.6 1669.0 1791.5 1819.1 1875.6 1885.3 799.7 895.6 898.4 974.3 1014.9 18270.3 18662.3 19007.6 19011.5 19070.0 812.3 932.3 956.9 960.8 982.8 8817.6 9533.7 10942.2 10948.2 10956.0 745510.0 749450.0 760790.0 762398.0 763789.0 67069.0 67500.0 93365.5 107398.5 111848.4 1111.5 1123.4 1138.5 1139.0 1139.5 16955.8 18207.7 18257.8 18423.0 18598.3 49079.8 49508.5 50954.5 50980.2 53321.9 75715.6 77077.0 77697.0 77774.8 78308.0 6134.3 7120.5 7552.6 7767.3 7846.8 10778.6 12312.8 12482.8 12573.5 12820.4 7647.4 10526.3 10573.2 10635.3 10880.7 8839.9 9359.3 9701.4 9834.0 10677.7 10717.1 10842.0 11014.3 11138.6 11286.6 7508.4 7689.9 7692.0 7748.0 7791.1 8963.5 10314.7 10632.9 10860.7 10874.6 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#3] mootools.html Cycles:180 Average:5965.15 Median:5084.41 stddev:3133.99 (61.6%) stddev-sans-first:3123.89 05:19:10 INFO - PROCESS | 2748 | Values: 1387.5 1524.5 1548.5 1775.7 1929.7 7902.4 8479.0 8671.7 8672.5 8674.3 8453.8 8517.4 8601.5 8603.0 8641.7 2168.1 2226.4 2454.2 2478.3 2479.2 2243.1 2246.6 2251.6 2255.3 2427.5 2402.8 2404.1 2409.6 2410.4 2420.3 2368.3 2379.2 2424.7 2425.1 2425.1 2120.4 2181.9 2183.8 2184.3 2413.9 7924.4 8315.5 8507.3 8509.6 8524.3 5224.6 5321.9 5357.4 5358.2 5358.4 3568.0 3619.5 3684.0 3693.2 3713.9 1011.0 1022.4 1034.9 1071.5 1073.7 11497.5 11542.8 11551.5 11560.1 11583.8 4823.3 4841.5 4872.7 4894.5 4905.0 10163.9 10176.8 10180.4 10183.6 10187.7 4292.8 5458.5 5482.7 5492.9 5497.4 4930.9 4938.3 4938.8 4938.9 4944.2 3363.2 3575.7 3685.7 3772.6 3806.7 1166.4 1168.9 1174.4 1177.7 1182.3 1262.9 1309.0 1327.1 1355.1 1357.8 7894.5 7980.6 8016.1 8021.6 8116.0 1030.0 1196.4 1203.1 1217.6 1225.4 5886.2 6057.2 6159.2 6246.8 6265.3 8238.0 10599.3 10613.5 10636.5 10643.5 9860.7 10330.6 10360.3 10396.2 10428.8 7823.8 8104.8 8155.5 8222.1 8247.5 7988.2 8066.8 8079.5 8093.2 8100.4 9097.9 9334.7 9357.7 9390.5 9532.6 9650.7 9724.1 9892.0 10044.2 10050.0 7313.4 7357.0 7397.6 7513.2 7522.7 6493.4 8072.5 8128.7 8159.2 8160.2 6472.9 7604.5 7608.8 7753.9 7782.5 7404.9 7409.4 7493.4 7642.4 7661.8 7623.3 7675.3 7737.1 7737.3 7745.8 6944.2 6995.4 6996.8 7009.7 7010.5 7038.0 7109.5 7111.2 7115.9 7117.5 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#4] prototype.html Cycles:180 Average:5702.69 Median:6461.98 stddev:1844.91 (28.6%) stddev-sans-first:1805.20 05:19:10 INFO - PROCESS | 2748 | Values: 313.2 317.7 325.9 326.7 329.3 5913.1 6313.2 6526.6 6590.0 6608.3 6299.2 6454.0 6470.0 6501.4 6514.1 4368.3 4686.9 4839.4 4853.6 4864.2 4579.6 4656.4 4711.1 4818.0 4874.0 3835.4 4759.3 4760.7 4768.1 4781.7 4245.4 4464.8 4751.9 4890.0 4919.0 4143.2 4146.5 4146.5 4147.4 4149.1 5990.5 6214.8 6215.2 6221.2 6265.5 4609.6 5736.1 5740.3 5744.6 5752.6 5958.5 6154.2 6554.8 7017.5 7019.8 4021.5 4447.4 4590.9 4664.7 4683.5 7438.2 7444.4 7448.1 7457.8 7470.4 3695.3 4524.9 4752.5 4752.7 4760.8 4803.8 4816.4 4824.8 4828.9 4831.6 4610.9 5679.0 5703.6 5717.3 5731.6 7044.9 7056.9 7057.9 7065.9 7091.1 1638.5 1666.9 1667.1 1667.8 1672.8 894.0 1054.4 1082.8 1084.3 1094.9 6544.3 6678.1 6728.8 6737.7 6744.1 6874.6 6983.1 7184.3 7195.3 7268.5 5639.9 6329.9 6503.2 6505.7 6515.3 3835.8 3869.5 3870.4 3873.6 3875.0 6161.3 7681.5 7706.7 7710.8 7711.2 6076.6 7425.6 7586.9 7625.9 7648.5 7713.2 7717.3 7744.2 7747.5 7761.0 7302.7 7313.3 7366.7 7368.5 7377.7 6516.8 6916.8 7204.2 7220.6 7235.4 6988.2 7677.2 7688.4 7719.2 7724.5 4559.0 4619.3 4625.7 4631.2 4640.7 6960.8 7138.6 7149.2 7213.0 7253.0 7026.6 7033.2 7037.3 7039.9 7048.4 6851.2 6974.6 6981.0 7023.0 7033.8 5618.7 6966.3 6972.0 6974.1 6977.0 5523.5 6757.7 6961.4 7125.5 7146.2 7008.9 7038.8 7132.0 7171.5 7175.7 05:19:10 INFO - PROCESS | 2748 | 05:19:10 INFO - PROCESS | 2748 | [#5] yui.html Cycles:180 Average:18488.18 Median:32247.85 stddev:26144.26 (81.1%) stddev-sans-first:26183.35 05:19:10 INFO - PROCESS | 2748 | Values: 665.4 725.1 730.5 745.2 771.9 15067.8 15292.7 15298.7 15309.8 15326.7 13899.9 13976.4 13992.8 15596.0 16005.0 858.8 873.4 875.1 875.7 875.9 690.9 713.4 863.7 867.3 882.2 903.9 915.1 916.2 917.1 918.5 718.2 911.3 911.5 912.9 913.0 546.9 667.8 678.4 679.5 679.7 15024.8 15123.0 15135.0 15143.0 15212.5 12214.9 12242.8 12280.6 12615.6 12927.9 8380.6 10356.2 10384.8 10412.7 11051.3 3481.9 3537.8 3622.8 3671.5 3679.8 87525.0 87696.0 88339.0 88467.7 88554.0 8874.6 8886.0 9148.8 9168.4 9520.6 21138.9 21140.7 21145.2 21145.9 21150.0 7354.0 7435.6 7452.9 7478.3 7589.0 3951.3 4086.7 4159.3 4226.4 4314.3 3568.8 3630.5 3638.5 3646.4 3647.0 602.4 632.3 640.7 650.5 653.6 526.3 570.0 574.5 634.0 640.8 7419.7 8712.4 9134.7 9335.4 9365.1 590.4 592.2 599.2 627.4 648.3 3839.4 3876.1 3908.2 4019.4 4025.9 86996.1 92830.0 93091.5 93237.5 93268.6 77197.0 77212.6 77427.8 77938.2 78237.0 63861.7 65971.3 66756.7 66774.3 66775.0 7773.0 7788.8 7912.2 7991.7 8045.0 47758.3 47823.5 48711.3 48878.0 50158.5 71263.3 71504.8 72456.6 73023.0 73206.6 11036.9 11110.6 11237.6 11252.5 11508.4 12339.6 12388.3 12437.0 12561.2 12717.2 11195.4 11412.8 11442.9 11551.5 12417.9 11221.0 11376.0 11474.6 11821.8 11918.2 11046.9 11295.7 11330.2 11709.9 11741.2 9659.6 9726.3 9932.4 9992.2 10201.7 7410.6 8785.3 8786.9 9127.3 9466.6 05:19:10 INFO - PROCESS | 2748 | -------- Summary: end -------- 05:19:10 INFO - PROCESS | 2748 | 05:19:11 INFO - TEST-INFO | 2748: exit 0 05:19:11 INFO - TEST-OK | dromaeo_css | took 1113568ms 05:19:11 INFO - TEST-START | kraken 05:19:11 INFO - operating with platform_type : mac_ 05:19:11 INFO - Initialising browser for kraken test... 05:19:11 INFO - TEST-INFO | started process 2750 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQa2fOo/profile http://localhost:53581/getInfo.html) 05:19:13 INFO - PROCESS | 2750 | __metrics Screen width/height:1600/1200 05:19:13 INFO - PROCESS | 2750 | colorDepth:24 05:19:13 INFO - PROCESS | 2750 | Browser inner width/height: 1024/689 05:19:13 INFO - PROCESS | 2750 | __metrics 05:19:13 INFO - PROCESS | 2750 | 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" 05:19:13 INFO - PROCESS | 2750 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:14 INFO - TEST-INFO | 2750: exit 0 05:19:14 INFO - Browser initialized. 05:19:14 INFO - Running cycle 1/1 for kraken test... 05:19:14 INFO - TEST-INFO | started process 2752 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQa2fOo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:26 INFO - PROCESS | 2752 | RSS: Main: 244387840 05:19:26 INFO - PROCESS | 2752 | 05:19:28 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53581/tests/kraken/driver.html?testName=audio-beat-detection) 05:19:29 INFO - PROCESS | 2752 | RSS: Main: 290570240 05:19:29 INFO - PROCESS | 2752 | 05:19:31 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53581/tests/kraken/driver.html?testName=audio-dft) 05:19:32 INFO - PROCESS | 2752 | RSS: Main: 301096960 05:19:32 INFO - PROCESS | 2752 | 05:19:35 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53581/tests/kraken/driver.html?testName=audio-fft) 05:19:36 INFO - PROCESS | 2752 | RSS: Main: 287948800 05:19:36 INFO - PROCESS | 2752 | 05:19:38 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53581/tests/kraken/driver.html?testName=audio-oscillator) 05:19:38 INFO - PROCESS | 2752 | RSS: Main: 289509376 05:19:38 INFO - PROCESS | 2752 | 05:19:41 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53581/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:19:41 INFO - PROCESS | 2752 | RSS: Main: 291168256 05:19:41 INFO - PROCESS | 2752 | 05:19:44 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53581/tests/kraken/driver.html?testName=imaging-darkroom) 05:19:45 INFO - PROCESS | 2752 | RSS: Main: 293273600 05:19:45 INFO - PROCESS | 2752 | 05:19:47 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53581/tests/kraken/driver.html?testName=imaging-desaturate) 05:19:48 INFO - PROCESS | 2752 | RSS: Main: 292651008 05:19:48 INFO - PROCESS | 2752 | 05:19:51 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53581/tests/kraken/driver.html?testName=json-parse-financial) 05:19:51 INFO - PROCESS | 2752 | RSS: Main: 293511168 05:19:51 INFO - PROCESS | 2752 | 05:19:53 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53581/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:19:54 INFO - PROCESS | 2752 | RSS: Main: 324853760 05:19:54 INFO - PROCESS | 2752 | 05:19:55 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:19:56 INFO - PROCESS | 2752 | RSS: Main: 326799360 05:19:56 INFO - PROCESS | 2752 | 05:19:58 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:19:59 INFO - PROCESS | 2752 | RSS: Main: 333324288 05:19:59 INFO - PROCESS | 2752 | 05:20:01 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:02 INFO - PROCESS | 2752 | RSS: Main: 334147584 05:20:02 INFO - PROCESS | 2752 | 05:20:04 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:05 INFO - PROCESS | 2752 | RSS: Main: 334508032 05:20:05 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | Cycle 1(1): loaded http://localhost:53581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53581/tests/kraken/driver.html?testName=ai-astar) 05:20:07 INFO - PROCESS | 2752 | RSS: Main: 394035200 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | __start_tp_report 05:20:07 INFO - PROCESS | 2752 | _x_x_mozilla_page_load 05:20:07 INFO - PROCESS | 2752 | _x_x_mozilla_page_load_details 05:20:07 INFO - PROCESS | 2752 | |i|pagename|runs| 05:20:07 INFO - PROCESS | 2752 | |0;ai-astar;110;108;110;110;107;112;117;111;112;111 05:20:07 INFO - PROCESS | 2752 | |1;audio-beat-detection;160;124;146;160;122;145;145;143;197;147 05:20:07 INFO - PROCESS | 2752 | |2;audio-dft;187;183;188;184;187;186;185;181;180;184 05:20:07 INFO - PROCESS | 2752 | |3;audio-fft;75;77;88;80;57;58;58;80;87;86 05:20:07 INFO - PROCESS | 2752 | |4;audio-oscillator;90;104;129;78;80;80;139;97;77;108 05:20:07 INFO - PROCESS | 2752 | |5;imaging-gaussian-blur;86;90;100;140;84;89;84;90;87;92 05:20:07 INFO - PROCESS | 2752 | |6;imaging-darkroom;131;93;94;95;94;93;94;94;108;150 05:20:07 INFO - PROCESS | 2752 | |7;imaging-desaturate;93;121;85;85;84;84;84;85;84;84 05:20:07 INFO - PROCESS | 2752 | |8;json-parse-financial;57;55;56;53;53;55;53;64;52;53 05:20:07 INFO - PROCESS | 2752 | |9;json-stringify-tinderbox;63;62;75;76;69;67;66;69;68;68 05:20:07 INFO - PROCESS | 2752 | |10;stanford-crypto-aes;82;84;82;83;83;79;80;82;81;86 05:20:07 INFO - PROCESS | 2752 | |11;stanford-crypto-ccm;133;116;129;96;127;130;131;129;109;122 05:20:07 INFO - PROCESS | 2752 | |12;stanford-crypto-pbkdf2;139;127;139;139;136;127;138;135;121;172 05:20:07 INFO - PROCESS | 2752 | |13;stanford-crypto-sha256-iterative;67;66;67;68;67;70;91;96;94;95 05:20:07 INFO - PROCESS | 2752 | __end_tp_report 05:20:07 INFO - PROCESS | 2752 | __start_cc_report 05:20:07 INFO - PROCESS | 2752 | _x_x_mozilla_cycle_collect,1155 05:20:07 INFO - PROCESS | 2752 | __end_cc_report 05:20:07 INFO - PROCESS | 2752 | __startTimestamp1455715207559__endTimestamp 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | ------- Summary: start ------- 05:20:07 INFO - PROCESS | 2752 | Number of tests: 14 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:2.70 (2.4%) stddev-sans-first:2.85 05:20:07 INFO - PROCESS | 2752 | Values: 110.0 108.0 110.0 110.0 107.0 112.0 117.0 111.0 112.0 111.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#1] audio-beat-detection Cycles:10 Average:148.90 Median:146.50 stddev:21.03 (14.4%) stddev-sans-first:21.92 05:20:07 INFO - PROCESS | 2752 | Values: 160.0 124.0 146.0 160.0 122.0 145.0 145.0 143.0 197.0 147.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.64 (1.4%) stddev-sans-first:2.64 05:20:07 INFO - PROCESS | 2752 | Values: 187.0 183.0 188.0 184.0 187.0 186.0 185.0 181.0 180.0 184.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#3] audio-fft Cycles:10 Average:74.60 Median:80.00 stddev:12.42 (15.5%) stddev-sans-first:13.17 05:20:07 INFO - PROCESS | 2752 | Values: 75.0 77.0 88.0 80.0 57.0 58.0 58.0 80.0 87.0 86.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:79.00 stddev:21.94 (27.8%) stddev-sans-first:23.07 05:20:07 INFO - PROCESS | 2752 | Values: 90.0 104.0 129.0 78.0 80.0 80.0 139.0 97.0 77.0 108.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:88.00 stddev:16.75 (19.0%) stddev-sans-first:17.50 05:20:07 INFO - PROCESS | 2752 | Values: 86.0 90.0 100.0 140.0 84.0 89.0 84.0 90.0 87.0 92.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.00 stddev:19.95 (21.2%) stddev-sans-first:18.73 05:20:07 INFO - PROCESS | 2752 | Values: 131.0 93.0 94.0 95.0 94.0 93.0 94.0 94.0 108.0 150.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#7] imaging-desaturate Cycles:10 Average:88.90 Median:84.50 stddev:11.61 (13.7%) stddev-sans-first:12.22 05:20:07 INFO - PROCESS | 2752 | Values: 93.0 121.0 85.0 85.0 84.0 84.0 84.0 85.0 84.0 84.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:3.51 (6.4%) stddev-sans-first:3.66 05:20:07 INFO - PROCESS | 2752 | Values: 57.0 55.0 56.0 53.0 53.0 55.0 53.0 64.0 52.0 53.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.50 stddev:4.47 (6.5%) stddev-sans-first:4.31 05:20:07 INFO - PROCESS | 2752 | Values: 63.0 62.0 75.0 76.0 69.0 67.0 66.0 69.0 68.0 68.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.50 stddev:1.99 (2.4%) stddev-sans-first:2.11 05:20:07 INFO - PROCESS | 2752 | Values: 82.0 84.0 82.0 83.0 83.0 79.0 80.0 82.0 81.0 86.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:129.50 stddev:11.88 (9.2%) stddev-sans-first:11.94 05:20:07 INFO - PROCESS | 2752 | Values: 133.0 116.0 129.0 96.0 127.0 130.0 131.0 129.0 109.0 122.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.30 Median:138.50 stddev:13.74 (9.9%) stddev-sans-first:14.56 05:20:07 INFO - PROCESS | 2752 | Values: 139.0 127.0 139.0 139.0 136.0 127.0 138.0 135.0 121.0 172.0 05:20:07 INFO - PROCESS | 2752 | 05:20:07 INFO - PROCESS | 2752 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:80.50 stddev:13.78 (17.1%) stddev-sans-first:14.02 05:20:07 INFO - PROCESS | 2752 | Values: 67.0 66.0 67.0 68.0 67.0 70.0 91.0 96.0 94.0 95.0 05:20:07 INFO - PROCESS | 2752 | -------- Summary: end -------- 05:20:07 INFO - PROCESS | 2752 | 05:20:08 INFO - TEST-INFO | 2752: exit 0 05:20:08 INFO - TEST-OK | kraken | took 57166ms 05:20:08 INFO - SUITE-END | took 1170s 05:20:08 INFO - Completed test suite (00:19:31) 05:20:08 INFO - Working with test: dromaeo_css 05:20:08 INFO - Generating results file: dromaeo_css 05:20:08 INFO - Working with test: kraken 05:20:08 INFO - Generating results file: kraken 05:20:08 INFO - javascript benchmark 05:20:08 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:20:09 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 05:20:09 INFO - process_Request line: dromaeo_css 7420.16 graph.html#tests=[[72,64,59]] 05:20:09 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:20:09 INFO - process_Request line: kraken 1447.90 graph.html#tests=[[232,64,59]] 05:20:09 INFO - RETURN: dromaeo_css: 7420.16 05:20:09 INFO - RETURN: kraken: 1447.90 05:20:09 INFO - javascript benchmark 05:20:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3320.252943087805, 3376.848041838117, 3400.0, 3420.2817408344245, 3445.638207988161, 5429.321334833146, 5442.319710036246, 5462.836114916549, 5498.917516237259, 5504.587155963299, 5484.670919744818, 5522.834314970547, 5563.165525171226, 5615.971920140393, 5624.240727501786, 4919.188333924916, 4957.925631115545, 4983.7508124593805, 5005.2492126181, 5121.001404726061, 5044.268581055745, 5052.166392545226, 5068.594512439015, 5104.948950510485, 5109.974450127763, 4551.203539380628, 4715.221988371914, 4919.975400123012, 5007.649464537484, 5068.898622027539, 4977.228529577885, 5008.649394542384, 5012.147934851084, 5023.296738456619, 5053.343065401511, 4939.481354457766, 4985.576226020793, 4986.950130498685, 5000.749962501896, 5013.448520662724, 5301.0, 5335.6798592084215, 5389.649672771257, 5432.619716619799, 5436.483534064258, 5084.949150508484, 5979.730912108922, 6043.546733994924, 6086.421789929949, 6093.451589356979, 6099.9085013724825, 6130.908036379458, 6166.260048794128, 6172.722227499773, 6181.258249010148, 5607.523360514361, 5626.1842032905815, 5634.408387119375, 5641.0, 5707.571932105108, 6081.665508397081, 6091.193004372268, 6172.105044768484, 6226.782062627786, 6238.376162383725, 5983.521318294526, 5993.700314984233, 5999.070144127667, 6013.037913933749, 6054.8183554493435, 6127.969360153171, 6159.414855588712, 6160.784372546939, 6161.0, 6168.845778855564, 5923.851903702442, 5993.460588547052, 6070.878582428415, 6073.392660733891, 6134.141220229171, 6020.367861374581, 6110.938890611126, 6131.908021379683, 6138.741557980603, 6149.985252433388, 4824.855254342376, 4836.347093142451, 4839.41926968766, 4841.636877234221, 4855.975720121377, 4232.830686772495, 4241.703080784316, 4279.679024073144, 4342.848000319974, 4348.956510434855, 5552.3892371839065, 5579.4978451938705, 5580.414056524007, 5585.636933599384, 5660.0, 6022.939770602326, 6102.877942441215, 6152.907706384318, 6157.199564056644, 6165.84585385369, 5518.53092487135, 5578.497935185853, 5606.355269144019, 5623.0, 5667.801626943037, 5221.895562088737, 5238.869028274247, 5243.4232234454175, 5247.370315562157, 5264.447233040552, 6114.084479019511, 6172.290186628506, 6172.9074063889975, 6223.595466294676, 6227.875442491125, 6057.57596968208, 6150.0, 6151.200343955257, 6151.6001459904965, 6153.738483610939, 5766.170271146665, 6218.502519798315, 6223.875522489434, 6239.032949892774, 6242.500599952084, 5815.244018277597, 5995.400459953969, 5997.850053748692, 6079.817605471843, 6143.938560614337, 5888.411158884077, 6098.390160984043, 6118.923762351665, 6128.908066379097, 6136.457808108435, 5958.731857066356, 5966.940330596639, 5974.731137098754, 5980.162777211107, 5998.310194327708, 5820.912686309794, 5869.765209391577, 5914.142449344907, 5925.229720136354, 5926.407359264039, 5788.421157884178, 5788.450097240755, 5795.710214489343, 5803.8549036274435, 5804.622699524517, 5886.793962211302, 5886.882262354643, 5925.822225333247, 5926.585139040226, 5948.167256583995, 5724.627899186539, 5805.0, 5811.4479124483105, 5815.04051831443, 5907.70461476933], "unit": "score", "name": "dojo.html", "value": 5549.230310790832}, {"lowerIsBetter": false, "replicates": [442.371831998562, 470.367355906306, 485.0033181811376, 586.0564491169217, 587.6709042935955, 30201.0, 30751.692483075163, 38108.85673429798, 38808.80595597025, 38837.0, 41126.0, 41598.79200603993, 41787.74636760902, 41853.953651158736, 41895.162096758046, 9603.66387176449, 9852.704418867446, 9854.162396196332, 9860.30977831552, 9866.605335786597, 9735.805283894319, 9898.059684330014, 9917.553710083064, 9919.0, 9924.45415502147, 9846.556904939294, 9934.751631209241, 9935.354201976885, 9944.0, 9958.854731705838, 9839.409635421898, 9915.157211636983, 9925.454100024495, 9928.0, 9936.105750482491, 9656.179224765903, 9807.411555306633, 9810.411375317504, 9816.705498835046, 9831.0, 40209.79895100535, 40441.59558404437, 40475.0, 40479.988000299934, 40498.39252411187, 3112.693236414324, 3150.952735708966, 3195.6484786673445, 3285.901422957315, 3515.4199557073052, 28791.85604071987, 29195.708042919723, 29626.851865740748, 29887.0, 30139.246518836986, 2719.3881376690283, 2789.191134570972, 2811.1847564206355, 2852.443773464175, 2854.957175642367, 485686.57156714343, 488736.99999999645, 488741.0, 489083.0, 489091.0, 61654.0, 61989.38010619836, 62216.75566488645, 62242.0, 62338.68830655908, 61713.0, 62003.6899815498, 62004.0, 62006.68996655078, 62045.0, 16100.275487603087, 16756.245968931427, 16793.99236045841, 16798.83201167997, 16811.243494042796, 12887.226766394046, 12899.419526121346, 12917.418716157796, 12925.095243333064, 12933.805992910115, 19102.71345929812, 19109.331173408864, 19766.60466790685, 19806.009699514965, 19806.108725107406, 6519.608823470607, 7732.0, 7735.381169506426, 7758.3017528422715, 7822.4524283299625, 970.45169479244, 978.2760757039881, 990.3612170150357, 1085.7285678580356, 1092.306385445248, 29000.564991525145, 29251.707482925325, 29324.413511729646, 29991.850040749658, 30044.54933176004, 979.2459805949379, 1048.7745134796055, 1102.6306187427142, 1110.0675432636624, 1206.258151236983, 13764.724705505834, 13815.792763108562, 14062.789058164135, 14973.700525989638, 15592.220388980506, 243590.0, 292155.0, 295764.0, 295904.0, 296890.0, 57661.8467630645, 57821.0, 57920.0, 58015.70992145012, 58099.709501453064, 1177.3936422742295, 1397.196611948134, 1421.9431222750975, 1421.992890035564, 1424.6509605146805, 30103.096907092822, 30282.84858575693, 30320.696793031788, 30670.84664576663, 30753.538696919117, 50437.0, 50782.74608626933, 50885.74557127191, 51002.0, 51004.74497627488, 382480.0, 383596.0, 383757.0, 384350.0, 384950.0, 30879.073627791204, 30993.53509697311, 31361.0, 31547.842260788548, 31637.36725265528, 44649.776751116035, 44767.10465790731, 45151.54848451474, 45695.0, 45914.08171836611, 36064.81967590145, 36969.6303036977, 38232.426513601764, 38236.0, 38504.422433662956, 36511.26977460489, 36566.817165914, 37175.442368363954, 37512.812435937645, 37617.62382376141, 36399.090022749646, 36986.8150659245, 37115.8144209288, 37516.43725344067, 37562.06094847542, 27346.726532734418, 28559.571606426336, 31705.04884853458, 32838.507422388204, 33451.0, 23724.762752372255, 24009.399765006016, 24463.75536244686, 24589.584732900545, 24765.504689906462], "unit": "score", "name": "ext.html", "value": 19205.132745930092}, {"lowerIsBetter": false, "replicates": [347.74093300491137, 364.74285628631816, 370.59975226755097, 371.9330520506308, 376.87374729465625, 14788.408463661453, 18422.723659145107, 19169.233230670772, 19431.708524372145, 19462.416127516197, 19240.3265885694, 19983.00084995748, 19991.800081999176, 20004.69992950107, 20009.0, 4138.896527586804, 4323.286657701476, 5173.560247378959, 5654.0, 6160.938390616081, 6334.208223972004, 6380.7447702092095, 6380.936190638104, 6416.230052393696, 6429.710663020175, 5877.70611469427, 6040.667763273018, 6242.8439289017915, 6348.111264422984, 6363.300036995926, 6167.074938759177, 6347.0, 6352.047192921053, 6354.364563543655, 6392.264889537716, 5432.239486471897, 5497.340319161726, 5499.670019798785, 5507.972460137674, 5509.752061157257, 24166.637500437508, 24242.636360454606, 24304.878475607686, 24418.901149448317, 24584.385390365205, 2153.9246126385656, 2155.1056311630655, 2160.5786871560053, 2252.8986195621073, 2422.4186195313173, 8849.469031858109, 10092.646757363531, 13641.113327633771, 14459.638509037359, 14719.0, 1657.5773177839694, 1723.3020626646228, 1766.9823301767076, 1780.866435017366, 1795.1742198588665, 95714.52142739312, 100435.9956400434, 100989.99010009879, 101196.49401752945, 102168.0, 10077.29458937882, 10216.693499195037, 10228.232882533839, 10279.023492768176, 10371.222158338154, 12402.503899843905, 12438.875611243771, 12444.315562644142, 12446.875531244752, 12458.75082498345, 8007.239312265326, 8103.351731861437, 8123.512589244664, 8182.7545173644885, 8205.71280005197, 7393.149787774367, 8424.115467875907, 8447.915520844836, 8506.659733610586, 8514.787130321667, 3919.4904662393706, 4097.549269580344, 4107.876763697094, 4262.42457268271, 4400.647948164139, 1668.9833101669071, 1791.4894255137224, 1819.0904547726136, 1875.559243577758, 1885.3118611706627, 799.7121036426998, 895.632790555869, 898.3756289378949, 974.337450533644, 1014.9441780702132, 18270.26918923255, 18662.253509859453, 19007.619847603248, 19011.524711882314, 19070.04649767506, 812.273015650989, 932.2914584915513, 956.8899576548647, 960.8325884050912, 982.7690492734201, 8817.57127603312, 9533.713988580354, 10942.17933654979, 10948.233623646427, 10956.0, 745510.0, 749450.0, 760790.0, 762398.0, 763789.0, 67068.99396509056, 67500.0, 93365.5331723337, 107398.46300768446, 111848.44075779569, 1111.4998250787178, 1123.4270522033657, 1138.527511082908, 1139.0, 1139.4530625299903, 16955.830441695423, 18207.724034495688, 18257.81742182561, 18423.0, 18598.349057782914, 49079.75460122676, 49508.50491495039, 50954.49045509497, 50980.235296469844, 53321.93356132778, 75715.62142189255, 77077.0, 77697.0, 77774.83337749825, 78308.0, 6134.294556125924, 7120.501564890408, 7552.6223688816435, 7767.262110099531, 7846.843063138591, 10778.622748203776, 12312.81530777057, 12482.75034499323, 12573.497060117494, 12820.423080961566, 7647.38820894316, 10526.31578947366, 10573.207009474474, 10635.308704934154, 10880.727981800519, 8839.911600883908, 9359.298052646214, 9701.417914925127, 9834.01659834011, 10677.679669609925, 10717.14262858985, 10842.0, 11014.339139651647, 11138.554457821596, 11286.604968826052, 7508.43686723487, 7689.923100769144, 7692.0, 7748.0, 7791.0650721913735, 8963.462192268486, 10314.690559283234, 10632.893671063499, 10860.674179774303, 10874.619388321371], "unit": "score", "name": "jquery.html", "value": 9185.769433773905}, {"lowerIsBetter": false, "replicates": [1387.458891032497, 1524.5273965070826, 1548.4967385599675, 1775.7425173349864, 1929.6816025355818, 7902.367810575154, 8478.957605211981, 8671.69649062283, 8672.52301123438, 8674.34942379321, 8453.830923381529, 8517.361197910166, 8601.526916019631, 8603.010653774818, 8641.65433382664, 2168.143583284602, 2226.3988723044768, 2454.177850420107, 2478.2565230430887, 2479.219046000513, 2243.147603910512, 2246.5731511012937, 2251.6172250717336, 2255.334676270496, 2427.514497100578, 2402.7957623602015, 2404.1224952892157, 2409.6144616861307, 2410.409449684821, 2420.3465064432594, 2368.348704106376, 2379.2267513058237, 2424.733279339271, 2425.1390756281444, 2425.1390756281617, 2120.4486833423266, 2181.8909054547366, 2183.825293976478, 2184.3119417383473, 2413.9396515087265, 7924.366050715987, 8315.459495132858, 8507.276881465083, 8509.61706723199, 8524.275436587897, 5224.582033437316, 5321.9201711973965, 5357.357117145967, 5358.2498450217, 5358.4373640767935, 3567.9821600892087, 3619.5475565554307, 3684.0237337105686, 3693.1874987502706, 3713.888583342477, 1010.989010989011, 1022.3814592171703, 1034.9120324772402, 1071.507106730897, 1073.6564299424188, 11497.482613282338, 11542.82685759706, 11551.53793848245, 11560.132990025697, 11583.826242606368, 4823.27650852368, 4841.491643377394, 4872.731999740048, 4894.535019173173, 4905.018996200774, 10163.898361016296, 10176.796464070678, 10180.440075795903, 10183.592656293813, 10187.745306367251, 4292.785360731951, 5458.536024437888, 5482.698451585121, 5492.862678433071, 5497.39528651848, 4930.926036109461, 4938.25926111079, 4938.777755000961, 4938.9012219755405, 4944.233643785219, 3363.226457914693, 3575.748488029169, 3685.6867166291167, 3772.6227377262053, 3806.71449641278, 1166.3759888459804, 1168.8714241433436, 1174.448009435564, 1177.6996865799167, 1182.2729021651621, 1262.9179103358251, 1308.9803652945213, 1327.1042046618493, 1355.105630284008, 1357.7895426208954, 7894.486858354188, 7980.600969951479, 8016.118226995026, 8021.639026243716, 8116.026076870813, 1029.9691009269734, 1196.4316949449117, 1203.0976767424431, 1217.634709587138, 1225.3628113380992, 5886.175935368966, 6057.212562366953, 6159.168512250879, 6246.781362652377, 6265.310815810257, 8237.958810205912, 10599.258051936291, 10613.469326533797, 10636.46817659114, 10643.52104155315, 9860.654877079267, 10330.586776528855, 10360.274780765274, 10396.22028347877, 10428.843567346496, 7823.804404889924, 8104.756857294291, 8155.510669359857, 8222.054463736627, 8247.546384948766, 7988.162722573725, 8066.757997259857, 8079.515229086273, 8093.190680932095, 8100.351971842357, 9097.909020909707, 9334.673286434941, 9357.672481463389, 9390.483523406005, 9532.618695252113, 9650.662226822027, 9724.076212759777, 9891.950540247253, 10044.246681499062, 10050.0, 7313.378362839218, 7357.0, 7397.630118493946, 7513.2111128330735, 7522.736704215326, 6493.448056915026, 8072.515649061075, 8128.674853005814, 8159.18408159203, 8160.183981601793, 6472.902906456313, 7604.5437273763755, 7608.8478230436185, 7753.883691744515, 7782.49413788102, 7404.851902962018, 7409.37020353276, 7493.363064139391, 7642.426817988562, 7661.808454788675, 7623.275788800055, 7675.347595454449, 7737.071551413641, 7737.3423259021365, 7745.7676269712, 6944.201416836926, 6995.370416662321, 6996.755113571001, 7009.684564194726, 7010.50926435165, 7038.014677945194, 7109.502334836512, 7111.182214045451, 7115.928840711526, 7117.537360071578], "unit": "score", "name": "mootools.html", "value": 4860.467722487395}, {"lowerIsBetter": false, "replicates": [313.1857171354479, 317.71881884532195, 325.94621887388587, 326.653435552762, 329.31831109603115, 5913.11303304504, 6313.242410910697, 6526.641034743087, 6590.044443555687, 6608.339166083395, 6299.2440907091095, 6454.0, 6469.967650161754, 6501.414872661459, 6514.1205937198465, 4368.344748287766, 4686.859394218179, 4839.419269687624, 4853.56317931387, 4864.197407427788, 4579.610733087691, 4656.371389862376, 4711.104890070893, 4817.9759101204445, 4873.951260487403, 3835.4055121456217, 4759.309900064489, 4760.738159401231, 4768.141734487791, 4781.689190202643, 4245.363195520666, 4464.843730469418, 4751.881202969919, 4890.046440944017, 4918.9508104919205, 4143.1713657268365, 4146.460960075171, 4146.481689788777, 4147.3778933159965, 4149.128682976579, 5990.460858522711, 6214.844628884269, 6215.223097112861, 6221.222347206599, 6265.498760099181, 4609.562091601292, 5736.082226843727, 5740.339860916007, 5744.569157313176, 5752.568557358173, 5958.493528050078, 6154.199954005995, 6554.836129096763, 7017.543859649107, 7019.789406317818, 4021.4973128358956, 4447.3996010538485, 4590.887843496072, 4664.673472856902, 4683.531646835323, 7438.181800001996, 7444.367228785505, 7448.1434635016585, 7457.813554661123, 7470.393865318928, 3695.2978934002454, 4524.88687782808, 4752.548507891814, 4752.667313288037, 4760.809567617326, 4803.831865884677, 4816.397950256218, 4824.758762061883, 4828.855134345975, 4831.598836337493, 4610.861674149781, 5678.971605141948, 5703.572232082611, 5717.256756621696, 5731.570132240098, 7044.859102817915, 7056.92943070573, 7057.89413158803, 7065.858682826315, 7091.149062112477, 1638.5493989153078, 1666.899986000844, 1667.1080971680092, 1667.816540180579, 1672.7741754862939, 893.9731808045769, 1054.383185836279, 1082.783443311341, 1084.3168803653648, 1094.8686157660973, 6544.28412208928, 6678.09845670838, 6728.831779205559, 6737.663116844138, 6744.055832183498, 6874.621895795779, 6983.127109111361, 7184.281571842774, 7195.280471952868, 7268.527545709512, 5639.943600563942, 6329.905051424233, 6503.154589903377, 6505.707243174069, 6515.250746164157, 3835.7890316032326, 3869.5356557212754, 3870.3613903706137, 3873.5932727063814, 3874.96125038746, 6161.322254551996, 7681.539107653559, 7706.691732330756, 7710.845783084419, 7711.190325015905, 6076.605020673642, 7425.62871856405, 7586.924130758732, 7625.923740762632, 7648.546776112521, 7713.151553329241, 7717.2668596483245, 7744.225577442324, 7747.535147891032, 7760.961195193988, 7302.744403945836, 7313.341799238094, 7366.742164024234, 7368.499962517762, 7377.741779037708, 6516.761815255087, 6916.827079323057, 7204.243554426919, 7220.602866842374, 7235.384992275505, 6988.231294557493, 7677.155512893689, 7688.384929205762, 7719.150893401609, 7724.459287849797, 4558.954410455853, 4619.284010978304, 4625.676202665781, 4631.2126938421225, 4640.675152739276, 6960.825979350557, 7138.607376594337, 7149.177844547944, 7213.0, 7252.963735181291, 7026.578405295699, 7033.156021277479, 7037.26108758573, 7039.8944015840825, 7048.436125110082, 6851.177858656993, 6974.61639609806, 6981.0, 7022.964885175541, 7033.788986330418, 5618.69097199658, 6966.338197871194, 6972.0, 6974.093367862146, 6976.965115174392, 5523.475269849358, 6757.729690812509, 6961.443084553325, 7125.5368401053765, 7146.249643787535, 7008.894866577108, 7038.753643622448, 7131.964340178266, 7171.462140339391, 7175.677094530863], "unit": "score", "name": "prototype.html", "value": 5089.839197267879}, {"lowerIsBetter": false, "replicates": [665.4111111666175, 725.0610459455007, 730.4813582356527, 745.191467258025, 771.9035120609924, 15067.849321506781, 15292.694146117072, 15298.69402611947, 15309.77035344468, 15326.693466130699, 13899.861001389983, 13976.440942362293, 13992.790108148385, 15596.0, 16005.039697618151, 858.8282343531288, 873.3929918706501, 875.0680525240598, 875.671623141322, 875.8905136857893, 690.9136357955256, 713.4043074033189, 863.7236084452946, 867.2671592504355, 882.2368651116769, 903.850864607339, 915.1077699243218, 916.225789208118, 917.0874979395511, 918.5177781664646, 718.2063819479505, 911.3074063711593, 911.4531281231241, 912.9087091290884, 913.0093848174705, 546.89335579562, 667.8363800868818, 678.3792829560995, 679.5277282288811, 679.7450955891541, 15024.849751502454, 15123.01700389471, 15135.0, 15143.0, 15212.543623691306, 12214.938925305316, 12242.81635775464, 12280.631581052583, 12615.621531354074, 12927.935360323232, 8380.622871970776, 10356.223283253787, 10384.844227336596, 10412.739681507946, 11051.336919784839, 3481.860725570974, 3537.7877327360443, 3622.7645203061716, 3671.541057367829, 3679.779213247187, 87525.0, 87695.99999999936, 88339.0, 88467.67298490528, 88554.0, 8874.645014199488, 8886.022537520868, 9148.771280717901, 9168.404053736485, 9520.619175233052, 21138.89430552868, 21140.682889756667, 21145.154193832383, 21145.942702864795, 21150.048247828883, 7354.0, 7435.553866767903, 7452.850942981218, 7478.2521747824785, 7589.0, 3951.3480275754755, 4086.734362266443, 4159.292920203573, 4226.429432026637, 4314.2958531380555, 3568.750187486851, 3630.49173115764, 3638.526991491089, 3646.3983442732183, 3647.015305867401, 602.3645054467491, 632.2570979099631, 640.7276907314466, 650.4601181019742, 653.5686446945089, 526.2816255810867, 569.971501424927, 574.5461085742301, 634.0236036503733, 640.7501074580917, 7419.703211871572, 8712.433691810138, 9134.680286190085, 9335.439873607469, 9365.139593401804, 590.3771521045338, 592.1887014789679, 599.1821164111044, 627.4039662320813, 648.2674577727138, 3839.4432807242792, 3876.1278712290073, 3908.179282350681, 4019.3569028955226, 4025.899352516152, 86996.1300386988, 92830.0, 93091.53454232686, 93237.5338123305, 93268.60097098548, 77197.0, 77212.61393693108, 77427.8385824213, 77938.22061779423, 78237.0, 63861.680691597176, 65971.34028659748, 66756.6662166686, 66774.33225667778, 66774.99837502441, 7773.0, 7788.805279868048, 7912.248336408032, 7991.679894903834, 8045.0, 47758.283625746335, 47823.52176478191, 48711.26933096077, 48878.0, 50158.498415016846, 71263.28736712567, 71504.77695065134, 72456.63771681108, 73023.0, 73206.63396682983, 11036.944815275872, 11110.555577776799, 11237.55049797999, 11252.493637786156, 11508.42457877086, 12339.62981110568, 12388.318642474751, 12437.0, 12561.183523070971, 12717.173383730027, 11195.440227988405, 11412.771744565229, 11442.885571144181, 11551.537938482366, 12417.875821242033, 11221.0, 11376.0, 11474.598389056342, 11821.822672659744, 11918.225315354473, 11046.917023607994, 11295.717607059889, 11330.206885517935, 11709.941450293034, 11741.236819606698, 9659.565319560777, 9726.319157658896, 9932.354396964174, 9992.150667193078, 10201.717595366546, 7410.592417417094, 8785.25325347327, 8786.868196977179, 9127.269818414643, 9466.574004169966], "unit": "score", "name": "yui.html", "value": 6891.2152151553655}], "name": "dromaeo_css", "value": 7420.155994532097}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 110.0, 110.0, 107.0, 112.0, 117.0, 111.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.8}, {"lowerIsBetter": true, "replicates": [160.0, 124.0, 146.0, 160.0, 122.0, 145.0, 145.0, 143.0, 197.0, 147.0], "unit": "score", "name": "audio-beat-detection", "value": 148.9}, {"lowerIsBetter": true, "replicates": [187.0, 183.0, 188.0, 184.0, 187.0, 186.0, 185.0, 181.0, 180.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.5}, {"lowerIsBetter": true, "replicates": [75.0, 77.0, 88.0, 80.0, 57.0, 58.0, 58.0, 80.0, 87.0, 86.0], "unit": "score", "name": "audio-fft", "value": 74.6}, {"lowerIsBetter": true, "replicates": [90.0, 104.0, 129.0, 78.0, 80.0, 80.0, 139.0, 97.0, 77.0, 108.0], "unit": "score", "name": "audio-oscillator", "value": 98.2}, {"lowerIsBetter": true, "replicates": [86.0, 90.0, 100.0, 140.0, 84.0, 89.0, 84.0, 90.0, 87.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 94.2}, {"lowerIsBetter": true, "replicates": [131.0, 93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 108.0, 150.0], "unit": "score", "name": "imaging-darkroom", "value": 104.6}, {"lowerIsBetter": true, "replicates": [93.0, 121.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 88.9}, {"lowerIsBetter": true, "replicates": [57.0, 55.0, 56.0, 53.0, 53.0, 55.0, 53.0, 64.0, 52.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.1}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 75.0, 76.0, 69.0, 67.0, 66.0, 69.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 82.0, 83.0, 83.0, 79.0, 80.0, 82.0, 81.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.2}, {"lowerIsBetter": true, "replicates": [133.0, 116.0, 129.0, 96.0, 127.0, 130.0, 131.0, 129.0, 109.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.2}, {"lowerIsBetter": true, "replicates": [139.0, 127.0, 139.0, 139.0, 136.0, 127.0, 138.0, 135.0, 121.0, 172.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.3}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 67.0, 68.0, 67.0, 70.0, 91.0, 96.0, 94.0, 95.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 78.1}], "name": "kraken", "value": 1447.9}]} 05:20:09 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1447.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7420.16"}}} 05:20:09 INFO - Return code: 0 05:20:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:20:09 INFO - # TBPL SUCCESS # 05:20:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:09 INFO - 2016-02-17 05:20:09.348 screenresolution[2756:25977] starting screenresolution argv=screenresolution get 05:20:09 INFO - 2016-02-17 05:20:09.351 screenresolution[2756:25977] Display 0: 1600x1200x32@60 05:20:09 INFO - 2016-02-17 05:20:09.357 screenresolution[2757:25980] starting screenresolution argv=screenresolution list 05:20:09 INFO - Available Modes on Display 0 05:20:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:09 INFO - Intel Iris: 05:20:09 INFO - Chipset Model: Intel Iris 05:20:09 INFO - Type: GPU 05:20:09 INFO - Bus: Built-In 05:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:09 INFO - Vendor: Intel (0x8086) 05:20:09 INFO - Device ID: 0x0a2e 05:20:09 INFO - Revision ID: 0x0009 05:20:09 INFO - Displays: 05:20:09 INFO - Display: 05:20:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:09 INFO - Display Serial Number: 1600x1200 60 05:20:09 INFO - Main Display: Yes 05:20:09 INFO - Mirror: Off 05:20:09 INFO - Online: Yes 05:20:09 INFO - Rotation: Supported 05:20:09 INFO - Return code: 0 05:20:09 INFO - Running post-action listener: _resource_record_post_action 05:20:09 INFO - Running post-run listener: _resource_record_post_run 05:20:10 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 155648; Write bytes: 711617024; Read time: 1; Write time: 2153 05:20:10 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397275648; Write bytes: 394760192; Read time: 29811; Write time: 817 05:20:10 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 155648; Write bytes: 300509696; Read time: 1; Write time: 1296 05:20:10 INFO - Running post-run listener: _upload_blobber_files 05:20:10 INFO - Blob upload gear active. 05:20:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:20:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:20:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:20:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:11 INFO - (blobuploader) - INFO - Done attempting. 05:20:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:20:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:20:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:12 INFO - (blobuploader) - INFO - Done attempting. 05:20:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:12 INFO - Return code: 0 05:20:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3451deaa95ce2ae5a2e3cad3490c2ca4d55c03fe445ec9af7d8867146e236c652861ec364a715c9884fd4b7bde4e98f522c1ae0d18a89cb744b9b10c807bb0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96b94853798136a8adddbe23e34415bf2fb124f02f925df235907b337b3d4a01e03d43fb8687caa50943ac87bf4535c9dbd466baa5a8dd9e62aa6ea96c38ffcc"} 05:20:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:12 INFO - Contents: 05:20:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3451deaa95ce2ae5a2e3cad3490c2ca4d55c03fe445ec9af7d8867146e236c652861ec364a715c9884fd4b7bde4e98f522c1ae0d18a89cb744b9b10c807bb0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96b94853798136a8adddbe23e34415bf2fb124f02f925df235907b337b3d4a01e03d43fb8687caa50943ac87bf4535c9dbd466baa5a8dd9e62aa6ea96c38ffcc"} 05:20:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:12 INFO - Copying logs to upload dir... 05:20:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.734561 ========= master_lag: 7.93 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-02-17 05:20:20.905539) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-02-17 05:20:20.917229) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/a3451deaa95ce2ae5a2e3cad3490c2ca4d55c03fe445ec9af7d8867146e236c652861ec364a715c9884fd4b7bde4e98f522c1ae0d18a89cb744b9b10c807bb0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96b94853798136a8adddbe23e34415bf2fb124f02f925df235907b337b3d4a01e03d43fb8687caa50943ac87bf4535c9dbd466baa5a8dd9e62aa6ea96c38ffcc"} build_url:https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/RIXaxVQeRWuY6zN7btSAuQ/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/a3451deaa95ce2ae5a2e3cad3490c2ca4d55c03fe445ec9af7d8867146e236c652861ec364a715c9884fd4b7bde4e98f522c1ae0d18a89cb744b9b10c807bb0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96b94853798136a8adddbe23e34415bf2fb124f02f925df235907b337b3d4a01e03d43fb8687caa50943ac87bf4535c9dbd466baa5a8dd9e62aa6ea96c38ffcc"}' ========= master_lag: 2.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-02-17 05:20:23.070252) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-02-17 05:20:23.070566) ========= 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.8bnKgaybDw/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.JMjjtx0MQ1/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005369 ========= master_lag: 6.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-02-17 05:20:29.249255) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 05:20:29.249533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 05:20:30.041910) ========= ========= Total master_lag: 16.45 =========