builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1455716402.64 results: success (0) buildid: 20160217032450 builduid: 531166f0468b4792b0e7999b2bfaf465 revision: 7f1d1993b0fded59292ea6d0603aee9e839377ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.639024) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.639378) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.639652) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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.005699 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.685630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.685920) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.769951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.770208) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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.005657 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.818370) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.818645) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.818998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:02.819267) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.8M=0.001s 2016-02-17 05:40:03 (15.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.541667 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:03.390264) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:03.390545) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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.026472 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:03.444736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 05:40:03.448052) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7f1d1993b0fded59292ea6d0603aee9e839377ec --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 7f1d1993b0fded59292ea6d0603aee9e839377ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:03,454 truncating revision to first 12 chars 2016-02-17 05:40:03,454 Setting DEBUG logging. 2016-02-17 05:40:03,454 attempt 1/10 2016-02-17 05:40:03,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7f1d1993b0fd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-17 05:40:04,517 unpacking tar archive at: fx-team-7f1d1993b0fd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.305437 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 05:40:04.768820) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:04.772146) ========= echo 7f1d1993b0fded59292ea6d0603aee9e839377ec in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'7f1d1993b0fded59292ea6d0603aee9e839377ec'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 7f1d1993b0fded59292ea6d0603aee9e839377ec program finished with exit code 0 elapsedTime=0.005396 script_repo_revision: '7f1d1993b0fded59292ea6d0603aee9e839377ec' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:04.825064) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:04.825338) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 05:40:04.840760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-02-17 05:40:04.841041) ========= /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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:04 INFO - MultiFileLogger online at 20160217 05:40:04 in /builds/slave/test 05:40:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:40:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:04 INFO - {'append_to_log': False, 05:40:04 INFO - 'base_work_dir': '/builds/slave/test', 05:40:04 INFO - 'blob_upload_branch': 'Fx-Team', 05:40:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:04 INFO - 'branch': 'Fx-Team', 05:40:04 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:04 INFO - 'config_files': ('talos/mac_config.py',), 05:40:04 INFO - 'default_actions': ('clobber', 05:40:04 INFO - 'read-buildbot-config', 05:40:04 INFO - 'download-and-extract', 05:40:04 INFO - 'populate-webroot', 05:40:04 INFO - 'create-virtualenv', 05:40:04 INFO - 'install', 05:40:04 INFO - 'run-tests'), 05:40:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:04 INFO - 'download_minidump_stackwalk': True, 05:40:04 INFO - 'download_symbols': 'ondemand', 05:40:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:04 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:04 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:04 INFO - 'installer_path': 'installer.exe', 05:40:04 INFO - 'log_level': 'info', 05:40:04 INFO - 'log_name': 'talos', 05:40:04 INFO - 'log_to_console': True, 05:40:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:40:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:40:04 INFO - 'opt_config_files': (), 05:40:04 INFO - 'pip_index': False, 05:40:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:04 INFO - 'cmd': ('bash', 05:40:04 INFO - '-c', 05:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:04 INFO - 'enabled': True, 05:40:04 INFO - 'halt_on_failure': False, 05:40:04 INFO - 'name': 'check_screen_resolution'},), 05:40:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:04 INFO - 'cmd': ('bash', 05:40:04 INFO - '-c', 05:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:04 INFO - 'enabled': True, 05:40:04 INFO - 'halt_on_failure': False, 05:40:04 INFO - 'name': 'check_screen_resolution'},), 05:40:04 INFO - 'run_cmd_checks_enabled': True, 05:40:04 INFO - 'sps_profile': False, 05:40:04 INFO - 'sps_profile_interval': 0, 05:40:04 INFO - 'suite': 'dromaeojs', 05:40:04 INFO - 'system_bits': '32', 05:40:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:04 INFO - 'title': 't-yosemite-r7-0185', 05:40:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:04 INFO - 'use_talos_json': True, 05:40:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:04 INFO - 'work_dir': 'build'} 05:40:04 INFO - ##### 05:40:04 INFO - ##### Running clobber step. 05:40:04 INFO - ##### 05:40:04 INFO - Running pre-action listener: _resource_record_pre_action 05:40:04 INFO - Running main action method: clobber 05:40:04 INFO - rmtree: /builds/slave/test/build 05:40:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:06 INFO - Running post-action listener: _resource_record_post_action 05:40:06 INFO - ##### 05:40:06 INFO - ##### Running read-buildbot-config step. 05:40:06 INFO - ##### 05:40:06 INFO - Running pre-action listener: _resource_record_pre_action 05:40:06 INFO - Running main action method: read_buildbot_config 05:40:06 INFO - Using buildbot properties: 05:40:06 INFO - { 05:40:06 INFO - "project": "", 05:40:06 INFO - "product": "firefox", 05:40:06 INFO - "script_repo_revision": "production", 05:40:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:40:06 INFO - "repository": "", 05:40:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:40:06 INFO - "buildid": "20160217032450", 05:40:06 INFO - "pgo_build": "False", 05:40:06 INFO - "basedir": "/builds/slave/test", 05:40:06 INFO - "buildnumber": 377, 05:40:06 INFO - "slavename": "t-yosemite-r7-0185", 05:40:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:40:06 INFO - "platform": "yosemite_r7", 05:40:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:40:06 INFO - "branch": "fx-team", 05:40:06 INFO - "repo_path": "integration/fx-team", 05:40:06 INFO - "revision": "7f1d1993b0fded59292ea6d0603aee9e839377ec", 05:40:06 INFO - "stage_platform": "macosx64", 05:40:06 INFO - "builduid": "531166f0468b4792b0e7999b2bfaf465", 05:40:06 INFO - "slavebuilddir": "test" 05:40:06 INFO - } 05:40:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 05:40:06 INFO - Running post-action listener: _resource_record_post_action 05:40:06 INFO - ##### 05:40:06 INFO - ##### Running download-and-extract step. 05:40:06 INFO - ##### 05:40:06 INFO - Running pre-action listener: _resource_record_pre_action 05:40:06 INFO - Running main action method: download_and_extract 05:40:06 INFO - mkdir: /builds/slave/test/build/tests 05:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:06 INFO - https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:40:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/test_packages.json 05:40:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/test_packages.json 05:40:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:40:11 INFO - Downloaded 1277 bytes. 05:40:11 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:40:11 INFO - Using the following test package requirements: 05:40:11 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:40:11 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:40:11 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:40:11 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'jsshell-mac.zip'], 05:40:11 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:40:11 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:40:11 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:40:11 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:40:11 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:40:11 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:40:11 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:40:11 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:40:11 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:11 INFO - https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:40:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:40:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:40:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:12 INFO - Downloaded 17806581 bytes. 05:40:12 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'] 05:40:12 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 05:40:15 INFO - Return code: 0 05:40:15 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 05:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:15 INFO - https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:40:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:40:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:40:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:16 INFO - Downloaded 17806581 bytes. 05:40:16 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'] 05:40:16 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 05:40:20 INFO - Return code: 0 05:40:20 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:40:20 INFO - https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:40:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:40:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:40:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:22 INFO - Downloaded 10707071 bytes. 05:40:22 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'] 05:40:22 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 05:40:22 INFO - Return code: 0 05:40:22 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:40:22 INFO - https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:40:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:40:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:40:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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 05:40:29 INFO - Downloaded 111234117 bytes. 05:40:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:40:29 INFO - mkdir: /builds/slave/test/properties 05:40:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:29 INFO - Contents: 05:40:29 INFO - build_url:https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:40:31 INFO - Running post-action listener: _resource_record_post_action 05:40:31 INFO - Running post-action listener: set_extra_try_arguments 05:40:31 INFO - ##### 05:40:31 INFO - ##### Running populate-webroot step. 05:40:31 INFO - ##### 05:40:31 INFO - Running pre-action listener: _resource_record_pre_action 05:40:31 INFO - Running main action method: populate_webroot 05:40:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:31 INFO - '--tppagecycles', 05:40:31 INFO - '7'], 05:40:31 INFO - 'tests': ['tsvgm']}}, 05:40:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:40:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:40:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'tests': ['damp', 'tps']}, 05:40:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['damp', 'tps']}, 05:40:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:40:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:31 INFO - 'other': {'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'other_l64': {'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'other_nol64': {'tests': ['a11yr', 05:40:31 INFO - 'ts_paint', 05:40:31 INFO - 'tpaint', 05:40:31 INFO - 'sessionrestore', 05:40:31 INFO - 'sessionrestore_no_auto_restore']}, 05:40:31 INFO - 'svgr': {'tests': ['tsvgx', 05:40:31 INFO - 'tsvgr_opacity', 05:40:31 INFO - 'tart', 05:40:31 INFO - 'tscrollx', 05:40:31 INFO - 'cart']}, 05:40:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['tsvgx', 05:40:31 INFO - 'tsvgr_opacity', 05:40:31 INFO - 'tart', 05:40:31 INFO - 'tscrollx', 05:40:31 INFO - 'cart']}, 05:40:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'tests': ['tp5o']}, 05:40:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'talos_options': ['--e10s'], 05:40:31 INFO - 'tests': ['tp5o']}, 05:40:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'talos_options': ['--xperf_path', 05:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:31 INFO - 'tests': ['tp5n']}, 05:40:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:31 INFO - 'talos_options': ['--e10s', 05:40:31 INFO - '--xperf_path', 05:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:31 INFO - 'tests': ['tp5n']}}, 05:40:31 INFO - 'talos.zip': {'path': '', 05:40:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:31 INFO - Running post-action listener: _resource_record_post_action 05:40:31 INFO - ##### 05:40:31 INFO - ##### Running create-virtualenv step. 05:40:31 INFO - ##### 05:40:31 INFO - Running pre-action listener: _resource_record_pre_action 05:40:31 INFO - Running main action method: create_virtualenv 05:40:31 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:31 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 05:40:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:31 INFO - Using real prefix '/tools/python27' 05:40:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:34 INFO - Installing pip.................done. 05:40:34 INFO - Return code: 0 05:40:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:34 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:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:34 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:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:34 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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:34 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 05:40:34 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 05:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:34 INFO - 'HOME': '/Users/cltbld', 05:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:34 INFO - 'LOGNAME': 'cltbld', 05:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:34 INFO - 'MOZ_NO_REMOTE': '1', 05:40:34 INFO - 'NO_EM_RESTART': '1', 05:40:34 INFO - 'PAGER': '/bin/cat', 05:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:34 INFO - 'PWD': '/builds/slave/test', 05:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:34 INFO - 'SHELL': '/bin/bash', 05:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:34 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:34 INFO - 'USER': 'cltbld', 05:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:34 INFO - 'XPC_FLAGS': '0x0', 05:40:34 INFO - 'XPC_SERVICE_NAME': '0', 05:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:35 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 05:40:35 INFO - Cleaning up... 05:40:35 INFO - Return code: 0 05:40:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:35 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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:35 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 05:40:35 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 05:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:35 INFO - 'HOME': '/Users/cltbld', 05:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:35 INFO - 'LOGNAME': 'cltbld', 05:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:35 INFO - 'MOZ_NO_REMOTE': '1', 05:40:35 INFO - 'NO_EM_RESTART': '1', 05:40:35 INFO - 'PAGER': '/bin/cat', 05:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:35 INFO - 'PWD': '/builds/slave/test', 05:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:35 INFO - 'SHELL': '/bin/bash', 05:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:35 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:35 INFO - 'USER': 'cltbld', 05:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:35 INFO - 'XPC_FLAGS': '0x0', 05:40:35 INFO - 'XPC_SERVICE_NAME': '0', 05:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:35 INFO - Downloading/unpacking psutil>=0.7.1 05:40:35 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:40:35 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:40:35 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:40:35 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:40:35 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:40:35 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:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:37 INFO - Installing collected packages: psutil 05:40:37 INFO - Running setup.py install for psutil 05:40:37 INFO - building 'psutil._psutil_osx' extension 05:40:37 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 05:40:38 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 05:40:38 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 05:40:38 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 05:40:38 INFO - building 'psutil._psutil_posix' extension 05:40:38 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 05:40:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:38 INFO - ^ 05:40:38 INFO - 1 warning generated. 05:40:38 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 05:40:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:39 INFO - Successfully installed psutil 05:40:39 INFO - Cleaning up... 05:40:39 INFO - Return code: 0 05:40:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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', '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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:40: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 mozsystemmonitor==0.0.0 05:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:39 INFO - 'HOME': '/Users/cltbld', 05:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:39 INFO - 'LOGNAME': 'cltbld', 05:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:39 INFO - 'MOZ_NO_REMOTE': '1', 05:40:39 INFO - 'NO_EM_RESTART': '1', 05:40:39 INFO - 'PAGER': '/bin/cat', 05:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:39 INFO - 'PWD': '/builds/slave/test', 05:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:39 INFO - 'SHELL': '/bin/bash', 05:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:39 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:39 INFO - 'USER': 'cltbld', 05:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:39 INFO - 'XPC_FLAGS': '0x0', 05:40:39 INFO - 'XPC_SERVICE_NAME': '0', 05:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:40:41 INFO - Installing collected packages: mozsystemmonitor 05:40:41 INFO - Running setup.py install for mozsystemmonitor 05:40:41 INFO - Successfully installed mozsystemmonitor 05:40:41 INFO - Cleaning up... 05:40:41 INFO - Return code: 0 05:40:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:41 INFO - 'HOME': '/Users/cltbld', 05:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:41 INFO - 'LOGNAME': 'cltbld', 05:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:41 INFO - 'MOZ_NO_REMOTE': '1', 05:40:41 INFO - 'NO_EM_RESTART': '1', 05:40:41 INFO - 'PAGER': '/bin/cat', 05:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:41 INFO - 'PWD': '/builds/slave/test', 05:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:41 INFO - 'SHELL': '/bin/bash', 05:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:41 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:41 INFO - 'USER': 'cltbld', 05:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:41 INFO - 'XPC_FLAGS': '0x0', 05:40:41 INFO - 'XPC_SERVICE_NAME': '0', 05:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:42 INFO - Downloading/unpacking blobuploader==1.2.4 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:45 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:40:45 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:40:45 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:40:45 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:40:45 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:40:45 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:40:45 INFO - Downloading docopt-0.6.1.tar.gz 05:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:45 INFO - Running setup.py install for blobuploader 05:40:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:45 INFO - Running setup.py install for requests 05:40:46 INFO - Running setup.py install for docopt 05:40:46 INFO - Successfully installed blobuploader requests docopt 05:40:46 INFO - Cleaning up... 05:40:46 INFO - Return code: 0 05:40:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:46 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:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:46 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:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:46 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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:46 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 05:40:46 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 05:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:46 INFO - 'HOME': '/Users/cltbld', 05:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:46 INFO - 'LOGNAME': 'cltbld', 05:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:46 INFO - 'MOZ_NO_REMOTE': '1', 05:40:46 INFO - 'NO_EM_RESTART': '1', 05:40:46 INFO - 'PAGER': '/bin/cat', 05:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:46 INFO - 'PWD': '/builds/slave/test', 05:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:46 INFO - 'SHELL': '/bin/bash', 05:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:46 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:46 INFO - 'USER': 'cltbld', 05:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:46 INFO - 'XPC_FLAGS': '0x0', 05:40:46 INFO - 'XPC_SERVICE_NAME': '0', 05:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:46 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-hHGmJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-KrFMuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-BI__po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-8eu_t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-QCjjo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-4lOphx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-pZLVna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-l9nu1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-uV5S1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-b2MygQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:47 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-DLO7Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-DfW31T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-IASQ2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-VSSZkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-avDWlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-cnPrJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:48 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-BZjxMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:48 INFO - Running setup.py install for manifestparser 05:40:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:48 INFO - Running setup.py install for mozcrash 05:40:48 INFO - Running setup.py install for mozdebug 05:40:49 INFO - Running setup.py install for mozdevice 05:40:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Running setup.py install for mozfile 05:40:49 INFO - Running setup.py install for mozhttpd 05:40:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Running setup.py install for mozinfo 05:40:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Running setup.py install for mozInstall 05:40:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:49 INFO - Running setup.py install for mozleak 05:40:49 INFO - Running setup.py install for mozlog 05:40:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Running setup.py install for moznetwork 05:40:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Running setup.py install for mozprocess 05:40:50 INFO - Running setup.py install for mozprofile 05:40:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Running setup.py install for mozrunner 05:40:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:50 INFO - Running setup.py install for mozscreenshot 05:40:50 INFO - Running setup.py install for moztest 05:40:51 INFO - Running setup.py install for mozversion 05:40:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:51 INFO - Cleaning up... 05:40:51 INFO - Return code: 0 05:40:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:51 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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:51 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 05:40:51 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 05:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:51 INFO - 'HOME': '/Users/cltbld', 05:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:51 INFO - 'LOGNAME': 'cltbld', 05:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:51 INFO - 'MOZ_NO_REMOTE': '1', 05:40:51 INFO - 'NO_EM_RESTART': '1', 05:40:51 INFO - 'PAGER': '/bin/cat', 05:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:51 INFO - 'PWD': '/builds/slave/test', 05:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:51 INFO - 'SHELL': '/bin/bash', 05:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:51 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:51 INFO - 'USER': 'cltbld', 05:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:51 INFO - 'XPC_FLAGS': '0x0', 05:40:51 INFO - 'XPC_SERVICE_NAME': '0', 05:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-cId0S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:51 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)) 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-C1Y3ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:51 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)) 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-44aYqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:51 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)) 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-qnflgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:51 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)) 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-0B2rFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:51 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)) 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:51 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-xtH9nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-cyS1G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-Jos4ZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-CGnGrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-imre0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-aydDvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-98azv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-aRdOyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-pKRO1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:52 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)) 05:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:52 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-UHqNTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:53 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)) 05:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:53 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-kHZd1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:53 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)) 05:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:53 INFO - Running setup.py (path:/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/pip-geWBTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:53 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)) 05:40:53 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)) 05:40:53 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)) 05:40:53 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)) 05:40:53 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)) 05:40:53 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)) 05:40:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:53 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:40:53 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:40:53 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:40:53 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:40:53 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:40:53 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:40:56 INFO - Downloading blessings-1.6.tar.gz 05:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:56 INFO - Installing collected packages: blessings 05:40:56 INFO - Running setup.py install for blessings 05:40:56 INFO - Successfully installed blessings 05:40:56 INFO - Cleaning up... 05:40:57 INFO - Return code: 0 05:40:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:57 INFO - Reading from file tmpfile_stdout 05:40:57 INFO - Current package versions: 05:40:57 INFO - blessings == 1.6 05:40:57 INFO - blobuploader == 1.2.4 05:40:57 INFO - docopt == 0.6.1 05:40:57 INFO - manifestparser == 1.1 05:40:57 INFO - mozInstall == 1.12 05:40:57 INFO - mozcrash == 0.16 05:40:57 INFO - mozdebug == 0.1 05:40:57 INFO - mozdevice == 0.48 05:40:57 INFO - mozfile == 1.2 05:40:57 INFO - mozhttpd == 0.7 05:40:57 INFO - mozinfo == 0.9 05:40:57 INFO - mozleak == 0.1 05:40:57 INFO - mozlog == 3.1 05:40:57 INFO - moznetwork == 0.27 05:40:57 INFO - mozprocess == 0.22 05:40:57 INFO - mozprofile == 0.28 05:40:57 INFO - mozrunner == 6.11 05:40:57 INFO - mozscreenshot == 0.1 05:40:57 INFO - mozsystemmonitor == 0.0 05:40:57 INFO - moztest == 0.7 05:40:57 INFO - mozversion == 1.4 05:40:57 INFO - psutil == 3.1.1 05:40:57 INFO - requests == 1.2.3 05:40:57 INFO - wsgiref == 0.1.2 05:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40:57 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 05:40:57 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 05:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:57 INFO - 'HOME': '/Users/cltbld', 05:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:57 INFO - 'LOGNAME': 'cltbld', 05:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:57 INFO - 'MOZ_NO_REMOTE': '1', 05:40:57 INFO - 'NO_EM_RESTART': '1', 05:40:57 INFO - 'PAGER': '/bin/cat', 05:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:57 INFO - 'PWD': '/builds/slave/test', 05:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:57 INFO - 'SHELL': '/bin/bash', 05:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:57 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:57 INFO - 'USER': 'cltbld', 05:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:57 INFO - 'XPC_FLAGS': '0x0', 05:40:57 INFO - 'XPC_SERVICE_NAME': '0', 05:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 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)) 05:40:57 INFO - Cleaning up... 05:40:57 INFO - Return code: 0 05:40:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:40: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', 'jsonschema'] in /builds/slave/test/build 05:40: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 jsonschema 05:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:57 INFO - 'HOME': '/Users/cltbld', 05:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:57 INFO - 'LOGNAME': 'cltbld', 05:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:57 INFO - 'MOZ_NO_REMOTE': '1', 05:40:57 INFO - 'NO_EM_RESTART': '1', 05:40:57 INFO - 'PAGER': '/bin/cat', 05:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:57 INFO - 'PWD': '/builds/slave/test', 05:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:57 INFO - 'SHELL': '/bin/bash', 05:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:40:57 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:57 INFO - 'USER': 'cltbld', 05:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:57 INFO - 'XPC_FLAGS': '0x0', 05:40:57 INFO - 'XPC_SERVICE_NAME': '0', 05:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:57 INFO - Downloading/unpacking jsonschema 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:41:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:00 INFO - Downloading/unpacking functools32 (from jsonschema) 05:41: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:41: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:41: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:41: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:41: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:41: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:41:00 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:00 INFO - warning: no files found matching '*.txt' 05:41:00 INFO - no previously-included directories found matching 'build' 05:41:00 INFO - no previously-included directories found matching 'dist' 05:41:00 INFO - no previously-included directories found matching '.git*' 05:41:00 INFO - Installing collected packages: jsonschema, functools32 05:41:00 INFO - Running setup.py install for functools32 05:41:00 INFO - warning: no files found matching '*.txt' 05:41:00 INFO - no previously-included directories found matching 'build' 05:41:00 INFO - no previously-included directories found matching 'dist' 05:41:00 INFO - no previously-included directories found matching '.git*' 05:41:00 INFO - Successfully installed jsonschema functools32 05:41:00 INFO - Cleaning up... 05:41:00 INFO - Return code: 0 05:41:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdba47da30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, '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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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 05:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:41:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:41:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:00 INFO - 'HOME': '/Users/cltbld', 05:41:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:00 INFO - 'LOGNAME': 'cltbld', 05:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:00 INFO - 'MOZ_NO_REMOTE': '1', 05:41:00 INFO - 'NO_EM_RESTART': '1', 05:41:00 INFO - 'PAGER': '/bin/cat', 05:41:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:00 INFO - 'PWD': '/builds/slave/test', 05:41:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:00 INFO - 'SHELL': '/bin/bash', 05:41:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:41:00 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:41:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:00 INFO - 'USER': 'cltbld', 05:41:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:00 INFO - 'XPC_FLAGS': '0x0', 05:41:00 INFO - 'XPC_SERVICE_NAME': '0', 05:41:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:01 INFO - Downloading/unpacking flake8 05:41:01 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:41:01 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:41:01 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:41:01 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:41:01 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:41:01 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:41:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:41:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:41:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:41:04 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:41:04 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:41:04 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:41:04 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:41:04 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:41:04 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:41:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:41:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:04 INFO - no previously-included directories found matching 'docs/_build' 05:41:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:41:04 INFO - Running setup.py install for pep8 05:41:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:04 INFO - no previously-included directories found matching 'docs/_build' 05:41:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:41:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:41:04 INFO - Cleaning up... 05:41:05 INFO - Return code: 0 05:41:05 INFO - Running post-action listener: _resource_record_post_action 05:41:05 INFO - Running post-action listener: _start_resource_monitoring 05:41:05 INFO - Starting resource monitoring. 05:41:05 INFO - ##### 05:41:05 INFO - ##### Running install step. 05:41:05 INFO - ##### 05:41:05 INFO - Running pre-action listener: _resource_record_pre_action 05:41:05 INFO - Running main action method: install 05:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:05 INFO - Reading from file tmpfile_stdout 05:41:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:05 INFO - Reading from file tmpfile_stdout 05:41:05 INFO - Output received: 05:41:05 INFO - Usage: mozinstall [options] installer 05:41:05 INFO - Options: 05:41:05 INFO - -h, --help show this help message and exit 05:41:05 INFO - -d DEST, --destination=DEST 05:41:05 INFO - Directory to install application into. [default: 05:41:05 INFO - "/builds/slave/test"] 05:41:05 INFO - --app=APP Application being installed. [default: firefox] 05:41:05 INFO - mkdir: /builds/slave/test/build/application 05:41:05 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:41:05 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:41:39 INFO - Reading from file tmpfile_stdout 05:41:39 INFO - Output received: 05:41:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:41:39 INFO - Running post-action listener: _resource_record_post_action 05:41:39 INFO - ##### 05:41:39 INFO - ##### Running run-tests step. 05:41:39 INFO - ##### 05:41:39 INFO - Running pre-action listener: _resource_record_pre_action 05:41:39 INFO - Running main action method: run_tests 05:41:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:39 INFO - 2016-02-17 05:41:39.567 screenresolution[1654:7594] starting screenresolution argv=screenresolution get 05:41:39 INFO - 2016-02-17 05:41:39.569 screenresolution[1654:7594] Display 0: 1600x1200x32@60 05:41:39 INFO - 2016-02-17 05:41:39.574 screenresolution[1655:7596] starting screenresolution argv=screenresolution list 05:41:39 INFO - Available Modes on Display 0 05:41:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:39 INFO - Intel Iris: 05:41:39 INFO - Chipset Model: Intel Iris 05:41:39 INFO - Type: GPU 05:41:39 INFO - Bus: Built-In 05:41:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:39 INFO - Vendor: Intel (0x8086) 05:41:39 INFO - Device ID: 0x0a2e 05:41:39 INFO - Revision ID: 0x0009 05:41:39 INFO - Displays: 05:41:39 INFO - Display: 05:41:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:39 INFO - Display Serial Number: 1600x1200 60 05:41:39 INFO - Main Display: Yes 05:41:39 INFO - Mirror: Off 05:41:39 INFO - Online: Yes 05:41:39 INFO - Rotation: Supported 05:41:39 INFO - Return code: 0 05:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:39 INFO - Python 2.7.3 05:41:39 INFO - Return code: 0 05:41:39 INFO - grabbing minidump binary from tooltool 05:41: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')]} 05:41:39 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 0x100dddd30>, '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 0x100c01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:39 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:41:39 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:41:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:39 INFO - Return code: 0 05:41:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:41:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:41:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:41:41 INFO - Return code: 0 05:41:41 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/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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:41:41 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/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwsAGlKmAK/Render', 05:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:41 INFO - 'HOME': '/Users/cltbld', 05:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:41 INFO - 'LOGNAME': 'cltbld', 05:41:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:41 INFO - 'MOZ_NO_REMOTE': '1', 05:41:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:41 INFO - 'NO_EM_RESTART': '1', 05:41:41 INFO - 'PAGER': '/bin/cat', 05:41:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:41 INFO - 'PWD': '/builds/slave/test', 05:41:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:41 INFO - 'SHELL': '/bin/bash', 05:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJyKfzcZyD/Listeners', 05:41:41 INFO - 'TMPDIR': '/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/', 05:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:41 INFO - 'USER': 'cltbld', 05:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:41 INFO - 'XPC_FLAGS': '0x0', 05:41:41 INFO - 'XPC_SERVICE_NAME': '0', 05:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:41 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/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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:41:41 INFO - mozversion application_buildid: 20160217032450 05:41:41 INFO - mozversion application_changeset: 7f1d1993b0fded59292ea6d0603aee9e839377ec 05:41:41 INFO - mozversion application_display_name: Nightly 05:41:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:41 INFO - mozversion application_name: Firefox 05:41:41 INFO - mozversion application_remotingname: firefox 05:41:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:41:41 INFO - mozversion application_vendor: Mozilla 05:41:41 INFO - mozversion application_version: 47.0a1 05:41:41 INFO - mozversion platform_buildid: 20160217032450 05:41:41 INFO - mozversion platform_changeset: 7f1d1993b0fded59292ea6d0603aee9e839377ec 05:41:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:41:41 INFO - mozversion platform_version: 47.0a1 05:41:41 INFO - using testdate: 1455716501 05:41:41 INFO - actual date: 1455716501 05:41:41 INFO - starting webserver on 'localhost:49277' 05:41:41 INFO - SUITE-START | Running 2 tests 05:41:41 INFO - TEST-START | dromaeo_css 05:41:41 INFO - operating with platform_type : mac_ 05:41:41 INFO - Initialising browser for dromaeo_css test... 05:41:41 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/tmp9OwXpi/profile http://localhost:49277/getInfo.html) 05:41:43 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:41:43 INFO - PROCESS | 1673 | colorDepth:24 05:41:43 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:41:43 INFO - PROCESS | 1673 | __metrics 05:41:43 INFO - PROCESS | 1673 | 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:41:43 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:41:44 INFO - TEST-INFO | 1673: exit 0 05:41:44 INFO - Browser initialized. 05:41:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:44 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/tmp9OwXpi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:56 INFO - PROCESS | 1676 | RSS: Main: 244428800 05:41:56 INFO - PROCESS | 1676 | 05:42:47 INFO - PROCESS | 1676 | 1455716567451 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:44:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:44:59 INFO - PROCESS | 1676 | RSS: Main: 464101376 05:44:59 INFO - PROCESS | 1676 | 05:48:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:48:02 INFO - PROCESS | 1676 | RSS: Main: 379604992 05:48:02 INFO - PROCESS | 1676 | 05:51:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:51:05 INFO - PROCESS | 1676 | RSS: Main: 376950784 05:51:05 INFO - PROCESS | 1676 | 05:54:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:54:08 INFO - PROCESS | 1676 | RSS: Main: 334811136 05:54:08 INFO - PROCESS | 1676 | 05:57:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:57:12 INFO - PROCESS | 1676 | RSS: Main: 336297984 05:57:12 INFO - PROCESS | 1676 | 06:00:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 06:00:15 INFO - PROCESS | 1676 | RSS: Main: 422301696 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | __start_tp_report 06:00:15 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 06:00:15 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 06:00:15 INFO - PROCESS | 1676 | |i|pagename|runs| 06:00:15 INFO - PROCESS | 1676 | |0;dojo.html;3447.603525594557;3492.2142517933453;3508.1755787389966;3519.0674471265133;3553.5558055243096;5602.1036634138545;5662.348829884564;5663.716814159291;5694.800681976131;5721.227634269372;5691;5718.571107166968;5759.366469688331;5882.529397648178;5887.058070708687;5071.467495912932;5124.359455068116;5150.536451719345;5157.277981082651;5216.530512253897;5201;5209.531142197202;5214.608904332172;5247;5313.123334649778;5134.4095429025765;5142.331496905415;5220.399654039796;5221.269022336875;5235.712035838027;5203.193505006692;5207.4792520748;5215.426303106693;5232.686038837682;5235.450277720861;5130.4613015633195;5175.663581867167;5188.844334669928;5196.168613021936;5216.817411390583;5534.446555344473;5611.831645050655;5684.2326285951285;5700.37295897451;5747.683877386742;6215.409536094064;6240.843978900519;6255.687215639246;6258.780942666985;6276.87446251077;6159.907601385983;6269.843253918643;6327.209098862642;6330.430261276507;6331.55679102463;5758.568107391962;5795.043817770063;5851.736671849777;5903.291605007384;5904.557158213065;6309.08518264849;6314.494840412756;6314.747410103636;6329.113924050636;6335.271443783931;5956.404359564009;6138.14066030756;6146.832101900714;6199.752009919643;6207.782727604512;6173.259208894871;6285.308616052231;6287.811365659038;6289.654069026156;6289.7484100636375;6232;6259.311475737665;6265.937340626627;6269.655168965751;6275.435210831047;6094.5014626406155;6179.62922224659;6216.160818289563;6245.375462453809;6285;4903.901921961612;4912.066707325596;4922.286268491049;4934.9753251233515;4944.90110197794;4373.709755622099;4411.272140096912;4417.271150260171;4433.00257442073;4461.174682683714;5605.691686957175;5652.604317697722;5728.312602487728;5777.7977770777825;5811.389804070596;6260.373962603794;6316.336784637547;6322.9051564226575;6345.397187267202;6352.841178970562;5777.480026797609;5819.243498345188;5829.88340233193;5851.473367396869;5892.410758924073;5315.388730296066;5354.982553314858;5383.650062745987;5416.214648875891;5425.430329815392;6352.968235158887;6355.872882542416;6358.904616430665;6359.046143078575;6363.9045414318825;6305.369463053658;6323.715432805627;6326.873462530815;6337.841553961188;6343.397377249154;6180.412860778219;6255.687215639109;6259.718312676032;6275.623462592259;6289.056641503811;6244.656543889948;6244.843878903063;6250.281217660027;6259.499240060875;6262.843428914313;6298.71655775482;6303.842403939938;6304.464120549621;6305.90541141874;6335.809925702236;6024.246969128859;6081.695915204311;6094.908576371269;6115.57501430398;6143.631382117087;6060.484858786876;6071.514278857592;6092.116643086816;6094.573379863367;6097.115918191926;5895.64626122434;5900.232969713909;5927.851803704942;5948.10778383246;5950.9404905952115;6074.815410994814;6127;6130.693465326805;6152.107944347955;6157.89157951573;6001.219841420588;6092.208012958287;6096.664683442275;6098.695065246809;6110.877782444415 06:00:15 INFO - PROCESS | 1676 | |1;ext.html;452.33506745084713;475.4413564062226;503.9269305950636;578.7816645960254;598.8862116197924;39573.406398904;39907.40138897912;40087.1982560349;40092.79953600231;40168.39747403784;42385.57614423863;43177.920551986135;43235.3514697279;43239.13521729571;43323.56676433235;9584.472853993027;9813.607455701764;9814;9824.01759824019;9829.066238707312;9667.758306042335;9845.966173551782;9862.852057219112;9869.506524673738;9869.654562090329;9786.119249267564;9908.702738917844;9914.107730304271;9918.008199180096;9919.702408927707;9477.099675530882;9760.2679799015;9847.310688251826;9856.704298870975;9862.556184971658;9674.70975870725;9814.362066465657;9832.90167098327;9834;9837.655682051094;39782;40081.599184008075;40096.59903400958;40157.59842401597;40342.79828600868;3131.3111115554543;3156.889508867179;3308.503724441329;3392.4402473591826;3438.3123375324785;28144;28218.435631287466;28860;29218.269543261376;29933.102006939826;2750.4499100179846;2760.889564417421;2829.2361062513214;2860.7997440179197;2949.675535691072;482633;489383;489958;490396;490620;47007.529924701;66221.66889165524;66550.33449665441;66811;66826.66586667133;54547.727261364234;70354;71191.28808711951;71314.9302760459;71318.64340678263;16237.431689890796;16685.83314166867;16692.332306707594;16701.91649041747;16712.331506739592;11772.823407648892;11950.521979120738;12756.48974041028;12868.549600763929;12952.352382381032;20418.591628167353;20759.481012974797;20770.273040443513;20773.857437841063;20776;8534.975802903691;8701.782455438664;8825.69110081144;8892.911070889208;8930.374873758905;939.5631031570216;965.4110992294694;966.8646389505475;980.9883657172412;1006.7432804634918;25422.491550168892;29333.973310934016;29518;29631.703682963325;29786;1025.3181634213313;1042.6455005298083;1061.0927656853435;1073.9731506712394;1128.6896103571419;14328;14330.57008289753;14583.05210161336;14843.406263749544;15878.92060539713;644285;651161;651537;651611;653625;52393;58916;59413.10880336712;59562;59574.702126489094;1475.4540819896922;1489.8808095352347;1490.6720521485256;1500.7523758580148;1501.0693370110382;29458;31551.84224078865;31636;32213.194670133435;32963.50554741633;43639;50173.24740128974;50466.2430063542;54916.17625735537;55714;493480;502864.82811031456;504938;505046;505334;43024.50473386825;43570.56429435665;43649;43971.34042989422;44002.779986099864;44862.55137448714;45574.54425455703;45627.315590265505;46093.30860037035;46696.29955550737;28186;36707.81646091752;37402.50389984479;38075.428868566436;38463.615363846;32153.713851445682;34409;37023.62976370202;37417.87746367613;37655.62344376631;36496.63503364933;36631.81684091669;37195.07012324713;37430;37639;32283.35433291368;33562;33907.321853563284;33952.83023584866;34164;24827.751722482542;24933.50132997366;25343.6198457027;25367.873160634077;25623.743762562135 06:00:15 INFO - PROCESS | 1676 | |2;jquery.html;306.15806532036896;364.64447164015087;368.5945459994005;371.58011447064814;374;13331.733365332688;15763;18809.811901880978;19967.201311947523;21240;18498.260069597232;20281.39155825324;20490.770553766746;20513.897430512865;20571.691424628607;6323.715432805523;6662.666866656672;6688;6698.665066746667;6700.128983232173;4660.464046634646;4668.15607834905;6134.662593557353;6487.902681459758;7495;7147.785566433015;7461.030066091427;7475.73834915778;7478.813029674247;7491.363234125106;7068.964655176743;7363.190049094595;7422.2577742225885;7425.22035186303;7434;5243.764030618631;7075.973983772325;7086.893696594555;7091.361777440004;7097.5031747777675;10420;13044.869551304555;13122.540711075066;13901;24124.75875241243;1994.7307113539632;2087.3424871165535;2114.2917122763893;2140.6146893559153;2537.5305568469707;9472.810543789155;9559.665411710557;9673.70978870635;9675.516224188763;10075.949620251926;1703.9318427262895;1762.145359500654;1772.299941523104;1809.4300295406904;1825.7809062912402;91913.16173676556;98091.50954245255;98434;98585;99675;10077.841048279543;10147.543360548792;10870.565177392815;11070.944645276722;11133.777324453466;13256.602301930765;13441.260730659713;13456;13480.988925830601;13481.797773033413;8412.116727743622;8430.283425908867;8431.831363372821;8537.615807288686;8539.274161696201;7801.517711634771;7806.882896756553;8581.485110893367;8586.484810911365;8680.131986801269;3577.928441431157;3989.8005099744896;4187.371894215892;4423.424954755926;4470.664700147502;1380.8550102239321;1869.7008478643625;1871.0644677661169;1879.107423973629;1901.5246188452886;400.7875825812324;826.5990994367727;847.6609356257546;864.2178828160525;900.8828852249165;9553.570089345858;10399;11759.706007349885;11896.524139034342;11933.880661193278;426.8975445893025;447.3022085546558;447.44516799169446;464.72116729962426;467.6282355527364;6013.819585412444;6062.393760623902;6145.938540614626;6153.661548614779;6191.164192834;668860;679402;744767;749523;758240;91048;92286;111135.33297000552;111232;112154;1057.1014637558014;1144.730988217768;1190.9464074116686;1193.1111322064944;1194.6296648039136;14268.549412138842;18073.728894066335;18083;18199.98601077137;18422.263109475472;50739.74630126826;50840.49159508358;51135.74432127816;51859.18492852732;52092.73953630208;64161;69414;72009;72498;73223;7622.208780936365;7758.224177582197;7823.647935842787;7826.608669566385;7833.37333013369;9510.47692376926;10801.891981080089;11859;11871.76256474883;12287.385630718607;10681;10753.032227099598;10808.513616887101;10852.837207442053;11134.498947547541;10440.634577790044;10608.787824243625;10640.390154294655;10909.39998300101;11193.27243729155;7497.137829149499;9231.630734770533;9812.901870981199;10755;11284.040856526959;7496.512726672749;7651.732189373345;7759.573223472763;7788.143304236416;7876.960615196887;7779.667526197508;7792.961035194788;7975.960120199362;8045.356371490149;10038.64864729731 06:00:15 INFO - PROCESS | 1676 | |3;mootools.html;1419.9503017394393;1569.7723830044642;1595.7047946129967;1843.1337271482407;1980.3365872432726;7996.120426753053;8618.784530386736;8750.649974001048;8757.386982911199;8767.342449316295;8449.155084491547;8700.65197392105;8704.303655707545;8708.695195668153;8712.825743485126;2194.9341519754435;2254.8196144308417;2371.762823717623;2478.2936862994006;2506.097804790274;2293.71328583927;2352.2472808701223;2503.6119401492797;2514.924552263435;2515.9119430819924;2419.5644783938883;2487.751224877516;2490.4645501217233;2495.7878580320694;2499.600063989762;2455.323846176796;2465.988944532733;2485.7762801347967;2491.1281051631963;2494.8128890333114;2112.894355282245;2393.0257537531434;2472;2493.3143385568933;2495.0768215760286;7375.557466552024;8786.692465763668;8866.935967683854;8870.600822962917;8874.467531948103;5363.249145119687;5471.37079235889;5508.586855985817;5517.200005999108;5526.309211348582;3560.946585801215;3568.750187486877;3578.874739384135;3598.4242521196484;3640.308341415123;1027.542743479152;1038.4392428088822;1050.3277902142636;1076.9084627806646;1083.0090467222483;12133.968612667846;12134.757304853854;12150.33173175475;12164.69588260283;12181.208221465666;4908.41099068107;4909.607231421478;4992.475790042066;5000.149974504343;5006;8989.876265466817;9291.21024712907;10236.33463824849;10237.283390162765;10238.385696858213;5494.917576236359;5522.144067669517;5530.032244357302;5540.085885828874;5544.500994910478;4969.3539839821315;5034.899302014012;5037.848864534069;5042.84871453857;5054.166062599704;3912.080661044652;3933.977165936877;3939.350007248829;3967.7024223183375;3972.066564357374;1263.993680031594;1271.8092286156966;1272.8281681973;1284.6916739982523;1288.8002359634272;1318.9076764626575;1333.8132661427408;1355.6610847288177;1357.518081081202;1362.3733082782032;7979.72070977513;8202.712905048415;8221.301189398848;8223.21879421454;8231.09457959624;496.08719955282453;504.6190126454503;509.75786501411534;512.3518748782793;512.9358830146232;5178.585713142939;6245.875082498324;6272.090546870678;6272.937270627326;6281.026440901667;10357.585696572052;10600.25798194119;10615.575376984834;10627.468626568796;10749.731256718645;10121.898781012243;10480.056794888496;10504.159667226604;10522.789544209072;10606.893931060591;8052.8792068117855;8216.21945915137;8223.629936652866;8287.668493260202;8304.335653147733;7963.084245311632;7979.72070977513;8066.677332906618;8151.83696326082;8192.67229310821;9499.95250023773;9588.952055239679;9610.51947402641;9735.659251926148;9860.359076659994;9729.4648794317;9990.950045249727;10003.599856005678;10014.599416023277;10023.749406264902;6559.278479367361;6728.629925354153;7155.8568828624175;7346.081739782528;7348.448866335153;8065.153158918464;8076.030876294883;8135.430519863553;8143.389245806469;8149.959250203711;7374.299441553044;7611.200823913409;7689.115751688628;7740.574268415066;7754.030746156731;7418.295261950106;7438.256174382735;7484.513506622052;7598.468107232662;7637.618119094134;7597.810054748676;7655.923440765744;7692.538447693157;7704.576248306397;7713.4600577959;5817.301923769175;6959.826004349932;6963.059986901703;6971.198312194163;7006.75476358325;7055.823604409931;7101.396381307647;7101.644917753988;7107.395871350993;7110.608916509642 06:00:15 INFO - PROCESS | 1676 | |4;prototype.html;317.78390694327857;322.28613620829873;325.7589383855947;326.7908538535337;330.8858443836877;6395.456386207178;6570.277269500357;6604.702788374522;6617.106690596768;6653.367930046644;6354.968225158879;6521.510886683494;6529.379708927644;6533.411992920648;6563.9169537026455;4881.267809828528;4886.340344053543;4894.77973491192;4908.582770464497;4911.312416261726;4659.324397962293;4735.0529894021165;4775.689580177277;4901.362822833044;4912.508749125077;4107.363358679409;4572.15415148197;4770.904581908359;4789.233722604384;4799.208130658437;4759.643026773006;4762.880927976794;4771.330034487936;4773.856784296477;4777.546133117348;4086;4099.7745124018165;4104.261232978063;4119.443875076856;4121.340585506335;6118.0517019861545;6219.751209951642;6234.719437625317;6317.684115794238;6325.240971083499;5645.604807663465;5656.858578535528;5666.744996475128;5671.886562268773;5673.319201695823;6645.435138013275;6947.652617369162;6971.825704357381;6979.371856532885;6982.2319544850025;4498.640108791322;4518.502964673883;4607.746573938432;4613.976930115362;4626.375439315717;7367.410607151415;7405.592692401916;7414.555126692416;7432.777016689454;7461.925380746177;4702.717836929795;4704.435467743892;4706.317583950308;4707.364505791743;4717.905641887143;4480.081583275421;4672.392588963413;4676.69601475903;4688;4773.7374444405195;5530.474604912527;5545.833624991256;5549.72251387429;5550.611457197957;5556.111022236423;6315.463185629273;6412.006139048455;7006.4394848412;7012.333828286304;7017.754378596725;1373.4025698821044;1565.530340897726;1617.4338981356432;1621.7486289625126;1622.7809245751673;1012.8099483107319;1029.7219750667427;1037.896210378956;1046.4767616191905;1048.0829274384914;5335.466453354711;6590.077389165521;6598.637074960923;6599.70301336441;6603.141591593062;7196.496245262886;7196.928030719626;7212.206657267697;7221.602811845294;7232;6246.875062498724;6526.086347911296;6558.80323590293;6570.342965703391;6570.737170513222;3812.7140464465274;3832.7317087803585;3840.423936409505;3848.1534062506203;3858.9228215435533;7533.284337987882;7535.54786712799;7540.359069479046;7542.622868856535;7547.05661792276;7117.6085315308155;7412.962935185397;7425.702971881173;7428.885667149971;7470.514416562906;6273.435390814849;7405.814854628569;7585.924140758521;7590;7600.543967361866;5627.493525582717;7157.606331651809;7188.568685878864;7215.567065976058;7259.891101633585;5412.945870541244;5657.858553536194;7123;7129.928700713134;7178.102737157856;7618.085829700472;7622;7629.54222746637;7636.121845987783;7648.235176482307;4610.907781844411;4650.767461626838;4655.743934083658;4659.091477161921;4660.720356778605;7024.894626580707;7301.744438944824;7329.74345897891;7343.48595598303;7382.187959324578;7088.503804733619;7104.325089116525;7110.395616372666;7130.358267755927;7139.464540159405;7084.433245340258;7094.53885497441;7102.822429439306;7106.751263705745;7118.679659415442;5697.943020569907;6650.600963942179;6958.826029349307;6976.407005404394;7154.642267886481;7111.502194846311;7121.893171602326;7133.286671333033;7136.536125151849;7149.60677162761;6784.559003664746;6966.164060312795;6970.372666460043;6976.127984002;6992.265812089658 06:00:15 INFO - PROCESS | 1676 | |5;yui.html;641.2369280556135;678.3792829560952;684.3840543510839;689.6758523493957;747.4100509327775;13597.728045439086;13825.032247742662;13839.308034598254;13846.861531384684;13849.792253116211;13793.586192414243;15648.765268520952;15887.761683574756;15888.126153061577;15895.761563576556;695;704.7427688893542;848.8132610825609;863.3999370437548;863.6027427383411;852.842224188524;856.1609622569854;861.7070196133313;861.7845538615346;863.5897948474487;907.1971305394741;913.4793167894303;915.1077699243251;915.2266334946959;916.5921165081542;729.4456213277921;737.6569894998852;759.6657470712869;900.045951291633;903.3721563513361;673.9460843132538;675.2605896543278;675.9864802703919;678.7895752316798;679.1442782094597;15228.619284517865;15234.619134521614;15258.847411525854;15297.08217506942;15314.387424503006;12495;12783.29691866947;12851.16467429614;12886.097973141885;13023.804642930363;10111.797764044752;10186.796264074677;10227.642032528825;10239.283250172492;10940.015398614083;3521.47177923311;3560.893173204808;3562.928741425183;3563.982180089083;3569.23261498779;79088;80607;81439.5928020362;82257.76613350745;88367.23265534718;9702;9805.264605154642;9866.408015519091;10119.494025298705;10227.079562839232;22064.430513879626;22539.98570064351;22572.88713556422;22580.887095564416;22591.548169036854;7700.268474494914;7760.961195194101;7792.610369481503;7889.447738658238;8011.319037881729;4109.876703698834;4154.522229943595;4157.729747566398;4198.538160802309;4215.241256573785;3563.6258192889372;3600.945985810215;3612.6026137125423;3612.620674829105;3613.2050948791225;632.9588576742589;644.0210879463283;649.399305642279;653.4021370445988;657.0341597851246;482.4210946863777;517.5523172455798;525.7739172155933;609.6281268426256;624.5565648389694;8714.782130446789;8745.570306015477;8813.118688131135;8817.559122043873;8957.507337096522;501.55612283129693;532.9626926115135;543.4239705911667;602.9638221706712;636.3318515558645;3913.491246137984;4035.6771458283265;4040.9797951010646;4042.656374208166;4121.1345617420675;92960;93799.53100234592;94035.05964940262;98901.01098989062;100807;78488.60755696186;79441.60279198567;80055;82326.58836705778;87325.12674873298;62551;72755;74841.6257918707;75148.62425687836;76888.84666730004;8723;9077.45535267886;9107.408018478778;9155.359124861196;9161.26709863196;50806.491935081656;50985.470435886986;51032;51382;52023;63695.4456831479;67490.9876351865;70340;71183.28816711767;71257.28742712506;11549.82675259855;11793.764124717285;11798.52805887755;11833.585824496102;11983.041356691618;12101.213421127599;12153.635390938285;12459.813102803284;12790.61628151157;12855.421506032064;11532.711682207677;11607.30356178632;11664.6500604982;11682.649520514398;12429.378531073591;11595.710107247387;11616.14656294399;11813.70465738329;11994.100442467025;12443.377831108228;11651.766964660828;11823.704407389883;11832.645020649394;11883.168178227786;11900.523979040741;9554.474503902075;9655.468949207861;9875.259355548218;9884.505774711091;10151.187904967732;8429.199226073513;8646.308295336485;8969.551522423983;9372.625094996123;9764 06:00:15 INFO - PROCESS | 1676 | __end_tp_report 06:00:15 INFO - PROCESS | 1676 | __start_cc_report 06:00:15 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2112 06:00:15 INFO - PROCESS | 1676 | __end_cc_report 06:00:15 INFO - PROCESS | 1676 | __startTimestamp1455717615214__endTimestamp 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | ------- Summary: start ------- 06:00:15 INFO - PROCESS | 1676 | Number of tests: 6 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5774.05 Median:6024.25 stddev:638.05 (10.6%) stddev-sans-first:613.35 06:00:15 INFO - PROCESS | 1676 | Values: 3447.6 3492.2 3508.2 3519.1 3553.6 5602.1 5662.3 5663.7 5694.8 5721.2 5691.0 5718.6 5759.4 5882.5 5887.1 5071.5 5124.4 5150.5 5157.3 5216.5 5201.0 5209.5 5214.6 5247.0 5313.1 5134.4 5142.3 5220.4 5221.3 5235.7 5203.2 5207.5 5215.4 5232.7 5235.5 5130.5 5175.7 5188.8 5196.2 5216.8 5534.4 5611.8 5684.2 5700.4 5747.7 6215.4 6240.8 6255.7 6258.8 6276.9 6159.9 6269.8 6327.2 6330.4 6331.6 5758.6 5795.0 5851.7 5903.3 5904.6 6309.1 6314.5 6314.7 6329.1 6335.3 5956.4 6138.1 6146.8 6199.8 6207.8 6173.3 6285.3 6287.8 6289.7 6289.7 6232.0 6259.3 6265.9 6269.7 6275.4 6094.5 6179.6 6216.2 6245.4 6285.0 4903.9 4912.1 4922.3 4935.0 4944.9 4373.7 4411.3 4417.3 4433.0 4461.2 5605.7 5652.6 5728.3 5777.8 5811.4 6260.4 6316.3 6322.9 6345.4 6352.8 5777.5 5819.2 5829.9 5851.5 5892.4 5315.4 5355.0 5383.7 5416.2 5425.4 6353.0 6355.9 6358.9 6359.0 6363.9 6305.4 6323.7 6326.9 6337.8 6343.4 6180.4 6255.7 6259.7 6275.6 6289.1 6244.7 6244.8 6250.3 6259.5 6262.8 6298.7 6303.8 6304.5 6305.9 6335.8 6024.2 6081.7 6094.9 6115.6 6143.6 6060.5 6071.5 6092.1 6094.6 6097.1 5895.6 5900.2 5927.9 5948.1 5950.9 6074.8 6127.0 6130.7 6152.1 6157.9 6001.2 6092.2 6096.7 6098.7 6110.9 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:68955.50 Median:41364.19 stddev:147295.30 (356.1%) stddev-sans-first:147618.70 06:00:15 INFO - PROCESS | 1676 | Values: 452.3 475.4 503.9 578.8 598.9 39573.4 39907.4 40087.2 40092.8 40168.4 42385.6 43177.9 43235.4 43239.1 43323.6 9584.5 9813.6 9814.0 9824.0 9829.1 9667.8 9846.0 9862.9 9869.5 9869.7 9786.1 9908.7 9914.1 9918.0 9919.7 9477.1 9760.3 9847.3 9856.7 9862.6 9674.7 9814.4 9832.9 9834.0 9837.7 39782.0 40081.6 40096.6 40157.6 40342.8 3131.3 3156.9 3308.5 3392.4 3438.3 28144.0 28218.4 28860.0 29218.3 29933.1 2750.4 2760.9 2829.2 2860.8 2949.7 482633.0 489383.0 489958.0 490396.0 490620.0 47007.5 66221.7 66550.3 66811.0 66826.7 54547.7 70354.0 71191.3 71314.9 71318.6 16237.4 16685.8 16692.3 16701.9 16712.3 11772.8 11950.5 12756.5 12868.5 12952.4 20418.6 20759.5 20770.3 20773.9 20776.0 8535.0 8701.8 8825.7 8892.9 8930.4 939.6 965.4 966.9 981.0 1006.7 25422.5 29334.0 29518.0 29631.7 29786.0 1025.3 1042.6 1061.1 1074.0 1128.7 14328.0 14330.6 14583.1 14843.4 15878.9 644285.0 651161.0 651537.0 651611.0 653625.0 52393.0 58916.0 59413.1 59562.0 59574.7 1475.5 1489.9 1490.7 1500.8 1501.1 29458.0 31551.8 31636.0 32213.2 32963.5 43639.0 50173.2 50466.2 54916.2 55714.0 493480.0 502864.8 504938.0 505046.0 505334.0 43024.5 43570.6 43649.0 43971.3 44002.8 44862.6 45574.5 45627.3 46093.3 46696.3 28186.0 36707.8 37402.5 38075.4 38463.6 32153.7 34409.0 37023.6 37417.9 37655.6 36496.6 36631.8 37195.1 37430.0 37639.0 32283.4 33562.0 33907.3 33952.8 34164.0 24827.8 24933.5 25343.6 25367.9 25623.7 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:35933.81 Median:4664.31 stddev:118693.55 (2544.7%) stddev-sans-first:118996.36 06:00:15 INFO - PROCESS | 1676 | Values: 306.2 364.6 368.6 371.6 374.0 13331.7 15763.0 18809.8 19967.2 21240.0 18498.3 20281.4 20490.8 20513.9 20571.7 6323.7 6662.7 6688.0 6698.7 6700.1 4660.5 4668.2 6134.7 6487.9 7495.0 7147.8 7461.0 7475.7 7478.8 7491.4 7069.0 7363.2 7422.3 7425.2 7434.0 5243.8 7076.0 7086.9 7091.4 7097.5 10420.0 13044.9 13122.5 13901.0 24124.8 1994.7 2087.3 2114.3 2140.6 2537.5 9472.8 9559.7 9673.7 9675.5 10075.9 1703.9 1762.1 1772.3 1809.4 1825.8 91913.2 98091.5 98434.0 98585.0 99675.0 10077.8 10147.5 10870.6 11070.9 11133.8 13256.6 13441.3 13456.0 13481.0 13481.8 8412.1 8430.3 8431.8 8537.6 8539.3 7801.5 7806.9 8581.5 8586.5 8680.1 3577.9 3989.8 4187.4 4423.4 4470.7 1380.9 1869.7 1871.1 1879.1 1901.5 400.8 826.6 847.7 864.2 900.9 9553.6 10399.0 11759.7 11896.5 11933.9 426.9 447.3 447.4 464.7 467.6 6013.8 6062.4 6145.9 6153.7 6191.2 668860.0 679402.0 744767.0 749523.0 758240.0 91048.0 92286.0 111135.3 111232.0 112154.0 1057.1 1144.7 1190.9 1193.1 1194.6 14268.5 18073.7 18083.0 18200.0 18422.3 50739.7 50840.5 51135.7 51859.2 52092.7 64161.0 69414.0 72009.0 72498.0 73223.0 7622.2 7758.2 7823.6 7826.6 7833.4 9510.5 10801.9 11859.0 11871.8 12287.4 10681.0 10753.0 10808.5 10852.8 11134.5 10440.6 10608.8 10640.4 10909.4 11193.3 7497.1 9231.6 9812.9 10755.0 11284.0 7496.5 7651.7 7759.6 7788.1 7877.0 7779.7 7793.0 7976.0 8045.4 10038.6 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:6034.56 Median:2845.76 stddev:3198.80 (112.4%) stddev-sans-first:3188.97 06:00:15 INFO - PROCESS | 1676 | Values: 1420.0 1569.8 1595.7 1843.1 1980.3 7996.1 8618.8 8750.6 8757.4 8767.3 8449.2 8700.7 8704.3 8708.7 8712.8 2194.9 2254.8 2371.8 2478.3 2506.1 2293.7 2352.2 2503.6 2514.9 2515.9 2419.6 2487.8 2490.5 2495.8 2499.6 2455.3 2466.0 2485.8 2491.1 2494.8 2112.9 2393.0 2472.0 2493.3 2495.1 7375.6 8786.7 8866.9 8870.6 8874.5 5363.2 5471.4 5508.6 5517.2 5526.3 3560.9 3568.8 3578.9 3598.4 3640.3 1027.5 1038.4 1050.3 1076.9 1083.0 12134.0 12134.8 12150.3 12164.7 12181.2 4908.4 4909.6 4992.5 5000.1 5006.0 8989.9 9291.2 10236.3 10237.3 10238.4 5494.9 5522.1 5530.0 5540.1 5544.5 4969.4 5034.9 5037.8 5042.8 5054.2 3912.1 3934.0 3939.4 3967.7 3972.1 1264.0 1271.8 1272.8 1284.7 1288.8 1318.9 1333.8 1355.7 1357.5 1362.4 7979.7 8202.7 8221.3 8223.2 8231.1 496.1 504.6 509.8 512.4 512.9 5178.6 6245.9 6272.1 6272.9 6281.0 10357.6 10600.3 10615.6 10627.5 10749.7 10121.9 10480.1 10504.2 10522.8 10606.9 8052.9 8216.2 8223.6 8287.7 8304.3 7963.1 7979.7 8066.7 8151.8 8192.7 9500.0 9589.0 9610.5 9735.7 9860.4 9729.5 9991.0 10003.6 10014.6 10023.7 6559.3 6728.6 7155.9 7346.1 7348.4 8065.2 8076.0 8135.4 8143.4 8150.0 7374.3 7611.2 7689.1 7740.6 7754.0 7418.3 7438.3 7484.5 7598.5 7637.6 7597.8 7655.9 7692.5 7704.6 7713.5 5817.3 6959.8 6963.1 6971.2 7006.8 7055.8 7101.4 7101.6 7107.4 7110.6 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5712.24 Median:6466.76 stddev:1830.30 (28.3%) stddev-sans-first:1790.09 06:00:15 INFO - PROCESS | 1676 | Values: 317.8 322.3 325.8 326.8 330.9 6395.5 6570.3 6604.7 6617.1 6653.4 6355.0 6521.5 6529.4 6533.4 6563.9 4881.3 4886.3 4894.8 4908.6 4911.3 4659.3 4735.1 4775.7 4901.4 4912.5 4107.4 4572.2 4770.9 4789.2 4799.2 4759.6 4762.9 4771.3 4773.9 4777.5 4086.0 4099.8 4104.3 4119.4 4121.3 6118.1 6219.8 6234.7 6317.7 6325.2 5645.6 5656.9 5666.7 5671.9 5673.3 6645.4 6947.7 6971.8 6979.4 6982.2 4498.6 4518.5 4607.7 4614.0 4626.4 7367.4 7405.6 7414.6 7432.8 7461.9 4702.7 4704.4 4706.3 4707.4 4717.9 4480.1 4672.4 4676.7 4688.0 4773.7 5530.5 5545.8 5549.7 5550.6 5556.1 6315.5 6412.0 7006.4 7012.3 7017.8 1373.4 1565.5 1617.4 1621.7 1622.8 1012.8 1029.7 1037.9 1046.5 1048.1 5335.5 6590.1 6598.6 6599.7 6603.1 7196.5 7196.9 7212.2 7221.6 7232.0 6246.9 6526.1 6558.8 6570.3 6570.7 3812.7 3832.7 3840.4 3848.2 3858.9 7533.3 7535.5 7540.4 7542.6 7547.1 7117.6 7413.0 7425.7 7428.9 7470.5 6273.4 7405.8 7585.9 7590.0 7600.5 5627.5 7157.6 7188.6 7215.6 7259.9 5412.9 5657.9 7123.0 7129.9 7178.1 7618.1 7622.0 7629.5 7636.1 7648.2 4610.9 4650.8 4655.7 4659.1 4660.7 7024.9 7301.7 7329.7 7343.5 7382.2 7088.5 7104.3 7110.4 7130.4 7139.5 7084.4 7094.5 7102.8 7106.8 7118.7 5697.9 6650.6 6958.8 6976.4 7154.6 7111.5 7121.9 7133.3 7136.5 7149.6 6784.6 6966.2 6970.4 6976.1 6992.3 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18851.68 Median:606.30 stddev:26560.51 (4380.8%) stddev-sans-first:26599.82 06:00:15 INFO - PROCESS | 1676 | Values: 641.2 678.4 684.4 689.7 747.4 13597.7 13825.0 13839.3 13846.9 13849.8 13793.6 15648.8 15887.8 15888.1 15895.8 695.0 704.7 848.8 863.4 863.6 852.8 856.2 861.7 861.8 863.6 907.2 913.5 915.1 915.2 916.6 729.4 737.7 759.7 900.0 903.4 673.9 675.3 676.0 678.8 679.1 15228.6 15234.6 15258.8 15297.1 15314.4 12495.0 12783.3 12851.2 12886.1 13023.8 10111.8 10186.8 10227.6 10239.3 10940.0 3521.5 3560.9 3562.9 3564.0 3569.2 79088.0 80607.0 81439.6 82257.8 88367.2 9702.0 9805.3 9866.4 10119.5 10227.1 22064.4 22540.0 22572.9 22580.9 22591.5 7700.3 7761.0 7792.6 7889.4 8011.3 4109.9 4154.5 4157.7 4198.5 4215.2 3563.6 3600.9 3612.6 3612.6 3613.2 633.0 644.0 649.4 653.4 657.0 482.4 517.6 525.8 609.6 624.6 8714.8 8745.6 8813.1 8817.6 8957.5 501.6 533.0 543.4 603.0 636.3 3913.5 4035.7 4041.0 4042.7 4121.1 92960.0 93799.5 94035.1 98901.0 100807.0 78488.6 79441.6 80055.0 82326.6 87325.1 62551.0 72755.0 74841.6 75148.6 76888.8 8723.0 9077.5 9107.4 9155.4 9161.3 50806.5 50985.5 51032.0 51382.0 52023.0 63695.4 67491.0 70340.0 71183.3 71257.3 11549.8 11793.8 11798.5 11833.6 11983.0 12101.2 12153.6 12459.8 12790.6 12855.4 11532.7 11607.3 11664.7 11682.6 12429.4 11595.7 11616.1 11813.7 11994.1 12443.4 11651.8 11823.7 11832.6 11883.2 11900.5 9554.5 9655.5 9875.3 9884.5 10151.2 8429.2 8646.3 8969.6 9372.6 9764.0 06:00:15 INFO - PROCESS | 1676 | -------- Summary: end -------- 06:00:15 INFO - PROCESS | 1676 | 06:00:15 INFO - TEST-INFO | 1676: exit 0 06:00:15 INFO - TEST-OK | dromaeo_css | took 1114384ms 06:00:15 INFO - TEST-START | kraken 06:00:15 INFO - operating with platform_type : mac_ 06:00:15 INFO - Initialising browser for kraken test... 06:00:15 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/tmp4Zf1KB/profile http://localhost:49277/getInfo.html) 06:00:17 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 06:00:17 INFO - PROCESS | 1682 | colorDepth:24 06:00:17 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 06:00:17 INFO - PROCESS | 1682 | __metrics 06:00:17 INFO - PROCESS | 1682 | 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" 06:00:17 INFO - PROCESS | 1682 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:00:18 INFO - TEST-INFO | 1682: exit 0 06:00:18 INFO - Browser initialized. 06:00:18 INFO - Running cycle 1/1 for kraken test... 06:00:18 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/T/tmp4Zf1KB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:30 INFO - PROCESS | 1684 | RSS: Main: 242946048 06:00:30 INFO - PROCESS | 1684 | 06:00:33 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:33 INFO - PROCESS | 1684 | RSS: Main: 291237888 06:00:33 INFO - PROCESS | 1684 | 06:00:36 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 06:00:37 INFO - PROCESS | 1684 | RSS: Main: 302047232 06:00:37 INFO - PROCESS | 1684 | 06:00:40 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 06:00:40 INFO - PROCESS | 1684 | RSS: Main: 285335552 06:00:40 INFO - PROCESS | 1684 | 06:00:42 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 06:00:43 INFO - PROCESS | 1684 | RSS: Main: 287285248 06:00:43 INFO - PROCESS | 1684 | 06:00:45 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:46 INFO - PROCESS | 1684 | RSS: Main: 289771520 06:00:46 INFO - PROCESS | 1684 | 06:00:48 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:49 INFO - PROCESS | 1684 | RSS: Main: 288456704 06:00:49 INFO - PROCESS | 1684 | 06:00:52 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:52 INFO - PROCESS | 1684 | RSS: Main: 288591872 06:00:52 INFO - PROCESS | 1684 | 06:00:55 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 06:00:56 INFO - PROCESS | 1684 | RSS: Main: 290934784 06:00:56 INFO - PROCESS | 1684 | 06:00:57 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:58 INFO - PROCESS | 1684 | RSS: Main: 325791744 06:00:58 INFO - PROCESS | 1684 | 06:01:00 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:01:00 INFO - PROCESS | 1684 | RSS: Main: 323072000 06:01:00 INFO - PROCESS | 1684 | 06:01:02 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:01:03 INFO - PROCESS | 1684 | RSS: Main: 331624448 06:01:03 INFO - PROCESS | 1684 | 06:01:06 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:01:06 INFO - PROCESS | 1684 | RSS: Main: 333410304 06:01:06 INFO - PROCESS | 1684 | 06:01:09 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:01:09 INFO - PROCESS | 1684 | RSS: Main: 333647872 06:01:09 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 06:01:11 INFO - PROCESS | 1684 | RSS: Main: 393441280 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | __start_tp_report 06:01:11 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 06:01:11 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 06:01:11 INFO - PROCESS | 1684 | |i|pagename|runs| 06:01:11 INFO - PROCESS | 1684 | |0;ai-astar;109;107;109;110;110;111;110;90;117;165 06:01:11 INFO - PROCESS | 1684 | |1;audio-beat-detection;157;122;144;153;122;142;139;120;157;119 06:01:11 INFO - PROCESS | 1684 | |2;audio-dft;185;183;183;183;186;186;184;182;180;179 06:01:11 INFO - PROCESS | 1684 | |3;audio-fft;70;87;87;75;56;58;57;81;75;56 06:01:11 INFO - PROCESS | 1684 | |4;audio-oscillator;90;95;127;78;81;83;112;77;77;110 06:01:11 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;86;92;86;92;86;91;85;91;86;92 06:01:11 INFO - PROCESS | 1684 | |6;imaging-darkroom;94;93;94;93;93;94;94;94;94;94 06:01:11 INFO - PROCESS | 1684 | |7;imaging-desaturate;85;84;84;87;85;107;134;133;85;87 06:01:11 INFO - PROCESS | 1684 | |8;json-parse-financial;56;54;54;54;54;53;55;64;52;52 06:01:11 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;64;67;70;75;68;68;65;66;68;66 06:01:11 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;83;83;83;82;84;101;106;108;102;82 06:01:11 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;123;127;124;132;126;107;129;133;127;113 06:01:11 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;139;127;138;138;137;142;138;138;138;139 06:01:11 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;67;65;67;69;83;93;94;93;75;67 06:01:11 INFO - PROCESS | 1684 | __end_tp_report 06:01:11 INFO - PROCESS | 1684 | __start_cc_report 06:01:11 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1142 06:01:11 INFO - PROCESS | 1684 | __end_cc_report 06:01:11 INFO - PROCESS | 1684 | __startTimestamp1455717671828__endTimestamp 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | ------- Summary: start ------- 06:01:11 INFO - PROCESS | 1684 | Number of tests: 14 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:113.80 Median:110.50 stddev:19.27 (17.4%) stddev-sans-first:20.36 06:01:11 INFO - PROCESS | 1684 | Values: 109.0 107.0 109.0 110.0 110.0 111.0 110.0 90.0 117.0 165.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:143.00 stddev:15.62 (10.9%) stddev-sans-first:14.88 06:01:11 INFO - PROCESS | 1684 | Values: 157.0 122.0 144.0 153.0 122.0 142.0 139.0 120.0 157.0 119.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:2.33 (1.3%) stddev-sans-first:2.37 06:01:11 INFO - PROCESS | 1684 | Values: 185.0 183.0 183.0 183.0 186.0 186.0 184.0 182.0 180.0 179.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:70.20 Median:75.00 stddev:12.71 (16.9%) stddev-sans-first:13.48 06:01:11 INFO - PROCESS | 1684 | Values: 70.0 87.0 87.0 75.0 56.0 58.0 57.0 81.0 75.0 56.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:79.50 stddev:17.64 (22.2%) stddev-sans-first:18.67 06:01:11 INFO - PROCESS | 1684 | Values: 90.0 95.0 127.0 78.0 81.0 83.0 112.0 77.0 77.0 110.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:91.00 stddev:3.09 (3.4%) stddev-sans-first:3.12 06:01:11 INFO - PROCESS | 1684 | Values: 86.0 92.0 86.0 92.0 86.0 91.0 85.0 91.0 86.0 92.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:01:11 INFO - PROCESS | 1684 | Values: 94.0 93.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:85.00 stddev:20.37 (24.0%) stddev-sans-first:21.13 06:01:11 INFO - PROCESS | 1684 | Values: 85.0 84.0 84.0 87.0 85.0 107.0 134.0 133.0 85.0 87.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:3.46 (6.4%) stddev-sans-first:3.64 06:01:11 INFO - PROCESS | 1684 | Values: 56.0 54.0 54.0 54.0 54.0 53.0 55.0 64.0 52.0 52.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:3.09 (4.5%) stddev-sans-first:2.98 06:01:11 INFO - PROCESS | 1684 | Values: 64.0 67.0 70.0 75.0 68.0 68.0 65.0 66.0 68.0 66.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:82.50 stddev:11.24 (13.6%) stddev-sans-first:11.50 06:01:11 INFO - PROCESS | 1684 | Values: 83.0 83.0 83.0 82.0 84.0 101.0 106.0 108.0 102.0 82.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:127.00 stddev:8.18 (6.4%) stddev-sans-first:8.67 06:01:11 INFO - PROCESS | 1684 | Values: 123.0 127.0 124.0 132.0 126.0 107.0 129.0 133.0 127.0 113.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.40 Median:138.00 stddev:3.89 (2.8%) stddev-sans-first:4.09 06:01:11 INFO - PROCESS | 1684 | Values: 139.0 127.0 138.0 138.0 137.0 142.0 138.0 138.0 138.0 139.0 06:01:11 INFO - PROCESS | 1684 | 06:01:11 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.30 Median:79.00 stddev:12.24 (15.5%) stddev-sans-first:12.40 06:01:11 INFO - PROCESS | 1684 | Values: 67.0 65.0 67.0 69.0 83.0 93.0 94.0 93.0 75.0 67.0 06:01:11 INFO - PROCESS | 1684 | -------- Summary: end -------- 06:01:11 INFO - PROCESS | 1684 | 06:01:12 INFO - TEST-INFO | 1684: exit 0 06:01:12 INFO - TEST-OK | kraken | took 56987ms 06:01:12 INFO - SUITE-END | took 1171s 06:01:12 INFO - Completed test suite (00:19:31) 06:01:12 INFO - Working with test: dromaeo_css 06:01:12 INFO - Generating results file: dromaeo_css 06:01:12 INFO - Working with test: kraken 06:01:12 INFO - Generating results file: kraken 06:01:12 INFO - javascript benchmark 06:01:12 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:01:13 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 06:01:13 INFO - process_Request line: dromaeo_css 7464.85 graph.html#tests=[[72,64,59]] 06:01:13 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:01:13 INFO - process_Request line: kraken 1429.80 graph.html#tests=[[232,64,59]] 06:01:13 INFO - RETURN: dromaeo_css: 7464.85 06:01:13 INFO - RETURN: kraken: 1429.80 06:01:13 INFO - javascript benchmark 06:01:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3447.603525594557, 3492.2142517933453, 3508.1755787389966, 3519.0674471265133, 3553.5558055243096, 5602.1036634138545, 5662.348829884564, 5663.716814159291, 5694.800681976131, 5721.227634269372, 5691.0, 5718.571107166968, 5759.366469688331, 5882.529397648178, 5887.058070708687, 5071.467495912932, 5124.359455068116, 5150.536451719345, 5157.277981082651, 5216.530512253897, 5201.0, 5209.531142197202, 5214.608904332172, 5247.0, 5313.123334649778, 5134.4095429025765, 5142.331496905415, 5220.399654039796, 5221.269022336875, 5235.712035838027, 5203.193505006692, 5207.4792520748, 5215.426303106693, 5232.686038837682, 5235.450277720861, 5130.4613015633195, 5175.663581867167, 5188.844334669928, 5196.168613021936, 5216.817411390583, 5534.446555344473, 5611.831645050655, 5684.2326285951285, 5700.37295897451, 5747.683877386742, 6215.409536094064, 6240.843978900519, 6255.687215639246, 6258.780942666985, 6276.87446251077, 6159.907601385983, 6269.843253918643, 6327.209098862642, 6330.430261276507, 6331.55679102463, 5758.568107391962, 5795.043817770063, 5851.736671849777, 5903.291605007384, 5904.557158213065, 6309.08518264849, 6314.494840412756, 6314.747410103636, 6329.113924050636, 6335.271443783931, 5956.404359564009, 6138.14066030756, 6146.832101900714, 6199.752009919643, 6207.782727604512, 6173.259208894871, 6285.308616052231, 6287.811365659038, 6289.654069026156, 6289.7484100636375, 6232.0, 6259.311475737665, 6265.937340626627, 6269.655168965751, 6275.435210831047, 6094.5014626406155, 6179.62922224659, 6216.160818289563, 6245.375462453809, 6285.0, 4903.901921961612, 4912.066707325596, 4922.286268491049, 4934.9753251233515, 4944.90110197794, 4373.709755622099, 4411.272140096912, 4417.271150260171, 4433.00257442073, 4461.174682683714, 5605.691686957175, 5652.604317697722, 5728.312602487728, 5777.7977770777825, 5811.389804070596, 6260.373962603794, 6316.336784637547, 6322.9051564226575, 6345.397187267202, 6352.841178970562, 5777.480026797609, 5819.243498345188, 5829.88340233193, 5851.473367396869, 5892.410758924073, 5315.388730296066, 5354.982553314858, 5383.650062745987, 5416.214648875891, 5425.430329815392, 6352.968235158887, 6355.872882542416, 6358.904616430665, 6359.046143078575, 6363.9045414318825, 6305.369463053658, 6323.715432805627, 6326.873462530815, 6337.841553961188, 6343.397377249154, 6180.412860778219, 6255.687215639109, 6259.718312676032, 6275.623462592259, 6289.056641503811, 6244.656543889948, 6244.843878903063, 6250.281217660027, 6259.499240060875, 6262.843428914313, 6298.71655775482, 6303.842403939938, 6304.464120549621, 6305.90541141874, 6335.809925702236, 6024.246969128859, 6081.695915204311, 6094.908576371269, 6115.57501430398, 6143.631382117087, 6060.484858786876, 6071.514278857592, 6092.116643086816, 6094.573379863367, 6097.115918191926, 5895.64626122434, 5900.232969713909, 5927.851803704942, 5948.10778383246, 5950.9404905952115, 6074.815410994814, 6127.0, 6130.693465326805, 6152.107944347955, 6157.89157951573, 6001.219841420588, 6092.208012958287, 6096.664683442275, 6098.695065246809, 6110.877782444415], "unit": "score", "name": "dojo.html", "value": 5733.291375482478}, {"lowerIsBetter": false, "replicates": [452.33506745084713, 475.4413564062226, 503.9269305950636, 578.7816645960254, 598.8862116197924, 39573.406398904, 39907.40138897912, 40087.1982560349, 40092.79953600231, 40168.39747403784, 42385.57614423863, 43177.920551986135, 43235.3514697279, 43239.13521729571, 43323.56676433235, 9584.472853993027, 9813.607455701764, 9814.0, 9824.01759824019, 9829.066238707312, 9667.758306042335, 9845.966173551782, 9862.852057219112, 9869.506524673738, 9869.654562090329, 9786.119249267564, 9908.702738917844, 9914.107730304271, 9918.008199180096, 9919.702408927707, 9477.099675530882, 9760.2679799015, 9847.310688251826, 9856.704298870975, 9862.556184971658, 9674.70975870725, 9814.362066465657, 9832.90167098327, 9834.0, 9837.655682051094, 39782.0, 40081.599184008075, 40096.59903400958, 40157.59842401597, 40342.79828600868, 3131.3111115554543, 3156.889508867179, 3308.503724441329, 3392.4402473591826, 3438.3123375324785, 28144.0, 28218.435631287466, 28860.0, 29218.269543261376, 29933.102006939826, 2750.4499100179846, 2760.889564417421, 2829.2361062513214, 2860.7997440179197, 2949.675535691072, 482633.0, 489383.0, 489958.0, 490396.0, 490620.0, 47007.529924701, 66221.66889165524, 66550.33449665441, 66811.0, 66826.66586667133, 54547.727261364234, 70354.0, 71191.28808711951, 71314.9302760459, 71318.64340678263, 16237.431689890796, 16685.83314166867, 16692.332306707594, 16701.91649041747, 16712.331506739592, 11772.823407648892, 11950.521979120738, 12756.48974041028, 12868.549600763929, 12952.352382381032, 20418.591628167353, 20759.481012974797, 20770.273040443513, 20773.857437841063, 20776.0, 8534.975802903691, 8701.782455438664, 8825.69110081144, 8892.911070889208, 8930.374873758905, 939.5631031570216, 965.4110992294694, 966.8646389505475, 980.9883657172412, 1006.7432804634918, 25422.491550168892, 29333.973310934016, 29518.0, 29631.703682963325, 29786.0, 1025.3181634213313, 1042.6455005298083, 1061.0927656853435, 1073.9731506712394, 1128.6896103571419, 14328.0, 14330.57008289753, 14583.05210161336, 14843.406263749544, 15878.92060539713, 644285.0, 651161.0, 651537.0, 651611.0, 653625.0, 52393.0, 58916.0, 59413.10880336712, 59562.0, 59574.702126489094, 1475.4540819896922, 1489.8808095352347, 1490.6720521485256, 1500.7523758580148, 1501.0693370110382, 29458.0, 31551.84224078865, 31636.0, 32213.194670133435, 32963.50554741633, 43639.0, 50173.24740128974, 50466.2430063542, 54916.17625735537, 55714.0, 493480.0, 502864.82811031456, 504938.0, 505046.0, 505334.0, 43024.50473386825, 43570.56429435665, 43649.0, 43971.34042989422, 44002.779986099864, 44862.55137448714, 45574.54425455703, 45627.315590265505, 46093.30860037035, 46696.29955550737, 28186.0, 36707.81646091752, 37402.50389984479, 38075.428868566436, 38463.615363846, 32153.713851445682, 34409.0, 37023.62976370202, 37417.87746367613, 37655.62344376631, 36496.63503364933, 36631.81684091669, 37195.07012324713, 37430.0, 37639.0, 32283.35433291368, 33562.0, 33907.321853563284, 33952.83023584866, 34164.0, 24827.751722482542, 24933.50132997366, 25343.6198457027, 25367.873160634077, 25623.743762562135], "unit": "score", "name": "ext.html", "value": 20265.576183495567}, {"lowerIsBetter": false, "replicates": [306.15806532036896, 364.64447164015087, 368.5945459994005, 371.58011447064814, 374.0, 13331.733365332688, 15763.0, 18809.811901880978, 19967.201311947523, 21240.0, 18498.260069597232, 20281.39155825324, 20490.770553766746, 20513.897430512865, 20571.691424628607, 6323.715432805523, 6662.666866656672, 6688.0, 6698.665066746667, 6700.128983232173, 4660.464046634646, 4668.15607834905, 6134.662593557353, 6487.902681459758, 7495.0, 7147.785566433015, 7461.030066091427, 7475.73834915778, 7478.813029674247, 7491.363234125106, 7068.964655176743, 7363.190049094595, 7422.2577742225885, 7425.22035186303, 7434.0, 5243.764030618631, 7075.973983772325, 7086.893696594555, 7091.361777440004, 7097.5031747777675, 10420.0, 13044.869551304555, 13122.540711075066, 13901.0, 24124.75875241243, 1994.7307113539632, 2087.3424871165535, 2114.2917122763893, 2140.6146893559153, 2537.5305568469707, 9472.810543789155, 9559.665411710557, 9673.70978870635, 9675.516224188763, 10075.949620251926, 1703.9318427262895, 1762.145359500654, 1772.299941523104, 1809.4300295406904, 1825.7809062912402, 91913.16173676556, 98091.50954245255, 98434.0, 98585.0, 99675.0, 10077.841048279543, 10147.543360548792, 10870.565177392815, 11070.944645276722, 11133.777324453466, 13256.602301930765, 13441.260730659713, 13456.0, 13480.988925830601, 13481.797773033413, 8412.116727743622, 8430.283425908867, 8431.831363372821, 8537.615807288686, 8539.274161696201, 7801.517711634771, 7806.882896756553, 8581.485110893367, 8586.484810911365, 8680.131986801269, 3577.928441431157, 3989.8005099744896, 4187.371894215892, 4423.424954755926, 4470.664700147502, 1380.8550102239321, 1869.7008478643625, 1871.0644677661169, 1879.107423973629, 1901.5246188452886, 400.7875825812324, 826.5990994367727, 847.6609356257546, 864.2178828160525, 900.8828852249165, 9553.570089345858, 10399.0, 11759.706007349885, 11896.524139034342, 11933.880661193278, 426.8975445893025, 447.3022085546558, 447.44516799169446, 464.72116729962426, 467.6282355527364, 6013.819585412444, 6062.393760623902, 6145.938540614626, 6153.661548614779, 6191.164192834, 668860.0, 679402.0, 744767.0, 749523.0, 758240.0, 91048.0, 92286.0, 111135.33297000552, 111232.0, 112154.0, 1057.1014637558014, 1144.730988217768, 1190.9464074116686, 1193.1111322064944, 1194.6296648039136, 14268.549412138842, 18073.728894066335, 18083.0, 18199.98601077137, 18422.263109475472, 50739.74630126826, 50840.49159508358, 51135.74432127816, 51859.18492852732, 52092.73953630208, 64161.0, 69414.0, 72009.0, 72498.0, 73223.0, 7622.208780936365, 7758.224177582197, 7823.647935842787, 7826.608669566385, 7833.37333013369, 9510.47692376926, 10801.891981080089, 11859.0, 11871.76256474883, 12287.385630718607, 10681.0, 10753.032227099598, 10808.513616887101, 10852.837207442053, 11134.498947547541, 10440.634577790044, 10608.787824243625, 10640.390154294655, 10909.39998300101, 11193.27243729155, 7497.137829149499, 9231.630734770533, 9812.901870981199, 10755.0, 11284.040856526959, 7496.512726672749, 7651.732189373345, 7759.573223472763, 7788.143304236416, 7876.960615196887, 7779.667526197508, 7792.961035194788, 7975.960120199362, 8045.356371490149, 10038.64864729731], "unit": "score", "name": "jquery.html", "value": 8674.18379894336}, {"lowerIsBetter": false, "replicates": [1419.9503017394393, 1569.7723830044642, 1595.7047946129967, 1843.1337271482407, 1980.3365872432726, 7996.120426753053, 8618.784530386736, 8750.649974001048, 8757.386982911199, 8767.342449316295, 8449.155084491547, 8700.65197392105, 8704.303655707545, 8708.695195668153, 8712.825743485126, 2194.9341519754435, 2254.8196144308417, 2371.762823717623, 2478.2936862994006, 2506.097804790274, 2293.71328583927, 2352.2472808701223, 2503.6119401492797, 2514.924552263435, 2515.9119430819924, 2419.5644783938883, 2487.751224877516, 2490.4645501217233, 2495.7878580320694, 2499.600063989762, 2455.323846176796, 2465.988944532733, 2485.7762801347967, 2491.1281051631963, 2494.8128890333114, 2112.894355282245, 2393.0257537531434, 2472.0, 2493.3143385568933, 2495.0768215760286, 7375.557466552024, 8786.692465763668, 8866.935967683854, 8870.600822962917, 8874.467531948103, 5363.249145119687, 5471.37079235889, 5508.586855985817, 5517.200005999108, 5526.309211348582, 3560.946585801215, 3568.750187486877, 3578.874739384135, 3598.4242521196484, 3640.308341415123, 1027.542743479152, 1038.4392428088822, 1050.3277902142636, 1076.9084627806646, 1083.0090467222483, 12133.968612667846, 12134.757304853854, 12150.33173175475, 12164.69588260283, 12181.208221465666, 4908.41099068107, 4909.607231421478, 4992.475790042066, 5000.149974504343, 5006.0, 8989.876265466817, 9291.21024712907, 10236.33463824849, 10237.283390162765, 10238.385696858213, 5494.917576236359, 5522.144067669517, 5530.032244357302, 5540.085885828874, 5544.500994910478, 4969.3539839821315, 5034.899302014012, 5037.848864534069, 5042.84871453857, 5054.166062599704, 3912.080661044652, 3933.977165936877, 3939.350007248829, 3967.7024223183375, 3972.066564357374, 1263.993680031594, 1271.8092286156966, 1272.8281681973, 1284.6916739982523, 1288.8002359634272, 1318.9076764626575, 1333.8132661427408, 1355.6610847288177, 1357.518081081202, 1362.3733082782032, 7979.72070977513, 8202.712905048415, 8221.301189398848, 8223.21879421454, 8231.09457959624, 496.08719955282453, 504.6190126454503, 509.75786501411534, 512.3518748782793, 512.9358830146232, 5178.585713142939, 6245.875082498324, 6272.090546870678, 6272.937270627326, 6281.026440901667, 10357.585696572052, 10600.25798194119, 10615.575376984834, 10627.468626568796, 10749.731256718645, 10121.898781012243, 10480.056794888496, 10504.159667226604, 10522.789544209072, 10606.893931060591, 8052.8792068117855, 8216.21945915137, 8223.629936652866, 8287.668493260202, 8304.335653147733, 7963.084245311632, 7979.72070977513, 8066.677332906618, 8151.83696326082, 8192.67229310821, 9499.95250023773, 9588.952055239679, 9610.51947402641, 9735.659251926148, 9860.359076659994, 9729.4648794317, 9990.950045249727, 10003.599856005678, 10014.599416023277, 10023.749406264902, 6559.278479367361, 6728.629925354153, 7155.8568828624175, 7346.081739782528, 7348.448866335153, 8065.153158918464, 8076.030876294883, 8135.430519863553, 8143.389245806469, 8149.959250203711, 7374.299441553044, 7611.200823913409, 7689.115751688628, 7740.574268415066, 7754.030746156731, 7418.295261950106, 7438.256174382735, 7484.513506622052, 7598.468107232662, 7637.618119094134, 7597.810054748676, 7655.923440765744, 7692.538447693157, 7704.576248306397, 7713.4600577959, 5817.301923769175, 6959.826004349932, 6963.059986901703, 6971.198312194163, 7006.75476358325, 7055.823604409931, 7101.396381307647, 7101.644917753988, 7107.395871350993, 7110.608916509642], "unit": "score", "name": "mootools.html", "value": 4837.970225221318}, {"lowerIsBetter": false, "replicates": [317.78390694327857, 322.28613620829873, 325.7589383855947, 326.7908538535337, 330.8858443836877, 6395.456386207178, 6570.277269500357, 6604.702788374522, 6617.106690596768, 6653.367930046644, 6354.968225158879, 6521.510886683494, 6529.379708927644, 6533.411992920648, 6563.9169537026455, 4881.267809828528, 4886.340344053543, 4894.77973491192, 4908.582770464497, 4911.312416261726, 4659.324397962293, 4735.0529894021165, 4775.689580177277, 4901.362822833044, 4912.508749125077, 4107.363358679409, 4572.15415148197, 4770.904581908359, 4789.233722604384, 4799.208130658437, 4759.643026773006, 4762.880927976794, 4771.330034487936, 4773.856784296477, 4777.546133117348, 4086.0, 4099.7745124018165, 4104.261232978063, 4119.443875076856, 4121.340585506335, 6118.0517019861545, 6219.751209951642, 6234.719437625317, 6317.684115794238, 6325.240971083499, 5645.604807663465, 5656.858578535528, 5666.744996475128, 5671.886562268773, 5673.319201695823, 6645.435138013275, 6947.652617369162, 6971.825704357381, 6979.371856532885, 6982.2319544850025, 4498.640108791322, 4518.502964673883, 4607.746573938432, 4613.976930115362, 4626.375439315717, 7367.410607151415, 7405.592692401916, 7414.555126692416, 7432.777016689454, 7461.925380746177, 4702.717836929795, 4704.435467743892, 4706.317583950308, 4707.364505791743, 4717.905641887143, 4480.081583275421, 4672.392588963413, 4676.69601475903, 4688.0, 4773.7374444405195, 5530.474604912527, 5545.833624991256, 5549.72251387429, 5550.611457197957, 5556.111022236423, 6315.463185629273, 6412.006139048455, 7006.4394848412, 7012.333828286304, 7017.754378596725, 1373.4025698821044, 1565.530340897726, 1617.4338981356432, 1621.7486289625126, 1622.7809245751673, 1012.8099483107319, 1029.7219750667427, 1037.896210378956, 1046.4767616191905, 1048.0829274384914, 5335.466453354711, 6590.077389165521, 6598.637074960923, 6599.70301336441, 6603.141591593062, 7196.496245262886, 7196.928030719626, 7212.206657267697, 7221.602811845294, 7232.0, 6246.875062498724, 6526.086347911296, 6558.80323590293, 6570.342965703391, 6570.737170513222, 3812.7140464465274, 3832.7317087803585, 3840.423936409505, 3848.1534062506203, 3858.9228215435533, 7533.284337987882, 7535.54786712799, 7540.359069479046, 7542.622868856535, 7547.05661792276, 7117.6085315308155, 7412.962935185397, 7425.702971881173, 7428.885667149971, 7470.514416562906, 6273.435390814849, 7405.814854628569, 7585.924140758521, 7590.0, 7600.543967361866, 5627.493525582717, 7157.606331651809, 7188.568685878864, 7215.567065976058, 7259.891101633585, 5412.945870541244, 5657.858553536194, 7123.0, 7129.928700713134, 7178.102737157856, 7618.085829700472, 7622.0, 7629.54222746637, 7636.121845987783, 7648.235176482307, 4610.907781844411, 4650.767461626838, 4655.743934083658, 4659.091477161921, 4660.720356778605, 7024.894626580707, 7301.744438944824, 7329.74345897891, 7343.48595598303, 7382.187959324578, 7088.503804733619, 7104.325089116525, 7110.395616372666, 7130.358267755927, 7139.464540159405, 7084.433245340258, 7094.53885497441, 7102.822429439306, 7106.751263705745, 7118.679659415442, 5697.943020569907, 6650.600963942179, 6958.826029349307, 6976.407005404394, 7154.642267886481, 7111.502194846311, 7121.893171602326, 7133.286671333033, 7136.536125151849, 7149.60677162761, 6784.559003664746, 6966.164060312795, 6970.372666460043, 6976.127984002, 6992.265812089658], "unit": "score", "name": "prototype.html", "value": 5096.25217288826}, {"lowerIsBetter": false, "replicates": [641.2369280556135, 678.3792829560952, 684.3840543510839, 689.6758523493957, 747.4100509327775, 13597.728045439086, 13825.032247742662, 13839.308034598254, 13846.861531384684, 13849.792253116211, 13793.586192414243, 15648.765268520952, 15887.761683574756, 15888.126153061577, 15895.761563576556, 695.0, 704.7427688893542, 848.8132610825609, 863.3999370437548, 863.6027427383411, 852.842224188524, 856.1609622569854, 861.7070196133313, 861.7845538615346, 863.5897948474487, 907.1971305394741, 913.4793167894303, 915.1077699243251, 915.2266334946959, 916.5921165081542, 729.4456213277921, 737.6569894998852, 759.6657470712869, 900.045951291633, 903.3721563513361, 673.9460843132538, 675.2605896543278, 675.9864802703919, 678.7895752316798, 679.1442782094597, 15228.619284517865, 15234.619134521614, 15258.847411525854, 15297.08217506942, 15314.387424503006, 12495.0, 12783.29691866947, 12851.16467429614, 12886.097973141885, 13023.804642930363, 10111.797764044752, 10186.796264074677, 10227.642032528825, 10239.283250172492, 10940.015398614083, 3521.47177923311, 3560.893173204808, 3562.928741425183, 3563.982180089083, 3569.23261498779, 79088.0, 80607.0, 81439.5928020362, 82257.76613350745, 88367.23265534718, 9702.0, 9805.264605154642, 9866.408015519091, 10119.494025298705, 10227.079562839232, 22064.430513879626, 22539.98570064351, 22572.88713556422, 22580.887095564416, 22591.548169036854, 7700.268474494914, 7760.961195194101, 7792.610369481503, 7889.447738658238, 8011.319037881729, 4109.876703698834, 4154.522229943595, 4157.729747566398, 4198.538160802309, 4215.241256573785, 3563.6258192889372, 3600.945985810215, 3612.6026137125423, 3612.620674829105, 3613.2050948791225, 632.9588576742589, 644.0210879463283, 649.399305642279, 653.4021370445988, 657.0341597851246, 482.4210946863777, 517.5523172455798, 525.7739172155933, 609.6281268426256, 624.5565648389694, 8714.782130446789, 8745.570306015477, 8813.118688131135, 8817.559122043873, 8957.507337096522, 501.55612283129693, 532.9626926115135, 543.4239705911667, 602.9638221706712, 636.3318515558645, 3913.491246137984, 4035.6771458283265, 4040.9797951010646, 4042.656374208166, 4121.1345617420675, 92960.0, 93799.53100234592, 94035.05964940262, 98901.01098989062, 100807.0, 78488.60755696186, 79441.60279198567, 80055.0, 82326.58836705778, 87325.12674873298, 62551.0, 72755.0, 74841.6257918707, 75148.62425687836, 76888.84666730004, 8723.0, 9077.45535267886, 9107.408018478778, 9155.359124861196, 9161.26709863196, 50806.491935081656, 50985.470435886986, 51032.0, 51382.0, 52023.0, 63695.4456831479, 67490.9876351865, 70340.0, 71183.28816711767, 71257.28742712506, 11549.82675259855, 11793.764124717285, 11798.52805887755, 11833.585824496102, 11983.041356691618, 12101.213421127599, 12153.635390938285, 12459.813102803284, 12790.61628151157, 12855.421506032064, 11532.711682207677, 11607.30356178632, 11664.6500604982, 11682.649520514398, 12429.378531073591, 11595.710107247387, 11616.14656294399, 11813.70465738329, 11994.100442467025, 12443.377831108228, 11651.766964660828, 11823.704407389883, 11832.645020649394, 11883.168178227786, 11900.523979040741, 9554.474503902075, 9655.468949207861, 9875.259355548218, 9884.505774711091, 10151.187904967732, 8429.199226073513, 8646.308295336485, 8969.551522423983, 9372.625094996123, 9764.0], "unit": "score", "name": "yui.html", "value": 6962.833998879926}], "name": "dromaeo_css", "value": 7464.852138922681}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 107.0, 109.0, 110.0, 110.0, 111.0, 110.0, 90.0, 117.0, 165.0], "unit": "score", "name": "ai-astar", "value": 113.8}, {"lowerIsBetter": true, "replicates": [157.0, 122.0, 144.0, 153.0, 122.0, 142.0, 139.0, 120.0, 157.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 137.5}, {"lowerIsBetter": true, "replicates": [185.0, 183.0, 183.0, 183.0, 186.0, 186.0, 184.0, 182.0, 180.0, 179.0], "unit": "score", "name": "audio-dft", "value": 183.1}, {"lowerIsBetter": true, "replicates": [70.0, 87.0, 87.0, 75.0, 56.0, 58.0, 57.0, 81.0, 75.0, 56.0], "unit": "score", "name": "audio-fft", "value": 70.2}, {"lowerIsBetter": true, "replicates": [90.0, 95.0, 127.0, 78.0, 81.0, 83.0, 112.0, 77.0, 77.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 93.0}, {"lowerIsBetter": true, "replicates": [86.0, 92.0, 86.0, 92.0, 86.0, 91.0, 85.0, 91.0, 86.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.7}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 93.7}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 87.0, 85.0, 107.0, 134.0, 133.0, 85.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 97.1}, {"lowerIsBetter": true, "replicates": [56.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0, 64.0, 52.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 54.8}, {"lowerIsBetter": true, "replicates": [64.0, 67.0, 70.0, 75.0, 68.0, 68.0, 65.0, 66.0, 68.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 83.0, 82.0, 84.0, 101.0, 106.0, 108.0, 102.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 91.4}, {"lowerIsBetter": true, "replicates": [123.0, 127.0, 124.0, 132.0, 126.0, 107.0, 129.0, 133.0, 127.0, 113.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.1}, {"lowerIsBetter": true, "replicates": [139.0, 127.0, 138.0, 138.0, 137.0, 142.0, 138.0, 138.0, 138.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.4}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 67.0, 69.0, 83.0, 93.0, 94.0, 93.0, 75.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.3}], "name": "kraken", "value": 1429.8}]} 06:01:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1429.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7464.85"}}} 06:01:13 INFO - Return code: 0 06:01:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:01:13 INFO - # TBPL SUCCESS # 06:01:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:01:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:01:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:01:13 INFO - 2016-02-17 06:01:13.655 screenresolution[1688:9706] starting screenresolution argv=screenresolution get 06:01:13 INFO - 2016-02-17 06:01:13.657 screenresolution[1688:9706] Display 0: 1600x1200x32@60 06:01:13 INFO - 2016-02-17 06:01:13.663 screenresolution[1689:9709] starting screenresolution argv=screenresolution list 06:01:13 INFO - Available Modes on Display 0 06:01:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:01:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:01:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:01:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:01:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:01:13 INFO - Intel Iris: 06:01:13 INFO - Chipset Model: Intel Iris 06:01:13 INFO - Type: GPU 06:01:13 INFO - Bus: Built-In 06:01:13 INFO - VRAM (Dynamic, Max): 1536 MB 06:01:13 INFO - Vendor: Intel (0x8086) 06:01:13 INFO - Device ID: 0x0a2e 06:01:13 INFO - Revision ID: 0x0009 06:01:13 INFO - Displays: 06:01:13 INFO - Display: 06:01:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:01:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:01:13 INFO - Display Serial Number: 1600x1200 60 06:01:13 INFO - Main Display: Yes 06:01:13 INFO - Mirror: Off 06:01:13 INFO - Online: Yes 06:01:13 INFO - Rotation: Supported 06:01:13 INFO - Return code: 0 06:01:13 INFO - Running post-action listener: _resource_record_post_action 06:01:13 INFO - Running post-run listener: _resource_record_post_run 06:01:14 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 38934016; Write bytes: 709813760; Read time: 418; Write time: 2180 06:01:14 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402754048; Write bytes: 393571840; Read time: 29760; Write time: 814 06:01:14 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 31845888; Write bytes: 299403264; Read time: 333; Write time: 1323 06:01:14 INFO - Running post-run listener: _upload_blobber_files 06:01:14 INFO - Blob upload gear active. 06:01:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:14 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 06:01:14 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'] 06:01:14 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 06:01:15 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:01:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:01:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:16 INFO - (blobuploader) - INFO - Done attempting. 06:01:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:01:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:01:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:17 INFO - (blobuploader) - INFO - Done attempting. 06:01:17 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:17 INFO - Return code: 0 06:01:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/38c808c0bd8413673019e423905ef29e95e63edc25559253ebb85ac78b0641b4738763d0c9f52cd56941a8696e1fe4a35ed49caada140371e78ed845ab8c42ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cf66fe3a2e94fce81952c383ac8b2003a2aabeeeac030167147ecf9527614775cba619a59cbc38fb8477aa8c01055d70c801ec6cbb2b1aa7b7de6aedae9ae24"} 06:01:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:17 INFO - Contents: 06:01:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/38c808c0bd8413673019e423905ef29e95e63edc25559253ebb85ac78b0641b4738763d0c9f52cd56941a8696e1fe4a35ed49caada140371e78ed845ab8c42ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cf66fe3a2e94fce81952c383ac8b2003a2aabeeeac030167147ecf9527614775cba619a59cbc38fb8477aa8c01055d70c801ec6cbb2b1aa7b7de6aedae9ae24"} 06:01:17 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:17 INFO - Copying logs to upload dir... 06:01:17 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.904344 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-02-17 06:01:17.793166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-17 06:01:17.800271) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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/38c808c0bd8413673019e423905ef29e95e63edc25559253ebb85ac78b0641b4738763d0c9f52cd56941a8696e1fe4a35ed49caada140371e78ed845ab8c42ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cf66fe3a2e94fce81952c383ac8b2003a2aabeeeac030167147ecf9527614775cba619a59cbc38fb8477aa8c01055d70c801ec6cbb2b1aa7b7de6aedae9ae24"} build_url:https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/HY-NZ5tmTjiV0XNK4tO0pQ/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/38c808c0bd8413673019e423905ef29e95e63edc25559253ebb85ac78b0641b4738763d0c9f52cd56941a8696e1fe4a35ed49caada140371e78ed845ab8c42ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cf66fe3a2e94fce81952c383ac8b2003a2aabeeeac030167147ecf9527614775cba619a59cbc38fb8477aa8c01055d70c801ec6cbb2b1aa7b7de6aedae9ae24"}' ========= master_lag: 1.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-17 06:01:18.843640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 06:01:18.846991) ========= 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.NwsAGlKmAK/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.yJyKfzcZyD/Listeners TMPDIR=/var/folders/0b/cxr9pkv947gdjd7dh7p3q67r00000w/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.004750 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 06:01:18.865553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 06:01:18.865823) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 06:01:18.866169) ========= ========= Total master_lag: 1.30 =========