builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0142 starttime: 1460046529.64 results: success (0) buildid: 20160407091414 builduid: 1aeebb0a188d4641857b967e81c21d16 revision: 1725b460c3e0de97590cb8764df9ace9115b813e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.643174) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.643598) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.643888) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005924 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.670114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.670513) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.689126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.689785) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005412 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.741959) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.742239) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.742635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.742976) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-07 09:28:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 136M=0s 2016-04-07 09:28:49 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.227467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.990383) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:49.991156) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.019947 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:50.042688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 09:28:50.043065) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1725b460c3e0de97590cb8764df9ace9115b813e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1725b460c3e0de97590cb8764df9ace9115b813e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-07 09:28:50,120 truncating revision to first 12 chars 2016-04-07 09:28:50,120 Setting DEBUG logging. 2016-04-07 09:28:50,120 attempt 1/10 2016-04-07 09:28:50,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1725b460c3e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 09:28:51,845 unpacking tar archive at: fx-team-1725b460c3e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.972086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 09:28:52.035409) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:52.036224) ========= echo 1725b460c3e0de97590cb8764df9ace9115b813e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1725b460c3e0de97590cb8764df9ace9115b813e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 1725b460c3e0de97590cb8764df9ace9115b813e program finished with exit code 0 elapsedTime=0.005208 script_repo_revision: '1725b460c3e0de97590cb8764df9ace9115b813e' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:52.090150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:52.090773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:28:52.106140) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 09:28:52.106471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:28:52 INFO - MultiFileLogger online at 20160407 09:28:52 in /builds/slave/test 09:28:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:28:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:52 INFO - {'append_to_log': False, 09:28:52 INFO - 'base_work_dir': '/builds/slave/test', 09:28:52 INFO - 'blob_upload_branch': 'Fx-Team', 09:28:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:52 INFO - 'branch': 'Fx-Team', 09:28:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:52 INFO - 'config_files': ('talos/mac_config.py',), 09:28:52 INFO - 'default_actions': ('clobber', 09:28:52 INFO - 'read-buildbot-config', 09:28:52 INFO - 'download-and-extract', 09:28:52 INFO - 'populate-webroot', 09:28:52 INFO - 'create-virtualenv', 09:28:52 INFO - 'install', 09:28:52 INFO - 'run-tests'), 09:28:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:52 INFO - 'download_minidump_stackwalk': True, 09:28:52 INFO - 'download_symbols': 'ondemand', 09:28:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:52 INFO - 'installer_path': 'installer.exe', 09:28:52 INFO - 'log_level': 'info', 09:28:52 INFO - 'log_name': 'talos', 09:28:52 INFO - 'log_to_console': True, 09:28:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:28:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:28:52 INFO - 'opt_config_files': (), 09:28:52 INFO - 'pip_index': False, 09:28:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:52 INFO - 'cmd': ('bash', 09:28:52 INFO - '-c', 09:28:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:52 INFO - 'enabled': True, 09:28:52 INFO - 'halt_on_failure': False, 09:28:52 INFO - 'name': 'check_screen_resolution'},), 09:28:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:52 INFO - 'cmd': ('bash', 09:28:52 INFO - '-c', 09:28:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:52 INFO - 'enabled': True, 09:28:52 INFO - 'halt_on_failure': False, 09:28:52 INFO - 'name': 'check_screen_resolution'},), 09:28:52 INFO - 'run_cmd_checks_enabled': True, 09:28:52 INFO - 'sps_profile': False, 09:28:52 INFO - 'sps_profile_interval': 0, 09:28:52 INFO - 'suite': 'dromaeojs', 09:28:52 INFO - 'system_bits': '32', 09:28:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:52 INFO - 'title': 't-yosemite-r7-0142', 09:28:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:52 INFO - 'use_talos_json': True, 09:28:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:52 INFO - 'work_dir': 'build'} 09:28:52 INFO - ##### 09:28:52 INFO - ##### Running clobber step. 09:28:52 INFO - ##### 09:28:52 INFO - Running pre-action listener: _resource_record_pre_action 09:28:52 INFO - Running main action method: clobber 09:28:52 INFO - rmtree: /builds/slave/test/build 09:28:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:53 INFO - Running post-action listener: _resource_record_post_action 09:28:53 INFO - ##### 09:28:53 INFO - ##### Running read-buildbot-config step. 09:28:53 INFO - ##### 09:28:53 INFO - Running pre-action listener: _resource_record_pre_action 09:28:53 INFO - Running main action method: read_buildbot_config 09:28:53 INFO - Using buildbot properties: 09:28:53 INFO - { 09:28:53 INFO - "project": "", 09:28:53 INFO - "product": "firefox", 09:28:53 INFO - "script_repo_revision": "production", 09:28:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:28:53 INFO - "repository": "", 09:28:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:28:53 INFO - "buildid": "20160407091414", 09:28:53 INFO - "stage_platform": "macosx64", 09:28:53 INFO - "basedir": "/builds/slave/test", 09:28:53 INFO - "buildnumber": 635, 09:28:53 INFO - "platform": "yosemite_r7", 09:28:53 INFO - "mozci_request": { 09:28:53 INFO - "priority": -1, 09:28:53 INFO - "type": "trigger_all_talos_jobs", 09:28:53 INFO - "times": 6 09:28:53 INFO - }, 09:28:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:28:53 INFO - "slavebuilddir": "test", 09:28:53 INFO - "branch": "fx-team", 09:28:53 INFO - "revision": "1725b460c3e0de97590cb8764df9ace9115b813e", 09:28:53 INFO - "slavename": "t-yosemite-r7-0142", 09:28:53 INFO - "builduid": "1aeebb0a188d4641857b967e81c21d16", 09:28:53 INFO - "repo_path": "integration/fx-team" 09:28:53 INFO - } 09:28:53 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg. 09:28:53 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json. 09:28:53 INFO - Running post-action listener: _resource_record_post_action 09:28:53 INFO - ##### 09:28:53 INFO - ##### Running download-and-extract step. 09:28:53 INFO - ##### 09:28:53 INFO - Running pre-action listener: _resource_record_pre_action 09:28:53 INFO - Running main action method: download_and_extract 09:28:53 INFO - mkdir: /builds/slave/test/build/tests 09:28: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')]} 09:28:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json 09:28:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:28:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:28:53 INFO - Downloaded 1199 bytes. 09:28:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:28:53 INFO - Using the following test package requirements: 09:28:53 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:28:53 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:28:53 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:28:53 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'jsshell-mac.zip'], 09:28:53 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:28:53 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:28:53 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:28:53 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:28:53 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:28:53 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:28:53 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:28:53 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:28: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')]} 09:28:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:28:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:28:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:54 INFO - Downloaded 17970713 bytes. 09:28:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:28:57 INFO - Return code: 0 09:28:57 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:28:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:58 INFO - Downloaded 17970713 bytes. 09:28:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:29:02 INFO - Return code: 0 09:29:02 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')]} 09:29:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:29:02 INFO - Downloaded 10847617 bytes. 09:29:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:29:03 INFO - Return code: 0 09:29: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')]} 09:29:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:29:05 INFO - Downloaded 108918030 bytes. 09:29:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:05 INFO - mkdir: /builds/slave/test/properties 09:29:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:05 INFO - Contents: 09:29:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:05 INFO - Running post-action listener: _resource_record_post_action 09:29:05 INFO - Running post-action listener: set_extra_try_arguments 09:29:05 INFO - ##### 09:29:05 INFO - ##### Running populate-webroot step. 09:29:05 INFO - ##### 09:29:05 INFO - Running pre-action listener: _resource_record_pre_action 09:29:05 INFO - Running main action method: populate_webroot 09:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:05 INFO - '--tppagecycles', 09:29:05 INFO - '7'], 09:29:05 INFO - 'tests': ['tsvgm']}}, 09:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['damp', 'tps']}, 09:29:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'tests': ['damp', 'tps']}, 09:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['dromaeo_dom']}, 09:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['a11yr', 09:29:05 INFO - 'ts_paint', 09:29:05 INFO - 'tpaint', 09:29:05 INFO - 'sessionrestore', 09:29:05 INFO - 'sessionrestore_no_auto_restore', 09:29:05 INFO - 'tabpaint']}, 09:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['tsvgx', 09:29:05 INFO - 'tsvgr_opacity', 09:29:05 INFO - 'tart', 09:29:05 INFO - 'tscrollx', 09:29:05 INFO - 'cart']}, 09:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:05 INFO - 'tsvgr_opacity', 09:29:05 INFO - 'tart', 09:29:05 INFO - 'tscrollx', 09:29:05 INFO - 'cart']}, 09:29:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'talos_options': ['--disable-e10s'], 09:29:05 INFO - 'tests': ['tp5o']}, 09:29:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'tests': ['tp5o']}, 09:29:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'talos_options': ['--disable-e10s', 09:29:05 INFO - '--xperf_path', 09:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:05 INFO - 'tests': ['tp5n']}, 09:29:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:05 INFO - 'talos_options': ['--xperf_path', 09:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:05 INFO - 'tests': ['tp5n']}}, 09:29:05 INFO - 'talos.zip': {'path': '', 09:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:05 INFO - Running post-action listener: _resource_record_post_action 09:29:05 INFO - ##### 09:29:05 INFO - ##### Running create-virtualenv step. 09:29:05 INFO - ##### 09:29:05 INFO - Running pre-action listener: _resource_record_pre_action 09:29:05 INFO - Running main action method: create_virtualenv 09:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:05 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 09:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:05 INFO - Using real prefix '/tools/python27' 09:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:09 INFO - Installing pip.................done. 09:29:09 INFO - Return code: 0 09:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:09 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')]} 09:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 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')]} 09:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:09 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 09:29:09 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 09:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:09 INFO - 'HOME': '/Users/cltbld', 09:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:09 INFO - 'LOGNAME': 'cltbld', 09:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:09 INFO - 'MOZ_NO_REMOTE': '1', 09:29:09 INFO - 'NO_EM_RESTART': '1', 09:29:09 INFO - 'PAGER': '/bin/cat', 09:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:09 INFO - 'PWD': '/builds/slave/test', 09:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:09 INFO - 'SHELL': '/bin/bash', 09:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:09 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:09 INFO - 'USER': 'cltbld', 09:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:09 INFO - 'XPC_FLAGS': '0x0', 09:29:09 INFO - 'XPC_SERVICE_NAME': '0', 09:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:09 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 09:29:09 INFO - Cleaning up... 09:29:09 INFO - Return code: 0 09:29:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:29:09 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')]} 09:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 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')]} 09:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:09 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 09:29:09 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 09:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:09 INFO - 'HOME': '/Users/cltbld', 09:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:09 INFO - 'LOGNAME': 'cltbld', 09:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:09 INFO - 'MOZ_NO_REMOTE': '1', 09:29:09 INFO - 'NO_EM_RESTART': '1', 09:29:09 INFO - 'PAGER': '/bin/cat', 09:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:09 INFO - 'PWD': '/builds/slave/test', 09:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:09 INFO - 'SHELL': '/bin/bash', 09:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:09 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:09 INFO - 'USER': 'cltbld', 09:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:09 INFO - 'XPC_FLAGS': '0x0', 09:29:09 INFO - 'XPC_SERVICE_NAME': '0', 09:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:10 INFO - Downloading/unpacking psutil>=0.7.1 09:29:10 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 09:29:10 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 09:29:10 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 09:29:10 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 09:29:10 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 09:29:10 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 09:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:13 INFO - Installing collected packages: psutil 09:29:13 INFO - Running setup.py install for psutil 09:29:13 INFO - building 'psutil._psutil_osx' extension 09:29:13 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 09:29:13 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 09:29:13 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 09:29:13 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 09:29:13 INFO - building 'psutil._psutil_posix' extension 09:29:13 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 09:29:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:13 INFO - ^ 09:29:13 INFO - 1 warning generated. 09:29:13 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 09:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:14 INFO - Successfully installed psutil 09:29:14 INFO - Cleaning up... 09:29:14 INFO - Return code: 0 09:29:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:29:14 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')]} 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:14 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')]} 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:14 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:14 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 09:29:14 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 09:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:14 INFO - 'HOME': '/Users/cltbld', 09:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:14 INFO - 'LOGNAME': 'cltbld', 09:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:14 INFO - 'MOZ_NO_REMOTE': '1', 09:29:14 INFO - 'NO_EM_RESTART': '1', 09:29:14 INFO - 'PAGER': '/bin/cat', 09:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:14 INFO - 'PWD': '/builds/slave/test', 09:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:14 INFO - 'SHELL': '/bin/bash', 09:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:14 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:14 INFO - 'USER': 'cltbld', 09:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:14 INFO - 'XPC_FLAGS': '0x0', 09:29:14 INFO - 'XPC_SERVICE_NAME': '0', 09:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:29:16 INFO - Installing collected packages: mozsystemmonitor 09:29:16 INFO - Running setup.py install for mozsystemmonitor 09:29:17 INFO - Successfully installed mozsystemmonitor 09:29:17 INFO - Cleaning up... 09:29:17 INFO - Return code: 0 09:29:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:17 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:17 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 09:29:17 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 09:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:17 INFO - 'HOME': '/Users/cltbld', 09:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:17 INFO - 'LOGNAME': 'cltbld', 09:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:17 INFO - 'MOZ_NO_REMOTE': '1', 09:29:17 INFO - 'NO_EM_RESTART': '1', 09:29:17 INFO - 'PAGER': '/bin/cat', 09:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:17 INFO - 'PWD': '/builds/slave/test', 09:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:17 INFO - 'SHELL': '/bin/bash', 09:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:17 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:17 INFO - 'USER': 'cltbld', 09:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:17 INFO - 'XPC_FLAGS': '0x0', 09:29:17 INFO - 'XPC_SERVICE_NAME': '0', 09:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:17 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 09:29:17 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 09:29:17 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 09:29:17 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 09:29:17 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 09:29:17 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 09:29:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:19 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 09:29:19 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 09:29:19 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 09:29:19 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 09:29:19 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 09:29:19 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 09:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:20 INFO - Downloading docopt-0.6.1.tar.gz 09:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:21 INFO - Running setup.py install for blobuploader 09:29:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:21 INFO - Running setup.py install for requests 09:29:21 INFO - Running setup.py install for docopt 09:29:21 INFO - Successfully installed blobuploader requests docopt 09:29:21 INFO - Cleaning up... 09:29:22 INFO - Return code: 0 09:29:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:22 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 09:29:22 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 09:29:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:22 INFO - 'HOME': '/Users/cltbld', 09:29:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:22 INFO - 'LOGNAME': 'cltbld', 09:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:22 INFO - 'MOZ_NO_REMOTE': '1', 09:29:22 INFO - 'NO_EM_RESTART': '1', 09:29:22 INFO - 'PAGER': '/bin/cat', 09:29:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:22 INFO - 'PWD': '/builds/slave/test', 09:29:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:22 INFO - 'SHELL': '/bin/bash', 09:29:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:22 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:22 INFO - 'USER': 'cltbld', 09:29:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:22 INFO - 'XPC_FLAGS': '0x0', 09:29:22 INFO - 'XPC_SERVICE_NAME': '0', 09:29:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rL70BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YT8AzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-O1F0KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-WHkjHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bItjG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BZQw05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-VP9BR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-xIiQei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2_JsIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Dt_FWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-yyODHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-B36qju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q3nIKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q4rC1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gsYdAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0BC2Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-5QOSx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:24 INFO - Running setup.py install for manifestparser 09:29:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:24 INFO - Running setup.py install for mozcrash 09:29:24 INFO - Running setup.py install for mozdebug 09:29:24 INFO - Running setup.py install for mozdevice 09:29:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:24 INFO - Running setup.py install for mozfile 09:29:24 INFO - Running setup.py install for mozhttpd 09:29:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Running setup.py install for mozinfo 09:29:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Running setup.py install for mozInstall 09:29:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Running setup.py install for mozleak 09:29:25 INFO - Running setup.py install for mozlog 09:29:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Running setup.py install for moznetwork 09:29:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:25 INFO - Running setup.py install for mozprocess 09:29:25 INFO - Running setup.py install for mozprofile 09:29:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:26 INFO - Running setup.py install for mozrunner 09:29:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:26 INFO - Running setup.py install for mozscreenshot 09:29:26 INFO - Running setup.py install for moztest 09:29:26 INFO - Running setup.py install for mozversion 09:29:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:26 INFO - Cleaning up... 09:29:26 INFO - Return code: 0 09:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29: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')]} 09:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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')]} 09:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:26 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:26 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 09:29:26 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 09:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:26 INFO - 'HOME': '/Users/cltbld', 09:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:26 INFO - 'LOGNAME': 'cltbld', 09:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:26 INFO - 'MOZ_NO_REMOTE': '1', 09:29:26 INFO - 'NO_EM_RESTART': '1', 09:29:26 INFO - 'PAGER': '/bin/cat', 09:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:26 INFO - 'PWD': '/builds/slave/test', 09:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:26 INFO - 'SHELL': '/bin/bash', 09:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:26 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:26 INFO - 'USER': 'cltbld', 09:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:26 INFO - 'XPC_FLAGS': '0x0', 09:29:26 INFO - 'XPC_SERVICE_NAME': '0', 09:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Tie9XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zd1bT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-VMTxXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-j9GJC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0NLYgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-nqtJqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-t2ePZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bx5oNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-42Qvcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:27 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)) 09:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rKhGO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rBLxBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q6Lf52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Jn21_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_wvzw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-nw9kVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-4Z2UFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:28 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)) 09:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:28 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-VYxPUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:28 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)) 09:29:28 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)) 09:29:28 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)) 09:29:28 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)) 09:29:28 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)) 09:29:28 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)) 09:29:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:28 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 09:29:28 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 09:29:28 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 09:29:28 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 09:29:28 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 09:29:28 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 09:29:31 INFO - Downloading blessings-1.6.tar.gz 09:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:31 INFO - Installing collected packages: blessings 09:29:31 INFO - Running setup.py install for blessings 09:29:31 INFO - Successfully installed blessings 09:29:31 INFO - Cleaning up... 09:29:31 INFO - Return code: 0 09:29:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:31 INFO - Reading from file tmpfile_stdout 09:29:31 INFO - Current package versions: 09:29:31 INFO - blessings == 1.6 09:29:31 INFO - blobuploader == 1.2.4 09:29:31 INFO - docopt == 0.6.1 09:29:31 INFO - manifestparser == 1.1 09:29:31 INFO - mozInstall == 1.12 09:29:31 INFO - mozcrash == 0.17 09:29:31 INFO - mozdebug == 0.1 09:29:31 INFO - mozdevice == 0.48 09:29:31 INFO - mozfile == 1.2 09:29:31 INFO - mozhttpd == 0.7 09:29:31 INFO - mozinfo == 0.9 09:29:31 INFO - mozleak == 0.1 09:29:31 INFO - mozlog == 3.1 09:29:31 INFO - moznetwork == 0.27 09:29:31 INFO - mozprocess == 0.22 09:29:31 INFO - mozprofile == 0.28 09:29:31 INFO - mozrunner == 6.11 09:29:31 INFO - mozscreenshot == 0.1 09:29:31 INFO - mozsystemmonitor == 0.0 09:29:31 INFO - moztest == 0.7 09:29:31 INFO - mozversion == 1.4 09:29:31 INFO - psutil == 3.1.1 09:29:31 INFO - requests == 1.2.3 09:29:31 INFO - wsgiref == 0.1.2 09:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:31 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 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:31 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 09:29:31 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 09:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:31 INFO - 'HOME': '/Users/cltbld', 09:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:31 INFO - 'LOGNAME': 'cltbld', 09:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:31 INFO - 'MOZ_NO_REMOTE': '1', 09:29:31 INFO - 'NO_EM_RESTART': '1', 09:29:31 INFO - 'PAGER': '/bin/cat', 09:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:31 INFO - 'PWD': '/builds/slave/test', 09:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:31 INFO - 'SHELL': '/bin/bash', 09:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:31 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:31 INFO - 'USER': 'cltbld', 09:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:31 INFO - 'XPC_FLAGS': '0x0', 09:29:31 INFO - 'XPC_SERVICE_NAME': '0', 09:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 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)) 09:29:32 INFO - Cleaning up... 09:29:32 INFO - Return code: 0 09:29:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:32 INFO - 'HOME': '/Users/cltbld', 09:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:32 INFO - 'LOGNAME': 'cltbld', 09:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:32 INFO - 'MOZ_NO_REMOTE': '1', 09:29:32 INFO - 'NO_EM_RESTART': '1', 09:29:32 INFO - 'PAGER': '/bin/cat', 09:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:32 INFO - 'PWD': '/builds/slave/test', 09:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:32 INFO - 'SHELL': '/bin/bash', 09:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:32 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:32 INFO - 'USER': 'cltbld', 09:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:32 INFO - 'XPC_FLAGS': '0x0', 09:29:32 INFO - 'XPC_SERVICE_NAME': '0', 09:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:32 INFO - Downloading/unpacking jsonschema 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:34 INFO - Downloading/unpacking functools32 (from jsonschema) 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:35 INFO - warning: no files found matching '*.txt' 09:29:35 INFO - no previously-included directories found matching 'build' 09:29:35 INFO - no previously-included directories found matching 'dist' 09:29:35 INFO - no previously-included directories found matching '.git*' 09:29:35 INFO - Installing collected packages: jsonschema, functools32 09:29:35 INFO - Running setup.py install for functools32 09:29:35 INFO - warning: no files found matching '*.txt' 09:29:35 INFO - no previously-included directories found matching 'build' 09:29:35 INFO - no previously-included directories found matching 'dist' 09:29:35 INFO - no previously-included directories found matching '.git*' 09:29:35 INFO - Successfully installed jsonschema functools32 09:29:35 INFO - Cleaning up... 09:29:35 INFO - Return code: 0 09:29:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fce8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcf8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff109d647c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, '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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:35 INFO - 'HOME': '/Users/cltbld', 09:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:35 INFO - 'LOGNAME': 'cltbld', 09:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:35 INFO - 'MOZ_NO_REMOTE': '1', 09:29:35 INFO - 'NO_EM_RESTART': '1', 09:29:35 INFO - 'PAGER': '/bin/cat', 09:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:35 INFO - 'PWD': '/builds/slave/test', 09:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:35 INFO - 'SHELL': '/bin/bash', 09:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:29:35 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:35 INFO - 'USER': 'cltbld', 09:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:35 INFO - 'XPC_FLAGS': '0x0', 09:29:35 INFO - 'XPC_SERVICE_NAME': '0', 09:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:35 INFO - Downloading/unpacking flake8 09:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:29:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:29:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:38 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 09:29:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:29:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:29:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:29:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:29:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:29:39 INFO - no previously-included directories found matching 'docs/_build' 09:29:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:29:39 INFO - Running setup.py install for pep8 09:29:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:29:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:29:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:29:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:29:39 INFO - no previously-included directories found matching 'docs/_build' 09:29:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:29:39 INFO - Cleaning up... 09:29:39 INFO - Return code: 0 09:29:39 INFO - Running post-action listener: _resource_record_post_action 09:29:39 INFO - Running post-action listener: _start_resource_monitoring 09:29:39 INFO - Starting resource monitoring. 09:29:39 INFO - ##### 09:29:39 INFO - ##### Running install step. 09:29:39 INFO - ##### 09:29:39 INFO - Running pre-action listener: _resource_record_pre_action 09:29:39 INFO - Running main action method: install 09:29:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:40 INFO - Reading from file tmpfile_stdout 09:29:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:40 INFO - Reading from file tmpfile_stdout 09:29:40 INFO - Output received: 09:29:40 INFO - Usage: mozinstall [options] installer 09:29:40 INFO - Options: 09:29:40 INFO - -h, --help show this help message and exit 09:29:40 INFO - -d DEST, --destination=DEST 09:29:40 INFO - Directory to install application into. [default: 09:29:40 INFO - "/builds/slave/test"] 09:29:40 INFO - --app=APP Application being installed. [default: firefox] 09:29:40 INFO - mkdir: /builds/slave/test/build/application 09:29:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:30:13 INFO - Reading from file tmpfile_stdout 09:30:13 INFO - Output received: 09:30:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:14 INFO - Running post-action listener: _resource_record_post_action 09:30:14 INFO - ##### 09:30:14 INFO - ##### Running run-tests step. 09:30:14 INFO - ##### 09:30:14 INFO - Running pre-action listener: _resource_record_pre_action 09:30:14 INFO - Running main action method: run_tests 09:30:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:14 INFO - 2016-04-07 09:30:14.015 screenresolution[2324:37178] starting screenresolution argv=screenresolution get 09:30:14 INFO - 2016-04-07 09:30:14.018 screenresolution[2324:37178] Display 0: 1600x1200x32@60 09:30:14 INFO - 2016-04-07 09:30:14.024 screenresolution[2325:37181] starting screenresolution argv=screenresolution list 09:30:14 INFO - Available Modes on Display 0 09:30:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:14 INFO - Intel Iris: 09:30:14 INFO - Chipset Model: Intel Iris 09:30:14 INFO - Type: GPU 09:30:14 INFO - Bus: Built-In 09:30:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:14 INFO - Vendor: Intel (0x8086) 09:30:14 INFO - Device ID: 0x0a2e 09:30:14 INFO - Revision ID: 0x0009 09:30:14 INFO - Displays: 09:30:14 INFO - Display: 09:30:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:14 INFO - Display Serial Number: 1600x1200 60 09:30:14 INFO - Main Display: Yes 09:30:14 INFO - Mirror: Off 09:30:14 INFO - Online: Yes 09:30:14 INFO - Rotation: Supported 09:30:14 INFO - Return code: 0 09:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:14 INFO - Python 2.7.3 09:30:14 INFO - Return code: 0 09:30:14 INFO - grabbing minidump binary from tooltool 09:30:14 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')]} 09:30:14 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 0x10fb87e00>, '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 0x10f9b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:14 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 09:30:14 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 09:30:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:14 INFO - Return code: 0 09:30:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:30:15 INFO - Return code: 0 09:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 09:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 09:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFbWbhlfHX/Render', 09:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:15 INFO - 'HOME': '/Users/cltbld', 09:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:15 INFO - 'LOGNAME': 'cltbld', 09:30:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:15 INFO - 'MOZ_NO_REMOTE': '1', 09:30:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:15 INFO - 'NO_EM_RESTART': '1', 09:30:15 INFO - 'PAGER': '/bin/cat', 09:30:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:15 INFO - 'PWD': '/builds/slave/test', 09:30:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:15 INFO - 'SHELL': '/bin/bash', 09:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jCdGiQEH8C/Listeners', 09:30:15 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:15 INFO - 'USER': 'cltbld', 09:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:15 INFO - 'XPC_FLAGS': '0x0', 09:30:15 INFO - 'XPC_SERVICE_NAME': '0', 09:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 09:30:15 INFO - mozversion application_buildid: 20160406110850 09:30:15 INFO - mozversion application_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:15 INFO - mozversion application_display_name: Nightly 09:30:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:15 INFO - mozversion application_name: Firefox 09:30:15 INFO - mozversion application_remotingname: firefox 09:30:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:30:15 INFO - mozversion application_vendor: Mozilla 09:30:15 INFO - mozversion application_version: 48.0a1 09:30:15 INFO - mozversion platform_buildid: 20160406110850 09:30:15 INFO - mozversion platform_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:30:15 INFO - mozversion platform_version: 48.0a1 09:30:15 INFO - using testdate: 1460046615 09:30:15 INFO - actual date: 1460046615 09:30:15 INFO - starting webserver on 'localhost:57266' 09:30:15 INFO - SUITE-START | Running 2 tests 09:30:15 INFO - TEST-START | dromaeo_css 09:30:15 INFO - operating with platform_type : mac_ 09:30:15 INFO - Initialising browser for dromaeo_css test... 09:30:15 INFO - TEST-INFO | started process 2343 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpC68uel/profile http://localhost:57266/getInfo.html) 09:30:17 INFO - PROCESS | 2343 | __metrics Screen width/height:1600/1200 09:30:17 INFO - PROCESS | 2343 | colorDepth:24 09:30:17 INFO - PROCESS | 2343 | Browser inner width/height: 1024/689 09:30:17 INFO - PROCESS | 2343 | __metrics 09:30:17 INFO - PROCESS | 2343 | 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" 09:30:17 ERROR - PROCESS | 2343 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:30:18 INFO - TEST-INFO | 2343: exit 0 09:30:18 INFO - Browser initialized. 09:30:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:18 INFO - TEST-INFO | started process 2345 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpC68uel/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:30 INFO - PROCESS | 2345 | RSS: Main: 236572672 09:30:30 INFO - PROCESS | 2345 | 09:31:21 INFO - PROCESS | 2345 | 1460046681876 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] 09:33:31 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57266/tests/dromaeo/cssquery-ext.html) 09:33:32 INFO - PROCESS | 2345 | RSS: Main: 317652992 09:33:32 INFO - PROCESS | 2345 | 09:36:35 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-ext.html (next: http://localhost:57266/tests/dromaeo/cssquery-jquery.html) 09:36:35 INFO - PROCESS | 2345 | RSS: Main: 361443328 09:36:35 INFO - PROCESS | 2345 | 09:39:37 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57266/tests/dromaeo/cssquery-mootools.html) 09:39:38 INFO - PROCESS | 2345 | RSS: Main: 361619456 09:39:38 INFO - PROCESS | 2345 | 09:42:40 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57266/tests/dromaeo/cssquery-prototype.html) 09:42:41 INFO - PROCESS | 2345 | RSS: Main: 323747840 09:42:41 INFO - PROCESS | 2345 | 09:45:44 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57266/tests/dromaeo/cssquery-yui.html) 09:45:44 INFO - PROCESS | 2345 | RSS: Main: 324161536 09:45:44 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:57266/tests/dromaeo/cssquery-yui.html (next: http://localhost:57266/tests/dromaeo/cssquery-dojo.html) 09:48:47 INFO - PROCESS | 2345 | RSS: Main: 389586944 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | __start_tp_report 09:48:47 INFO - PROCESS | 2345 | _x_x_mozilla_page_load 09:48:47 INFO - PROCESS | 2345 | _x_x_mozilla_page_load_details 09:48:47 INFO - PROCESS | 2345 | |i|pagename|runs| 09:48:47 INFO - PROCESS | 2345 | |0;dojo.html;3357.6474470180633;3387.898363049108;3431.125063108907;3440.415129427997;3449.9482507762386;5471.5896307776975;5545.528630066444;5566.610337276387;5576.358718747345;5613.859653508659;5553.888922221553;5592.692401917893;5647.887042259153;5677.205191273215;5717.285339332583;4998.2752500887345;5009.949900501003;5017.64876458648;5026.4219614744225;5145.691258524501;5138.409082955471;5141.640085194038;5142.922856157161;5143.177091665334;5209.1404918188455;4251.553586873365;4999.650024498286;5075.467075957008;5095.464976177485;5107.719075450848;5037.874053148664;5078.466760990117;5079.415867175248;5082.923756143661;5088.262201980692;4959.330490383824;5053.545180933696;5074.985002999415;5086.338775959139;5145.459726728677;5438.102713052381;5441.727913604304;5442.183672449125;5447.4824891635235;5476.972615136939;5916.704164791743;5945.108233764927;5960.97019514904;5970.044792833126;5978.940210597882;4880.707157570557;6071.633309239542;6077;6133.264008318985;6181.876362472771;5678;5682.232898558725;5682.971585142089;5699.943000569982;5706.4864162225595;6119.41115851624;6229.844253893689;6315.715792789336;6339;6343.841403964845;5838.620489668158;5863.355030946593;5877.853053673607;5885.352611212763;5926.762929482772;6071.180390647294;6085.726142323606;6090.634561926298;6092.147099406086;6120.663363515049;5889.911651325234;5988.251468566429;5994.730237139339;6002.039673652194;6020.946334391464;5993.940060599338;6034.125051867542;6046.969765151234;6060.393960603992;6084.452399284086;4774.832880849153;4788.353572267769;4810.39870016248;4810.8797280068275;4860.295257187688;4313.676474264443;4354.804033818423;4359.760213188241;4363.912721745547;4366.275935060035;5479.890402191933;5510.5316048135555;5538.667679939216;5570.944290557042;5587.022271102541;6175.320714721377;6188.690565471708;6195.628262304276;6200.720967556381;6203.751849926042;5370.489803468664;5463.644863083887;5494.697791621419;5497.532709719719;5513.641613295123;5238.423773384925;5282.683039017671;5296.92054619181;5326.493983071602;5327.520523152934;6271.686415679289;6291.622502649856;6317.494600432046;6322.430981211713;6326.335734747785;6031.788887388921;6077.939220607737;6166.414190651881;6173.475254603409;6175.876482470415;6211.409916057967;6293.402126797947;6294.370562943852;6299.181106456132;6312.968435157978;6012.218411606463;6020.698965051817;6036.698165091816;6041.456268935817;6049.334573196857;6151.323354431099;6168.290646575701;6171.444569988723;6173;6174.166487524307;5798.246227990334;5849.356570777296;5885.323187833454;5946.108083787466;5966.313873904556;5905.852353691193;5906.70466476683;5915.467607915309;5915.940840591711;5936.821895343147;5238.057149713089;5446.128619420873;5750.0512415450985;5756.3380211276235;5790.913136303043;4549.908022074745;5322.308099946982;5953.404659534012;5968.641881487125;5992.250968628921;5793.79721709755;5807.709614519173;5809.970950145223;5811.186433899173;5813.738381772915 09:48:47 INFO - PROCESS | 2345 | |1;ext.html;457.32316172065345;531.8618157143711;585.0872638683654;623.8533944523037;634.5209366927969;37299.4405083924;37515;37517.06207344818;37688.811555942215;37776.055598610015;34066;40112.19775604494;40602.59397406025;40693.389599156035;40697.59302406975;9471;9660.468674222915;9687.951560242189;9695.46674932882;9700;9528.285378596633;9672.516374181298;9690.127888490035;9692.806143877118;9694.66068687596;9652.565634546427;9731.854022189673;9756.219502439788;9756.365836220622;9827.11555959967;9698.078682525183;9799.265055120859;9822.017798220193;9839.163671087967;9842.261830362715;9511.66709165176;9636.855447168298;9651.469169195692;9652.710418687451;9656.95171524138;32234.35531289361;38881;39315.41026884599;39345.21309573821;39415.8029209855;2858.2997165694537;2871.813332133405;2876.3815779607276;2885.1488810800656;3152.5586417901513;27089.916403343843;27132.59301110485;27460.17619471419;27524.862375688193;28136.859315703292;2746.093789049609;2766.9308267293277;2776.056140912079;2834.7873909456666;2876.7842411819197;495929;497033;497175;497885;498861;59986.10020849691;60090.09864852031;60321.69839150776;60420.69789651024;60482.8830990922;55464.44535554673;56136.43863561394;63724.36275637277;64414;64816.67591662106;16356;16395.67208655844;16493.752593711102;16514.504564863073;16558.254878530493;12840.871591284154;12900.483980640856;12923.741525169444;12936.223826570436;12949.964002879935;16391.590210244838;19062;19369.4189174325;19407.90296048539;19457;6874.828129296708;7734.1492435832015;7978.361731061501;8005.719799806979;8047.746630943345;912.6623149434752;979.6962941488019;1061.5488417422564;1099.2964502718266;1108.1135091926426;27333.180004599893;27567.31081722973;28136.29659258494;28171.873125075177;28270;1063.7340664833791;1099.9780004399868;1103.6027030269097;1110.1008183371496;1175.1715040896147;14360.425582976564;14457.78313325301;14698.044627099205;14724;15258.694826103638;539827;545773;551575;552296;552963;56262;56286.43713562812;56349;56675.71662141663;56678.43321566814;1352.8038434426953;1437.590286768266;1451.3178805961181;1458.4603696632103;1477.2687519677613;29003.709962900102;29172.562411564268;29228.83084676589;29795.851020744758;29832.701672982992;47515.049699006515;48163.75918120527;48236.79408014687;48371.5162848367;48714.83353582006;412940;434487;434686;435359;435819;42965.570344297405;43592.34611480767;43635;44104.558954410044;44399;44939.775301123285;45075;45179.54820451885;47070.58788236359;47085.52914470811;35767.821160894026;36196.81901590475;36278.91163265106;36642.08394790151;37247.813760932106;35295.82352088223;35542.111447214025;35659;36147.457788133725;36852.81573592115;36408.45387319139;36904;38052.61947380491;38058.429123562615;38107;32179.839100804347;32954.17614559655;33043.66956330406;33053.50419743754;33240;21198.453289236317;21351.46621334479;21464.356069317946;21539.676904846754;21710.131594736034 09:48:47 INFO - PROCESS | 2345 | |2;jquery.html;358.9264200838828;363.3441637843692;364.10067134178576;368.5338047370079;372.8173195134385;19448.51378715531;20034.89982550087;20276.59446811063;20736.585268294624;20752.584948301024;19393.806061939376;19809.910454924975;20576.176952921865;20593.27923522677;20686.96565171743;5613.66318020918;5827.0094084005605;5851.473367396933;5851.531877449794;5867.41325867412;4123.814428350732;4351.260285751414;4991.425986011601;6317.968410157942;6376.139221205148;6245.843853903643;6297.716602752864;6300.9369906301035;6305.653189074599;6329.873402531946;6124.203853499039;6266.811995640138;6287.7170527325925;6316.463100636429;6319;3548.6273941236313;5233.502817232356;5461.617686761928;5465.945340546584;5470.452954704538;24794.5041099177;25415.872920635462;25679.61480577793;25981.74018259812;26011.73988260131;2131.936041918729;2238.6194346961056;2357.3399448154546;2395.568797616428;2580.8064395170436;14483.275836208253;14553.199574023425;14670.266486675624;14775.187364694937;15176.772348414783;1640.6956469606573;1690.805557360907;1697.13446142467;1745.903975281359;1750.0199888062725;83477;99184;103249.96750032554;103430;103768.48115759373;10424.5830166794;11100.833487497694;11228.831567526493;11236.988671019648;11269.013711366495;13815.861841381658;13818.516351927634;13950.790738138936;13960.650983725489;13970.580882573538;8516.489010659381;8547.487150770974;8557.828843423096;8625.827483450421;8650.697225597074;7382.379255364376;8394.916050839536;8464.280536154496;8664.306855451548;8669.653213871501;3689.2805902849223;3809.7333186676665;4394.055278115221;4444.799984000728;4489.6408287336935;1452.6005348529238;1716.957076073108;1719.8452139307271;1789.9284028638708;1795.317779243879;836.7114643449021;901.5987885390938;917.3349321741684;995.5370752599931;998.9950050249847;19386.515337116685;20014.199432022557;20264.07627986539;20673.966301684857;20835.270765523135;855.4952577978897;940.2571968145226;972.6936015155203;981.7251169672503;997.586001809241;10744.623938162284;11175.44122793857;11217.663470095747;11218.887811121947;11222;535008;596893;602378;616756;616849;112220;120075;121368.78631213751;123193.38403308105;125728;1002.964896228643;1019.5819713917407;1040.5889673578895;1041.0266400915018;1041.3127335958382;15300;17629.294828206726;18024.90987545054;18306.81693183051;18539.81460185435;39196.60803391929;50550.747246263534;50725.49274507208;52053;52897.71022897679;72931;74071.25928740644;74299;75000.62499687467;75539.24460755542;7769.8834517481155;7792.337651299703;7858.6070696466095;7894.328982036591;8024.799380015546;7869;9118.133777291147;12350.938245308716;12503.4373453193;12591.87408125907;10099.192064634959;10621.309614875008;10642.574297028032;10844.583956053817;11191.440427978405;10697.839532407175;10792.244542881923;10951.945240273748;11101.278416902875;11312.095032397225;10410.167186625204;11294.94352528232;11369.431528423711;11562.421878906189;11570.363630000431;7793.883091753742;7926.960365198137;8060.232061190727;8226.177382261725;8272.33821294307;10574.629887953884;10829.512671929624;10856.674299771019;11128.221024528206;11282.379469128948 09:48:47 INFO - PROCESS | 2345 | |3;mootools.html;1440.827100747909;1566.2247187642115;1600.3358606178433;1876.8310852023315;2021.4036859126556;7977;8718.433301835385;8900.02099769025;8935.927688677351;8943.552822358888;8617.698380556667;8636.56817159141;8702.347323950713;8709.825803483927;8719.607617657204;2179.716636837209;2404.615261558151;2425.9514809703796;2468.2101727447225;2468.4939587384633;2206.933791986235;2403.0507949359994;2479.566075936713;2500.6874140732407;2503.9749602503925;2387.2002879035545;2414.8792560372;2430.7933825624755;2443.7556244375596;2445.8165637577254;2400.8559486430872;2433.3065076453213;2447.510497900409;2455.668484754562;2468.8642124683133;2410.3974006498374;2449.3019489445423;2467.235157101304;2468.60502319628;2474.74015228402;8867.068957759471;8906.242969347613;8909.331800114953;8910.287177025823;8923.241524470428;5416.133418652997;5486.561075113981;5552.666839989613;5554.833354999357;5564.387917329091;3475.1659601695665;3533.5406397168463;3606.278744251134;3614.8011859347725;3627.093226693327;1030.706248719119;1044.8171569975225;1051.3796859852723;1052.4001319248032;1061.4268295120626;11809;11819.409029548488;11830.290182589073;11832.994195493393;11867.05063594919;4833.468318484964;4855.490173531799;4867.196912509467;4933.975330123327;4939.333190019373;10033.498325083716;10043.045910638477;10052.59789608422;10059.144972677259;10079.848802267972;5452.700101494376;5457.5633949283965;5493.285872836585;5502.532284755761;5505.03661859173;4005.91988160241;4231.005713657288;4434.911301773946;4595.471520775087;4966.15575352191;3846.8845934622004;3905.179912218466;3917.7061720371084;3940.625640564142;3954.268460334847;1308.941097650608;1312.534050412109;1325.5559387605067;1326.1380102933172;1330.5742162508097;1336.3385124363308;1365.789615492014;1372.1629805818443;1379.0277854112826;1392.7980442835733;8289.461185023074;8322.54226017575;8350.457220280663;8363.749087527383;8399.41204115706;503.1994769895232;520.4379270387972;525.3433208489388;532.6111938284996;533.8745394832212;6161.568690191643;6234.376562343729;6277.686115694197;6292.716827742763;6306.590071645328;10418.791624167474;10767.83848242277;10778.730531736612;10783.784324313627;10819.242653014371;10394.740131496621;10518.368897866152;10584.947075264574;10629.5216715248;10662.573497060186;8153.633086511121;8248.628811703487;8264.793380165544;8294.212049855254;8376.246137847502;7630.7329243476215;7859.4891332063235;7867.8819817703925;7870.527768333918;7893.881591776013;9258.53707314645;9621.951890240503;9682.806343873224;9684.51577421112;9706;9700.854487182556;9826.9017309826;9991.100800927952;9996.800063998824;9996.850047249152;6644.800655980328;6760.391564759196;6969.756058537927;7424.071991001125;7474.2525747424825;6587.934120658732;7695.384369250334;7704.345130663956;8194.34445244391;8212.384071194565;7183.138023437221;7241.891371629536;7245.818854528679;7322.450816188913;7368.300011498899;7135.464840136907;7147.606881621353;7227.566346019255;7267.200607933018;7288.635568221462;7559.470837041355;7561.848763024599;7601.885971710318;7646.235376462309;7652.73215437457;6911.5853048815225;6915.861682766216;6963.61700106499;6964.721411143498;6975.9302406975285;6610.305917878553;6646.700898459484;6669.333066693486;6686.398224159849;6718.496112791658 09:48:47 INFO - PROCESS | 2345 | |4;prototype.html;321.79887570268585;324.19761091299034;330.06755914541424;332.05364710574855;335.4599095456315;6330.746770129195;6387.137736405584;6411.230652321717;6422.325655806138;6439.452646525046;6259.217597800275;6277.717502712378;6321.873562528758;6332.303446620868;6340.968295158529;4503.752293623849;4545.409096817409;4654.929366245766;4715.952840471594;4725.22033864412;4706.388169537972;4716.268978308368;4719.882002949919;4723.62211023119;4725.1258517174265;3910.76535407875;4643.257078867382;4673.6027437667835;4677.766111694419;4680.134175177586;4452.931296488836;4509.98525331801;4627.583517483409;4641.744704041276;4662.626989840838;3351.8994430167136;3989.7606143631474;4014.979925100356;4025.9130034890413;4040.9493531681974;6093.512518998469;6109.083637454373;6115.9694201529155;6124.387561243884;6138.754449822047;5553.611247212697;5609.859753506154;5616.775328986836;5621.18492818539;5646.153077038436;6743.089682892795;6765.830854228634;6772.255051944331;6776.42400395967;6793.762218322385;4244.681648876316;4389.31965545341;4402.669799765031;4403.097365040191;4411.602955733999;6122.979416792447;6715.630640314829;6881.243063263037;7050.083489146429;7071.8939215911805;4478.261086920622;4484.596386325246;4492.887677808081;4495.865124046219;4511.030128522383;4541.9545804542195;4557.544245575482;4559.6808223424705;4561.29299958507;4572.7713614319155;5342.011727830364;5388.110961691277;5392.460753924576;5395.082835917903;5396.865078372994;6483.3516648335735;6716.630585317756;6731.932680673229;6741.426978706865;6742.561733487308;1556.6730986492737;1568.827428982811;1580.3678528588657;1584.4612831637298;1587.0239802521367;951.8619800128943;972.6206779356057;1001.6043662753171;1009.0363702663939;1031.4120951057976;6015.30823955242;6111.755529778759;6117.204763380732;6142.78500252489;6189.96905015481;7070.328318809705;7071.82320441993;7072.357735766084;7084.681189346492;7089.503734738622;5109;6173.228346456693;6195.96902015496;6197.783077592263;6203.534734894901;3666.5966743658178;3719.75821571597;3727.4408838674417;3729.328720830222;3731.589525152231;6517.837054073591;6941.965290173618;6972.1981972072845;7158.427325813923;7243.0946131733535;7058.611776352247;7069.187043489962;7235.493515453868;7301.525400848928;7314.939333796569;7298.671559779823;7318.670659820321;7321.963390183015;7335.285944200573;7339.082614673166;7049.929500704928;7054.929450705427;7059.435245180476;7065.187503436965;7072.398846098139;6952.339527744856;6960.930390696029;6978.162620485574;6984.79045628632;6996.125484314461;6974.337437943387;7301.890471642823;7317.414606831547;7325.816854578678;7335.4498412618195;4496.415465989531;4506.1663592235545;4528.728276303301;4532.4334458192725;4539.931901021421;7058.752943646948;7067.328603782633;7068.045813815069;7068.71725130989;7071;6824.556403833735;6829.1804983400325;6840.726370945106;6845.281245469353;6846.452283817183;6564.418381156041;6704.329567043256;6801.795946121624;6810.489213309121;6810.795676129724;6632.701528431328;6648.90026649591;6665.333466653296;6682.899756503559;6691.130153080068;6795.218549866829;6810.727570897307;6817.386435220854;6818.318168183142;6829.146356705412;5462.481064298885;5484.204790305464;6664.600123992576;6665.333466653296;6675.0654908313845 09:48:47 INFO - PROCESS | 2345 | |5;yui.html;660.0956689335613;667.7696194812788;682.532465261296;756.4062211164236;785.8310463250403;14786.852131478696;15155.772663410044;15167.165805880672;15169.77245341318;15173.468928587488;13865.4453821847;13914.860851391482;13936.581902542915;13937.024408291423;13975.881929445646;733.1056111543921;749.636426333228;752.3191511681937;753.7550296153772;754.3361841579402;893.3210759822516;912.5300470257822;913.2830727878617;916.4226537281503;916.6562539047857;947.5309721687775;948.8244674735162;953.8855337359527;955.5413401567251;959.8272310984055;938.1181689212119;949.1789601994294;949.7862980829327;951.5147274889787;953.8044700836311;574.287883025046;706.8020954132851;710.9040279562296;714.7033980897921;715.6421789105448;14606.707865842729;14793.630159245997;14810.62973425673;14812.851871481364;14815.629609259748;12597.244165350017;12642.873571264354;12727.490900363975;13013.804792928113;13079;9886.9505652472;10098.343607665478;10102.595896164145;10653.520591573399;10926;3571.2500374921296;3657.9757667852778;3666.028502446827;3745.3632882410056;3766.6798322142376;70823;77822.61088694577;78822;78972;80472.59763701144;9188.1730644241;9336.15974562279;9402.15380615748;9682;10213;19643.508912277306;19906.50233744139;19923.701144482846;19925.804451732944;19970.70043949342;7354.448416368794;7471.252874712594;7488.901179389822;7566.167721550625;7582.241775822484;4072.53165885921;4136.896577585584;4137.275976704064;4143.02638879863;4198.181354635878;3580.6598373154507;3615.5480564929385;3621.058524783575;3626.5104210931713;3632.3461776879885;643.0932385336653;643.1446176585131;654.6628486329544;663.3068443476578;670.661316035399;527.6992114494778;564.2589630748114;595.1967621296099;595.7855172138027;605.9636421814618;8171.918280817115;8311.044229913516;8342.916570834335;8382.958085209535;8482.830343393098;577.4889223037558;606.848287928018;614.4715544631613;615.0681717198448;618.4928358745875;3781.4138808484727;3784.1863999239745;3797.3924172132324;3826.4834247376803;3894.9805250973563;80883;89003.55498222467;89777;92553;93012;71384.64922586195;71603;72085.91871121938;74683.62658186675;75612.62193688996;67673;68070.65964670145;68636;70289.59420811555;70594;7608.353289970193;7666.769996900102;7864.72473463426;8058.879116813369;8193.09875913661;36030.279394412486;36214.81892590626;45303.32045019393;48910.51089489202;49545.50454495553;57811;64009.679951599945;67865.642687145;69067.65466172637;70079.29920700932;11078.113750899747;11152.776944460904;11280.717982050515;11346.205765596329;11511.079113671054;12069.637910862688;12084;12330.075244356889;12499.875001249871;12865.871341286467;11467.426628668367;11545.826812597987;11865.11011674145;11983.76032479363;12044.337561434206;11353.262037967506;11463.598774042868;11679.883201168219;11824.113191510844;11936.164468487123;8618.569071546272;11482;11524.250923689933;11530.711732206762;11619.09522619345;9336.39313444624;9532.332736708364;9605.711828645151;9653.662121825982;9759.9024009759;8520.531370774666;9101.271898247991;9661;9695.272854536079;9761.974992625956 09:48:47 INFO - PROCESS | 2345 | __end_tp_report 09:48:47 INFO - PROCESS | 2345 | __start_cc_report 09:48:47 INFO - PROCESS | 2345 | _x_x_mozilla_cycle_collect,1187 09:48:47 INFO - PROCESS | 2345 | __end_cc_report 09:48:47 INFO - PROCESS | 2345 | __startTimestamp1460047727878__endTimestamp 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | ------- Summary: start ------- 09:48:47 INFO - PROCESS | 2345 | Number of tests: 6 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#0] dojo.html Cycles:165 Average:5587.94 Median:5790.91 stddev:631.11 (10.9%) stddev-sans-first:608.31 09:48:47 INFO - PROCESS | 2345 | Values: 3357.6 3387.9 3431.1 3440.4 3449.9 5471.6 5545.5 5566.6 5576.4 5613.9 5553.9 5592.7 5647.9 5677.2 5717.3 4998.3 5009.9 5017.6 5026.4 5145.7 5138.4 5141.6 5142.9 5143.2 5209.1 4251.6 4999.7 5075.5 5095.5 5107.7 5037.9 5078.5 5079.4 5082.9 5088.3 4959.3 5053.5 5075.0 5086.3 5145.5 5438.1 5441.7 5442.2 5447.5 5477.0 5916.7 5945.1 5961.0 5970.0 5978.9 4880.7 6071.6 6077.0 6133.3 6181.9 5678.0 5682.2 5683.0 5699.9 5706.5 6119.4 6229.8 6315.7 6339.0 6343.8 5838.6 5863.4 5877.9 5885.4 5926.8 6071.2 6085.7 6090.6 6092.1 6120.7 5889.9 5988.3 5994.7 6002.0 6020.9 5993.9 6034.1 6047.0 6060.4 6084.5 4774.8 4788.4 4810.4 4810.9 4860.3 4313.7 4354.8 4359.8 4363.9 4366.3 5479.9 5510.5 5538.7 5570.9 5587.0 6175.3 6188.7 6195.6 6200.7 6203.8 5370.5 5463.6 5494.7 5497.5 5513.6 5238.4 5282.7 5296.9 5326.5 5327.5 6271.7 6291.6 6317.5 6322.4 6326.3 6031.8 6077.9 6166.4 6173.5 6175.9 6211.4 6293.4 6294.4 6299.2 6313.0 6012.2 6020.7 6036.7 6041.5 6049.3 6151.3 6168.3 6171.4 6173.0 6174.2 5798.2 5849.4 5885.3 5946.1 5966.3 5905.9 5906.7 5915.5 5915.9 5936.8 5238.1 5446.1 5750.1 5756.3 5790.9 4549.9 5322.3 5953.4 5968.6 5992.3 5793.8 5807.7 5810.0 5811.2 5813.7 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#1] ext.html Cycles:180 Average:63455.96 Median:39098.21 stddev:131561.10 (336.5%) stddev-sans-first:131845.14 09:48:47 INFO - PROCESS | 2345 | Values: 457.3 531.9 585.1 623.9 634.5 37299.4 37515.0 37517.1 37688.8 37776.1 34066.0 40112.2 40602.6 40693.4 40697.6 9471.0 9660.5 9688.0 9695.5 9700.0 9528.3 9672.5 9690.1 9692.8 9694.7 9652.6 9731.9 9756.2 9756.4 9827.1 9698.1 9799.3 9822.0 9839.2 9842.3 9511.7 9636.9 9651.5 9652.7 9657.0 32234.4 38881.0 39315.4 39345.2 39415.8 2858.3 2871.8 2876.4 2885.1 3152.6 27089.9 27132.6 27460.2 27524.9 28136.9 2746.1 2766.9 2776.1 2834.8 2876.8 495929.0 497033.0 497175.0 497885.0 498861.0 59986.1 60090.1 60321.7 60420.7 60482.9 55464.4 56136.4 63724.4 64414.0 64816.7 16356.0 16395.7 16493.8 16514.5 16558.3 12840.9 12900.5 12923.7 12936.2 12950.0 16391.6 19062.0 19369.4 19407.9 19457.0 6874.8 7734.1 7978.4 8005.7 8047.7 912.7 979.7 1061.5 1099.3 1108.1 27333.2 27567.3 28136.3 28171.9 28270.0 1063.7 1100.0 1103.6 1110.1 1175.2 14360.4 14457.8 14698.0 14724.0 15258.7 539827.0 545773.0 551575.0 552296.0 552963.0 56262.0 56286.4 56349.0 56675.7 56678.4 1352.8 1437.6 1451.3 1458.5 1477.3 29003.7 29172.6 29228.8 29795.9 29832.7 47515.0 48163.8 48236.8 48371.5 48714.8 412940.0 434487.0 434686.0 435359.0 435819.0 42965.6 43592.3 43635.0 44104.6 44399.0 44939.8 45075.0 45179.5 47070.6 47085.5 35767.8 36196.8 36278.9 36642.1 37247.8 35295.8 35542.1 35659.0 36147.5 36852.8 36408.5 36904.0 38052.6 38058.4 38107.0 32179.8 32954.2 33043.7 33053.5 33240.0 21198.5 21351.5 21464.4 21539.7 21710.1 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#2] jquery.html Cycles:180 Average:33892.96 Median:25105.19 stddev:98610.00 (392.8%) stddev-sans-first:98854.48 09:48:47 INFO - PROCESS | 2345 | Values: 358.9 363.3 364.1 368.5 372.8 19448.5 20034.9 20276.6 20736.6 20752.6 19393.8 19809.9 20576.2 20593.3 20687.0 5613.7 5827.0 5851.5 5851.5 5867.4 4123.8 4351.3 4991.4 6318.0 6376.1 6245.8 6297.7 6300.9 6305.7 6329.9 6124.2 6266.8 6287.7 6316.5 6319.0 3548.6 5233.5 5461.6 5465.9 5470.5 24794.5 25415.9 25679.6 25981.7 26011.7 2131.9 2238.6 2357.3 2395.6 2580.8 14483.3 14553.2 14670.3 14775.2 15176.8 1640.7 1690.8 1697.1 1745.9 1750.0 83477.0 99184.0 103250.0 103430.0 103768.5 10424.6 11100.8 11228.8 11237.0 11269.0 13815.9 13818.5 13950.8 13960.7 13970.6 8516.5 8547.5 8557.8 8625.8 8650.7 7382.4 8394.9 8464.3 8664.3 8669.7 3689.3 3809.7 4394.1 4444.8 4489.6 1452.6 1717.0 1719.8 1789.9 1795.3 836.7 901.6 917.3 995.5 999.0 19386.5 20014.2 20264.1 20674.0 20835.3 855.5 940.3 972.7 981.7 997.6 10744.6 11175.4 11217.7 11218.9 11222.0 535008.0 596893.0 602378.0 616756.0 616849.0 112220.0 120075.0 121368.8 123193.4 125728.0 1003.0 1019.6 1040.6 1041.0 1041.3 15300.0 17629.3 18024.9 18306.8 18539.8 39196.6 50550.7 50725.5 52053.0 52897.7 72931.0 74071.3 74299.0 75000.6 75539.2 7769.9 7792.3 7858.6 7894.3 8024.8 7869.0 9118.1 12350.9 12503.4 12591.9 10099.2 10621.3 10642.6 10844.6 11191.4 10697.8 10792.2 10951.9 11101.3 11312.1 10410.2 11294.9 11369.4 11562.4 11570.4 7793.9 7927.0 8060.2 8226.2 8272.3 10574.6 10829.5 10856.7 11128.2 11282.4 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#3] mootools.html Cycles:180 Average:5998.84 Median:5434.42 stddev:3192.68 (58.7%) stddev-sans-first:3183.25 09:48:47 INFO - PROCESS | 2345 | Values: 1440.8 1566.2 1600.3 1876.8 2021.4 7977.0 8718.4 8900.0 8935.9 8943.6 8617.7 8636.6 8702.3 8709.8 8719.6 2179.7 2404.6 2426.0 2468.2 2468.5 2206.9 2403.1 2479.6 2500.7 2504.0 2387.2 2414.9 2430.8 2443.8 2445.8 2400.9 2433.3 2447.5 2455.7 2468.9 2410.4 2449.3 2467.2 2468.6 2474.7 8867.1 8906.2 8909.3 8910.3 8923.2 5416.1 5486.6 5552.7 5554.8 5564.4 3475.2 3533.5 3606.3 3614.8 3627.1 1030.7 1044.8 1051.4 1052.4 1061.4 11809.0 11819.4 11830.3 11833.0 11867.1 4833.5 4855.5 4867.2 4934.0 4939.3 10033.5 10043.0 10052.6 10059.1 10079.8 5452.7 5457.6 5493.3 5502.5 5505.0 4005.9 4231.0 4434.9 4595.5 4966.2 3846.9 3905.2 3917.7 3940.6 3954.3 1308.9 1312.5 1325.6 1326.1 1330.6 1336.3 1365.8 1372.2 1379.0 1392.8 8289.5 8322.5 8350.5 8363.7 8399.4 503.2 520.4 525.3 532.6 533.9 6161.6 6234.4 6277.7 6292.7 6306.6 10418.8 10767.8 10778.7 10783.8 10819.2 10394.7 10518.4 10584.9 10629.5 10662.6 8153.6 8248.6 8264.8 8294.2 8376.2 7630.7 7859.5 7867.9 7870.5 7893.9 9258.5 9622.0 9682.8 9684.5 9706.0 9700.9 9826.9 9991.1 9996.8 9996.9 6644.8 6760.4 6969.8 7424.1 7474.3 6587.9 7695.4 7704.3 8194.3 8212.4 7183.1 7241.9 7245.8 7322.5 7368.3 7135.5 7147.6 7227.6 7267.2 7288.6 7559.5 7561.8 7601.9 7646.2 7652.7 6911.6 6915.9 6963.6 6964.7 6975.9 6610.3 6646.7 6669.3 6686.4 6718.5 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#4] prototype.html Cycles:180 Average:5532.48 Median:6231.38 stddev:1764.25 (28.3%) stddev-sans-first:1725.31 09:48:47 INFO - PROCESS | 2345 | Values: 321.8 324.2 330.1 332.1 335.5 6330.7 6387.1 6411.2 6422.3 6439.5 6259.2 6277.7 6321.9 6332.3 6341.0 4503.8 4545.4 4654.9 4716.0 4725.2 4706.4 4716.3 4719.9 4723.6 4725.1 3910.8 4643.3 4673.6 4677.8 4680.1 4452.9 4510.0 4627.6 4641.7 4662.6 3351.9 3989.8 4015.0 4025.9 4040.9 6093.5 6109.1 6116.0 6124.4 6138.8 5553.6 5609.9 5616.8 5621.2 5646.2 6743.1 6765.8 6772.3 6776.4 6793.8 4244.7 4389.3 4402.7 4403.1 4411.6 6123.0 6715.6 6881.2 7050.1 7071.9 4478.3 4484.6 4492.9 4495.9 4511.0 4542.0 4557.5 4559.7 4561.3 4572.8 5342.0 5388.1 5392.5 5395.1 5396.9 6483.4 6716.6 6731.9 6741.4 6742.6 1556.7 1568.8 1580.4 1584.5 1587.0 951.9 972.6 1001.6 1009.0 1031.4 6015.3 6111.8 6117.2 6142.8 6190.0 7070.3 7071.8 7072.4 7084.7 7089.5 5109.0 6173.2 6196.0 6197.8 6203.5 3666.6 3719.8 3727.4 3729.3 3731.6 6517.8 6942.0 6972.2 7158.4 7243.1 7058.6 7069.2 7235.5 7301.5 7314.9 7298.7 7318.7 7322.0 7335.3 7339.1 7049.9 7054.9 7059.4 7065.2 7072.4 6952.3 6960.9 6978.2 6984.8 6996.1 6974.3 7301.9 7317.4 7325.8 7335.4 4496.4 4506.2 4528.7 4532.4 4539.9 7058.8 7067.3 7068.0 7068.7 7071.0 6824.6 6829.2 6840.7 6845.3 6846.5 6564.4 6704.3 6801.8 6810.5 6810.8 6632.7 6648.9 6665.3 6682.9 6691.1 6795.2 6810.7 6817.4 6818.3 6829.1 5462.5 5484.2 6664.6 6665.3 6675.1 09:48:47 INFO - PROCESS | 2345 | 09:48:47 INFO - PROCESS | 2345 | [#5] yui.html Cycles:180 Average:17683.41 Median:32314.09 stddev:24537.35 (75.9%) stddev-sans-first:24572.89 09:48:47 INFO - PROCESS | 2345 | Values: 660.1 667.8 682.5 756.4 785.8 14786.9 15155.8 15167.2 15169.8 15173.5 13865.4 13914.9 13936.6 13937.0 13975.9 733.1 749.6 752.3 753.8 754.3 893.3 912.5 913.3 916.4 916.7 947.5 948.8 953.9 955.5 959.8 938.1 949.2 949.8 951.5 953.8 574.3 706.8 710.9 714.7 715.6 14606.7 14793.6 14810.6 14812.9 14815.6 12597.2 12642.9 12727.5 13013.8 13079.0 9887.0 10098.3 10102.6 10653.5 10926.0 3571.3 3658.0 3666.0 3745.4 3766.7 70823.0 77822.6 78822.0 78972.0 80472.6 9188.2 9336.2 9402.2 9682.0 10213.0 19643.5 19906.5 19923.7 19925.8 19970.7 7354.4 7471.3 7488.9 7566.2 7582.2 4072.5 4136.9 4137.3 4143.0 4198.2 3580.7 3615.5 3621.1 3626.5 3632.3 643.1 643.1 654.7 663.3 670.7 527.7 564.3 595.2 595.8 606.0 8171.9 8311.0 8342.9 8383.0 8482.8 577.5 606.8 614.5 615.1 618.5 3781.4 3784.2 3797.4 3826.5 3895.0 80883.0 89003.6 89777.0 92553.0 93012.0 71384.6 71603.0 72085.9 74683.6 75612.6 67673.0 68070.7 68636.0 70289.6 70594.0 7608.4 7666.8 7864.7 8058.9 8193.1 36030.3 36214.8 45303.3 48910.5 49545.5 57811.0 64009.7 67865.6 69067.7 70079.3 11078.1 11152.8 11280.7 11346.2 11511.1 12069.6 12084.0 12330.1 12499.9 12865.9 11467.4 11545.8 11865.1 11983.8 12044.3 11353.3 11463.6 11679.9 11824.1 11936.2 8618.6 11482.0 11524.3 11530.7 11619.1 9336.4 9532.3 9605.7 9653.7 9759.9 8520.5 9101.3 9661.0 9695.3 9762.0 09:48:47 INFO - PROCESS | 2345 | -------- Summary: end -------- 09:48:47 INFO - PROCESS | 2345 | 09:48:48 INFO - TEST-INFO | 2345: exit 0 09:48:48 INFO - TEST-OK | dromaeo_css | took 1112570ms 09:48:48 INFO - TEST-START | kraken 09:48:48 INFO - operating with platform_type : mac_ 09:48:48 INFO - Initialising browser for kraken test... 09:48:48 INFO - TEST-INFO | started process 2348 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpvD9zbX/profile http://localhost:57266/getInfo.html) 09:48:50 INFO - PROCESS | 2348 | __metrics Screen width/height:1600/1200 09:48:50 INFO - PROCESS | 2348 | colorDepth:24 09:48:50 INFO - PROCESS | 2348 | Browser inner width/height: 1024/689 09:48:50 INFO - PROCESS | 2348 | __metrics 09:48:50 INFO - PROCESS | 2348 | 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" 09:48:50 ERROR - PROCESS | 2348 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:48:51 INFO - TEST-INFO | 2348: exit 0 09:48:51 INFO - Browser initialized. 09:48:51 INFO - Running cycle 1/1 for kraken test... 09:48:51 INFO - TEST-INFO | started process 2350 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpvD9zbX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:03 INFO - PROCESS | 2350 | RSS: Main: 236544000 09:49:03 INFO - PROCESS | 2350 | 09:49:05 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57266/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:06 INFO - PROCESS | 2350 | RSS: Main: 282505216 09:49:06 INFO - PROCESS | 2350 | 09:49:08 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57266/tests/kraken/driver.html?testName=audio-dft) 09:49:09 INFO - PROCESS | 2350 | RSS: Main: 293007360 09:49:09 INFO - PROCESS | 2350 | 09:49:12 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57266/tests/kraken/driver.html?testName=audio-fft) 09:49:13 INFO - PROCESS | 2350 | RSS: Main: 280489984 09:49:13 INFO - PROCESS | 2350 | 09:49:15 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57266/tests/kraken/driver.html?testName=audio-oscillator) 09:49:15 INFO - PROCESS | 2350 | RSS: Main: 282144768 09:49:15 INFO - PROCESS | 2350 | 09:49:17 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:18 INFO - PROCESS | 2350 | RSS: Main: 284086272 09:49:18 INFO - PROCESS | 2350 | 09:49:21 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57266/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:21 INFO - PROCESS | 2350 | RSS: Main: 283357184 09:49:21 INFO - PROCESS | 2350 | 09:49:24 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57266/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:25 INFO - PROCESS | 2350 | RSS: Main: 283496448 09:49:25 INFO - PROCESS | 2350 | 09:49:28 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57266/tests/kraken/driver.html?testName=json-parse-financial) 09:49:28 INFO - PROCESS | 2350 | RSS: Main: 283803648 09:49:28 INFO - PROCESS | 2350 | 09:49:30 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:31 INFO - PROCESS | 2350 | RSS: Main: 316227584 09:49:31 INFO - PROCESS | 2350 | 09:49:32 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:33 INFO - PROCESS | 2350 | RSS: Main: 316358656 09:49:33 INFO - PROCESS | 2350 | 09:49:35 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:36 INFO - PROCESS | 2350 | RSS: Main: 323100672 09:49:36 INFO - PROCESS | 2350 | 09:49:38 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:39 INFO - PROCESS | 2350 | RSS: Main: 324243456 09:49:39 INFO - PROCESS | 2350 | 09:49:42 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:42 INFO - PROCESS | 2350 | RSS: Main: 324419584 09:49:42 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:57266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57266/tests/kraken/driver.html?testName=ai-astar) 09:49:44 INFO - PROCESS | 2350 | RSS: Main: 387297280 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | __start_tp_report 09:49:44 INFO - PROCESS | 2350 | _x_x_mozilla_page_load 09:49:44 INFO - PROCESS | 2350 | _x_x_mozilla_page_load_details 09:49:44 INFO - PROCESS | 2350 | |i|pagename|runs| 09:49:44 INFO - PROCESS | 2350 | |0;ai-astar;112;108;110;112;113;112;116;112;113;112 09:49:44 INFO - PROCESS | 2350 | |1;audio-beat-detection;159;127;174;188;114;142;143;117;166;120 09:49:44 INFO - PROCESS | 2350 | |2;audio-dft;188;186;186;187;188;186;183;186;183;184 09:49:44 INFO - PROCESS | 2350 | |3;audio-fft;82;77;84;81;56;57;56;81;70;56 09:49:44 INFO - PROCESS | 2350 | |4;audio-oscillator;95;100;131;78;78;79;117;79;79;118 09:49:44 INFO - PROCESS | 2350 | |5;imaging-gaussian-blur;91;87;89;86;88;85;88;86;88;90 09:49:44 INFO - PROCESS | 2350 | |6;imaging-darkroom;89;147;93;94;93;94;93;93;96;93 09:49:44 INFO - PROCESS | 2350 | |7;imaging-desaturate;88;98;142;135;86;87;87;85;85;85 09:49:44 INFO - PROCESS | 2350 | |8;json-parse-financial;54;54;54;52;54;53;53;64;54;52 09:49:44 INFO - PROCESS | 2350 | |9;json-stringify-tinderbox;63;73;73;72;74;67;66;69;70;69 09:49:44 INFO - PROCESS | 2350 | |10;stanford-crypto-aes;81;83;84;82;82;86;82;84;83;106 09:49:44 INFO - PROCESS | 2350 | |11;stanford-crypto-ccm;134;124;122;128;135;130;128;131;122;132 09:49:44 INFO - PROCESS | 2350 | |12;stanford-crypto-pbkdf2;142;130;151;175;174;130;139;141;137;140 09:49:44 INFO - PROCESS | 2350 | |13;stanford-crypto-sha256-iterative;69;68;66;67;67;66;68;66;66;67 09:49:44 INFO - PROCESS | 2350 | __end_tp_report 09:49:44 INFO - PROCESS | 2350 | __start_cc_report 09:49:44 INFO - PROCESS | 2350 | _x_x_mozilla_cycle_collect,1269 09:49:44 INFO - PROCESS | 2350 | __end_cc_report 09:49:44 INFO - PROCESS | 2350 | __startTimestamp1460047784580__endTimestamp 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | ------- Summary: start ------- 09:49:44 INFO - PROCESS | 2350 | Number of tests: 14 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:2.05 (1.8%) stddev-sans-first:2.18 09:49:44 INFO - PROCESS | 2350 | Values: 112.0 108.0 110.0 112.0 113.0 112.0 116.0 112.0 113.0 112.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:151.00 stddev:25.89 (17.1%) stddev-sans-first:26.96 09:49:44 INFO - PROCESS | 2350 | Values: 159.0 127.0 174.0 188.0 114.0 142.0 143.0 117.0 166.0 120.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:1.83 (1.0%) stddev-sans-first:1.74 09:49:44 INFO - PROCESS | 2350 | Values: 188.0 186.0 186.0 187.0 188.0 186.0 183.0 186.0 183.0 184.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#3] audio-fft Cycles:10 Average:70.00 Median:79.00 stddev:12.42 (15.7%) stddev-sans-first:12.39 09:49:44 INFO - PROCESS | 2350 | Values: 82.0 77.0 84.0 81.0 56.0 57.0 56.0 81.0 70.0 56.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:78.50 stddev:20.22 (25.8%) stddev-sans-first:21.44 09:49:44 INFO - PROCESS | 2350 | Values: 95.0 100.0 131.0 78.0 78.0 79.0 117.0 79.0 79.0 118.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.00 stddev:1.87 (2.1%) stddev-sans-first:1.59 09:49:44 INFO - PROCESS | 2350 | Values: 91.0 87.0 89.0 86.0 88.0 85.0 88.0 86.0 88.0 90.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:93.00 stddev:17.13 (18.4%) stddev-sans-first:17.82 09:49:44 INFO - PROCESS | 2350 | Values: 89.0 147.0 93.0 94.0 93.0 94.0 93.0 93.0 96.0 93.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#7] imaging-desaturate Cycles:10 Average:97.80 Median:86.50 stddev:21.85 (25.3%) stddev-sans-first:22.89 09:49:44 INFO - PROCESS | 2350 | Values: 88.0 98.0 142.0 135.0 86.0 87.0 87.0 85.0 85.0 85.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:3.47 (6.4%) stddev-sans-first:3.68 09:49:44 INFO - PROCESS | 2350 | Values: 54.0 54.0 54.0 52.0 54.0 53.0 53.0 64.0 54.0 52.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:71.00 stddev:3.53 (5.0%) stddev-sans-first:2.83 09:49:44 INFO - PROCESS | 2350 | Values: 63.0 73.0 73.0 72.0 74.0 67.0 66.0 69.0 70.0 69.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:83.00 stddev:7.41 (8.9%) stddev-sans-first:7.69 09:49:44 INFO - PROCESS | 2350 | Values: 81.0 83.0 84.0 82.0 82.0 86.0 82.0 84.0 83.0 106.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:130.50 stddev:4.70 (3.6%) stddev-sans-first:4.56 09:49:44 INFO - PROCESS | 2350 | Values: 134.0 124.0 122.0 128.0 135.0 130.0 128.0 131.0 122.0 132.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.90 Median:141.50 stddev:16.22 (11.5%) stddev-sans-first:17.15 09:49:44 INFO - PROCESS | 2350 | Values: 142.0 130.0 151.0 175.0 174.0 130.0 139.0 141.0 137.0 140.0 09:49:44 INFO - PROCESS | 2350 | 09:49:44 INFO - PROCESS | 2350 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.05 (1.6%) stddev-sans-first:0.83 09:49:44 INFO - PROCESS | 2350 | Values: 69.0 68.0 66.0 67.0 67.0 66.0 68.0 66.0 66.0 67.0 09:49:44 INFO - PROCESS | 2350 | -------- Summary: end -------- 09:49:44 INFO - PROCESS | 2350 | 09:49:45 INFO - TEST-INFO | 2350: exit 0 09:49:45 INFO - TEST-OK | kraken | took 56994ms 09:49:45 INFO - SUITE-END | took 1169s 09:49:45 INFO - Completed test suite (00:19:29) 09:49:45 INFO - javascript benchmark 09:49:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3357.6474470180633, 3387.898363049108, 3431.125063108907, 3440.415129427997, 3449.9482507762386, 5471.5896307776975, 5545.528630066444, 5566.610337276387, 5576.358718747345, 5613.859653508659, 5553.888922221553, 5592.692401917893, 5647.887042259153, 5677.205191273215, 5717.285339332583, 4998.2752500887345, 5009.949900501003, 5017.64876458648, 5026.4219614744225, 5145.691258524501, 5138.409082955471, 5141.640085194038, 5142.922856157161, 5143.177091665334, 5209.1404918188455, 4251.553586873365, 4999.650024498286, 5075.467075957008, 5095.464976177485, 5107.719075450848, 5037.874053148664, 5078.466760990117, 5079.415867175248, 5082.923756143661, 5088.262201980692, 4959.330490383824, 5053.545180933696, 5074.985002999415, 5086.338775959139, 5145.459726728677, 5438.102713052381, 5441.727913604304, 5442.183672449125, 5447.4824891635235, 5476.972615136939, 5916.704164791743, 5945.108233764927, 5960.97019514904, 5970.044792833126, 5978.940210597882, 4880.707157570557, 6071.633309239542, 6077.0, 6133.264008318985, 6181.876362472771, 5678.0, 5682.232898558725, 5682.971585142089, 5699.943000569982, 5706.4864162225595, 6119.41115851624, 6229.844253893689, 6315.715792789336, 6339.0, 6343.841403964845, 5838.620489668158, 5863.355030946593, 5877.853053673607, 5885.352611212763, 5926.762929482772, 6071.180390647294, 6085.726142323606, 6090.634561926298, 6092.147099406086, 6120.663363515049, 5889.911651325234, 5988.251468566429, 5994.730237139339, 6002.039673652194, 6020.946334391464, 5993.940060599338, 6034.125051867542, 6046.969765151234, 6060.393960603992, 6084.452399284086, 4774.832880849153, 4788.353572267769, 4810.39870016248, 4810.8797280068275, 4860.295257187688, 4313.676474264443, 4354.804033818423, 4359.760213188241, 4363.912721745547, 4366.275935060035, 5479.890402191933, 5510.5316048135555, 5538.667679939216, 5570.944290557042, 5587.022271102541, 6175.320714721377, 6188.690565471708, 6195.628262304276, 6200.720967556381, 6203.751849926042, 5370.489803468664, 5463.644863083887, 5494.697791621419, 5497.532709719719, 5513.641613295123, 5238.423773384925, 5282.683039017671, 5296.92054619181, 5326.493983071602, 5327.520523152934, 6271.686415679289, 6291.622502649856, 6317.494600432046, 6322.430981211713, 6326.335734747785, 6031.788887388921, 6077.939220607737, 6166.414190651881, 6173.475254603409, 6175.876482470415, 6211.409916057967, 6293.402126797947, 6294.370562943852, 6299.181106456132, 6312.968435157978, 6012.218411606463, 6020.698965051817, 6036.698165091816, 6041.456268935817, 6049.334573196857, 6151.323354431099, 6168.290646575701, 6171.444569988723, 6173.0, 6174.166487524307, 5798.246227990334, 5849.356570777296, 5885.323187833454, 5946.108083787466, 5966.313873904556, 5905.852353691193, 5906.70466476683, 5915.467607915309, 5915.940840591711, 5936.821895343147, 5238.057149713089, 5446.128619420873, 5750.0512415450985, 5756.3380211276235, 5790.913136303043, 4549.908022074745, 5322.308099946982, 5953.404659534012, 5968.641881487125, 5992.250968628921, 5793.79721709755, 5807.709614519173, 5809.970950145223, 5811.186433899173, 5813.738381772915], "unit": "score", "name": "dojo.html", "value": 5549.402823010313}, {"lowerIsBetter": false, "replicates": [457.32316172065345, 531.8618157143711, 585.0872638683654, 623.8533944523037, 634.5209366927969, 37299.4405083924, 37515.0, 37517.06207344818, 37688.811555942215, 37776.055598610015, 34066.0, 40112.19775604494, 40602.59397406025, 40693.389599156035, 40697.59302406975, 9471.0, 9660.468674222915, 9687.951560242189, 9695.46674932882, 9700.0, 9528.285378596633, 9672.516374181298, 9690.127888490035, 9692.806143877118, 9694.66068687596, 9652.565634546427, 9731.854022189673, 9756.219502439788, 9756.365836220622, 9827.11555959967, 9698.078682525183, 9799.265055120859, 9822.017798220193, 9839.163671087967, 9842.261830362715, 9511.66709165176, 9636.855447168298, 9651.469169195692, 9652.710418687451, 9656.95171524138, 32234.35531289361, 38881.0, 39315.41026884599, 39345.21309573821, 39415.8029209855, 2858.2997165694537, 2871.813332133405, 2876.3815779607276, 2885.1488810800656, 3152.5586417901513, 27089.916403343843, 27132.59301110485, 27460.17619471419, 27524.862375688193, 28136.859315703292, 2746.093789049609, 2766.9308267293277, 2776.056140912079, 2834.7873909456666, 2876.7842411819197, 495929.0, 497033.0, 497175.0, 497885.0, 498861.0, 59986.10020849691, 60090.09864852031, 60321.69839150776, 60420.69789651024, 60482.8830990922, 55464.44535554673, 56136.43863561394, 63724.36275637277, 64414.0, 64816.67591662106, 16356.0, 16395.67208655844, 16493.752593711102, 16514.504564863073, 16558.254878530493, 12840.871591284154, 12900.483980640856, 12923.741525169444, 12936.223826570436, 12949.964002879935, 16391.590210244838, 19062.0, 19369.4189174325, 19407.90296048539, 19457.0, 6874.828129296708, 7734.1492435832015, 7978.361731061501, 8005.719799806979, 8047.746630943345, 912.6623149434752, 979.6962941488019, 1061.5488417422564, 1099.2964502718266, 1108.1135091926426, 27333.180004599893, 27567.31081722973, 28136.29659258494, 28171.873125075177, 28270.0, 1063.7340664833791, 1099.9780004399868, 1103.6027030269097, 1110.1008183371496, 1175.1715040896147, 14360.425582976564, 14457.78313325301, 14698.044627099205, 14724.0, 15258.694826103638, 539827.0, 545773.0, 551575.0, 552296.0, 552963.0, 56262.0, 56286.43713562812, 56349.0, 56675.71662141663, 56678.43321566814, 1352.8038434426953, 1437.590286768266, 1451.3178805961181, 1458.4603696632103, 1477.2687519677613, 29003.709962900102, 29172.562411564268, 29228.83084676589, 29795.851020744758, 29832.701672982992, 47515.049699006515, 48163.75918120527, 48236.79408014687, 48371.5162848367, 48714.83353582006, 412940.0, 434487.0, 434686.0, 435359.0, 435819.0, 42965.570344297405, 43592.34611480767, 43635.0, 44104.558954410044, 44399.0, 44939.775301123285, 45075.0, 45179.54820451885, 47070.58788236359, 47085.52914470811, 35767.821160894026, 36196.81901590475, 36278.91163265106, 36642.08394790151, 37247.813760932106, 35295.82352088223, 35542.111447214025, 35659.0, 36147.457788133725, 36852.81573592115, 36408.45387319139, 36904.0, 38052.61947380491, 38058.429123562615, 38107.0, 32179.839100804347, 32954.17614559655, 33043.66956330406, 33053.50419743754, 33240.0, 21198.453289236317, 21351.46621334479, 21464.356069317946, 21539.676904846754, 21710.131594736034], "unit": "score", "name": "ext.html", "value": 19552.64850114557}, {"lowerIsBetter": false, "replicates": [358.9264200838828, 363.3441637843692, 364.10067134178576, 368.5338047370079, 372.8173195134385, 19448.51378715531, 20034.89982550087, 20276.59446811063, 20736.585268294624, 20752.584948301024, 19393.806061939376, 19809.910454924975, 20576.176952921865, 20593.27923522677, 20686.96565171743, 5613.66318020918, 5827.0094084005605, 5851.473367396933, 5851.531877449794, 5867.41325867412, 4123.814428350732, 4351.260285751414, 4991.425986011601, 6317.968410157942, 6376.139221205148, 6245.843853903643, 6297.716602752864, 6300.9369906301035, 6305.653189074599, 6329.873402531946, 6124.203853499039, 6266.811995640138, 6287.7170527325925, 6316.463100636429, 6319.0, 3548.6273941236313, 5233.502817232356, 5461.617686761928, 5465.945340546584, 5470.452954704538, 24794.5041099177, 25415.872920635462, 25679.61480577793, 25981.74018259812, 26011.73988260131, 2131.936041918729, 2238.6194346961056, 2357.3399448154546, 2395.568797616428, 2580.8064395170436, 14483.275836208253, 14553.199574023425, 14670.266486675624, 14775.187364694937, 15176.772348414783, 1640.6956469606573, 1690.805557360907, 1697.13446142467, 1745.903975281359, 1750.0199888062725, 83477.0, 99184.0, 103249.96750032554, 103430.0, 103768.48115759373, 10424.5830166794, 11100.833487497694, 11228.831567526493, 11236.988671019648, 11269.013711366495, 13815.861841381658, 13818.516351927634, 13950.790738138936, 13960.650983725489, 13970.580882573538, 8516.489010659381, 8547.487150770974, 8557.828843423096, 8625.827483450421, 8650.697225597074, 7382.379255364376, 8394.916050839536, 8464.280536154496, 8664.306855451548, 8669.653213871501, 3689.2805902849223, 3809.7333186676665, 4394.055278115221, 4444.799984000728, 4489.6408287336935, 1452.6005348529238, 1716.957076073108, 1719.8452139307271, 1789.9284028638708, 1795.317779243879, 836.7114643449021, 901.5987885390938, 917.3349321741684, 995.5370752599931, 998.9950050249847, 19386.515337116685, 20014.199432022557, 20264.07627986539, 20673.966301684857, 20835.270765523135, 855.4952577978897, 940.2571968145226, 972.6936015155203, 981.7251169672503, 997.586001809241, 10744.623938162284, 11175.44122793857, 11217.663470095747, 11218.887811121947, 11222.0, 535008.0, 596893.0, 602378.0, 616756.0, 616849.0, 112220.0, 120075.0, 121368.78631213751, 123193.38403308105, 125728.0, 1002.964896228643, 1019.5819713917407, 1040.5889673578895, 1041.0266400915018, 1041.3127335958382, 15300.0, 17629.294828206726, 18024.90987545054, 18306.81693183051, 18539.81460185435, 39196.60803391929, 50550.747246263534, 50725.49274507208, 52053.0, 52897.71022897679, 72931.0, 74071.25928740644, 74299.0, 75000.62499687467, 75539.24460755542, 7769.8834517481155, 7792.337651299703, 7858.6070696466095, 7894.328982036591, 8024.799380015546, 7869.0, 9118.133777291147, 12350.938245308716, 12503.4373453193, 12591.87408125907, 10099.192064634959, 10621.309614875008, 10642.574297028032, 10844.583956053817, 11191.440427978405, 10697.839532407175, 10792.244542881923, 10951.945240273748, 11101.278416902875, 11312.095032397225, 10410.167186625204, 11294.94352528232, 11369.431528423711, 11562.421878906189, 11570.363630000431, 7793.883091753742, 7926.960365198137, 8060.232061190727, 8226.177382261725, 8272.33821294307, 10574.629887953884, 10829.512671929624, 10856.674299771019, 11128.221024528206, 11282.379469128948], "unit": "score", "name": "jquery.html", "value": 9372.597236751062}, {"lowerIsBetter": false, "replicates": [1440.827100747909, 1566.2247187642115, 1600.3358606178433, 1876.8310852023315, 2021.4036859126556, 7977.0, 8718.433301835385, 8900.02099769025, 8935.927688677351, 8943.552822358888, 8617.698380556667, 8636.56817159141, 8702.347323950713, 8709.825803483927, 8719.607617657204, 2179.716636837209, 2404.615261558151, 2425.9514809703796, 2468.2101727447225, 2468.4939587384633, 2206.933791986235, 2403.0507949359994, 2479.566075936713, 2500.6874140732407, 2503.9749602503925, 2387.2002879035545, 2414.8792560372, 2430.7933825624755, 2443.7556244375596, 2445.8165637577254, 2400.8559486430872, 2433.3065076453213, 2447.510497900409, 2455.668484754562, 2468.8642124683133, 2410.3974006498374, 2449.3019489445423, 2467.235157101304, 2468.60502319628, 2474.74015228402, 8867.068957759471, 8906.242969347613, 8909.331800114953, 8910.287177025823, 8923.241524470428, 5416.133418652997, 5486.561075113981, 5552.666839989613, 5554.833354999357, 5564.387917329091, 3475.1659601695665, 3533.5406397168463, 3606.278744251134, 3614.8011859347725, 3627.093226693327, 1030.706248719119, 1044.8171569975225, 1051.3796859852723, 1052.4001319248032, 1061.4268295120626, 11809.0, 11819.409029548488, 11830.290182589073, 11832.994195493393, 11867.05063594919, 4833.468318484964, 4855.490173531799, 4867.196912509467, 4933.975330123327, 4939.333190019373, 10033.498325083716, 10043.045910638477, 10052.59789608422, 10059.144972677259, 10079.848802267972, 5452.700101494376, 5457.5633949283965, 5493.285872836585, 5502.532284755761, 5505.03661859173, 4005.91988160241, 4231.005713657288, 4434.911301773946, 4595.471520775087, 4966.15575352191, 3846.8845934622004, 3905.179912218466, 3917.7061720371084, 3940.625640564142, 3954.268460334847, 1308.941097650608, 1312.534050412109, 1325.5559387605067, 1326.1380102933172, 1330.5742162508097, 1336.3385124363308, 1365.789615492014, 1372.1629805818443, 1379.0277854112826, 1392.7980442835733, 8289.461185023074, 8322.54226017575, 8350.457220280663, 8363.749087527383, 8399.41204115706, 503.1994769895232, 520.4379270387972, 525.3433208489388, 532.6111938284996, 533.8745394832212, 6161.568690191643, 6234.376562343729, 6277.686115694197, 6292.716827742763, 6306.590071645328, 10418.791624167474, 10767.83848242277, 10778.730531736612, 10783.784324313627, 10819.242653014371, 10394.740131496621, 10518.368897866152, 10584.947075264574, 10629.5216715248, 10662.573497060186, 8153.633086511121, 8248.628811703487, 8264.793380165544, 8294.212049855254, 8376.246137847502, 7630.7329243476215, 7859.4891332063235, 7867.8819817703925, 7870.527768333918, 7893.881591776013, 9258.53707314645, 9621.951890240503, 9682.806343873224, 9684.51577421112, 9706.0, 9700.854487182556, 9826.9017309826, 9991.100800927952, 9996.800063998824, 9996.850047249152, 6644.800655980328, 6760.391564759196, 6969.756058537927, 7424.071991001125, 7474.2525747424825, 6587.934120658732, 7695.384369250334, 7704.345130663956, 8194.34445244391, 8212.384071194565, 7183.138023437221, 7241.891371629536, 7245.818854528679, 7322.450816188913, 7368.300011498899, 7135.464840136907, 7147.606881621353, 7227.566346019255, 7267.200607933018, 7288.635568221462, 7559.470837041355, 7561.848763024599, 7601.885971710318, 7646.235376462309, 7652.73215437457, 6911.5853048815225, 6915.861682766216, 6963.61700106499, 6964.721411143498, 6975.9302406975285, 6610.305917878553, 6646.700898459484, 6669.333066693486, 6686.398224159849, 6718.496112791658], "unit": "score", "name": "mootools.html", "value": 4816.308808817105}, {"lowerIsBetter": false, "replicates": [321.79887570268585, 324.19761091299034, 330.06755914541424, 332.05364710574855, 335.4599095456315, 6330.746770129195, 6387.137736405584, 6411.230652321717, 6422.325655806138, 6439.452646525046, 6259.217597800275, 6277.717502712378, 6321.873562528758, 6332.303446620868, 6340.968295158529, 4503.752293623849, 4545.409096817409, 4654.929366245766, 4715.952840471594, 4725.22033864412, 4706.388169537972, 4716.268978308368, 4719.882002949919, 4723.62211023119, 4725.1258517174265, 3910.76535407875, 4643.257078867382, 4673.6027437667835, 4677.766111694419, 4680.134175177586, 4452.931296488836, 4509.98525331801, 4627.583517483409, 4641.744704041276, 4662.626989840838, 3351.8994430167136, 3989.7606143631474, 4014.979925100356, 4025.9130034890413, 4040.9493531681974, 6093.512518998469, 6109.083637454373, 6115.9694201529155, 6124.387561243884, 6138.754449822047, 5553.611247212697, 5609.859753506154, 5616.775328986836, 5621.18492818539, 5646.153077038436, 6743.089682892795, 6765.830854228634, 6772.255051944331, 6776.42400395967, 6793.762218322385, 4244.681648876316, 4389.31965545341, 4402.669799765031, 4403.097365040191, 4411.602955733999, 6122.979416792447, 6715.630640314829, 6881.243063263037, 7050.083489146429, 7071.8939215911805, 4478.261086920622, 4484.596386325246, 4492.887677808081, 4495.865124046219, 4511.030128522383, 4541.9545804542195, 4557.544245575482, 4559.6808223424705, 4561.29299958507, 4572.7713614319155, 5342.011727830364, 5388.110961691277, 5392.460753924576, 5395.082835917903, 5396.865078372994, 6483.3516648335735, 6716.630585317756, 6731.932680673229, 6741.426978706865, 6742.561733487308, 1556.6730986492737, 1568.827428982811, 1580.3678528588657, 1584.4612831637298, 1587.0239802521367, 951.8619800128943, 972.6206779356057, 1001.6043662753171, 1009.0363702663939, 1031.4120951057976, 6015.30823955242, 6111.755529778759, 6117.204763380732, 6142.78500252489, 6189.96905015481, 7070.328318809705, 7071.82320441993, 7072.357735766084, 7084.681189346492, 7089.503734738622, 5109.0, 6173.228346456693, 6195.96902015496, 6197.783077592263, 6203.534734894901, 3666.5966743658178, 3719.75821571597, 3727.4408838674417, 3729.328720830222, 3731.589525152231, 6517.837054073591, 6941.965290173618, 6972.1981972072845, 7158.427325813923, 7243.0946131733535, 7058.611776352247, 7069.187043489962, 7235.493515453868, 7301.525400848928, 7314.939333796569, 7298.671559779823, 7318.670659820321, 7321.963390183015, 7335.285944200573, 7339.082614673166, 7049.929500704928, 7054.929450705427, 7059.435245180476, 7065.187503436965, 7072.398846098139, 6952.339527744856, 6960.930390696029, 6978.162620485574, 6984.79045628632, 6996.125484314461, 6974.337437943387, 7301.890471642823, 7317.414606831547, 7325.816854578678, 7335.4498412618195, 4496.415465989531, 4506.1663592235545, 4528.728276303301, 4532.4334458192725, 4539.931901021421, 7058.752943646948, 7067.328603782633, 7068.045813815069, 7068.71725130989, 7071.0, 6824.556403833735, 6829.1804983400325, 6840.726370945106, 6845.281245469353, 6846.452283817183, 6564.418381156041, 6704.329567043256, 6801.795946121624, 6810.489213309121, 6810.795676129724, 6632.701528431328, 6648.90026649591, 6665.333466653296, 6682.899756503559, 6691.130153080068, 6795.218549866829, 6810.727570897307, 6817.386435220854, 6818.318168183142, 6829.146356705412, 5462.481064298885, 5484.204790305464, 6664.600123992576, 6665.333466653296, 6675.0654908313845], "unit": "score", "name": "prototype.html", "value": 4943.130731602068}, {"lowerIsBetter": false, "replicates": [660.0956689335613, 667.7696194812788, 682.532465261296, 756.4062211164236, 785.8310463250403, 14786.852131478696, 15155.772663410044, 15167.165805880672, 15169.77245341318, 15173.468928587488, 13865.4453821847, 13914.860851391482, 13936.581902542915, 13937.024408291423, 13975.881929445646, 733.1056111543921, 749.636426333228, 752.3191511681937, 753.7550296153772, 754.3361841579402, 893.3210759822516, 912.5300470257822, 913.2830727878617, 916.4226537281503, 916.6562539047857, 947.5309721687775, 948.8244674735162, 953.8855337359527, 955.5413401567251, 959.8272310984055, 938.1181689212119, 949.1789601994294, 949.7862980829327, 951.5147274889787, 953.8044700836311, 574.287883025046, 706.8020954132851, 710.9040279562296, 714.7033980897921, 715.6421789105448, 14606.707865842729, 14793.630159245997, 14810.62973425673, 14812.851871481364, 14815.629609259748, 12597.244165350017, 12642.873571264354, 12727.490900363975, 13013.804792928113, 13079.0, 9886.9505652472, 10098.343607665478, 10102.595896164145, 10653.520591573399, 10926.0, 3571.2500374921296, 3657.9757667852778, 3666.028502446827, 3745.3632882410056, 3766.6798322142376, 70823.0, 77822.61088694577, 78822.0, 78972.0, 80472.59763701144, 9188.1730644241, 9336.15974562279, 9402.15380615748, 9682.0, 10213.0, 19643.508912277306, 19906.50233744139, 19923.701144482846, 19925.804451732944, 19970.70043949342, 7354.448416368794, 7471.252874712594, 7488.901179389822, 7566.167721550625, 7582.241775822484, 4072.53165885921, 4136.896577585584, 4137.275976704064, 4143.02638879863, 4198.181354635878, 3580.6598373154507, 3615.5480564929385, 3621.058524783575, 3626.5104210931713, 3632.3461776879885, 643.0932385336653, 643.1446176585131, 654.6628486329544, 663.3068443476578, 670.661316035399, 527.6992114494778, 564.2589630748114, 595.1967621296099, 595.7855172138027, 605.9636421814618, 8171.918280817115, 8311.044229913516, 8342.916570834335, 8382.958085209535, 8482.830343393098, 577.4889223037558, 606.848287928018, 614.4715544631613, 615.0681717198448, 618.4928358745875, 3781.4138808484727, 3784.1863999239745, 3797.3924172132324, 3826.4834247376803, 3894.9805250973563, 80883.0, 89003.55498222467, 89777.0, 92553.0, 93012.0, 71384.64922586195, 71603.0, 72085.91871121938, 74683.62658186675, 75612.62193688996, 67673.0, 68070.65964670145, 68636.0, 70289.59420811555, 70594.0, 7608.353289970193, 7666.769996900102, 7864.72473463426, 8058.879116813369, 8193.09875913661, 36030.279394412486, 36214.81892590626, 45303.32045019393, 48910.51089489202, 49545.50454495553, 57811.0, 64009.679951599945, 67865.642687145, 69067.65466172637, 70079.29920700932, 11078.113750899747, 11152.776944460904, 11280.717982050515, 11346.205765596329, 11511.079113671054, 12069.637910862688, 12084.0, 12330.075244356889, 12499.875001249871, 12865.871341286467, 11467.426628668367, 11545.826812597987, 11865.11011674145, 11983.76032479363, 12044.337561434206, 11353.262037967506, 11463.598774042868, 11679.883201168219, 11824.113191510844, 11936.164468487123, 8618.569071546272, 11482.0, 11524.250923689933, 11530.711732206762, 11619.09522619345, 9336.39313444624, 9532.332736708364, 9605.711828645151, 9653.662121825982, 9759.9024009759, 8520.531370774666, 9101.271898247991, 9661.0, 9695.272854536079, 9761.974992625956], "unit": "score", "name": "yui.html", "value": 6820.489157717875}], "name": "dromaeo_css", "value": 7407.074997824959}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 110.0, 112.0, 113.0, 112.0, 116.0, 112.0, 113.0, 112.0], "unit": "score", "name": "ai-astar", "value": 112.0}, {"lowerIsBetter": true, "replicates": [159.0, 127.0, 174.0, 188.0, 114.0, 142.0, 143.0, 117.0, 166.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [188.0, 186.0, 186.0, 187.0, 188.0, 186.0, 183.0, 186.0, 183.0, 184.0], "unit": "score", "name": "audio-dft", "value": 185.7}, {"lowerIsBetter": true, "replicates": [82.0, 77.0, 84.0, 81.0, 56.0, 57.0, 56.0, 81.0, 70.0, 56.0], "unit": "score", "name": "audio-fft", "value": 70.0}, {"lowerIsBetter": true, "replicates": [95.0, 100.0, 131.0, 78.0, 78.0, 79.0, 117.0, 79.0, 79.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 95.4}, {"lowerIsBetter": true, "replicates": [91.0, 87.0, 89.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 90.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.8}, {"lowerIsBetter": true, "replicates": [89.0, 147.0, 93.0, 94.0, 93.0, 94.0, 93.0, 93.0, 96.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 98.5}, {"lowerIsBetter": true, "replicates": [88.0, 98.0, 142.0, 135.0, 86.0, 87.0, 87.0, 85.0, 85.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 97.8}, {"lowerIsBetter": true, "replicates": [54.0, 54.0, 54.0, 52.0, 54.0, 53.0, 53.0, 64.0, 54.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 54.4}, {"lowerIsBetter": true, "replicates": [63.0, 73.0, 73.0, 72.0, 74.0, 67.0, 66.0, 69.0, 70.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.6}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 84.0, 82.0, 82.0, 86.0, 82.0, 84.0, 83.0, 106.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [134.0, 124.0, 122.0, 128.0, 135.0, 130.0, 128.0, 131.0, 122.0, 132.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 128.6}, {"lowerIsBetter": true, "replicates": [142.0, 130.0, 151.0, 175.0, 174.0, 130.0, 139.0, 141.0, 137.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.9}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 66.0, 67.0, 67.0, 66.0, 68.0, 66.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken", "value": 1443.0}]} 09:49:45 INFO - Return code: 0 09:49:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:49:45 ERROR - # TBPL SUCCESS # 09:49:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:45 INFO - 2016-04-07 09:49:45.904 screenresolution[2355:39170] starting screenresolution argv=screenresolution get 09:49:45 INFO - 2016-04-07 09:49:45.907 screenresolution[2355:39170] Display 0: 1600x1200x32@60 09:49:45 INFO - 2016-04-07 09:49:45.913 screenresolution[2356:39172] starting screenresolution argv=screenresolution list 09:49:45 INFO - Available Modes on Display 0 09:49:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:46 INFO - Intel Iris: 09:49:46 INFO - Chipset Model: Intel Iris 09:49:46 INFO - Type: GPU 09:49:46 INFO - Bus: Built-In 09:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:46 INFO - Vendor: Intel (0x8086) 09:49:46 INFO - Device ID: 0x0a2e 09:49:46 INFO - Revision ID: 0x0009 09:49:46 INFO - Displays: 09:49:46 INFO - Display: 09:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:46 INFO - Display Serial Number: 1600x1200 60 09:49:46 INFO - Main Display: Yes 09:49:46 INFO - Mirror: Off 09:49:46 INFO - Online: Yes 09:49:46 INFO - Rotation: Supported 09:49:46 INFO - Return code: 0 09:49:46 INFO - Running post-action listener: _resource_record_post_action 09:49:46 INFO - Running post-run listener: _resource_record_post_run 09:49:47 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 409600; Write bytes: 693988352; Read time: 5; Write time: 2002 09:49:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392962560; Write bytes: 395847680; Read time: 29606; Write time: 794 09:49:47 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 380928; Write bytes: 297292800; Read time: 4; Write time: 1205 09:49:47 INFO - Running post-run listener: _upload_blobber_files 09:49:47 INFO - Blob upload gear active. 09:49:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:47 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 09:49:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:49:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:49:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:48 INFO - (blobuploader) - INFO - Done attempting. 09:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:49 INFO - (blobuploader) - INFO - Done attempting. 09:49:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:49:49 INFO - Return code: 0 09:49:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:49:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:49:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd614652659c60ae4b11c92c66f1db02af8643e8d76d6974b96e8b73444ce2329d74b6adffdbdacdc41edead6383d20007737501b2081efcdfabed7333c691b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af0b5f1e3f9ea8b3434e1f9ddc00cc8ab37d16e587a8569b1af27bea4d6fdd2e3ee730fb19ceb37db81e2fe418eff7e410d0d236c95b17b6c8ac442e16081f59"} 09:49:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:49:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:49:49 INFO - Contents: 09:49:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd614652659c60ae4b11c92c66f1db02af8643e8d76d6974b96e8b73444ce2329d74b6adffdbdacdc41edead6383d20007737501b2081efcdfabed7333c691b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af0b5f1e3f9ea8b3434e1f9ddc00cc8ab37d16e587a8569b1af27bea4d6fdd2e3ee730fb19ceb37db81e2fe418eff7e410d0d236c95b17b6c8ac442e16081f59"} 09:49:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:49:49 INFO - Copying logs to upload dir... 09:49:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:49:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.410960 ========= master_lag: 0.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 09:49:49.949144) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:49:49.952287) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd614652659c60ae4b11c92c66f1db02af8643e8d76d6974b96e8b73444ce2329d74b6adffdbdacdc41edead6383d20007737501b2081efcdfabed7333c691b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af0b5f1e3f9ea8b3434e1f9ddc00cc8ab37d16e587a8569b1af27bea4d6fdd2e3ee730fb19ceb37db81e2fe418eff7e410d0d236c95b17b6c8ac442e16081f59"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011364 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd614652659c60ae4b11c92c66f1db02af8643e8d76d6974b96e8b73444ce2329d74b6adffdbdacdc41edead6383d20007737501b2081efcdfabed7333c691b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af0b5f1e3f9ea8b3434e1f9ddc00cc8ab37d16e587a8569b1af27bea4d6fdd2e3ee730fb19ceb37db81e2fe418eff7e410d0d236c95b17b6c8ac442e16081f59"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:49:50.020031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:49:50.020381) ========= 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.sFbWbhlfHX/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.jCdGiQEH8C/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005393 ========= master_lag: 0.36 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:49:50.385988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:49:50.386299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:49:50.388756) ========= ========= Total master_lag: 1.04 =========