builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1459506569.14 results: success (0) buildid: 20160401023838 builduid: 5d2396a916624b93b7956f8f49c09241 revision: dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:29.138769) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:29.139206) ========= ========= Started set props: basedir (results: 0, elapsed: 4 secs) (at 2016-04-01 03:29:29.139491) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005370 basedir: '/builds/slave/test' ========= master_lag: 4.86 ========= ========= Finished set props: basedir (results: 0, elapsed: 4 secs) (at 2016-04-01 03:29:34.005312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-01 03:29:34.005659) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-01 03:29:38.839444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:38.839962) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.006129 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:38.888975) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:38.889266) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:38.889603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:38.889887) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-04-01 03:29:38-- 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% 14.6M=0.001s 2016-04-01 03:29:39 (14.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160556 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:39.391480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:39.391817) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.026243 ========= master_lag: 0.58 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:40.002683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-01 03:29:40.003097) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-04-01 03:29:40,086 truncating revision to first 12 chars 2016-04-01 03:29:40,086 Setting DEBUG logging. 2016-04-01 03:29:40,086 attempt 1/10 2016-04-01 03:29:40,086 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dcb34dd97794?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-01 03:29:40,745 unpacking tar archive at: mozilla-aurora-dcb34dd97794/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913182 ========= master_lag: 0.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-01 03:29:41.477568) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:41.477930) ========= echo dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 program finished with exit code 0 elapsedTime=0.005710 script_repo_revision: 'dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:41.506192) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:41.506462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-01 03:29:41.520648) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-01 03:29:41.521320) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:29:41 INFO - MultiFileLogger online at 20160401 03:29:41 in /builds/slave/test 03:29:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:29:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:41 INFO - {'append_to_log': False, 03:29:41 INFO - 'base_work_dir': '/builds/slave/test', 03:29:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:29:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:41 INFO - 'branch': 'Mozilla-Aurora', 03:29:41 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:41 INFO - 'config_files': ('talos/mac_config.py',), 03:29:41 INFO - 'default_actions': ('clobber', 03:29:41 INFO - 'read-buildbot-config', 03:29:41 INFO - 'download-and-extract', 03:29:41 INFO - 'populate-webroot', 03:29:41 INFO - 'create-virtualenv', 03:29:41 INFO - 'install', 03:29:41 INFO - 'run-tests'), 03:29:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:41 INFO - 'download_minidump_stackwalk': True, 03:29:41 INFO - 'download_symbols': 'ondemand', 03:29:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:29:41 INFO - 'tooltool.py': '/tools/tooltool.py', 03:29:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:29:41 INFO - '/tools/misc-python/virtualenv.py')}, 03:29:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:41 INFO - 'installer_path': 'installer.exe', 03:29:41 INFO - 'log_level': 'info', 03:29:41 INFO - 'log_name': 'talos', 03:29:41 INFO - 'log_to_console': True, 03:29:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:29:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:29:41 INFO - 'opt_config_files': (), 03:29:41 INFO - 'pip_index': False, 03:29:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:41 INFO - 'cmd': ('bash', 03:29:41 INFO - '-c', 03:29:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:41 INFO - 'enabled': True, 03:29:41 INFO - 'halt_on_failure': False, 03:29:41 INFO - 'name': 'check_screen_resolution'},), 03:29:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:41 INFO - 'cmd': ('bash', 03:29:41 INFO - '-c', 03:29:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:41 INFO - 'enabled': True, 03:29:41 INFO - 'halt_on_failure': False, 03:29:41 INFO - 'name': 'check_screen_resolution'},), 03:29:41 INFO - 'run_cmd_checks_enabled': True, 03:29:41 INFO - 'sps_profile': False, 03:29:41 INFO - 'sps_profile_interval': 0, 03:29:41 INFO - 'suite': 'dromaeojs', 03:29:41 INFO - 'system_bits': '32', 03:29:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:41 INFO - 'title': 't-yosemite-r7-0084', 03:29:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:41 INFO - 'use_talos_json': True, 03:29:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:41 INFO - 'work_dir': 'build'} 03:29:41 INFO - ##### 03:29:41 INFO - ##### Running clobber step. 03:29:41 INFO - ##### 03:29:41 INFO - Running pre-action listener: _resource_record_pre_action 03:29:41 INFO - Running main action method: clobber 03:29:41 INFO - rmtree: /builds/slave/test/build 03:29:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - ##### 03:29:43 INFO - ##### Running read-buildbot-config step. 03:29:43 INFO - ##### 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: read_buildbot_config 03:29:43 INFO - Using buildbot properties: 03:29:43 INFO - { 03:29:43 INFO - "project": "", 03:29:43 INFO - "product": "firefox", 03:29:43 INFO - "script_repo_revision": "production", 03:29:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:29:43 INFO - "repository": "", 03:29:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:29:43 INFO - "buildid": "20160401023838", 03:29:43 INFO - "pgo_build": "False", 03:29:43 INFO - "basedir": "/builds/slave/test", 03:29:43 INFO - "buildnumber": 152, 03:29:43 INFO - "slavename": "t-yosemite-r7-0084", 03:29:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:29:43 INFO - "platform": "yosemite_r7", 03:29:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:29:43 INFO - "branch": "mozilla-aurora", 03:29:43 INFO - "repo_path": "releases/mozilla-aurora", 03:29:43 INFO - "revision": "dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9", 03:29:43 INFO - "stage_platform": "macosx64", 03:29:43 INFO - "builduid": "5d2396a916624b93b7956f8f49c09241", 03:29:43 INFO - "slavebuilddir": "test" 03:29:43 INFO - } 03:29:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - ##### 03:29:43 INFO - ##### Running download-and-extract step. 03:29:43 INFO - ##### 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: download_and_extract 03:29:43 INFO - mkdir: /builds/slave/test/build/tests 03:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:43 INFO - https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:29:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:29:44 INFO - Downloaded 1277 bytes. 03:29:44 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:44 INFO - Using the following test package requirements: 03:29:44 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:44 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:29:44 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:29:44 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'jsshell-mac.zip'], 03:29:44 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:29:44 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:44 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:29:44 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:29:44 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:29:44 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:44 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:44 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:29:44 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:44 INFO - https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:29:45 INFO - Downloaded 17903092 bytes. 03:29:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:29:48 INFO - Return code: 0 03:29:48 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:48 INFO - https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:29:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:29:50 INFO - Downloaded 17903092 bytes. 03:29:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:29:53 INFO - Return code: 0 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:53 INFO - https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:29:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:29:54 INFO - Downloaded 10845161 bytes. 03:29:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:29:55 INFO - Return code: 0 03:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:55 INFO - https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:29:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:29:58 INFO - Downloaded 115178342 bytes. 03:29:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:58 INFO - mkdir: /builds/slave/test/properties 03:29:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:58 INFO - Contents: 03:29:58 INFO - build_url:https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:59 INFO - Running post-action listener: _resource_record_post_action 03:29:59 INFO - Running post-action listener: set_extra_try_arguments 03:29:59 INFO - ##### 03:29:59 INFO - ##### Running populate-webroot step. 03:29:59 INFO - ##### 03:29:59 INFO - Running pre-action listener: _resource_record_pre_action 03:29:59 INFO - Running main action method: populate_webroot 03:29:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:29:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:29:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:29:59 INFO - '--tppagecycles', 03:29:59 INFO - '7'], 03:29:59 INFO - 'tests': ['tsvgm']}}, 03:29:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:29:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:29:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:29:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:29:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'tests': ['damp', 'tps']}, 03:29:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['damp', 'tps']}, 03:29:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:29:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:59 INFO - 'other': {'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'other_l64': {'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'other_nol64': {'tests': ['a11yr', 03:29:59 INFO - 'ts_paint', 03:29:59 INFO - 'tpaint', 03:29:59 INFO - 'sessionrestore', 03:29:59 INFO - 'sessionrestore_no_auto_restore', 03:29:59 INFO - 'tabpaint']}, 03:29:59 INFO - 'svgr': {'tests': ['tsvgx', 03:29:59 INFO - 'tsvgr_opacity', 03:29:59 INFO - 'tart', 03:29:59 INFO - 'tscrollx', 03:29:59 INFO - 'cart']}, 03:29:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['tsvgx', 03:29:59 INFO - 'tsvgr_opacity', 03:29:59 INFO - 'tart', 03:29:59 INFO - 'tscrollx', 03:29:59 INFO - 'cart']}, 03:29:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'tests': ['tp5o']}, 03:29:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'talos_options': ['--e10s'], 03:29:59 INFO - 'tests': ['tp5o']}, 03:29:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'talos_options': ['--xperf_path', 03:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:59 INFO - 'tests': ['tp5n']}, 03:29:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:59 INFO - 'talos_options': ['--e10s', 03:29:59 INFO - '--xperf_path', 03:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:59 INFO - 'tests': ['tp5n']}}, 03:29:59 INFO - 'talos.zip': {'path': '', 03:29:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:29:59 INFO - Running post-action listener: _resource_record_post_action 03:29:59 INFO - ##### 03:29:59 INFO - ##### Running create-virtualenv step. 03:29:59 INFO - ##### 03:29:59 INFO - Running pre-action listener: _resource_record_pre_action 03:29:59 INFO - Running main action method: create_virtualenv 03:29:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:59 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 03:29:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:30:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:30:00 INFO - Using real prefix '/tools/python27' 03:30:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:30:00 INFO - Installing distribute.............................................................................................................................................................................................done. 03:30:03 INFO - Installing pip.................done. 03:30:03 INFO - Return code: 0 03:30:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:03 INFO - 'HOME': '/Users/cltbld', 03:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:03 INFO - 'LOGNAME': 'cltbld', 03:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:03 INFO - 'MOZ_NO_REMOTE': '1', 03:30:03 INFO - 'NO_EM_RESTART': '1', 03:30:03 INFO - 'PAGER': '/bin/cat', 03:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:03 INFO - 'PWD': '/builds/slave/test', 03:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:03 INFO - 'SHELL': '/bin/bash', 03:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:03 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:03 INFO - 'USER': 'cltbld', 03:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:03 INFO - 'XPC_FLAGS': '0x0', 03:30:03 INFO - 'XPC_SERVICE_NAME': '0', 03:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:03 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 03:30:03 INFO - Cleaning up... 03:30:03 INFO - Return code: 0 03:30:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:03 INFO - 'HOME': '/Users/cltbld', 03:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:03 INFO - 'LOGNAME': 'cltbld', 03:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:03 INFO - 'MOZ_NO_REMOTE': '1', 03:30:03 INFO - 'NO_EM_RESTART': '1', 03:30:03 INFO - 'PAGER': '/bin/cat', 03:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:03 INFO - 'PWD': '/builds/slave/test', 03:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:03 INFO - 'SHELL': '/bin/bash', 03:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:03 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:03 INFO - 'USER': 'cltbld', 03:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:03 INFO - 'XPC_FLAGS': '0x0', 03:30:03 INFO - 'XPC_SERVICE_NAME': '0', 03:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:04 INFO - Downloading/unpacking psutil>=0.7.1 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:30:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:07 INFO - Installing collected packages: psutil 03:30:07 INFO - Running setup.py install for psutil 03:30:07 INFO - building 'psutil._psutil_osx' extension 03:30:07 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 03:30:07 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 03:30:07 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 03:30:07 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 03:30:08 INFO - building 'psutil._psutil_posix' extension 03:30:08 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 03:30:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:30:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:30:08 INFO - ^ 03:30:08 INFO - 1 warning generated. 03:30:08 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 03:30:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:08 INFO - Successfully installed psutil 03:30:08 INFO - Cleaning up... 03:30:08 INFO - Return code: 0 03:30:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:08 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:08 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 03:30:08 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 03:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:08 INFO - 'HOME': '/Users/cltbld', 03:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:08 INFO - 'LOGNAME': 'cltbld', 03:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:08 INFO - 'MOZ_NO_REMOTE': '1', 03:30:08 INFO - 'NO_EM_RESTART': '1', 03:30:08 INFO - 'PAGER': '/bin/cat', 03:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:08 INFO - 'PWD': '/builds/slave/test', 03:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:08 INFO - 'SHELL': '/bin/bash', 03:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:08 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:08 INFO - 'USER': 'cltbld', 03:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:08 INFO - 'XPC_FLAGS': '0x0', 03:30:08 INFO - 'XPC_SERVICE_NAME': '0', 03:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:30:08 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 03:30:08 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 03:30:08 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 03:30:08 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 03:30:08 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 03:30:08 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 03:30:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:30:11 INFO - Installing collected packages: mozsystemmonitor 03:30:11 INFO - Running setup.py install for mozsystemmonitor 03:30:11 INFO - Successfully installed mozsystemmonitor 03:30:11 INFO - Cleaning up... 03:30:11 INFO - Return code: 0 03:30:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30: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')]} 03:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:11 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:11 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 03:30:11 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 03:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:11 INFO - 'HOME': '/Users/cltbld', 03:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:11 INFO - 'LOGNAME': 'cltbld', 03:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:11 INFO - 'MOZ_NO_REMOTE': '1', 03:30:11 INFO - 'NO_EM_RESTART': '1', 03:30:11 INFO - 'PAGER': '/bin/cat', 03:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:11 INFO - 'PWD': '/builds/slave/test', 03:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:11 INFO - 'SHELL': '/bin/bash', 03:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:11 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:11 INFO - 'USER': 'cltbld', 03:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:11 INFO - 'XPC_FLAGS': '0x0', 03:30:11 INFO - 'XPC_SERVICE_NAME': '0', 03:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:11 INFO - Downloading/unpacking blobuploader==1.2.4 03:30:11 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 03:30:11 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 03:30:11 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 03:30:11 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 03:30:11 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 03:30:11 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 03:30:13 INFO - Downloading blobuploader-1.2.4.tar.gz 03:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:30:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:30:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:14 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 03:30:15 INFO - Downloading docopt-0.6.1.tar.gz 03:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:30:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:30:15 INFO - Running setup.py install for blobuploader 03:30:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:30:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:30:15 INFO - Running setup.py install for requests 03:30:15 INFO - Running setup.py install for docopt 03:30:16 INFO - Successfully installed blobuploader requests docopt 03:30:16 INFO - Cleaning up... 03:30:16 INFO - Return code: 0 03:30:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:16 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:16 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 03:30:16 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 03:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:16 INFO - 'HOME': '/Users/cltbld', 03:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:16 INFO - 'LOGNAME': 'cltbld', 03:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:16 INFO - 'MOZ_NO_REMOTE': '1', 03:30:16 INFO - 'NO_EM_RESTART': '1', 03:30:16 INFO - 'PAGER': '/bin/cat', 03:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:16 INFO - 'PWD': '/builds/slave/test', 03:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:16 INFO - 'SHELL': '/bin/bash', 03:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:16 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:16 INFO - 'USER': 'cltbld', 03:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:16 INFO - 'XPC_FLAGS': '0x0', 03:30:16 INFO - 'XPC_SERVICE_NAME': '0', 03:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ZnaXR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-V_n75G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-wdLNVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-oWQX5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SJyW47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-2ByXzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:16 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-O_zZjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-cUslbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Upirc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-40J4pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3DA69P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-QN9uxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-5W1yZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uG3mEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-IM8Z6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-unjReo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-qHZbjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:30:18 INFO - Running setup.py install for manifestparser 03:30:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:30:18 INFO - Running setup.py install for mozcrash 03:30:18 INFO - Running setup.py install for mozdebug 03:30:18 INFO - Running setup.py install for mozdevice 03:30:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:30:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:30:18 INFO - Running setup.py install for mozfile 03:30:18 INFO - Running setup.py install for mozhttpd 03:30:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:30:18 INFO - Running setup.py install for mozinfo 03:30:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Running setup.py install for mozInstall 03:30:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Running setup.py install for mozleak 03:30:19 INFO - Running setup.py install for mozlog 03:30:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Running setup.py install for moznetwork 03:30:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:30:19 INFO - Running setup.py install for mozprocess 03:30:19 INFO - Running setup.py install for mozprofile 03:30:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:30:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:30:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:30:20 INFO - Running setup.py install for mozrunner 03:30:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:30:20 INFO - Running setup.py install for mozscreenshot 03:30:20 INFO - Running setup.py install for moztest 03:30:20 INFO - Running setup.py install for mozversion 03:30:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:30:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:30:20 INFO - Cleaning up... 03:30:20 INFO - Return code: 0 03:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:21 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:21 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 03:30:21 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 03:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:21 INFO - 'HOME': '/Users/cltbld', 03:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:21 INFO - 'LOGNAME': 'cltbld', 03:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:21 INFO - 'MOZ_NO_REMOTE': '1', 03:30:21 INFO - 'NO_EM_RESTART': '1', 03:30:21 INFO - 'PAGER': '/bin/cat', 03:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:21 INFO - 'PWD': '/builds/slave/test', 03:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:21 INFO - 'SHELL': '/bin/bash', 03:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:21 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:21 INFO - 'USER': 'cltbld', 03:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:21 INFO - 'XPC_FLAGS': '0x0', 03:30:21 INFO - 'XPC_SERVICE_NAME': '0', 03:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-NXqdkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:21 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)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4TD3dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-inJl3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:21 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)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-tZbfOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:21 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)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-upfDQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:21 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)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-xBGDBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:21 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)) 03:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:21 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-x_T60z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3WVZcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Edv3uK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gONCqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-b9qPqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-yNHzCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-NOuFwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9mtM2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-t0ViHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:22 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)) 03:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-GSc0db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:23 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)) 03:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8lfq6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:23 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)) 03:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:23 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)) 03:30:23 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)) 03:30:23 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)) 03:30:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30:23 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 03:30:23 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 03:30:23 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 03:30:23 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 03:30:23 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 03:30:23 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 03:30:25 INFO - Downloading blessings-1.6.tar.gz 03:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:25 INFO - Installing collected packages: blessings 03:30:25 INFO - Running setup.py install for blessings 03:30:26 INFO - Successfully installed blessings 03:30:26 INFO - Cleaning up... 03:30:26 INFO - Return code: 0 03:30:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:26 INFO - Reading from file tmpfile_stdout 03:30:26 INFO - Current package versions: 03:30:26 INFO - blessings == 1.6 03:30:26 INFO - blobuploader == 1.2.4 03:30:26 INFO - docopt == 0.6.1 03:30:26 INFO - manifestparser == 1.1 03:30:26 INFO - mozInstall == 1.12 03:30:26 INFO - mozcrash == 0.17 03:30:26 INFO - mozdebug == 0.1 03:30:26 INFO - mozdevice == 0.48 03:30:26 INFO - mozfile == 1.2 03:30:26 INFO - mozhttpd == 0.7 03:30:26 INFO - mozinfo == 0.9 03:30:26 INFO - mozleak == 0.1 03:30:26 INFO - mozlog == 3.1 03:30:26 INFO - moznetwork == 0.27 03:30:26 INFO - mozprocess == 0.22 03:30:26 INFO - mozprofile == 0.28 03:30:26 INFO - mozrunner == 6.11 03:30:26 INFO - mozscreenshot == 0.1 03:30:26 INFO - mozsystemmonitor == 0.0 03:30:26 INFO - moztest == 0.7 03:30:26 INFO - mozversion == 1.4 03:30:26 INFO - psutil == 3.1.1 03:30:26 INFO - requests == 1.2.3 03:30:26 INFO - wsgiref == 0.1.2 03:30:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:26 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:26 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 03:30:26 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 03:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:26 INFO - 'HOME': '/Users/cltbld', 03:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:26 INFO - 'LOGNAME': 'cltbld', 03:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:26 INFO - 'MOZ_NO_REMOTE': '1', 03:30:26 INFO - 'NO_EM_RESTART': '1', 03:30:26 INFO - 'PAGER': '/bin/cat', 03:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:26 INFO - 'PWD': '/builds/slave/test', 03:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:26 INFO - 'SHELL': '/bin/bash', 03:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:26 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:26 INFO - 'USER': 'cltbld', 03:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:26 INFO - 'XPC_FLAGS': '0x0', 03:30:26 INFO - 'XPC_SERVICE_NAME': '0', 03:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 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)) 03:30:26 INFO - Cleaning up... 03:30:26 INFO - Return code: 0 03:30:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:26 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:26 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 03:30:26 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 03:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:26 INFO - 'HOME': '/Users/cltbld', 03:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:26 INFO - 'LOGNAME': 'cltbld', 03:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:26 INFO - 'MOZ_NO_REMOTE': '1', 03:30:26 INFO - 'NO_EM_RESTART': '1', 03:30:26 INFO - 'PAGER': '/bin/cat', 03:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:26 INFO - 'PWD': '/builds/slave/test', 03:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:26 INFO - 'SHELL': '/bin/bash', 03:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:26 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:26 INFO - 'USER': 'cltbld', 03:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:26 INFO - 'XPC_FLAGS': '0x0', 03:30:26 INFO - 'XPC_SERVICE_NAME': '0', 03:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:27 INFO - Downloading/unpacking jsonschema 03:30:27 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 03:30:27 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 03:30:27 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 03:30:27 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 03:30:27 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 03:30:27 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 03:30:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:30:29 INFO - Downloading/unpacking functools32 (from jsonschema) 03:30:29 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 03:30:29 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 03:30:29 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 03:30:29 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 03:30:29 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 03:30:29 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 03:30:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:30:29 INFO - warning: no files found matching '*.txt' 03:30:29 INFO - no previously-included directories found matching 'build' 03:30:29 INFO - no previously-included directories found matching 'dist' 03:30:29 INFO - no previously-included directories found matching '.git*' 03:30:29 INFO - Installing collected packages: jsonschema, functools32 03:30:29 INFO - Running setup.py install for functools32 03:30:30 INFO - warning: no files found matching '*.txt' 03:30:30 INFO - no previously-included directories found matching 'build' 03:30:30 INFO - no previously-included directories found matching 'dist' 03:30:30 INFO - no previously-included directories found matching '.git*' 03:30:30 INFO - Successfully installed jsonschema functools32 03:30:30 INFO - Cleaning up... 03:30:30 INFO - Return code: 0 03:30:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:30:30 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')]} 03:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 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')]} 03:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 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 0x10c0e3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0ea4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac58d7a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, '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.jJMJa5TR54/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.j8ffPXTsyu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 03:30:30 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 03:30:30 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 03:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:30 INFO - 'HOME': '/Users/cltbld', 03:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:30 INFO - 'LOGNAME': 'cltbld', 03:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:30 INFO - 'MOZ_NO_REMOTE': '1', 03:30:30 INFO - 'NO_EM_RESTART': '1', 03:30:30 INFO - 'PAGER': '/bin/cat', 03:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:30 INFO - 'PWD': '/builds/slave/test', 03:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:30 INFO - 'SHELL': '/bin/bash', 03:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:30:30 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:30 INFO - 'USER': 'cltbld', 03:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:30 INFO - 'XPC_FLAGS': '0x0', 03:30:30 INFO - 'XPC_SERVICE_NAME': '0', 03:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:30 INFO - Downloading/unpacking flake8 03:30:30 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 03:30:30 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 03:30:30 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 03:30:30 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 03:30:30 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 03:30:30 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 03:30:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:30:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:30:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:30:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:30:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:30:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:30:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:30:34 INFO - no previously-included directories found matching 'docs/_build' 03:30:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:30:34 INFO - Running setup.py install for pep8 03:30:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:30:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:30:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:30:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:30:34 INFO - no previously-included directories found matching 'docs/_build' 03:30:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:30:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:30:34 INFO - Cleaning up... 03:30:34 INFO - Return code: 0 03:30:34 INFO - Running post-action listener: _resource_record_post_action 03:30:34 INFO - Running post-action listener: _start_resource_monitoring 03:30:34 INFO - Starting resource monitoring. 03:30:34 INFO - ##### 03:30:34 INFO - ##### Running install step. 03:30:34 INFO - ##### 03:30:34 INFO - Running pre-action listener: _resource_record_pre_action 03:30:34 INFO - Running main action method: install 03:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:34 INFO - Reading from file tmpfile_stdout 03:30:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:34 INFO - Reading from file tmpfile_stdout 03:30:34 INFO - Output received: 03:30:34 INFO - Usage: mozinstall [options] installer 03:30:34 INFO - Options: 03:30:34 INFO - -h, --help show this help message and exit 03:30:34 INFO - -d DEST, --destination=DEST 03:30:34 INFO - Directory to install application into. [default: 03:30:34 INFO - "/builds/slave/test"] 03:30:34 INFO - --app=APP Application being installed. [default: firefox] 03:30:34 INFO - mkdir: /builds/slave/test/build/application 03:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:31:06 INFO - Reading from file tmpfile_stdout 03:31:06 INFO - Output received: 03:31:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:31:06 INFO - Running post-action listener: _resource_record_post_action 03:31:06 INFO - ##### 03:31:06 INFO - ##### Running run-tests step. 03:31:06 INFO - ##### 03:31:06 INFO - Running pre-action listener: _resource_record_pre_action 03:31:06 INFO - Running main action method: run_tests 03:31:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:06 INFO - 2016-04-01 03:31:06.868 screenresolution[1642:7566] starting screenresolution argv=screenresolution get 03:31:06 INFO - 2016-04-01 03:31:06.871 screenresolution[1642:7566] Display 0: 1600x1200x32@60 03:31:06 INFO - 2016-04-01 03:31:06.877 screenresolution[1643:7568] starting screenresolution argv=screenresolution list 03:31:06 INFO - Available Modes on Display 0 03:31:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:31:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:31:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:31:07 INFO - Intel Iris: 03:31:07 INFO - Chipset Model: Intel Iris 03:31:07 INFO - Type: GPU 03:31:07 INFO - Bus: Built-In 03:31:07 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:07 INFO - Vendor: Intel (0x8086) 03:31:07 INFO - Device ID: 0x0a2e 03:31:07 INFO - Revision ID: 0x0009 03:31:07 INFO - Displays: 03:31:07 INFO - Display: 03:31:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:31:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:07 INFO - Display Serial Number: 1600x1200 60 03:31:07 INFO - Main Display: Yes 03:31:07 INFO - Mirror: Off 03:31:07 INFO - Online: Yes 03:31:07 INFO - Rotation: Supported 03:31:07 INFO - Return code: 0 03:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:07 INFO - Python 2.7.3 03:31:07 INFO - Return code: 0 03:31:07 INFO - grabbing minidump binary from tooltool 03:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:07 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 0x10bf87d30>, '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 0x10bdebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:07 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 03:31:07 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 03:31:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:07 INFO - Return code: 0 03:31:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:31:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:31:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:31:08 INFO - Return code: 0 03:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 03:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 03:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jJMJa5TR54/Render', 03:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:08 INFO - 'HOME': '/Users/cltbld', 03:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:08 INFO - 'LOGNAME': 'cltbld', 03:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:08 INFO - 'MOZ_NO_REMOTE': '1', 03:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:08 INFO - 'NO_EM_RESTART': '1', 03:31:08 INFO - 'PAGER': '/bin/cat', 03:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:08 INFO - 'PWD': '/builds/slave/test', 03:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:08 INFO - 'SHELL': '/bin/bash', 03:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j8ffPXTsyu/Listeners', 03:31:08 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 03:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:08 INFO - 'USER': 'cltbld', 03:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:08 INFO - 'XPC_FLAGS': '0x0', 03:31:08 INFO - 'XPC_SERVICE_NAME': '0', 03:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 03:31:08 INFO - mozversion application_buildid: 20160401023838 03:31:08 INFO - mozversion application_changeset: dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 03:31:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:31:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:08 INFO - mozversion application_name: Firefox 03:31:08 INFO - mozversion application_remotingname: firefox-dev 03:31:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:08 INFO - mozversion application_vendor: Mozilla 03:31:08 INFO - mozversion application_version: 47.0a2 03:31:08 INFO - mozversion platform_buildid: 20160401023838 03:31:08 INFO - mozversion platform_changeset: dcb34dd977943c61c6dfe2d2754c93f8e9d0c2a9 03:31:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:08 INFO - mozversion platform_version: 47.0a2 03:31:08 INFO - using testdate: 1459506668 03:31:08 INFO - actual date: 1459506668 03:31:08 INFO - starting webserver on 'localhost:49270' 03:31:08 INFO - SUITE-START | Running 2 tests 03:31:08 INFO - TEST-START | dromaeo_css 03:31:08 INFO - operating with platform_type : mac_ 03:31:08 INFO - Initialising browser for dromaeo_css test... 03:31:08 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpBZK5vk/profile http://localhost:49270/getInfo.html) 03:31:10 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:31:10 INFO - PROCESS | 1661 | colorDepth:24 03:31:10 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:31:10 INFO - PROCESS | 1661 | __metrics 03:31:11 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:31:11 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:31:11 INFO - TEST-INFO | 1661: exit 0 03:31:11 INFO - Browser initialized. 03:31:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpBZK5vk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:23 INFO - PROCESS | 1663 | RSS: Main: 210309120 03:31:23 INFO - PROCESS | 1663 | 03:32:14 INFO - PROCESS | 1663 | 1459506734595 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] 03:34:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 03:34:26 INFO - PROCESS | 1663 | RSS: Main: 314826752 03:34:26 INFO - PROCESS | 1663 | 03:37:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 03:37:28 INFO - PROCESS | 1663 | RSS: Main: 349732864 03:37:28 INFO - PROCESS | 1663 | 03:40:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 03:40:31 INFO - PROCESS | 1663 | RSS: Main: 342114304 03:40:31 INFO - PROCESS | 1663 | 03:43:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 03:43:34 INFO - PROCESS | 1663 | RSS: Main: 307564544 03:43:34 INFO - PROCESS | 1663 | 03:46:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 03:46:37 INFO - PROCESS | 1663 | RSS: Main: 308322304 03:46:37 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 03:49:40 INFO - PROCESS | 1663 | RSS: Main: 356020224 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | __start_tp_report 03:49:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:49:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:49:40 INFO - PROCESS | 1663 | |i|pagename|runs| 03:49:40 INFO - PROCESS | 1663 | |0;dojo.html;3469.5316132322137;3523.172054567178;3529.4352903535423;3554.1470047188673;3582;5760.308762948442;5778.191053252543;5785.7685692572295;5792.957267691814;5797.913031304533;5725;5780.682062486561;5784.363719990798;5796.768129274824;5800.941990580093;5164.302819119427;5186.19613959835;5197.870053248661;5211.947880521203;5292.259083728281;5294.205869119624;5314.990151871151;5328.067588172074;5331.147016477365;5335.946640533603;5260.237265596486;5297.920531192035;5304.177852432879;5326.6005049621235;5333.38666053403;5307.787688492475;5319.042572336958;5335.7865685372535;5343.278657381282;5371;5045.571126454256;5220.895582088375;5233.267342572043;5240.371155461331;5276.709780962045;5650.208970744099;5681.715914204315;5684.602077854552;5685.459881311269;5713.285839270091;6143.723532441006;6292.779752708633;6311.715972781236;6316.810495685137;6317.084022816712;5536.390996990328;6222.875542489125;6239.220097487814;6256.03031530109;6304.1804565406665;4949.727764972925;5906.084556893688;5917.408259174092;5921.467067963904;5923.674197919156;6343.238811342576;6351.55539112267;6360.300366959631;6364.904526432108;6375.394337537927;6067.387790625388;6124.625058545733;6224.813255602339;6263.749450022039;6279;6196.442320191204;6275.843103922347;6288.056791481223;6288.088227207121;6304.054391841169;6162.599431036968;6208.286047104551;6261.342559031236;6275.1842260506755;6286.308506064329;6141.762184092961;6197.752089916443;6281.466075383552;6291.402316779899;6297.275813281532;4896.853094407174;4908.04293162837;4909.165441874889;4911.926321105187;4927.60579153674;3826.5025546678753;4433.113377324548;4480.529544397857;4481.103779244164;4482.843100491533;5698.287714035746;5718.170865224519;5746.569007324384;5765.3369862465515;5788.334341550775;6252.781152659635;6261.68691565429;6266.843328916813;6282.434580887742;6288.025356069817;5610.943890561042;5673.262475878109;5713.257276554022;5723.54211663066;5742.942570574324;5423.105187643994;5433.1850222466155;5437.13005919051;5440.6735595864375;5471.5896307777075;6361.840953976188;6364.968175159002;6376.458001069961;6380.680965951591;6394.328595497405;6213.471854892385;6253.030780229072;6254.249490061222;6261.5616906816085;6280.528960328085;6315.08431277453;6356.364363563606;6391.456726178141;6393.041043843461;6412.711427985659;6268.843278917881;6302.117703521419;6310.305866354605;6316.873662526632;6318.747250109944;6217.036359364306;6231.158793562811;6250.562460627712;6285.182926219562;6309.652969086561;4923;6165.72254248551;6187.071939209155;6187.195664563578;6208.658523781235;6182.319944805978;6193.473554747897;6209.844753881009;6216.53375996793;6230.719617617127;5882.205902203148;5896.616719913192;5978.521718262442;6080.7567697291615;6117.705611546597;6111.014442317695;6170.413810687977;6188.412100850412;6189;6202.2867370253;6076.908846367396;6105.633661980295;6110.908336375047;6113.144159817718;6136.662483563447 03:49:40 INFO - PROCESS | 1663 | |1;ext.html;499.7301457213105;591.8842980980852;627.3694936588728;641.3906788550877;659.2155335151172;31811.363772724533;32423.351532969355;32460;32484.863029793964;32492.512612310835;33445.665543344556;41499.792501037526;42309.57690423095;42337.78831105848;42377.36433953493;9712.465814380206;9882.9505852471;9888.95055524725;9901.257405694601;9915.504224788769;9701.417914925092;9846.704598862045;9852.26108041896;9854.605815767361;9860.654877079303;9800;9888.802223955516;9896.455694936776;9901.059399357086;9905.702828915144;9763.951180244088;10009;10037.89962100377;10038.949805251;10041.995800419973;9783.266255030901;9891.802163956752;9932.95033524835;9943.801123977551;9945.70162895107;33544.83227583871;42440.1511969762;42542.78728606368;42590;42591.14817703629;3234.7897386669792;3737.5514938207316;3886.902827429337;3930.213957208541;4030.9193816123807;28501.429971400663;29308.267293317625;29559.261018474495;30066.54900176499;30297.93957211487;2718.2524805678595;2790.274528622552;2847.7152284771564;2848.686644469107;2904.419116176773;478545;478860.9999999965;480110;480201;481108;47441.5255847444;48704.512954870705;65143;65575.34424655788;65604.34395655982;39256.41115383271;47206.76396618064;47321;47642;47681.76159119252;16310;16452.012879227284;16485;16502.33990640385;16513;13071.346432678329;13102.148360356547;13105.868941310466;13150.60548183556;13151.671208219872;14232.003759736926;15096.094234345974;15300;15305.46430874914;15309.6172595686;5874.970625146847;6504.219493660791;6554.344565543408;6583.275839657634;6585.539012269191;953.618552578974;1045.1482042135635;1051.3008849115406;1083.4366129612715;1094.3597995172754;30402.69597304043;30423.087307380814;30527.08418747441;30576.388472230432;30645.540316895265;1005.1556692378291;1095.9643137235237;1138.430784607696;1142.2289954280993;1185.2414454748969;15145.091294522364;15269.465568705264;15473.84526154724;15539.844601554067;15659.608509787346;526636;532093;537006;537965;538978;61063;61477.69261153755;61525.38474615197;61548.692256539325;61657.38342616606;1455.8034665320047;1485.2722166138515;1508.8264849542225;1518.9848101519065;1521.117751704009;24273.635895461935;29257.561136582543;29299.974500892367;29319.70680293255;30085.69914300829;46703.766481167375;47057.52942470532;47080.29379559378;47085.52914470811;47147.82130446629;287330;294052;294807;294934;295155;27006.594901076893;30139.547906780976;30159.396812064075;30688.53967190449;34752.478712818825;42822.57177428186;43226.567734323515;43471.56528434802;44032.77983610061;44183.77908110439;35659;36012.27975440529;36497.27005459823;36549.81725091358;37186;31223.687763122078;35903.820480897426;36054.8197259012;36547.45178822373;36600.45099323459;36114;36807.89576312715;37501;37829.81085094557;38307.2338553233;27387.589186161826;32254;32702;33239.33521329608;33269.83365083159;24201.51596968086;24536.509269814862;24854.005839766207;24881;25343.6915724331 03:49:40 INFO - PROCESS | 1663 | |2;jquery.html;375.6637809160801;378.9962100378995;380.5965676383036;383.4554931996565;384.40609258695315;20035.499112522193;20135.798642013575;20202.494937626565;20894.582108357823;20932.267370642032;10652.147828173747;12363.690907727312;12507.249565026104;12508.374581270946;12516.812247816268;5279.732864112624;5354.384245811723;5525.613207075507;5532.7786888524415;5534.944650553503;5529.695866727328;5551.916721249185;5582.581306402016;5585.273914391123;5597.132444471114;5567.8608034799045;5576.9163462548095;5858.882822343572;6129.233845769279;6176.81469555914;5295.7881684732565;5311.840644780644;5347.983883062206;5348.277982472394;5362.061639213122;4780.521947805191;4787.928181077286;5480.91778623321;5700.914486282709;5702.743376548024;13666.453341866314;13804.861951380459;13867.306634668326;13890.305484725824;24376.756232437627;2022.7168196452362;2122.649762789138;2315.270689732729;2384.7734465225776;2741.1776467060004;10016.64941727036;10084;10367.94816025915;13225.338733063309;14665.780013299809;1716.1076240355071;1771.9811108612598;1791.1492041280417;1808.6563552924902;1811.6829554827984;105295.94704053014;111467;112140;112494;114517.63659174187;9707.611695532241;11965;12026.699332516757;12038.939805300917;12217.083718721131;14541.34563944625;14576.781348279785;14593.343299551334;14611.415543378358;14631.341589628491;9001.819963600692;9062.320325975577;9062.81874362509;9065.456072635663;9158.267338612895;7338;9186.816263674822;9197.724068277963;9235.630574777068;9298.349115561981;4656.790444429941;4671.416072990876;4682.953170468251;4692.085043416501;4733.124371991193;1878.0140426276314;1906.5805522784965;1948.9415317540497;1949.4736421166206;1959.6668566343756;681.9488538359643;856.9228769410782;902.7697937026016;976.4190306767462;1009.3741880034279;20190.889501077287;21034;21130.577388452144;21189.682154767692;21201.83389913539;992.2062350119875;996.0786272697726;1030.1141018723893;1031.886492485826;1043.473046111724;10869.83695244572;11161.944190278997;11188.272762270426;11203.719907002227;11231.438428078563;650505;651788;656621;658717;664717;118279;119231.40384298196;122891.77108228803;124150;124797;1045.6758404894356;1054.4411461925192;1077.5097330714507;1078.37454276521;1080.5407701726735;17952.820471795116;18196.818031820043;18331.08344582792;18741.156647950604;18967.810321896606;52280.73859630677;53083.469165307855;53601.449815049586;54046.189307159635;54888.45111548834;71267;71807;72196.63901680458;72751;72884;8149.684855746533;8163.551004694798;8253.422260441952;8309.501429914224;8614.91385086141;9651.98654141305;12559.748805024032;13046.478140874258;13147.211167329991;13218.933905330412;11577.189596728149;11678.591249306233;11832.34922079294;11843;11942.223755455867;10642.8403573948;11503.194776365573;11536.769264614828;11733.471993760131;11740.647780566596;8852.55737213124;11233.191702074482;11619.477123529292;11925.701857453287;11979.520819167485;7834.843303133792;8116.107228205011;8187.1403502631365;8223.95888020556;8570.057293697813;11424.71438214018;11637.88362116368;11709.76580468403;11807.704807379885;11960.76078478408 03:49:40 INFO - PROCESS | 1663 | |3;mootools.html;1370.8971827112966;1468.1631470062055;1640.1225344440722;1886.726424668423;2054.506918339599;8278.089410164877;8885.422447540914;9069.455832650046;9084.00075991642;9110.681126160585;8712.782180445476;8985.550722463884;9000.8649870252;9006.09939006099;9009;2533.2906786099875;2565.3201901496027;2568.884400201986;2572.7427257274217;2572.742725727431;2524.6970363556397;2586.2112055822968;2588.0682954136496;2594.377349436141;2599.662043934286;2541.7077036140804;2571.7556832100922;2572.125477337705;2572.2154742803436;2577.6133579962966;2562.1032638576444;2574.2663340947834;2583.3799888026924;2592.7925765938867;2593.3775893785355;2544.3511904464426;2576.330154159932;2581.070814506776;2589.4562141950214;2589.8575578343184;8958;8988.235999939947;8993.325500587482;8998;9009.144131307516;5512.834614961557;5667.1782591524;5673.546116310686;5719.399463056361;5730.4269573042775;3618.710503159767;3751.043483911585;3755.793431361274;3782.1490164712995;3807.0672685192303;1051.805415998043;1077.1921059205595;1086.3319058778873;1096.660035389024;1107.4739498738115;12482.938950189155;12497.75004499914;12499;12510.124291299613;12538.623841284776;5056.721880296545;5057.671251368649;5098.59211263098;5148.922766158511;5174.818881339135;10403.063724264852;10418.270721049454;10451.477426128815;10474;10480.895191047992;5669.858253543694;5676.148577713294;5715.2855893013375;5720.799772007959;5724.084146536534;4524.864254072383;4696.7651617419;4946.727929963887;5048.873778155576;5175.404828444702;4088.4908932368025;4130.215259100762;4165.300750012441;4195.685323600681;4205.095904380573;1094.4691824465128;1332.946682132704;1333.5399287245884;1363.659085228693;1369.815074964887;1011.7672935224939;1041.0058394233488;1138.8519492465925;1296.4101333893057;1437.4681367893945;8324.29243514296;8408.41141120128;8414.873776893352;8531.74404767858;8778.517181555077;1243.8445194350707;1246.6322434881731;1253.1854294708512;1264.178284115314;1274.120856608937;6461.289258181596;6533.803985880431;6583.045458408504;6606.702698378488;6613.570117942415;10600.151987840954;10942.069924056384;11049.502772375263;11057.668269951915;11079.279846809932;10316.123129534073;10767.407792571326;10884.40135792539;11093.778124437466;11096;8411.327093832479;8536.44513106646;8565.015023272279;8644.610992505352;8656.999999999874;8284.875726864222;8338.583070846555;8341.16588341161;8412.789680258042;8419.536925469158;9537.998510156334;9790.412575245507;9819.263555233529;9845.261605379483;9929;9655.9034409655;10074.647387341407;10124.797504050026;10139.746506337106;10186.286959912735;8039.236272554098;8332.458390204618;8357.206065423776;8369.414141010071;8379.203975622306;8502.404831661725;8502.659893604186;8550.871736924075;8560.91439085601;8570.35722320841;7325.413966882743;7378.299061589355;7451.44114191427;7480.0649918760155;7600.087989441303;7274;7378.631068446449;7448.180700123091;7510.812229694083;7621.923780762343;7954.522728636301;8025;8028;8030.63862126194;8040;7162.677679504539;7234.276572342934;7242.420606351585;7254;7273.454490913308;7319.158296795724;7339.412846972123;7371.963140184479;7373.225811289952;7420.109586849612 03:49:40 INFO - PROCESS | 1663 | |4;prototype.html;322.74180655475624;325.3298205696266;329.9208190034391;331.3042610517911;335.20221871944784;6578.769743058994;6652.966735166329;6684.729901318746;6696.990902729186;6731.293214212511;6558.901616475757;6579;6601.933980660192;6621.205455345354;6627.9337206627915;4759.7144171349655;4852.32067510547;4865.367502224706;4938.456769755324;4964.429090654585;4301.612605004221;4882.194437917739;4977.975110124463;5001.849944501652;5025.924611130836;4926.137925862978;4927.384076990376;4928.507149285078;4939;4950.702957822542;4658.86023419298;4756.7145971241835;4938.851834444972;4946.356973593446;4946.802127914915;4214.978925105385;4230.725002874803;4230.76730779807;4230.894227644303;4242.130363275552;6448.742050317982;6467;6479.092926990225;6486.059521369422;6495.837604059889;5220.112580861262;5832.1834943108;5849.444302791228;5863.882722345572;5877.471027607536;6037.909431358533;6843.657817109125;7132.251113633098;7137;7143.642817859086;3963.980180099081;4677.74272415017;4754.976225118852;4756.07256584966;4757.524247575249;7421.25787421259;7499.550026998343;7504.737334193277;7510.136334321568;7521.984532088208;3952.5849785772657;3969.861054863066;4816.590589799836;4820.108279968217;4830.4444988826035;4887.340209071801;4890.975545122252;4894.510548945077;4895.8041678332465;4902.877428064256;5188.8962220755375;5422.590126567042;5823.98080335948;5842.824715258549;5844.415558444121;6813.454923606099;7195.820104497325;7196.784096477114;7200.603966781878;7205.603691796897;1553.5960650230795;1590.5705459525864;1663.484319860847;1703.8892471989282;1707.086708610912;1035.1304903880625;1066.2589500297374;1088.7659153282082;1100.3837850803575;1125.8029844777132;6460.353964603502;6633.7346506140175;6639.136912201383;6651;6654.567453115532;6750.32496750321;6913.827154321081;7478.326950574366;7487.401007919353;7489.251074892467;6596.0105984102765;6596.70314835824;6600.636964966877;6604.14146160996;6605.834854128685;3874.3994680824517;3920.313945059603;3922.8626998054933;3925.156091440354;3928.52857657083;7431.85136297271;7694.961525192451;7701.499402538817;7704.075510938723;7704.383649308042;7586.431017673696;7675.424343174284;7682.001339825899;7683.654235559413;7684.731034413769;7672.846543069108;7683.193264707237;7687.308142267223;7687.692492300469;7699.615019249015;7137.714491420285;7348.59582722934;7423.443241756998;7436.814079648052;7445.516041457288;5987.910181347371;7145.678049560848;7320.304571065741;7321.926780732338;7337.229590892879;6350.841228969128;7659.770206893802;7677.808054798675;7677.961610191914;7680.6543705534505;4622.329762184531;4666.650001249987;4669.929951050669;4674.415698037746;4681.99337142516;7116.964415177891;7364.926350736639;7376.4098872089035;7380.520266182681;7388.6675099621725;7139.464540159613;7155.284471552803;7169.175544812413;7208.89186662211;7224.927750722425;7196.172440169447;7203.459740519377;7203.603801790951;7220.205777364381;7229.602371869598;7040.471964602778;7051.153861536648;7053.788386348418;7057.258987806206;7070.045543851515;6239.688015599111;6897.827554311182;7118.110236220473;7171.964140179266;7182.569045857265;6632.602043877383;6715.496337774589;6870.31296870309;6877.828054298482;6931.549449285781 03:49:40 INFO - PROCESS | 1663 | |5;yui.html;645.2257291250498;768.1051574915224;769.9384049276055;786.3826895886726;787.6180052674451;15017.174055426947;15231.543053708392;15236.771448428268;18012.909935450305;18307.267709291646;16534.42129525467;16577.254023568938;16645.50063498097;16665.83334166661;16674.58313542162;784;788.9900926813688;964.7877466957258;984.8621193032981;991.9881720644938;819.213554987213;952.8808898887639;970.5583959298502;971.4608392342247;973.5570315506427;1018.1040684197895;1038.48075962019;1039.3815679670622;1039.9168066554696;1040.5733649203828;829.0383155539575;994.318891559277;1017.959281628734;1021.4841505039944;1025.887152413234;751.4401770680823;751.812046988253;754.1741792736944;755.8110472381904;756.2399788212866;15678.372865085497;15760.290786914617;15778.447754328543;15778.842211577852;15782.842171578252;12263.202891812003;12348.938255308665;12425.937870310681;12476.50093996239;12559.372031398394;10152.847707284396;10283.537240824106;10345.172386209086;10511.106555942677;10512.31669941459;3531.7351198659944;3531.7351198659944;3553;3589.407747721623;3591.407417776064;81133.59433202796;82130;82484.58757706173;82546.58726706388;89041.66437503378;9622.13400793918;9708.368956017977;9729.70810875675;9856.408615482951;10163.288569800043;21517.67723484149;21606;21620.45948851291;21622.675659865115;21639.67540486894;7397.334239918433;7536.585487798114;7536.7362142324755;7564.962175189199;7577.659005344773;4007.6393124618926;4058.979705101515;4095.651869591118;4178.080822219107;4358.542353052947;3745.4943582616543;3770.547534295902;3770.792606406619;3788.052986753312;3791.279656865187;643.6170478565247;664.8703502817001;673.054358626131;677.9661016949133;691.3777600159897;546.8960897429475;584.3105135939547;604.7157835817159;610.5164450385518;621.9502439804804;7917.524948503108;8347.791305217419;8765.298776097898;9072.637094516278;9128.90871091294;587.3979171349401;588.2088590845307;625.3652542669195;636.7357546618194;652.5758257132902;3855.190410013872;3931.5478719947573;4012.6990475713856;4031.395290706359;4160.646345060643;87771;92727.53636231775;94476;95283;96184;75548.48903022018;76260.84450495362;77382;77633.61183194048;78326.43347133025;51124;51629.880563307684;54392.45607543953;54934.62663433446;55053.72473137609;6532.346765323429;7806.024246969129;7816.374690024898;8030.5181689098845;8049.83900322002;45344;46809.53190468188;47081;47232;48664.51335486741;58684;59856.70071649614;63825;64035.67982160059;64869;9976.60093596248;10073.546690399096;10214.182865370902;10759.3544387337;10775.515101820594;9029.77425564366;11030.44847757625;11471.541138354707;11522.193446458667;11587;8839.646414143363;11196.83204751913;11776;12096.520213356416;12382.56629801419;11594.362310073027;11603.535858565563;11709.76580468403;11808.114391420506;11974.940125299667;9094.954525227331;11101.222914396238;11124.332540047622;11144.33134011962;12006.519739210335;9363.906360936304;9528.61885524599;9669.564869581027;9800.754981125529;10077.244206684674;9532.475713835805;9819.410835349901;9827;9856.57446096856;9910.801783964425 03:49:40 INFO - PROCESS | 1663 | __end_tp_report 03:49:40 INFO - PROCESS | 1663 | __start_cc_report 03:49:40 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,970 03:49:40 INFO - PROCESS | 1663 | __end_cc_report 03:49:40 INFO - PROCESS | 1663 | __startTimestamp1459507780691__endTimestamp 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:49:40 INFO - PROCESS | 1663 | Number of tests: 6 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5786.06 Median:6080.76 stddev:644.53 (10.6%) stddev-sans-first:620.36 03:49:40 INFO - PROCESS | 1663 | Values: 3469.5 3523.2 3529.4 3554.1 3582.0 5760.3 5778.2 5785.8 5793.0 5797.9 5725.0 5780.7 5784.4 5796.8 5800.9 5164.3 5186.2 5197.9 5211.9 5292.3 5294.2 5315.0 5328.1 5331.1 5335.9 5260.2 5297.9 5304.2 5326.6 5333.4 5307.8 5319.0 5335.8 5343.3 5371.0 5045.6 5220.9 5233.3 5240.4 5276.7 5650.2 5681.7 5684.6 5685.5 5713.3 6143.7 6292.8 6311.7 6316.8 6317.1 5536.4 6222.9 6239.2 6256.0 6304.2 4949.7 5906.1 5917.4 5921.5 5923.7 6343.2 6351.6 6360.3 6364.9 6375.4 6067.4 6124.6 6224.8 6263.7 6279.0 6196.4 6275.8 6288.1 6288.1 6304.1 6162.6 6208.3 6261.3 6275.2 6286.3 6141.8 6197.8 6281.5 6291.4 6297.3 4896.9 4908.0 4909.2 4911.9 4927.6 3826.5 4433.1 4480.5 4481.1 4482.8 5698.3 5718.2 5746.6 5765.3 5788.3 6252.8 6261.7 6266.8 6282.4 6288.0 5610.9 5673.3 5713.3 5723.5 5742.9 5423.1 5433.2 5437.1 5440.7 5471.6 6361.8 6365.0 6376.5 6380.7 6394.3 6213.5 6253.0 6254.2 6261.6 6280.5 6315.1 6356.4 6391.5 6393.0 6412.7 6268.8 6302.1 6310.3 6316.9 6318.7 6217.0 6231.2 6250.6 6285.2 6309.7 4923.0 6165.7 6187.1 6187.2 6208.7 6182.3 6193.5 6209.8 6216.5 6230.7 5882.2 5896.6 5978.5 6080.8 6117.7 6111.0 6170.4 6188.4 6189.0 6202.3 6076.9 6105.6 6110.9 6113.1 6136.7 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:58018.10 Median:36084.41 stddev:119264.25 (330.5%) stddev-sans-first:119520.63 03:49:40 INFO - PROCESS | 1663 | Values: 499.7 591.9 627.4 641.4 659.2 31811.4 32423.4 32460.0 32484.9 32492.5 33445.7 41499.8 42309.6 42337.8 42377.4 9712.5 9883.0 9889.0 9901.3 9915.5 9701.4 9846.7 9852.3 9854.6 9860.7 9800.0 9888.8 9896.5 9901.1 9905.7 9764.0 10009.0 10037.9 10038.9 10042.0 9783.3 9891.8 9933.0 9943.8 9945.7 33544.8 42440.2 42542.8 42590.0 42591.1 3234.8 3737.6 3886.9 3930.2 4030.9 28501.4 29308.3 29559.3 30066.5 30297.9 2718.3 2790.3 2847.7 2848.7 2904.4 478545.0 478861.0 480110.0 480201.0 481108.0 47441.5 48704.5 65143.0 65575.3 65604.3 39256.4 47206.8 47321.0 47642.0 47681.8 16310.0 16452.0 16485.0 16502.3 16513.0 13071.3 13102.1 13105.9 13150.6 13151.7 14232.0 15096.1 15300.0 15305.5 15309.6 5875.0 6504.2 6554.3 6583.3 6585.5 953.6 1045.1 1051.3 1083.4 1094.4 30402.7 30423.1 30527.1 30576.4 30645.5 1005.2 1096.0 1138.4 1142.2 1185.2 15145.1 15269.5 15473.8 15539.8 15659.6 526636.0 532093.0 537006.0 537965.0 538978.0 61063.0 61477.7 61525.4 61548.7 61657.4 1455.8 1485.3 1508.8 1519.0 1521.1 24273.6 29257.6 29300.0 29319.7 30085.7 46703.8 47057.5 47080.3 47085.5 47147.8 287330.0 294052.0 294807.0 294934.0 295155.0 27006.6 30139.5 30159.4 30688.5 34752.5 42822.6 43226.6 43471.6 44032.8 44183.8 35659.0 36012.3 36497.3 36549.8 37186.0 31223.7 35903.8 36054.8 36547.5 36600.5 36114.0 36807.9 37501.0 37829.8 38307.2 27387.6 32254.0 32702.0 33239.3 33269.8 24201.5 24536.5 24854.0 24881.0 25343.7 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35734.99 Median:20913.42 stddev:108792.09 (520.2%) stddev-sans-first:109064.88 03:49:40 INFO - PROCESS | 1663 | Values: 375.7 379.0 380.6 383.5 384.4 20035.5 20135.8 20202.5 20894.6 20932.3 10652.1 12363.7 12507.2 12508.4 12516.8 5279.7 5354.4 5525.6 5532.8 5534.9 5529.7 5551.9 5582.6 5585.3 5597.1 5567.9 5576.9 5858.9 6129.2 6176.8 5295.8 5311.8 5348.0 5348.3 5362.1 4780.5 4787.9 5480.9 5700.9 5702.7 13666.5 13804.9 13867.3 13890.3 24376.8 2022.7 2122.6 2315.3 2384.8 2741.2 10016.6 10084.0 10367.9 13225.3 14665.8 1716.1 1772.0 1791.1 1808.7 1811.7 105295.9 111467.0 112140.0 112494.0 114517.6 9707.6 11965.0 12026.7 12038.9 12217.1 14541.3 14576.8 14593.3 14611.4 14631.3 9001.8 9062.3 9062.8 9065.5 9158.3 7338.0 9186.8 9197.7 9235.6 9298.3 4656.8 4671.4 4683.0 4692.1 4733.1 1878.0 1906.6 1948.9 1949.5 1959.7 681.9 856.9 902.8 976.4 1009.4 20190.9 21034.0 21130.6 21189.7 21201.8 992.2 996.1 1030.1 1031.9 1043.5 10869.8 11161.9 11188.3 11203.7 11231.4 650505.0 651788.0 656621.0 658717.0 664717.0 118279.0 119231.4 122891.8 124150.0 124797.0 1045.7 1054.4 1077.5 1078.4 1080.5 17952.8 18196.8 18331.1 18741.2 18967.8 52280.7 53083.5 53601.4 54046.2 54888.5 71267.0 71807.0 72196.6 72751.0 72884.0 8149.7 8163.6 8253.4 8309.5 8614.9 9652.0 12559.7 13046.5 13147.2 13218.9 11577.2 11678.6 11832.3 11843.0 11942.2 10642.8 11503.2 11536.8 11733.5 11740.6 8852.6 11233.2 11619.5 11925.7 11979.5 7834.8 8116.1 8187.1 8224.0 8570.1 11424.7 11637.9 11709.8 11807.7 11960.8 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:6286.18 Median:5123.76 stddev:3283.88 (64.1%) stddev-sans-first:3272.31 03:49:40 INFO - PROCESS | 1663 | Values: 1370.9 1468.2 1640.1 1886.7 2054.5 8278.1 8885.4 9069.5 9084.0 9110.7 8712.8 8985.6 9000.9 9006.1 9009.0 2533.3 2565.3 2568.9 2572.7 2572.7 2524.7 2586.2 2588.1 2594.4 2599.7 2541.7 2571.8 2572.1 2572.2 2577.6 2562.1 2574.3 2583.4 2592.8 2593.4 2544.4 2576.3 2581.1 2589.5 2589.9 8958.0 8988.2 8993.3 8998.0 9009.1 5512.8 5667.2 5673.5 5719.4 5730.4 3618.7 3751.0 3755.8 3782.1 3807.1 1051.8 1077.2 1086.3 1096.7 1107.5 12482.9 12497.8 12499.0 12510.1 12538.6 5056.7 5057.7 5098.6 5148.9 5174.8 10403.1 10418.3 10451.5 10474.0 10480.9 5669.9 5676.1 5715.3 5720.8 5724.1 4524.9 4696.8 4946.7 5048.9 5175.4 4088.5 4130.2 4165.3 4195.7 4205.1 1094.5 1332.9 1333.5 1363.7 1369.8 1011.8 1041.0 1138.9 1296.4 1437.5 8324.3 8408.4 8414.9 8531.7 8778.5 1243.8 1246.6 1253.2 1264.2 1274.1 6461.3 6533.8 6583.0 6606.7 6613.6 10600.2 10942.1 11049.5 11057.7 11079.3 10316.1 10767.4 10884.4 11093.8 11096.0 8411.3 8536.4 8565.0 8644.6 8657.0 8284.9 8338.6 8341.2 8412.8 8419.5 9538.0 9790.4 9819.3 9845.3 9929.0 9655.9 10074.6 10124.8 10139.7 10186.3 8039.2 8332.5 8357.2 8369.4 8379.2 8502.4 8502.7 8550.9 8560.9 8570.4 7325.4 7378.3 7451.4 7480.1 7600.1 7274.0 7378.6 7448.2 7510.8 7621.9 7954.5 8025.0 8028.0 8030.6 8040.0 7162.7 7234.3 7242.4 7254.0 7273.5 7319.2 7339.4 7372.0 7373.2 7420.1 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5822.22 Median:6601.29 stddev:1859.23 (28.2%) stddev-sans-first:1818.05 03:49:40 INFO - PROCESS | 1663 | Values: 322.7 325.3 329.9 331.3 335.2 6578.8 6653.0 6684.7 6697.0 6731.3 6558.9 6579.0 6601.9 6621.2 6627.9 4759.7 4852.3 4865.4 4938.5 4964.4 4301.6 4882.2 4978.0 5001.8 5025.9 4926.1 4927.4 4928.5 4939.0 4950.7 4658.9 4756.7 4938.9 4946.4 4946.8 4215.0 4230.7 4230.8 4230.9 4242.1 6448.7 6467.0 6479.1 6486.1 6495.8 5220.1 5832.2 5849.4 5863.9 5877.5 6037.9 6843.7 7132.3 7137.0 7143.6 3964.0 4677.7 4755.0 4756.1 4757.5 7421.3 7499.6 7504.7 7510.1 7522.0 3952.6 3969.9 4816.6 4820.1 4830.4 4887.3 4891.0 4894.5 4895.8 4902.9 5188.9 5422.6 5824.0 5842.8 5844.4 6813.5 7195.8 7196.8 7200.6 7205.6 1553.6 1590.6 1663.5 1703.9 1707.1 1035.1 1066.3 1088.8 1100.4 1125.8 6460.4 6633.7 6639.1 6651.0 6654.6 6750.3 6913.8 7478.3 7487.4 7489.3 6596.0 6596.7 6600.6 6604.1 6605.8 3874.4 3920.3 3922.9 3925.2 3928.5 7431.9 7695.0 7701.5 7704.1 7704.4 7586.4 7675.4 7682.0 7683.7 7684.7 7672.8 7683.2 7687.3 7687.7 7699.6 7137.7 7348.6 7423.4 7436.8 7445.5 5987.9 7145.7 7320.3 7321.9 7337.2 6350.8 7659.8 7677.8 7678.0 7680.7 4622.3 4666.7 4669.9 4674.4 4682.0 7117.0 7364.9 7376.4 7380.5 7388.7 7139.5 7155.3 7169.2 7208.9 7224.9 7196.2 7203.5 7203.6 7220.2 7229.6 7040.5 7051.2 7053.8 7057.3 7070.0 6239.7 6897.8 7118.1 7172.0 7182.6 6632.6 6715.5 6870.3 6877.8 6931.5 03:49:40 INFO - PROCESS | 1663 | 03:49:40 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17794.62 Median:54994.18 stddev:24706.13 (44.9%) stddev-sans-first:24741.88 03:49:40 INFO - PROCESS | 1663 | Values: 645.2 768.1 769.9 786.4 787.6 15017.2 15231.5 15236.8 18012.9 18307.3 16534.4 16577.3 16645.5 16665.8 16674.6 784.0 789.0 964.8 984.9 992.0 819.2 952.9 970.6 971.5 973.6 1018.1 1038.5 1039.4 1039.9 1040.6 829.0 994.3 1018.0 1021.5 1025.9 751.4 751.8 754.2 755.8 756.2 15678.4 15760.3 15778.4 15778.8 15782.8 12263.2 12348.9 12425.9 12476.5 12559.4 10152.8 10283.5 10345.2 10511.1 10512.3 3531.7 3531.7 3553.0 3589.4 3591.4 81133.6 82130.0 82484.6 82546.6 89041.7 9622.1 9708.4 9729.7 9856.4 10163.3 21517.7 21606.0 21620.5 21622.7 21639.7 7397.3 7536.6 7536.7 7565.0 7577.7 4007.6 4059.0 4095.7 4178.1 4358.5 3745.5 3770.5 3770.8 3788.1 3791.3 643.6 664.9 673.1 678.0 691.4 546.9 584.3 604.7 610.5 622.0 7917.5 8347.8 8765.3 9072.6 9128.9 587.4 588.2 625.4 636.7 652.6 3855.2 3931.5 4012.7 4031.4 4160.6 87771.0 92727.5 94476.0 95283.0 96184.0 75548.5 76260.8 77382.0 77633.6 78326.4 51124.0 51629.9 54392.5 54934.6 55053.7 6532.3 7806.0 7816.4 8030.5 8049.8 45344.0 46809.5 47081.0 47232.0 48664.5 58684.0 59856.7 63825.0 64035.7 64869.0 9976.6 10073.5 10214.2 10759.4 10775.5 9029.8 11030.4 11471.5 11522.2 11587.0 8839.6 11196.8 11776.0 12096.5 12382.6 11594.4 11603.5 11709.8 11808.1 11974.9 9095.0 11101.2 11124.3 11144.3 12006.5 9363.9 9528.6 9669.6 9800.8 10077.2 9532.5 9819.4 9827.0 9856.6 9910.8 03:49:40 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:49:40 INFO - PROCESS | 1663 | 03:49:41 INFO - TEST-INFO | 1663: exit 0 03:49:41 INFO - TEST-OK | dromaeo_css | took 1112594ms 03:49:41 INFO - TEST-START | kraken 03:49:41 INFO - operating with platform_type : mac_ 03:49:41 INFO - Initialising browser for kraken test... 03:49:41 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp457rv8/profile http://localhost:49270/getInfo.html) 03:49:43 INFO - PROCESS | 1694 | __metrics Screen width/height:1600/1200 03:49:43 INFO - PROCESS | 1694 | colorDepth:24 03:49:43 INFO - PROCESS | 1694 | Browser inner width/height: 1024/689 03:49:43 INFO - PROCESS | 1694 | __metrics 03:49:43 INFO - PROCESS | 1694 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:49:43 INFO - PROCESS | 1694 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:49:44 INFO - TEST-INFO | 1694: exit 0 03:49:44 INFO - Browser initialized. 03:49:44 INFO - Running cycle 1/1 for kraken test... 03:49:44 INFO - TEST-INFO | started process 1696 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp457rv8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:56 INFO - PROCESS | 1696 | RSS: Main: 209432576 03:49:56 INFO - PROCESS | 1696 | 03:49:58 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:59 INFO - PROCESS | 1696 | RSS: Main: 264380416 03:49:59 INFO - PROCESS | 1696 | 03:50:01 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 03:50:02 INFO - PROCESS | 1696 | RSS: Main: 275173376 03:50:02 INFO - PROCESS | 1696 | 03:50:05 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 03:50:06 INFO - PROCESS | 1696 | RSS: Main: 264622080 03:50:06 INFO - PROCESS | 1696 | 03:50:07 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 03:50:08 INFO - PROCESS | 1696 | RSS: Main: 266596352 03:50:08 INFO - PROCESS | 1696 | 03:50:10 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:50:11 INFO - PROCESS | 1696 | RSS: Main: 267554816 03:50:11 INFO - PROCESS | 1696 | 03:50:13 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 03:50:14 INFO - PROCESS | 1696 | RSS: Main: 268759040 03:50:14 INFO - PROCESS | 1696 | 03:50:17 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 03:50:17 INFO - PROCESS | 1696 | RSS: Main: 267677696 03:50:17 INFO - PROCESS | 1696 | 03:50:20 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 03:50:21 INFO - PROCESS | 1696 | RSS: Main: 269357056 03:50:21 INFO - PROCESS | 1696 | 03:50:23 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:50:23 INFO - PROCESS | 1696 | RSS: Main: 301391872 03:50:23 INFO - PROCESS | 1696 | 03:50:25 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:50:26 INFO - PROCESS | 1696 | RSS: Main: 301547520 03:50:26 INFO - PROCESS | 1696 | 03:50:28 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:50:28 INFO - PROCESS | 1696 | RSS: Main: 307884032 03:50:28 INFO - PROCESS | 1696 | 03:50:31 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:50:31 INFO - PROCESS | 1696 | RSS: Main: 310353920 03:50:31 INFO - PROCESS | 1696 | 03:50:34 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:50:34 INFO - PROCESS | 1696 | RSS: Main: 310300672 03:50:34 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 03:50:36 INFO - PROCESS | 1696 | RSS: Main: 369668096 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | __start_tp_report 03:50:36 INFO - PROCESS | 1696 | _x_x_mozilla_page_load 03:50:36 INFO - PROCESS | 1696 | _x_x_mozilla_page_load_details 03:50:36 INFO - PROCESS | 1696 | |i|pagename|runs| 03:50:36 INFO - PROCESS | 1696 | |0;ai-astar;113;108;109;108;110;107;118;108;111;109 03:50:36 INFO - PROCESS | 1696 | |1;audio-beat-detection;152;116;152;144;116;138;115;146;147;119 03:50:36 INFO - PROCESS | 1696 | |2;audio-dft;183;183;185;184;186;187;184;184;182;184 03:50:36 INFO - PROCESS | 1696 | |3;audio-fft;66;75;70;118;55;58;57;58;125;64 03:50:36 INFO - PROCESS | 1696 | |4;audio-oscillator;91;95;133;80;78;89;116;75;82;113 03:50:36 INFO - PROCESS | 1696 | |5;imaging-gaussian-blur;87;86;89;86;88;84;89;86;89;85 03:50:36 INFO - PROCESS | 1696 | |6;imaging-darkroom;93;93;94;93;93;94;93;102;136;93 03:50:36 INFO - PROCESS | 1696 | |7;imaging-desaturate;85;86;83;83;84;86;81;129;142;106 03:50:36 INFO - PROCESS | 1696 | |8;json-parse-financial;56;59;63;58;58;57;55;69;82;85 03:50:36 INFO - PROCESS | 1696 | |9;json-stringify-tinderbox;65;72;61;73;69;66;62;66;65;85 03:50:36 INFO - PROCESS | 1696 | |10;stanford-crypto-aes;81;80;80;80;81;79;81;79;78;84 03:50:36 INFO - PROCESS | 1696 | |11;stanford-crypto-ccm;117;117;118;117;129;115;127;126;127;117 03:50:36 INFO - PROCESS | 1696 | |12;stanford-crypto-pbkdf2;134;122;131;131;135;121;133;130;132;132 03:50:36 INFO - PROCESS | 1696 | |13;stanford-crypto-sha256-iterative;65;67;64;64;64;66;66;67;66;64 03:50:36 INFO - PROCESS | 1696 | __end_tp_report 03:50:36 INFO - PROCESS | 1696 | __start_cc_report 03:50:36 INFO - PROCESS | 1696 | _x_x_mozilla_cycle_collect,1208 03:50:36 INFO - PROCESS | 1696 | __end_cc_report 03:50:36 INFO - PROCESS | 1696 | __startTimestamp1459507836749__endTimestamp 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | ------- Summary: start ------- 03:50:36 INFO - PROCESS | 1696 | Number of tests: 14 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#0] ai-astar Cycles:10 Average:110.10 Median:109.50 stddev:3.28 (3.0%) stddev-sans-first:3.31 03:50:36 INFO - PROCESS | 1696 | Values: 113.0 108.0 109.0 108.0 110.0 107.0 118.0 108.0 111.0 109.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:145.00 stddev:16.02 (11.0%) stddev-sans-first:15.69 03:50:36 INFO - PROCESS | 1696 | Values: 152.0 116.0 152.0 144.0 116.0 138.0 115.0 146.0 147.0 119.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:1.48 (0.8%) stddev-sans-first:1.50 03:50:36 INFO - PROCESS | 1696 | Values: 183.0 183.0 185.0 184.0 186.0 187.0 184.0 184.0 182.0 184.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#3] audio-fft Cycles:10 Average:74.60 Median:61.00 stddev:25.55 (41.9%) stddev-sans-first:26.91 03:50:36 INFO - PROCESS | 1696 | Values: 66.0 75.0 70.0 118.0 55.0 58.0 57.0 58.0 125.0 64.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:81.00 stddev:19.27 (23.8%) stddev-sans-first:20.38 03:50:36 INFO - PROCESS | 1696 | Values: 91.0 95.0 133.0 80.0 78.0 89.0 116.0 75.0 82.0 113.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:1.79 (2.0%) stddev-sans-first:1.90 03:50:36 INFO - PROCESS | 1696 | Values: 87.0 86.0 89.0 86.0 88.0 84.0 89.0 86.0 89.0 85.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:93.00 stddev:13.50 (14.5%) stddev-sans-first:14.18 03:50:36 INFO - PROCESS | 1696 | Values: 93.0 93.0 94.0 93.0 93.0 94.0 93.0 102.0 136.0 93.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:83.50 stddev:21.94 (26.3%) stddev-sans-first:22.87 03:50:36 INFO - PROCESS | 1696 | Values: 85.0 86.0 83.0 83.0 84.0 86.0 81.0 129.0 142.0 106.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:61.00 stddev:10.96 (18.0%) stddev-sans-first:11.22 03:50:36 INFO - PROCESS | 1696 | Values: 56.0 59.0 63.0 58.0 58.0 57.0 55.0 69.0 82.0 85.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:67.50 stddev:7.00 (10.4%) stddev-sans-first:7.31 03:50:36 INFO - PROCESS | 1696 | Values: 65.0 72.0 61.0 73.0 69.0 66.0 62.0 66.0 65.0 85.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:1.64 (2.0%) stddev-sans-first:1.72 03:50:36 INFO - PROCESS | 1696 | Values: 81.0 80.0 80.0 80.0 81.0 79.0 81.0 79.0 78.0 84.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:122.00 stddev:5.48 (4.5%) stddev-sans-first:5.61 03:50:36 INFO - PROCESS | 1696 | Values: 117.0 117.0 118.0 117.0 129.0 115.0 127.0 126.0 127.0 117.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.10 Median:132.00 stddev:4.77 (3.6%) stddev-sans-first:4.85 03:50:36 INFO - PROCESS | 1696 | Values: 134.0 122.0 131.0 131.0 135.0 121.0 133.0 130.0 132.0 132.0 03:50:36 INFO - PROCESS | 1696 | 03:50:36 INFO - PROCESS | 1696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:66.00 stddev:1.25 (1.9%) stddev-sans-first:1.32 03:50:36 INFO - PROCESS | 1696 | Values: 65.0 67.0 64.0 64.0 64.0 66.0 66.0 67.0 66.0 64.0 03:50:36 INFO - PROCESS | 1696 | -------- Summary: end -------- 03:50:36 INFO - PROCESS | 1696 | 03:50:37 INFO - TEST-INFO | 1696: exit 0 03:50:37 INFO - TEST-OK | kraken | took 56302ms 03:50:37 INFO - SUITE-END | took 1168s 03:50:37 INFO - Completed test suite (00:19:29) 03:50:37 INFO - javascript benchmark 03:50:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3469.5316132322137, 3523.172054567178, 3529.4352903535423, 3554.1470047188673, 3582.0, 5760.308762948442, 5778.191053252543, 5785.7685692572295, 5792.957267691814, 5797.913031304533, 5725.0, 5780.682062486561, 5784.363719990798, 5796.768129274824, 5800.941990580093, 5164.302819119427, 5186.19613959835, 5197.870053248661, 5211.947880521203, 5292.259083728281, 5294.205869119624, 5314.990151871151, 5328.067588172074, 5331.147016477365, 5335.946640533603, 5260.237265596486, 5297.920531192035, 5304.177852432879, 5326.6005049621235, 5333.38666053403, 5307.787688492475, 5319.042572336958, 5335.7865685372535, 5343.278657381282, 5371.0, 5045.571126454256, 5220.895582088375, 5233.267342572043, 5240.371155461331, 5276.709780962045, 5650.208970744099, 5681.715914204315, 5684.602077854552, 5685.459881311269, 5713.285839270091, 6143.723532441006, 6292.779752708633, 6311.715972781236, 6316.810495685137, 6317.084022816712, 5536.390996990328, 6222.875542489125, 6239.220097487814, 6256.03031530109, 6304.1804565406665, 4949.727764972925, 5906.084556893688, 5917.408259174092, 5921.467067963904, 5923.674197919156, 6343.238811342576, 6351.55539112267, 6360.300366959631, 6364.904526432108, 6375.394337537927, 6067.387790625388, 6124.625058545733, 6224.813255602339, 6263.749450022039, 6279.0, 6196.442320191204, 6275.843103922347, 6288.056791481223, 6288.088227207121, 6304.054391841169, 6162.599431036968, 6208.286047104551, 6261.342559031236, 6275.1842260506755, 6286.308506064329, 6141.762184092961, 6197.752089916443, 6281.466075383552, 6291.402316779899, 6297.275813281532, 4896.853094407174, 4908.04293162837, 4909.165441874889, 4911.926321105187, 4927.60579153674, 3826.5025546678753, 4433.113377324548, 4480.529544397857, 4481.103779244164, 4482.843100491533, 5698.287714035746, 5718.170865224519, 5746.569007324384, 5765.3369862465515, 5788.334341550775, 6252.781152659635, 6261.68691565429, 6266.843328916813, 6282.434580887742, 6288.025356069817, 5610.943890561042, 5673.262475878109, 5713.257276554022, 5723.54211663066, 5742.942570574324, 5423.105187643994, 5433.1850222466155, 5437.13005919051, 5440.6735595864375, 5471.5896307777075, 6361.840953976188, 6364.968175159002, 6376.458001069961, 6380.680965951591, 6394.328595497405, 6213.471854892385, 6253.030780229072, 6254.249490061222, 6261.5616906816085, 6280.528960328085, 6315.08431277453, 6356.364363563606, 6391.456726178141, 6393.041043843461, 6412.711427985659, 6268.843278917881, 6302.117703521419, 6310.305866354605, 6316.873662526632, 6318.747250109944, 6217.036359364306, 6231.158793562811, 6250.562460627712, 6285.182926219562, 6309.652969086561, 4923.0, 6165.72254248551, 6187.071939209155, 6187.195664563578, 6208.658523781235, 6182.319944805978, 6193.473554747897, 6209.844753881009, 6216.53375996793, 6230.719617617127, 5882.205902203148, 5896.616719913192, 5978.521718262442, 6080.7567697291615, 6117.705611546597, 6111.014442317695, 6170.413810687977, 6188.412100850412, 6189.0, 6202.2867370253, 6076.908846367396, 6105.633661980295, 6110.908336375047, 6113.144159817718, 6136.662483563447], "unit": "score", "name": "dojo.html", "value": 5746.081849874818}, {"lowerIsBetter": false, "replicates": [499.7301457213105, 591.8842980980852, 627.3694936588728, 641.3906788550877, 659.2155335151172, 31811.363772724533, 32423.351532969355, 32460.0, 32484.863029793964, 32492.512612310835, 33445.665543344556, 41499.792501037526, 42309.57690423095, 42337.78831105848, 42377.36433953493, 9712.465814380206, 9882.9505852471, 9888.95055524725, 9901.257405694601, 9915.504224788769, 9701.417914925092, 9846.704598862045, 9852.26108041896, 9854.605815767361, 9860.654877079303, 9800.0, 9888.802223955516, 9896.455694936776, 9901.059399357086, 9905.702828915144, 9763.951180244088, 10009.0, 10037.89962100377, 10038.949805251, 10041.995800419973, 9783.266255030901, 9891.802163956752, 9932.95033524835, 9943.801123977551, 9945.70162895107, 33544.83227583871, 42440.1511969762, 42542.78728606368, 42590.0, 42591.14817703629, 3234.7897386669792, 3737.5514938207316, 3886.902827429337, 3930.213957208541, 4030.9193816123807, 28501.429971400663, 29308.267293317625, 29559.261018474495, 30066.54900176499, 30297.93957211487, 2718.2524805678595, 2790.274528622552, 2847.7152284771564, 2848.686644469107, 2904.419116176773, 478545.0, 478860.9999999965, 480110.0, 480201.0, 481108.0, 47441.5255847444, 48704.512954870705, 65143.0, 65575.34424655788, 65604.34395655982, 39256.41115383271, 47206.76396618064, 47321.0, 47642.0, 47681.76159119252, 16310.0, 16452.012879227284, 16485.0, 16502.33990640385, 16513.0, 13071.346432678329, 13102.148360356547, 13105.868941310466, 13150.60548183556, 13151.671208219872, 14232.003759736926, 15096.094234345974, 15300.0, 15305.46430874914, 15309.6172595686, 5874.970625146847, 6504.219493660791, 6554.344565543408, 6583.275839657634, 6585.539012269191, 953.618552578974, 1045.1482042135635, 1051.3008849115406, 1083.4366129612715, 1094.3597995172754, 30402.69597304043, 30423.087307380814, 30527.08418747441, 30576.388472230432, 30645.540316895265, 1005.1556692378291, 1095.9643137235237, 1138.430784607696, 1142.2289954280993, 1185.2414454748969, 15145.091294522364, 15269.465568705264, 15473.84526154724, 15539.844601554067, 15659.608509787346, 526636.0, 532093.0, 537006.0, 537965.0, 538978.0, 61063.0, 61477.69261153755, 61525.38474615197, 61548.692256539325, 61657.38342616606, 1455.8034665320047, 1485.2722166138515, 1508.8264849542225, 1518.9848101519065, 1521.117751704009, 24273.635895461935, 29257.561136582543, 29299.974500892367, 29319.70680293255, 30085.69914300829, 46703.766481167375, 47057.52942470532, 47080.29379559378, 47085.52914470811, 47147.82130446629, 287330.0, 294052.0, 294807.0, 294934.0, 295155.0, 27006.594901076893, 30139.547906780976, 30159.396812064075, 30688.53967190449, 34752.478712818825, 42822.57177428186, 43226.567734323515, 43471.56528434802, 44032.77983610061, 44183.77908110439, 35659.0, 36012.27975440529, 36497.27005459823, 36549.81725091358, 37186.0, 31223.687763122078, 35903.820480897426, 36054.8197259012, 36547.45178822373, 36600.45099323459, 36114.0, 36807.89576312715, 37501.0, 37829.81085094557, 38307.2338553233, 27387.589186161826, 32254.0, 32702.0, 33239.33521329608, 33269.83365083159, 24201.51596968086, 24536.509269814862, 24854.005839766207, 24881.0, 25343.6915724331], "unit": "score", "name": "ext.html", "value": 19119.762437249068}, {"lowerIsBetter": false, "replicates": [375.6637809160801, 378.9962100378995, 380.5965676383036, 383.4554931996565, 384.40609258695315, 20035.499112522193, 20135.798642013575, 20202.494937626565, 20894.582108357823, 20932.267370642032, 10652.147828173747, 12363.690907727312, 12507.249565026104, 12508.374581270946, 12516.812247816268, 5279.732864112624, 5354.384245811723, 5525.613207075507, 5532.7786888524415, 5534.944650553503, 5529.695866727328, 5551.916721249185, 5582.581306402016, 5585.273914391123, 5597.132444471114, 5567.8608034799045, 5576.9163462548095, 5858.882822343572, 6129.233845769279, 6176.81469555914, 5295.7881684732565, 5311.840644780644, 5347.983883062206, 5348.277982472394, 5362.061639213122, 4780.521947805191, 4787.928181077286, 5480.91778623321, 5700.914486282709, 5702.743376548024, 13666.453341866314, 13804.861951380459, 13867.306634668326, 13890.305484725824, 24376.756232437627, 2022.7168196452362, 2122.649762789138, 2315.270689732729, 2384.7734465225776, 2741.1776467060004, 10016.64941727036, 10084.0, 10367.94816025915, 13225.338733063309, 14665.780013299809, 1716.1076240355071, 1771.9811108612598, 1791.1492041280417, 1808.6563552924902, 1811.6829554827984, 105295.94704053014, 111467.0, 112140.0, 112494.0, 114517.63659174187, 9707.611695532241, 11965.0, 12026.699332516757, 12038.939805300917, 12217.083718721131, 14541.34563944625, 14576.781348279785, 14593.343299551334, 14611.415543378358, 14631.341589628491, 9001.819963600692, 9062.320325975577, 9062.81874362509, 9065.456072635663, 9158.267338612895, 7338.0, 9186.816263674822, 9197.724068277963, 9235.630574777068, 9298.349115561981, 4656.790444429941, 4671.416072990876, 4682.953170468251, 4692.085043416501, 4733.124371991193, 1878.0140426276314, 1906.5805522784965, 1948.9415317540497, 1949.4736421166206, 1959.6668566343756, 681.9488538359643, 856.9228769410782, 902.7697937026016, 976.4190306767462, 1009.3741880034279, 20190.889501077287, 21034.0, 21130.577388452144, 21189.682154767692, 21201.83389913539, 992.2062350119875, 996.0786272697726, 1030.1141018723893, 1031.886492485826, 1043.473046111724, 10869.83695244572, 11161.944190278997, 11188.272762270426, 11203.719907002227, 11231.438428078563, 650505.0, 651788.0, 656621.0, 658717.0, 664717.0, 118279.0, 119231.40384298196, 122891.77108228803, 124150.0, 124797.0, 1045.6758404894356, 1054.4411461925192, 1077.5097330714507, 1078.37454276521, 1080.5407701726735, 17952.820471795116, 18196.818031820043, 18331.08344582792, 18741.156647950604, 18967.810321896606, 52280.73859630677, 53083.469165307855, 53601.449815049586, 54046.189307159635, 54888.45111548834, 71267.0, 71807.0, 72196.63901680458, 72751.0, 72884.0, 8149.684855746533, 8163.551004694798, 8253.422260441952, 8309.501429914224, 8614.91385086141, 9651.98654141305, 12559.748805024032, 13046.478140874258, 13147.211167329991, 13218.933905330412, 11577.189596728149, 11678.591249306233, 11832.34922079294, 11843.0, 11942.223755455867, 10642.8403573948, 11503.194776365573, 11536.769264614828, 11733.471993760131, 11740.647780566596, 8852.55737213124, 11233.191702074482, 11619.477123529292, 11925.701857453287, 11979.520819167485, 7834.843303133792, 8116.107228205011, 8187.1403502631365, 8223.95888020556, 8570.057293697813, 11424.71438214018, 11637.88362116368, 11709.76580468403, 11807.704807379885, 11960.76078478408], "unit": "score", "name": "jquery.html", "value": 9362.855383959228}, {"lowerIsBetter": false, "replicates": [1370.8971827112966, 1468.1631470062055, 1640.1225344440722, 1886.726424668423, 2054.506918339599, 8278.089410164877, 8885.422447540914, 9069.455832650046, 9084.00075991642, 9110.681126160585, 8712.782180445476, 8985.550722463884, 9000.8649870252, 9006.09939006099, 9009.0, 2533.2906786099875, 2565.3201901496027, 2568.884400201986, 2572.7427257274217, 2572.742725727431, 2524.6970363556397, 2586.2112055822968, 2588.0682954136496, 2594.377349436141, 2599.662043934286, 2541.7077036140804, 2571.7556832100922, 2572.125477337705, 2572.2154742803436, 2577.6133579962966, 2562.1032638576444, 2574.2663340947834, 2583.3799888026924, 2592.7925765938867, 2593.3775893785355, 2544.3511904464426, 2576.330154159932, 2581.070814506776, 2589.4562141950214, 2589.8575578343184, 8958.0, 8988.235999939947, 8993.325500587482, 8998.0, 9009.144131307516, 5512.834614961557, 5667.1782591524, 5673.546116310686, 5719.399463056361, 5730.4269573042775, 3618.710503159767, 3751.043483911585, 3755.793431361274, 3782.1490164712995, 3807.0672685192303, 1051.805415998043, 1077.1921059205595, 1086.3319058778873, 1096.660035389024, 1107.4739498738115, 12482.938950189155, 12497.75004499914, 12499.0, 12510.124291299613, 12538.623841284776, 5056.721880296545, 5057.671251368649, 5098.59211263098, 5148.922766158511, 5174.818881339135, 10403.063724264852, 10418.270721049454, 10451.477426128815, 10474.0, 10480.895191047992, 5669.858253543694, 5676.148577713294, 5715.2855893013375, 5720.799772007959, 5724.084146536534, 4524.864254072383, 4696.7651617419, 4946.727929963887, 5048.873778155576, 5175.404828444702, 4088.4908932368025, 4130.215259100762, 4165.300750012441, 4195.685323600681, 4205.095904380573, 1094.4691824465128, 1332.946682132704, 1333.5399287245884, 1363.659085228693, 1369.815074964887, 1011.7672935224939, 1041.0058394233488, 1138.8519492465925, 1296.4101333893057, 1437.4681367893945, 8324.29243514296, 8408.41141120128, 8414.873776893352, 8531.74404767858, 8778.517181555077, 1243.8445194350707, 1246.6322434881731, 1253.1854294708512, 1264.178284115314, 1274.120856608937, 6461.289258181596, 6533.803985880431, 6583.045458408504, 6606.702698378488, 6613.570117942415, 10600.151987840954, 10942.069924056384, 11049.502772375263, 11057.668269951915, 11079.279846809932, 10316.123129534073, 10767.407792571326, 10884.40135792539, 11093.778124437466, 11096.0, 8411.327093832479, 8536.44513106646, 8565.015023272279, 8644.610992505352, 8656.999999999874, 8284.875726864222, 8338.583070846555, 8341.16588341161, 8412.789680258042, 8419.536925469158, 9537.998510156334, 9790.412575245507, 9819.263555233529, 9845.261605379483, 9929.0, 9655.9034409655, 10074.647387341407, 10124.797504050026, 10139.746506337106, 10186.286959912735, 8039.236272554098, 8332.458390204618, 8357.206065423776, 8369.414141010071, 8379.203975622306, 8502.404831661725, 8502.659893604186, 8550.871736924075, 8560.91439085601, 8570.35722320841, 7325.413966882743, 7378.299061589355, 7451.44114191427, 7480.0649918760155, 7600.087989441303, 7274.0, 7378.631068446449, 7448.180700123091, 7510.812229694083, 7621.923780762343, 7954.522728636301, 8025.0, 8028.0, 8030.63862126194, 8040.0, 7162.677679504539, 7234.276572342934, 7242.420606351585, 7254.0, 7273.454490913308, 7319.158296795724, 7339.412846972123, 7371.963140184479, 7373.225811289952, 7420.109586849612], "unit": "score", "name": "mootools.html", "value": 5128.501652194207}, {"lowerIsBetter": false, "replicates": [322.74180655475624, 325.3298205696266, 329.9208190034391, 331.3042610517911, 335.20221871944784, 6578.769743058994, 6652.966735166329, 6684.729901318746, 6696.990902729186, 6731.293214212511, 6558.901616475757, 6579.0, 6601.933980660192, 6621.205455345354, 6627.9337206627915, 4759.7144171349655, 4852.32067510547, 4865.367502224706, 4938.456769755324, 4964.429090654585, 4301.612605004221, 4882.194437917739, 4977.975110124463, 5001.849944501652, 5025.924611130836, 4926.137925862978, 4927.384076990376, 4928.507149285078, 4939.0, 4950.702957822542, 4658.86023419298, 4756.7145971241835, 4938.851834444972, 4946.356973593446, 4946.802127914915, 4214.978925105385, 4230.725002874803, 4230.76730779807, 4230.894227644303, 4242.130363275552, 6448.742050317982, 6467.0, 6479.092926990225, 6486.059521369422, 6495.837604059889, 5220.112580861262, 5832.1834943108, 5849.444302791228, 5863.882722345572, 5877.471027607536, 6037.909431358533, 6843.657817109125, 7132.251113633098, 7137.0, 7143.642817859086, 3963.980180099081, 4677.74272415017, 4754.976225118852, 4756.07256584966, 4757.524247575249, 7421.25787421259, 7499.550026998343, 7504.737334193277, 7510.136334321568, 7521.984532088208, 3952.5849785772657, 3969.861054863066, 4816.590589799836, 4820.108279968217, 4830.4444988826035, 4887.340209071801, 4890.975545122252, 4894.510548945077, 4895.8041678332465, 4902.877428064256, 5188.8962220755375, 5422.590126567042, 5823.98080335948, 5842.824715258549, 5844.415558444121, 6813.454923606099, 7195.820104497325, 7196.784096477114, 7200.603966781878, 7205.603691796897, 1553.5960650230795, 1590.5705459525864, 1663.484319860847, 1703.8892471989282, 1707.086708610912, 1035.1304903880625, 1066.2589500297374, 1088.7659153282082, 1100.3837850803575, 1125.8029844777132, 6460.353964603502, 6633.7346506140175, 6639.136912201383, 6651.0, 6654.567453115532, 6750.32496750321, 6913.827154321081, 7478.326950574366, 7487.401007919353, 7489.251074892467, 6596.0105984102765, 6596.70314835824, 6600.636964966877, 6604.14146160996, 6605.834854128685, 3874.3994680824517, 3920.313945059603, 3922.8626998054933, 3925.156091440354, 3928.52857657083, 7431.85136297271, 7694.961525192451, 7701.499402538817, 7704.075510938723, 7704.383649308042, 7586.431017673696, 7675.424343174284, 7682.001339825899, 7683.654235559413, 7684.731034413769, 7672.846543069108, 7683.193264707237, 7687.308142267223, 7687.692492300469, 7699.615019249015, 7137.714491420285, 7348.59582722934, 7423.443241756998, 7436.814079648052, 7445.516041457288, 5987.910181347371, 7145.678049560848, 7320.304571065741, 7321.926780732338, 7337.229590892879, 6350.841228969128, 7659.770206893802, 7677.808054798675, 7677.961610191914, 7680.6543705534505, 4622.329762184531, 4666.650001249987, 4669.929951050669, 4674.415698037746, 4681.99337142516, 7116.964415177891, 7364.926350736639, 7376.4098872089035, 7380.520266182681, 7388.6675099621725, 7139.464540159613, 7155.284471552803, 7169.175544812413, 7208.89186662211, 7224.927750722425, 7196.172440169447, 7203.459740519377, 7203.603801790951, 7220.205777364381, 7229.602371869598, 7040.471964602778, 7051.153861536648, 7053.788386348418, 7057.258987806206, 7070.045543851515, 6239.688015599111, 6897.827554311182, 7118.110236220473, 7171.964140179266, 7182.569045857265, 6632.602043877383, 6715.496337774589, 6870.31296870309, 6877.828054298482, 6931.549449285781], "unit": "score", "name": "prototype.html", "value": 5198.619412367396}, {"lowerIsBetter": false, "replicates": [645.2257291250498, 768.1051574915224, 769.9384049276055, 786.3826895886726, 787.6180052674451, 15017.174055426947, 15231.543053708392, 15236.771448428268, 18012.909935450305, 18307.267709291646, 16534.42129525467, 16577.254023568938, 16645.50063498097, 16665.83334166661, 16674.58313542162, 784.0, 788.9900926813688, 964.7877466957258, 984.8621193032981, 991.9881720644938, 819.213554987213, 952.8808898887639, 970.5583959298502, 971.4608392342247, 973.5570315506427, 1018.1040684197895, 1038.48075962019, 1039.3815679670622, 1039.9168066554696, 1040.5733649203828, 829.0383155539575, 994.318891559277, 1017.959281628734, 1021.4841505039944, 1025.887152413234, 751.4401770680823, 751.812046988253, 754.1741792736944, 755.8110472381904, 756.2399788212866, 15678.372865085497, 15760.290786914617, 15778.447754328543, 15778.842211577852, 15782.842171578252, 12263.202891812003, 12348.938255308665, 12425.937870310681, 12476.50093996239, 12559.372031398394, 10152.847707284396, 10283.537240824106, 10345.172386209086, 10511.106555942677, 10512.31669941459, 3531.7351198659944, 3531.7351198659944, 3553.0, 3589.407747721623, 3591.407417776064, 81133.59433202796, 82130.0, 82484.58757706173, 82546.58726706388, 89041.66437503378, 9622.13400793918, 9708.368956017977, 9729.70810875675, 9856.408615482951, 10163.288569800043, 21517.67723484149, 21606.0, 21620.45948851291, 21622.675659865115, 21639.67540486894, 7397.334239918433, 7536.585487798114, 7536.7362142324755, 7564.962175189199, 7577.659005344773, 4007.6393124618926, 4058.979705101515, 4095.651869591118, 4178.080822219107, 4358.542353052947, 3745.4943582616543, 3770.547534295902, 3770.792606406619, 3788.052986753312, 3791.279656865187, 643.6170478565247, 664.8703502817001, 673.054358626131, 677.9661016949133, 691.3777600159897, 546.8960897429475, 584.3105135939547, 604.7157835817159, 610.5164450385518, 621.9502439804804, 7917.524948503108, 8347.791305217419, 8765.298776097898, 9072.637094516278, 9128.90871091294, 587.3979171349401, 588.2088590845307, 625.3652542669195, 636.7357546618194, 652.5758257132902, 3855.190410013872, 3931.5478719947573, 4012.6990475713856, 4031.395290706359, 4160.646345060643, 87771.0, 92727.53636231775, 94476.0, 95283.0, 96184.0, 75548.48903022018, 76260.84450495362, 77382.0, 77633.61183194048, 78326.43347133025, 51124.0, 51629.880563307684, 54392.45607543953, 54934.62663433446, 55053.72473137609, 6532.346765323429, 7806.024246969129, 7816.374690024898, 8030.5181689098845, 8049.83900322002, 45344.0, 46809.53190468188, 47081.0, 47232.0, 48664.51335486741, 58684.0, 59856.70071649614, 63825.0, 64035.67982160059, 64869.0, 9976.60093596248, 10073.546690399096, 10214.182865370902, 10759.3544387337, 10775.515101820594, 9029.77425564366, 11030.44847757625, 11471.541138354707, 11522.193446458667, 11587.0, 8839.646414143363, 11196.83204751913, 11776.0, 12096.520213356416, 12382.56629801419, 11594.362310073027, 11603.535858565563, 11709.76580468403, 11808.114391420506, 11974.940125299667, 9094.954525227331, 11101.222914396238, 11124.332540047622, 11144.33134011962, 12006.519739210335, 9363.906360936304, 9528.61885524599, 9669.564869581027, 9800.754981125529, 10077.244206684674, 9532.475713835805, 9819.410835349901, 9827.0, 9856.57446096856, 9910.801783964425], "unit": "score", "name": "yui.html", "value": 6968.748197605177}], "name": "dromaeo_css", "value": 7589.642145266231}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 109.0, 108.0, 110.0, 107.0, 118.0, 108.0, 111.0, 109.0], "unit": "score", "name": "ai-astar", "value": 110.1}, {"lowerIsBetter": true, "replicates": [152.0, 116.0, 152.0, 144.0, 116.0, 138.0, 115.0, 146.0, 147.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 134.5}, {"lowerIsBetter": true, "replicates": [183.0, 183.0, 185.0, 184.0, 186.0, 187.0, 184.0, 184.0, 182.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.2}, {"lowerIsBetter": true, "replicates": [66.0, 75.0, 70.0, 118.0, 55.0, 58.0, 57.0, 58.0, 125.0, 64.0], "unit": "score", "name": "audio-fft", "value": 74.6}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 133.0, 80.0, 78.0, 89.0, 116.0, 75.0, 82.0, 113.0], "unit": "score", "name": "audio-oscillator", "value": 95.2}, {"lowerIsBetter": true, "replicates": [87.0, 86.0, 89.0, 86.0, 88.0, 84.0, 89.0, 86.0, 89.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 102.0, 136.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 98.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 83.0, 83.0, 84.0, 86.0, 81.0, 129.0, 142.0, 106.0], "unit": "score", "name": "imaging-desaturate", "value": 96.5}, {"lowerIsBetter": true, "replicates": [56.0, 59.0, 63.0, 58.0, 58.0, 57.0, 55.0, 69.0, 82.0, 85.0], "unit": "score", "name": "json-parse-financial", "value": 64.2}, {"lowerIsBetter": true, "replicates": [65.0, 72.0, 61.0, 73.0, 69.0, 66.0, 62.0, 66.0, 65.0, 85.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [81.0, 80.0, 80.0, 80.0, 81.0, 79.0, 81.0, 79.0, 78.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.3}, {"lowerIsBetter": true, "replicates": [117.0, 117.0, 118.0, 117.0, 129.0, 115.0, 127.0, 126.0, 127.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.0}, {"lowerIsBetter": true, "replicates": [134.0, 122.0, 131.0, 131.0, 135.0, 121.0, 133.0, 130.0, 132.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.1}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 64.0, 64.0, 64.0, 66.0, 66.0, 67.0, 66.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1409.6999999999998}]} 03:50:38 INFO - Return code: 0 03:50:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:50:38 INFO - # TBPL SUCCESS # 03:50:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:50:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:50:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:50:38 INFO - 2016-04-01 03:50:38.074 screenresolution[1700:9982] starting screenresolution argv=screenresolution get 03:50:38 INFO - 2016-04-01 03:50:38.077 screenresolution[1700:9982] Display 0: 1600x1200x32@60 03:50:38 INFO - 2016-04-01 03:50:38.083 screenresolution[1701:9985] starting screenresolution argv=screenresolution list 03:50:38 INFO - Available Modes on Display 0 03:50:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:50:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:50:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:50:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:50:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:50:38 INFO - Intel Iris: 03:50:38 INFO - Chipset Model: Intel Iris 03:50:38 INFO - Type: GPU 03:50:38 INFO - Bus: Built-In 03:50:38 INFO - VRAM (Dynamic, Max): 1536 MB 03:50:38 INFO - Vendor: Intel (0x8086) 03:50:38 INFO - Device ID: 0x0a2e 03:50:38 INFO - Revision ID: 0x0009 03:50:38 INFO - Displays: 03:50:38 INFO - Display: 03:50:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:50:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:50:38 INFO - Display Serial Number: 1600x1200 60 03:50:38 INFO - Main Display: Yes 03:50:38 INFO - Mirror: Off 03:50:38 INFO - Online: Yes 03:50:38 INFO - Rotation: Supported 03:50:38 INFO - Return code: 0 03:50:38 INFO - Running post-action listener: _resource_record_post_action 03:50:38 INFO - Running post-run listener: _resource_record_post_run 03:50:39 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 37590528; Write bytes: 666325504; Read time: 392; Write time: 2002 03:50:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358525440; Write bytes: 355104768; Read time: 28107; Write time: 711 03:50:39 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 35669504; Write bytes: 300046848; Read time: 369; Write time: 1258 03:50:39 INFO - Running post-run listener: _upload_blobber_files 03:50:39 INFO - Blob upload gear active. 03:50:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:50:39 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 03:50:39 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:50:39 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:50:39 INFO - (blobuploader) - INFO - Open directory for files ... 03:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:41 INFO - (blobuploader) - INFO - Done attempting. 03:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:42 INFO - (blobuploader) - INFO - Done attempting. 03:50:42 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:42 INFO - Return code: 0 03:50:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e944c901369c072f11eb657d4553e18806853fd3ffef3af0b48a19e11f550b306bd47115ac817946798e2c1c35d41b723e2e16590a8252940b803d405a8ab9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0f011a46d3d5ab52c47781085571c7f6ce8001887e326434676fc99475c958710cb78c5c4eac30a1082263546864c2aa3ac8430d5dbc3de061959a69dfda636"} 03:50:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:42 INFO - Contents: 03:50:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e944c901369c072f11eb657d4553e18806853fd3ffef3af0b48a19e11f550b306bd47115ac817946798e2c1c35d41b723e2e16590a8252940b803d405a8ab9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0f011a46d3d5ab52c47781085571c7f6ce8001887e326434676fc99475c958710cb78c5c4eac30a1082263546864c2aa3ac8430d5dbc3de061959a69dfda636"} 03:50:42 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:42 INFO - Copying logs to upload dir... 03:50:42 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.685258 ========= master_lag: 3.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-01 03:50:45.392647) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-01 03:50:45.397172) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/Mozilla-Aurora/sha512/1e944c901369c072f11eb657d4553e18806853fd3ffef3af0b48a19e11f550b306bd47115ac817946798e2c1c35d41b723e2e16590a8252940b803d405a8ab9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0f011a46d3d5ab52c47781085571c7f6ce8001887e326434676fc99475c958710cb78c5c4eac30a1082263546864c2aa3ac8430d5dbc3de061959a69dfda636"} build_url:https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011236 build_url: 'https://queue.taskcluster.net/v1/task/WcrLbmymTv-Tg5iOmlB15Q/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e944c901369c072f11eb657d4553e18806853fd3ffef3af0b48a19e11f550b306bd47115ac817946798e2c1c35d41b723e2e16590a8252940b803d405a8ab9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0f011a46d3d5ab52c47781085571c7f6ce8001887e326434676fc99475c958710cb78c5c4eac30a1082263546864c2aa3ac8430d5dbc3de061959a69dfda636"}' ========= master_lag: 4.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-01 03:50:49.875063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-01 03:50:49.875364) ========= 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.jJMJa5TR54/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.j8ffPXTsyu/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005402 ========= master_lag: 4.85 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-01 03:50:54.735636) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-01 03:50:54.735932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-01 03:50:54.768742) ========= ========= Total master_lag: 18.92 =========