builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1464953956.0 results: success (0) buildid: 20160603025910 builduid: 747ba9cbac514ceea3ab5c1c2603dd74 revision: 718e392bad427b53b782741e1e7999c29db45746 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:15.996119) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:15.996613) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:15.996889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.019663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.020082) ========= 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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-06-03 04:39:16-- 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% 165M=0s 2016-06-03 04:39:16 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135819 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.176810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.177134) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.027465 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.226539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.226904) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 718e392bad427b53b782741e1e7999c29db45746 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 718e392bad427b53b782741e1e7999c29db45746 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-06-03 04:39:16,301 truncating revision to first 12 chars 2016-06-03 04:39:16,301 Setting DEBUG logging. 2016-06-03 04:39:16,301 attempt 1/10 2016-06-03 04:39:16,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/718e392bad42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 04:39:16,592 unpacking tar archive at: mozilla-central-718e392bad42/testing/mozharness/ program finished with exit code 0 elapsedTime=0.565417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.812522) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.812980) ========= script_repo_revision: 718e392bad427b53b782741e1e7999c29db45746 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.813521) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.814052) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:39:16.830813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-06-03 04:39:16.831218) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:39:17 INFO - MultiFileLogger online at 20160603 04:39:17 in /builds/slave/test 04:39:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:39:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:39:17 INFO - {'append_to_log': False, 04:39:17 INFO - 'base_work_dir': '/builds/slave/test', 04:39:17 INFO - 'blob_upload_branch': 'Firefox', 04:39:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:39:17 INFO - 'branch': 'Firefox', 04:39:17 INFO - 'buildbot_json_path': 'buildprops.json', 04:39:17 INFO - 'config_files': ('talos/mac_config.py',), 04:39:17 INFO - 'default_actions': ('clobber', 04:39:17 INFO - 'read-buildbot-config', 04:39:17 INFO - 'download-and-extract', 04:39:17 INFO - 'populate-webroot', 04:39:17 INFO - 'create-virtualenv', 04:39:17 INFO - 'install', 04:39:17 INFO - 'run-tests'), 04:39:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:39:17 INFO - 'download_minidump_stackwalk': True, 04:39:17 INFO - 'download_symbols': 'ondemand', 04:39:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:39:17 INFO - 'tooltool.py': '/tools/tooltool.py', 04:39:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:39:17 INFO - '/tools/misc-python/virtualenv.py')}, 04:39:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:39:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:39:17 INFO - 'installer_path': 'installer.exe', 04:39:17 INFO - 'log_level': 'info', 04:39:17 INFO - 'log_name': 'talos', 04:39:17 INFO - 'log_to_console': True, 04:39:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:39:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:39:17 INFO - 'opt_config_files': (), 04:39:17 INFO - 'pip_index': False, 04:39:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:17 INFO - 'cmd': ('bash', 04:39:17 INFO - '-c', 04:39:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:17 INFO - 'enabled': True, 04:39:17 INFO - 'halt_on_failure': False, 04:39:17 INFO - 'name': 'check_screen_resolution'},), 04:39:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:17 INFO - 'cmd': ('bash', 04:39:17 INFO - '-c', 04:39:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:17 INFO - 'enabled': True, 04:39:17 INFO - 'halt_on_failure': False, 04:39:17 INFO - 'name': 'check_screen_resolution'},), 04:39:17 INFO - 'run_cmd_checks_enabled': True, 04:39:17 INFO - 'sps_profile': False, 04:39:17 INFO - 'sps_profile_interval': 0, 04:39:17 INFO - 'suite': 'dromaeojs-e10s', 04:39:17 INFO - 'system_bits': '32', 04:39:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:39:17 INFO - 'title': 't-yosemite-r7-0052', 04:39:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:39:17 INFO - 'use_talos_json': True, 04:39:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:39:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:39:17 INFO - 'work_dir': 'build'} 04:39:17 INFO - ##### 04:39:17 INFO - ##### Running clobber step. 04:39:17 INFO - ##### 04:39:17 INFO - Running pre-action listener: _resource_record_pre_action 04:39:17 INFO - Running main action method: clobber 04:39:17 INFO - rmtree: /builds/slave/test/build 04:39:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:39:18 INFO - Running post-action listener: _resource_record_post_action 04:39:18 INFO - ##### 04:39:18 INFO - ##### Finished clobber step (success) 04:39:18 INFO - ##### 04:39:18 INFO - ##### 04:39:18 INFO - ##### Running read-buildbot-config step. 04:39:18 INFO - ##### 04:39:18 INFO - Running pre-action listener: _resource_record_pre_action 04:39:18 INFO - Running main action method: read_buildbot_config 04:39:18 INFO - Using buildbot properties: 04:39:18 INFO - { 04:39:18 INFO - "project": "", 04:39:18 INFO - "product": "firefox", 04:39:18 INFO - "script_repo_revision": "production", 04:39:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:39:18 INFO - "repository": "", 04:39:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:39:18 INFO - "buildid": "20160603025910", 04:39:18 INFO - "pgo_build": "False", 04:39:18 INFO - "basedir": "/builds/slave/test", 04:39:18 INFO - "buildnumber": 51, 04:39:18 INFO - "slavename": "t-yosemite-r7-0052", 04:39:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:39:18 INFO - "platform": "yosemite_r7", 04:39:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:39:18 INFO - "branch": "mozilla-central", 04:39:18 INFO - "repo_path": "mozilla-central", 04:39:18 INFO - "revision": "718e392bad427b53b782741e1e7999c29db45746", 04:39:18 INFO - "stage_platform": "macosx64", 04:39:18 INFO - "builduid": "747ba9cbac514ceea3ab5c1c2603dd74", 04:39:18 INFO - "slavebuilddir": "test" 04:39:18 INFO - } 04:39:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:39:18 INFO - Running post-action listener: _resource_record_post_action 04:39:18 INFO - ##### 04:39:18 INFO - ##### Finished read-buildbot-config step (success) 04:39:18 INFO - ##### 04:39:18 INFO - ##### 04:39:18 INFO - ##### Running download-and-extract step. 04:39:18 INFO - ##### 04:39:18 INFO - Running pre-action listener: _resource_record_pre_action 04:39:18 INFO - Running main action method: download_and_extract 04:39:18 INFO - mkdir: /builds/slave/test/build/tests 04:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:18 INFO - https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:39:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:39:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:39:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:39:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:39:19 INFO - Downloaded 1199 bytes. 04:39:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:39:19 INFO - Using the following test package requirements: 04:39:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:39:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:39:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:39:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'jsshell-mac.zip'], 04:39:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:39:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:39:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:39:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:39:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:39:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:39:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:39:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:19 INFO - https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:39:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:21 INFO - Downloaded 18713617 bytes. 04:39:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:39:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:39:24 INFO - Return code: 0 04:39:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:24 INFO - https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:39:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:39:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:25 INFO - Downloaded 18713617 bytes. 04:39:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:39:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:39:29 INFO - Return code: 0 04:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:29 INFO - https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:39:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:39:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:39:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:39:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:39:30 INFO - Downloaded 10834055 bytes. 04:39:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:39:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:39:30 INFO - Return code: 0 04:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:30 INFO - https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:39:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:39:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:39:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:39:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:39:34 INFO - Downloaded 111096754 bytes. 04:39:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:39:34 INFO - mkdir: /builds/slave/test/properties 04:39:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:34 INFO - Contents: 04:39:34 INFO - build_url:https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:39:35 INFO - Running post-action listener: _resource_record_post_action 04:39:35 INFO - Running post-action listener: set_extra_try_arguments 04:39:35 INFO - ##### 04:39:35 INFO - ##### Finished download-and-extract step (success) 04:39:35 INFO - ##### 04:39:35 INFO - ##### 04:39:35 INFO - ##### Running populate-webroot step. 04:39:35 INFO - ##### 04:39:35 INFO - Running pre-action listener: _resource_record_pre_action 04:39:35 INFO - Running main action method: populate_webroot 04:39:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:35 INFO - '--tppagecycles', 04:39:35 INFO - '7'], 04:39:35 INFO - 'tests': ['tsvgm']}}, 04:39:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['damp', 'tps']}, 04:39:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'tests': ['damp', 'tps']}, 04:39:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['dromaeo_dom']}, 04:39:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['a11yr', 04:39:35 INFO - 'ts_paint', 04:39:35 INFO - 'tpaint', 04:39:35 INFO - 'sessionrestore', 04:39:35 INFO - 'sessionrestore_no_auto_restore', 04:39:35 INFO - 'tabpaint']}, 04:39:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['tsvgx', 04:39:35 INFO - 'tsvgr_opacity', 04:39:35 INFO - 'tart', 04:39:35 INFO - 'tscrollx', 04:39:35 INFO - 'cart']}, 04:39:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:35 INFO - 'tsvgr_opacity', 04:39:35 INFO - 'tart', 04:39:35 INFO - 'tscrollx', 04:39:35 INFO - 'cart']}, 04:39:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'talos_options': ['--disable-e10s'], 04:39:35 INFO - 'tests': ['tp5o']}, 04:39:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'tests': ['tp5o']}, 04:39:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'talos_options': ['--disable-e10s', 04:39:35 INFO - '--xperf_path', 04:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:35 INFO - 'tests': ['tp5n']}, 04:39:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:35 INFO - 'talos_options': ['--xperf_path', 04:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:35 INFO - 'tests': ['tp5n']}}, 04:39:35 INFO - 'talos.zip': {'path': '', 04:39:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:35 INFO - Running post-action listener: _resource_record_post_action 04:39:35 INFO - ##### 04:39:35 INFO - ##### Finished populate-webroot step (success) 04:39:35 INFO - ##### 04:39:35 INFO - ##### 04:39:35 INFO - ##### Running create-virtualenv step. 04:39:35 INFO - ##### 04:39:35 INFO - Running pre-action listener: _resource_record_pre_action 04:39:35 INFO - Running main action method: create_virtualenv 04:39:35 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:39:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:35 INFO - Using real prefix '/tools/python27' 04:39:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:36 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:39 INFO - Installing pip.................done. 04:39:39 INFO - Return code: 0 04:39:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:39 INFO - 'HOME': '/Users/cltbld', 04:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:39 INFO - 'LOGNAME': 'cltbld', 04:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:39 INFO - 'MOZ_NO_REMOTE': '1', 04:39:39 INFO - 'NO_EM_RESTART': '1', 04:39:39 INFO - 'PAGER': '/bin/cat', 04:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:39 INFO - 'PWD': '/builds/slave/test', 04:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:39 INFO - 'SHELL': '/bin/bash', 04:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:39 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:39 INFO - 'USER': 'cltbld', 04:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:39 INFO - 'XPC_FLAGS': '0x0', 04:39:39 INFO - 'XPC_SERVICE_NAME': '0', 04:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:39:39 INFO - Cleaning up... 04:39:39 INFO - Return code: 0 04:39:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:39 INFO - 'HOME': '/Users/cltbld', 04:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:39 INFO - 'LOGNAME': 'cltbld', 04:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:39 INFO - 'MOZ_NO_REMOTE': '1', 04:39:39 INFO - 'NO_EM_RESTART': '1', 04:39:39 INFO - 'PAGER': '/bin/cat', 04:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:39 INFO - 'PWD': '/builds/slave/test', 04:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:39 INFO - 'SHELL': '/bin/bash', 04:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:39 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:39 INFO - 'USER': 'cltbld', 04:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:39 INFO - 'XPC_FLAGS': '0x0', 04:39:39 INFO - 'XPC_SERVICE_NAME': '0', 04:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:39 INFO - Downloading/unpacking psutil>=3.1.1 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:42 INFO - Installing collected packages: psutil 04:39:42 INFO - Running setup.py install for psutil 04:39:42 INFO - building 'psutil._psutil_osx' extension 04:39:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:39:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:39:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:39:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:39:43 INFO - building 'psutil._psutil_posix' extension 04:39:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:39:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:39:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:39:43 INFO - ^ 04:39:43 INFO - 1 warning generated. 04:39:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:39:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:43 INFO - Successfully installed psutil 04:39:43 INFO - Cleaning up... 04:39:43 INFO - Return code: 0 04:39:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:43 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:43 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.3'] in /builds/slave/test/build 04:39:43 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.3 04:39:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:43 INFO - 'HOME': '/Users/cltbld', 04:39:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:43 INFO - 'LOGNAME': 'cltbld', 04:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:43 INFO - 'MOZ_NO_REMOTE': '1', 04:39:43 INFO - 'NO_EM_RESTART': '1', 04:39:43 INFO - 'PAGER': '/bin/cat', 04:39:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:43 INFO - 'PWD': '/builds/slave/test', 04:39:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:43 INFO - 'SHELL': '/bin/bash', 04:39:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:43 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:43 INFO - 'USER': 'cltbld', 04:39:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:43 INFO - 'XPC_FLAGS': '0x0', 04:39:43 INFO - 'XPC_SERVICE_NAME': '0', 04:39:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:46 INFO - Installing collected packages: mozsystemmonitor 04:39:46 INFO - Running setup.py install for mozsystemmonitor 04:39:46 INFO - Successfully installed mozsystemmonitor 04:39:46 INFO - Cleaning up... 04:39:46 INFO - Return code: 0 04:39:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:46 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:46 INFO - 'HOME': '/Users/cltbld', 04:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:46 INFO - 'LOGNAME': 'cltbld', 04:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:46 INFO - 'MOZ_NO_REMOTE': '1', 04:39:46 INFO - 'NO_EM_RESTART': '1', 04:39:46 INFO - 'PAGER': '/bin/cat', 04:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:46 INFO - 'PWD': '/builds/slave/test', 04:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:46 INFO - 'SHELL': '/bin/bash', 04:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:46 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:46 INFO - 'USER': 'cltbld', 04:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:46 INFO - 'XPC_FLAGS': '0x0', 04:39:46 INFO - 'XPC_SERVICE_NAME': '0', 04:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:49 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:50 INFO - Downloading docopt-0.6.1.tar.gz 04:39:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:50 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:50 INFO - Running setup.py install for blobuploader 04:39:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:50 INFO - Running setup.py install for requests 04:39:51 INFO - Running setup.py install for docopt 04:39:51 INFO - Successfully installed blobuploader requests docopt 04:39:51 INFO - Cleaning up... 04:39:51 INFO - Return code: 0 04:39:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:51 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:39:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:51 INFO - 'HOME': '/Users/cltbld', 04:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:51 INFO - 'LOGNAME': 'cltbld', 04:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:51 INFO - 'MOZ_NO_REMOTE': '1', 04:39:51 INFO - 'NO_EM_RESTART': '1', 04:39:51 INFO - 'PAGER': '/bin/cat', 04:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:51 INFO - 'PWD': '/builds/slave/test', 04:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:51 INFO - 'SHELL': '/bin/bash', 04:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:51 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:51 INFO - 'USER': 'cltbld', 04:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:51 INFO - 'XPC_FLAGS': '0x0', 04:39:51 INFO - 'XPC_SERVICE_NAME': '0', 04:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-nCWyrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xan3kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-A6dMHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-nvubHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-13wn7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-yuz2rS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-GouGsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-QDR4NQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-QcIUxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-T6MFr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-aTSP1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-rFXAQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-hapFKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CQxpiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:53 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Ec3NjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:53 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-FYKVAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:53 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-koSenN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:53 INFO - Running setup.py install for manifestparser 04:39:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:53 INFO - Running setup.py install for mozcrash 04:39:53 INFO - Running setup.py install for mozdebug 04:39:53 INFO - Running setup.py install for mozdevice 04:39:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:53 INFO - Running setup.py install for mozfile 04:39:54 INFO - Running setup.py install for mozhttpd 04:39:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Running setup.py install for mozinfo 04:39:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Running setup.py install for mozInstall 04:39:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Running setup.py install for mozleak 04:39:54 INFO - Running setup.py install for mozlog 04:39:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:54 INFO - Running setup.py install for moznetwork 04:39:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Running setup.py install for mozprocess 04:39:55 INFO - Running setup.py install for mozprofile 04:39:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Running setup.py install for mozrunner 04:39:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Running setup.py install for mozscreenshot 04:39:55 INFO - Running setup.py install for moztest 04:39:55 INFO - Running setup.py install for mozversion 04:39:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:55 INFO - Cleaning up... 04:39:55 INFO - Return code: 0 04:39:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:55 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:55 INFO - 'HOME': '/Users/cltbld', 04:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:55 INFO - 'LOGNAME': 'cltbld', 04:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:55 INFO - 'MOZ_NO_REMOTE': '1', 04:39:55 INFO - 'NO_EM_RESTART': '1', 04:39:55 INFO - 'PAGER': '/bin/cat', 04:39:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:55 INFO - 'PWD': '/builds/slave/test', 04:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:55 INFO - 'SHELL': '/bin/bash', 04:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:39:55 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:55 INFO - 'USER': 'cltbld', 04:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:55 INFO - 'XPC_FLAGS': '0x0', 04:39:55 INFO - 'XPC_SERVICE_NAME': '0', 04:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-OznYpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-YEgIgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:56 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)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-KuXk_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-qbwPUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-7VG3Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-LTvcyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AfFkJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-b7KRRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-gtmwzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-JL6fc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-MGLzyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xuM6Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-RXyQiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-1CloID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ytYQzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-PZb6wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-tSi_ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:39:58 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)) 04:39:58 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)) 04:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:39:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:00 INFO - Downloading blessings-1.6.tar.gz 04:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:40:00 INFO - Installing collected packages: blessings 04:40:00 INFO - Running setup.py install for blessings 04:40:00 INFO - Successfully installed blessings 04:40:00 INFO - Cleaning up... 04:40:00 INFO - Return code: 0 04:40:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:40:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:01 INFO - Reading from file tmpfile_stdout 04:40:01 INFO - Current package versions: 04:40:01 INFO - blessings == 1.6 04:40:01 INFO - blobuploader == 1.2.4 04:40:01 INFO - docopt == 0.6.1 04:40:01 INFO - manifestparser == 1.1 04:40:01 INFO - mozInstall == 1.12 04:40:01 INFO - mozcrash == 0.17 04:40:01 INFO - mozdebug == 0.1 04:40:01 INFO - mozdevice == 0.48 04:40:01 INFO - mozfile == 1.2 04:40:01 INFO - mozhttpd == 0.7 04:40:01 INFO - mozinfo == 0.9 04:40:01 INFO - mozleak == 0.1 04:40:01 INFO - mozlog == 3.2 04:40:01 INFO - moznetwork == 0.27 04:40:01 INFO - mozprocess == 0.22 04:40:01 INFO - mozprofile == 0.28 04:40:01 INFO - mozrunner == 6.11 04:40:01 INFO - mozscreenshot == 0.1 04:40:01 INFO - mozsystemmonitor == 0.3 04:40:01 INFO - moztest == 0.7 04:40:01 INFO - mozversion == 1.4 04:40:01 INFO - psutil == 3.1.1 04:40:01 INFO - requests == 1.2.3 04:40:01 INFO - wsgiref == 0.1.2 04:40:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:01 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:01 INFO - 'HOME': '/Users/cltbld', 04:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:01 INFO - 'LOGNAME': 'cltbld', 04:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:01 INFO - 'MOZ_NO_REMOTE': '1', 04:40:01 INFO - 'NO_EM_RESTART': '1', 04:40:01 INFO - 'PAGER': '/bin/cat', 04:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:01 INFO - 'PWD': '/builds/slave/test', 04:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:01 INFO - 'SHELL': '/bin/bash', 04:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:40:01 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:01 INFO - 'USER': 'cltbld', 04:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:01 INFO - 'XPC_FLAGS': '0x0', 04:40:01 INFO - 'XPC_SERVICE_NAME': '0', 04:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:40:01 INFO - Cleaning up... 04:40:01 INFO - Return code: 0 04:40:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:01 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:01 INFO - 'HOME': '/Users/cltbld', 04:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:01 INFO - 'LOGNAME': 'cltbld', 04:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:01 INFO - 'MOZ_NO_REMOTE': '1', 04:40:01 INFO - 'NO_EM_RESTART': '1', 04:40:01 INFO - 'PAGER': '/bin/cat', 04:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:01 INFO - 'PWD': '/builds/slave/test', 04:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:01 INFO - 'SHELL': '/bin/bash', 04:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:40:01 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:01 INFO - 'USER': 'cltbld', 04:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:01 INFO - 'XPC_FLAGS': '0x0', 04:40:01 INFO - 'XPC_SERVICE_NAME': '0', 04:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:01 INFO - Downloading/unpacking jsonschema 04:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:40:04 INFO - Downloading/unpacking functools32 (from jsonschema) 04:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:04 INFO - Downloading functools32-3.2.3-2.tar.gz 04:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:40:04 INFO - warning: no files found matching '*.txt' 04:40:04 INFO - no previously-included directories found matching 'build' 04:40:04 INFO - no previously-included directories found matching 'dist' 04:40:04 INFO - no previously-included directories found matching '.git*' 04:40:04 INFO - Installing collected packages: jsonschema, functools32 04:40:04 INFO - Running setup.py install for functools32 04:40:04 INFO - warning: no files found matching '*.txt' 04:40:04 INFO - no previously-included directories found matching 'build' 04:40:04 INFO - no previously-included directories found matching 'dist' 04:40:04 INFO - no previously-included directories found matching '.git*' 04:40:04 INFO - Successfully installed jsonschema functools32 04:40:04 INFO - Cleaning up... 04:40:04 INFO - Return code: 0 04:40:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:04 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 0x10dda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ddac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb76ac51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, '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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:04 INFO - 'HOME': '/Users/cltbld', 04:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:04 INFO - 'LOGNAME': 'cltbld', 04:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:04 INFO - 'MOZ_NO_REMOTE': '1', 04:40:04 INFO - 'NO_EM_RESTART': '1', 04:40:04 INFO - 'PAGER': '/bin/cat', 04:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:04 INFO - 'PWD': '/builds/slave/test', 04:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:04 INFO - 'SHELL': '/bin/bash', 04:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:40:04 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:04 INFO - 'USER': 'cltbld', 04:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:04 INFO - 'XPC_FLAGS': '0x0', 04:40:04 INFO - 'XPC_SERVICE_NAME': '0', 04:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:05 INFO - Downloading/unpacking flake8 04:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:40:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:40:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:40:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:40:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:40:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:40:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:40:08 INFO - no previously-included directories found matching 'docs/_build' 04:40:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:40:08 INFO - Running setup.py install for pep8 04:40:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:40:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:40:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:40:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:40:08 INFO - no previously-included directories found matching 'docs/_build' 04:40:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:40:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:40:08 INFO - Cleaning up... 04:40:09 INFO - Return code: 0 04:40:09 INFO - Running post-action listener: _resource_record_post_action 04:40:09 INFO - Running post-action listener: _start_resource_monitoring 04:40:09 INFO - Starting resource monitoring. 04:40:09 INFO - ##### 04:40:09 INFO - ##### Finished create-virtualenv step (success) 04:40:09 INFO - ##### 04:40:09 INFO - ##### 04:40:09 INFO - ##### Running install step. 04:40:09 INFO - ##### 04:40:09 INFO - Running pre-action listener: _resource_record_pre_action 04:40:09 INFO - Running main action method: install 04:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:09 INFO - Reading from file tmpfile_stdout 04:40:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:40:09 INFO - Reading from file tmpfile_stdout 04:40:09 INFO - Output received: 04:40:09 INFO - Usage: mozinstall [options] installer 04:40:09 INFO - Options: 04:40:09 INFO - -h, --help show this help message and exit 04:40:09 INFO - -d DEST, --destination=DEST 04:40:09 INFO - Directory to install application into. [default: 04:40:09 INFO - "/builds/slave/test"] 04:40:09 INFO - --app=APP Application being installed. [default: firefox] 04:40:09 INFO - mkdir: /builds/slave/test/build/application 04:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:40:43 INFO - Reading from file tmpfile_stdout 04:40:43 INFO - Output received: 04:40:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:40:43 INFO - Running post-action listener: _resource_record_post_action 04:40:43 INFO - ##### 04:40:43 INFO - ##### Finished install step (success) 04:40:43 INFO - ##### 04:40:43 INFO - ##### 04:40:43 INFO - ##### Running run-tests step. 04:40:43 INFO - ##### 04:40:43 INFO - Running pre-action listener: _resource_record_pre_action 04:40:43 INFO - Running main action method: run_tests 04:40:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:43 INFO - 2016-06-03 04:40:43.461 screenresolution[1645:7398] starting screenresolution argv=screenresolution get 04:40:43 INFO - 2016-06-03 04:40:43.464 screenresolution[1645:7398] Display 0: 1600x1200x32@60 04:40:43 INFO - 2016-06-03 04:40:43.470 screenresolution[1646:7400] starting screenresolution argv=screenresolution list 04:40:43 INFO - Available Modes on Display 0 04:40:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:43 INFO - Intel Iris: 04:40:43 INFO - Chipset Model: Intel Iris 04:40:43 INFO - Type: GPU 04:40:43 INFO - Bus: Built-In 04:40:43 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:43 INFO - Vendor: Intel (0x8086) 04:40:43 INFO - Device ID: 0x0a2e 04:40:43 INFO - Revision ID: 0x0009 04:40:43 INFO - Displays: 04:40:43 INFO - Display: 04:40:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:43 INFO - Display Serial Number: 1600x1200 60 04:40:43 INFO - Main Display: Yes 04:40:43 INFO - Mirror: Off 04:40:43 INFO - Online: Yes 04:40:43 INFO - Rotation: Supported 04:40:43 INFO - Return code: 0 04:40:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:40:43 INFO - Python 2.7.3 04:40:43 INFO - Return code: 0 04:40:43 INFO - grabbing minidump binary from tooltool 04:40:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:43 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc4ad30>, '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 0x10da6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:40:43 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 04:40:43 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 04:40:43 INFO - Calling ['/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'] with output_timeout 600 04:40:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:40:43 INFO - Return code: 0 04:40:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:40:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:40:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:40:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:40:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:40:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:40:44 INFO - Return code: 0 04:40:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wPo4Fqt7Q3/Render', 04:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:44 INFO - 'HOME': '/Users/cltbld', 04:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:44 INFO - 'LOGNAME': 'cltbld', 04:40:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:40:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:44 INFO - 'MOZ_NO_REMOTE': '1', 04:40:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:40:44 INFO - 'NO_EM_RESTART': '1', 04:40:44 INFO - 'PAGER': '/bin/cat', 04:40:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:44 INFO - 'PWD': '/builds/slave/test', 04:40:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:44 INFO - 'SHELL': '/bin/bash', 04:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mZXyqyqEJp/Listeners', 04:40:44 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 04:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:44 INFO - 'USER': 'cltbld', 04:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:44 INFO - 'XPC_FLAGS': '0x0', 04:40:44 INFO - 'XPC_SERVICE_NAME': '0', 04:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:40:45 INFO - mozversion application_buildid: 20160603025910 04:40:45 INFO - mozversion application_changeset: 718e392bad427b53b782741e1e7999c29db45746 04:40:45 INFO - mozversion application_display_name: Nightly 04:40:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:40:45 INFO - mozversion application_name: Firefox 04:40:45 INFO - mozversion application_remotingname: firefox 04:40:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:40:45 INFO - mozversion application_vendor: Mozilla 04:40:45 INFO - mozversion application_version: 49.0a1 04:40:45 INFO - mozversion platform_buildid: 20160603025910 04:40:45 INFO - mozversion platform_changeset: 718e392bad427b53b782741e1e7999c29db45746 04:40:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:40:45 INFO - mozversion platform_version: 49.0a1 04:40:45 INFO - using testdate: 1464954045 04:40:45 INFO - actual date: 1464954045 04:40:45 INFO - starting webserver on 'localhost:49270' 04:40:45 INFO - SUITE-START | Running 2 tests 04:40:45 INFO - TEST-START | dromaeo_css 04:40:45 INFO - operating with platform_type : mac_ 04:40:45 INFO - Initialising browser for dromaeo_css test... 04:40:45 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpf6ZUZh/profile http://localhost:49270/getInfo.html) 04:40:48 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 04:40:48 INFO - PROCESS | 1664 | colorDepth:24 04:40:48 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 04:40:48 INFO - PROCESS | 1664 | __metrics 04:40:48 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 04:40:48 INFO - TEST-INFO | 1664: exit 0 04:40:48 INFO - Browser initialized. 04:40:48 INFO - Running cycle 1/1 for dromaeo_css test... 04:40:48 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpf6ZUZh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:01 INFO - PROCESS | 1667 | RSS: Main: 253288448 04:41:01 INFO - PROCESS | 1667 | 04:41:50 INFO - PROCESS | 1667 | 1464954110766 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] 04:44:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:44:02 INFO - PROCESS | 1667 | RSS: Main: 265703424 04:44:02 INFO - PROCESS | 1667 | 04:47:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:47:05 INFO - PROCESS | 1667 | RSS: Main: 276803584 04:47:05 INFO - PROCESS | 1667 | 04:50:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:50:08 INFO - PROCESS | 1667 | RSS: Main: 278769664 04:50:08 INFO - PROCESS | 1667 | 04:53:09 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:53:10 INFO - PROCESS | 1667 | RSS: Main: 280264704 04:53:10 INFO - PROCESS | 1667 | 04:56:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 04:56:13 INFO - PROCESS | 1667 | RSS: Main: 283852800 04:56:13 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 04:59:16 INFO - PROCESS | 1667 | RSS: Main: 284303360 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | __start_tp_report 04:59:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 04:59:16 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 04:59:16 INFO - PROCESS | 1667 | |i|pagename|runs| 04:59:16 INFO - PROCESS | 1667 | |0;dojo.html;3026.9848650756744;3506.614272430033;3516.718662507;3532.6997205237553;3578.910527236819;5828.096645020023;5845.4446827551365;5883.352831188566;5973.074173503103;6042.728077236524;5404.190046017558;5836.795712150076;5878.588498805086;5922.496587790032;5937.050071988483;5241.39723931748;5316.680999140045;5390.56875449965;5396.78412863485;5436.673799572019;146.7070412152835;5187.974060129713;5302.284191634138;5356.330458692663;5382.6770393776305;5274.8417547473255;5277.8416647500635;5308.654937429074;5326.280952071479;5353.437889021675;5302.840914772563;5303.469653034705;5328.20076988451;5356.1697936819855;5363.758630861621;5297.549708274802;5310;5313.415524292324;5329.413764485904;5350.117230656976;5277.920831187535;5683.147527870811;5834.854128646775;5839.649621022752;5883.294004719461;5688.089905615123;5977.521798256173;6217.595856269328;6273.905891411632;6283.654399008053;6227.498394076296;6245.687715614201;6260.718267677966;6299.181106456132;6328.715207815613;5791.536677065825;5942.435468630473;5965.642061476326;6000.369961154103;6080.0575910733905;5399.973000134974;6274.905876411857;6301.306856245809;6327.96836015817;6346;6251.874962500725;6288.496920246369;6340.144080549159;6356.936430635635;6365.649889256136;5238.292830467914;6233.2208473940755;6310.905336419957;6312.116303717483;6314.778982735582;6254.15568898202;6292.811215663537;6300.432961033529;6315.747370105144;6328.272248691367;6291.5281353898645;6321.747130114836;6330.715117819709;6333.904991425133;6334.873302533923;4377.796106070856;4590.931136032962;4910.6808057476865;4921.926171107436;5019.347484826949;4410.977945110254;4456.866294011184;4458.710183838105;4461.219286624828;4505.797239124247;5814.156947242626;5959.135925290809;5975.25309336333;6006.429389208106;6034.607750496291;6181.84545386369;6232.283287422005;6239.500839932794;6265;6290.150829638032;5903.085021821625;5935.376785437467;5946.791862284799;5983.461488466059;6003.6397816131175;4951.282064100686;5505.587080968863;5536.197251398525;5556.805511807148;5566.888662226814;6291.087792270187;6373.936260637334;6445.4199122079235;6473.935260647428;6492.870142597028;6270.404311590391;6303.968480157753;6310.05349197624;6310.7791227306825;6345.587536810092;6289.779857705142;6498.057781621733;6519.902201467076;6526.902096468461;6534.673266336569;6254.3745625437095;6288.779892703734;6294.181756371826;6303.527235457451;6338.492920566436;5289.894202116013;6251.437370636664;6300.401461861115;6314.62112273265;6329.367063293818;6105.267367915879;6117.736059405397;6208.255009398901;6283.905741413791;6287.905681414691;5278.36659600835;6191.1641928339095;6226.221722284715;6268;6278.937210627835;5619.129034999418;6134.110553969738;6214.937850621436;6215.720292586935;6261.749530018748;5114.667546609458;6096.829073522031;6165.3526379731275;6184.81445556634;6293.874122517615;6187.752489900354;6220.533459990609;6225.284092329259;6229.283632382153;6231.594946328474 04:59:16 INFO - PROCESS | 1667 | |1;ext.html;494.16486138426035;498.85283841266823;501.5486062543711;504.7526711911163;505.1185680986674;38619;41828.372574411405;41882.581174188286;41905.58094419055;41930.58069419305;42657.7867110664;43283.78358108213;43406.13187736244;43444.913877153085;43570;9421.952890235556;9639.373440726366;9648.565814538328;9667.419954802699;9671.468069256189;8148;9487.715368538955;9515.096065873731;9554.808903821919;9586.13724764771;9477.43135411874;9543.713688589318;9549.809003819919;9584.329096963216;9587.185089267421;8103.95948020259;9500.524973751319;9565.617375304979;9577.904220957771;9607.759806004906;9292.628294868266;9338.439693618404;9402.62389504419;9486.051394860528;9492.762680933032;40019.79990100031;40224;40289;40782.77651670454;40828.38757418641;3012.448588976679;3114.5795317632283;3156.6054243219596;3219.307848812493;3752.5496940367057;22653.886730566406;32072.839635801905;32254.354912901847;32568.348633027443;32601.510977335358;3225.451673215559;3369.7978121312553;3384.6107697614843;3387.0685561470546;3392.86428542858;492843;526911;535070.3246483782;535400.645993539;543712;58021.419785801605;59251.407485925454;59477.810443790884;59587.40412595905;59621.50946226379;60452.86150677154;60559.39440605538;60584.394156058755;60851.69574152189;60969.78060438766;14245.21651309188;16282.75575866363;16369.59076023085;16572.75140872888;16613.750793738105;11479.770404591862;11751.47118379675;12702;12996.870031299755;13009.934950325378;16526.66946661084;16689;16694.4156954506;16732.58168545796;16748.497545073667;5494.5549230592005;6385.233771947396;6451.258105317855;6460.192475940507;6540.771073012517;928.9907100929039;972.2562239886493;973.9318247722733;1011.039512463163;1158.6350299655583;27557.31106722348;28751.712482874904;30204.697953020626;30458.69541304603;30569.847150764548;1082.9099837314059;1086.2830531848942;1095.5891540672249;1096.3038470571244;1170.303669316755;14861.777073343908;15593.454229102153;15627.921860390625;15662.686746265239;15733.921330393276;653156;677815;723377;732948;926484;60941.39058609357;61378.38621613816;61849.072263916074;61901.690491547255;61932.38067619356;1344.7243315120377;1437.6118448018976;1463.5024091808837;1473.7325899726227;1477.8891583131308;30780.230494236926;30960.69039309578;31487.52768708517;31582.684173157973;31740;51151;51552;52192.21711674404;52204.73897630488;52281.47718522918;608151;611902;612036;612064;613230;25527.6170857441;25625.871870640527;26021.739782601933;26288.34279143037;26394.73605263923;40181.1963760729;47365;49596.75201623969;49815.51660350586;49944.25083623676;35665;39592.40611390889;40907.77276681704;41619.58380416157;42065;40530.79734601308;40841.695892218624;41046.58953410428;41377.1724565501;41508.92455377158;38879.63921262742;39061.21877562376;39799;39882.00294992532;39926;34940.82529587336;35116.64883351134;35516.28967420585;35622.465663014555;35740.749073783336;25505;25671.379151752863;25931.351716206493;26140.34649133787;26347.341316467242 04:59:16 INFO - PROCESS | 1667 | |2;jquery.html;369.8520591763294;379.99620003799964;380.41416219022705;384.70954429405805;398.02285389369104;19079.332223372185;20632.48418789531;21207;21261;21291.57416851664;20718.48203794906;21435;21614.675779863315;21632.026558804853;21855.672164917538;5254;5686.232358631594;5931.051031834886;5943.613665111776;5953.523718102541;5846.415358464162;5863.736131874055;5900.52795776339;5900.64596124234;5924.081767326072;5469;5895.086261629433;5907.4978626816555;5955.434233747787;5975.282966044081;5842.941570584282;5844.1818145459665;5897.793577224798;6089.543284253698;6120.908186377208;5792.565557583156;5837.9124313135335;5856.502197313233;5871.148683440877;5873.85315367115;25145;25265.242042738748;26016;26042.609360859602;26187.607185892226;2128.595566842295;2155.277981876073;2206.090037412366;2303.4817166137655;2455.533448644752;9720.562574684156;10419.270651054429;12549.498020079185;12880.549180778627;14259.714805703932;1858.6933156029238;1873.175802646831;1878.4985918711;1908.1604094198647;2123.883186424746;73479.63260183718;114826;120218;123330.38334808269;124753;9310.627574896927;10189.643362482277;11818;11834.644960651194;12013.459394327276;14618.926905365617;14715.337809798584;14724.411023558938;14740.262986850614;14771.187584682732;8569.143085691505;8578.914210857936;8669.783255418664;8685.131486851264;8716.951516393643;7066.434685225169;8403.663853445794;8564.52895090776;8655.394122411371;8675.913240867636;4465.843695470643;4602.171609110393;4623.491415944243;4629.189891768927;4683.7423941682855;1854.147092337513;1945.5525229197365;1986.3941497843296;1993.2126809910005;1995.7305763722002;845.1421806866036;891.8617614269799;902.6299217320998;994.3238597753452;1019.4902548725637;16785.66428671444;19647.312344068174;21103.788962110488;21418.143274269165;21467.463313416978;928.7445952363154;930.6044930904338;954.6324665003884;972.4635076578988;984.8571957066185;7745.6127193640095;11407.828882566768;11432;11464.885351146548;11515.76968460626;511893;519084;528088;607857;608059;122542;132776;140405;140533;143078.2846085763;997.1424574865609;1002.0730823987782;1031.9948400257952;1048.5124417146064;1055.8996895294936;15537.378504859678;19189.040547972825;19389.836609803457;19469.415917522496;19658.50853728668;56170;56764;58540;58580;58861.822763543634;79961;83155.58422208093;89199;89730.10269897217;90735;6984.825379365557;8092.312153467021;8153.8369232616205;8169.937908071913;8257.174282571936;11378.715532111764;13072.411741471466;13160;13242.933785331012;13243.66890827737;8994.550272486218;11210.719732006764;11453.083753299883;11453.141014423785;11554.884451155716;9849.310548261554;11347.602833900773;11360.488778004845;11637.5344986203;11681.707957301136;9849.409035457895;11026;11769.99595309606;11788.941055295012;11807.586734464252;5695.401982791747;7503.362214211853;7559;7572.280633339824;8488.236058754741;8381.916180838114;11312.886871131182;11630;11679.240849344766;11851.348175850066 04:59:16 INFO - PROCESS | 1667 | |3;mootools.html;1539.4534940096266;1593.9760903586446;1597.4249270262703;1935.3710044235627;1956.7162761399607;8014.95992520038;8603.139686031394;8618.310535157188;9109;9151.954240228797;8711.520866352348;8784.93554014806;8916.509591972439;8941.597628106734;8952.104789521045;2327.627579587266;2330.0912644068867;2359.917402890899;2360.9291721248305;2364.586197415454;2327.592671282527;2346.143657564985;2395.4788709169716;2399.2562305685206;2408.4219787251022;2343.7773411525877;2387.7015373078366;2402.4354276744953;2403.375122468153;2407.626817843237;2330.3125577954543;2353.364591560278;2357.3635118518077;2366.3965688749345;2371.5612611666897;2276.658501224813;2293.8050265727434;2305.631099024148;2311.144876395744;2319.478117423583;8840.248578870804;8885.733427997171;8944.776380590472;8946.731598052069;8953.641854325819;4297.484301883794;4619.330197121399;4789.688670236458;5356.276902618136;5416;3547.3614749345106;3604.567451905762;3668.321360548307;3693.649103335179;3713.6657700806786;1073.1575713065206;1088.3198001249218;1098.1050443888205;1100.6037826382496;1102.1513434655271;11783.528658853636;11810.822837657443;11935.462904169246;11950.402479876058;11954.58158964432;4716.9056618867435;4736.242201247784;4831.9275210871865;4854;4863.148948933993;10047.346922450164;10093.798124037477;10099;10117.443540605338;10122.392656440637;5309.601779866525;5318.388385335658;5419.539339156137;5423.159410291411;5439.401665816757;4435.6229720473475;4756;4914.287428322873;4929.137400954819;4948.62885283598;3934.9213015740097;3956.643902048829;3987.860424885115;4070.8371665133654;4090.795460226977;1295.7473292707832;1322.2000689582856;1323.4639970811836;1328.581496828496;1331.73365326935;800.7757827808223;819.815541503157;873.4628203654706;932.4032619123766;970.8980557041409;5190.896182076337;5664.631798933056;5805.129230615357;5928.644281343047;5950.702464876739;429.1695569073852;434.218406867637;447.47421779409063;448.8429049832533;450.4976950699946;3491.720662347057;3943.526776786804;4012.3981402789814;4037.2934736421007;4051.8784436466362;8961.731148065568;10179.491025448699;10513.894861051445;10699.518521666543;10709.839352409721;10644.308119972176;10646.893531064745;10679.999999999845;10681.83977240342;10683.03852653265;8049.919500804917;8201.753947381589;8277.62750676221;8329.292010179082;8348.206920342436;5725.799597014085;5746.741396637078;5778.768849245983;5833.620814647034;5867.413258674099;9385.765355866159;9743.610255589698;9762.60949562038;9936.701898943043;9942.453165075991;9719.319647624598;10042.14641755459;10279;10280.177585793268;10302;6692.163479565054;7031.964840175767;7402.1117465904435;7450.813729656802;7552.509086909334;8076.434649574473;8476.364272679697;8510.191531804468;8575.828483430172;8593.398462107842;6632.436242919406;6674.599524028574;7310.926890731024;7430.851382972419;7541.622918854145;7479.813004674709;7483.363914067365;7498.887516687145;7533.962330188314;7562.470627056274;6952.652367381712;6952.652367381712;6963.1296087989;7057.329553692391;7078.575285482888;4847.75761211931;4939.5554400104165;4963.851084467472;4977.925331119964;5012.649114561946;4942;4987.925181122214;5029.5221953914315;5092.108880945893;5139.408967968582 04:59:16 INFO - PROCESS | 1667 | |4;prototype.html;297.80047368263274;306.9186665533634;312.9859156337965;314.5266374106969;315.8562853901475;4627.814887404504;4737.73942433166;5104.065162531545;5209.270702101709;5390.8921821563645;4613.731223913425;5118.773333599886;5214.217867319904;5407.486288802567;5560.499555040045;3610.729195310349;3755.6432138946893;3935.1736135411597;4052.8581499647516;4147.9792601037025;3644.268972238179;3781.4894989176382;4035.535913369971;4079.4288799568085;4219.873403797891;4134.754217938918;4141.316682747358;4146.274401979642;4159.502579071532;4200.831966721327;3931.2727145478034;4152.647025002879;4169.520505141917;4170.478690163714;4213.494380674339;3574.767640103385;3641.362761516724;3661.761985470936;3675.3751862183494;3716.572594151653;4818.951810481885;5232.764525596357;5262.394824595182;5311;5319.5744340452675;4643;4658.208104622222;4693.835715749967;4704.95295047052;4714.599259062984;4927.901441971177;5290.497402746733;5370.301860758115;5763.1355296705415;5803.7098145093;3179.4277030134567;3879.4180872869297;3899.20636507207;3910.902227444308;3928.624981256566;5198.896022079537;5209.947900520984;5458.426865179178;5813.256023193026;6119.714859879431;3949.2693851636964;3963.583823698528;3980.8805735827973;3997.7801220932547;4023.4769479967417;3851.2463588534333;3937.1928754605237;4013.2174226026227;4068.898277543085;4085.611866872642;4110.260153172458;4641.953580464152;4670.1827180244;4706.670533062721;4709.340692303081;4948;5166.173412254097;5364.497940576644;5804.738786754606;5868.647881127146;1057.1489950589848;1311.9176679239665;1329.7008173160962;1354.0453979944116;1370.4792178971927;787.1262898183012;800.9547540459639;810.8337790752881;824.8699281983601;832.3216578488513;4469.709468884577;4482.798274077674;4616.8614941551805;4620.861374158781;4640.97679511607;4758.357621721023;5026.89946201074;5099.3370861787735;5106.54463477907;5112.182050871843;4182.769947652909;4367.148406060851;4582.381378513925;4635.953640463552;4660.976695116503;2591.041314713568;2729.3586007288272;3182.315802102513;3198.6801319867827;3213.903582892517;6260.373962603703;6293.748250069946;6311.305756366796;6338.862263179663;6355.3644635536075;5336.491848831043;5563.66618002913;6055.515558755289;6256.405641464054;6295.464885484785;6345.682715864281;6345.841353966188;6359.823432664879;6373.3945275198785;6403.743850246031;5226.71253081069;5459.00746227632;5925.881482370414;6039.607425517327;6045.75816967334;5886.470217680429;5902.61632993854;5947.197128387786;5965.821025369246;6012.188354572077;5843.2403787507355;6073.787417440369;6237.688115594111;6366.440222145604;6388.8083357499345;3562.590302115186;3755.6291953437344;3852.6339997700175;3971.72197946141;3983.570022286345;5125.589952803842;5314.441983591667;5555.944440555542;5973.761049557969;6024.969875150597;4985.750712464434;5505.311836020497;5516.9448305516435;5601.80393686219;5768.596198266081;5068.569171620454;5553.944460555343;5786.971065144816;5807.738651760596;5825.50483208932;5204.219367094966;5383.1117865553315;5583.664980101044;5762.1068734346245;5792.768289268382;5036.56457909494;5684.186327767781;5705.543556515552;5766.740496677743;5834.679092649775;4775.952240477551;5666.518345940642;5736.42635736423;5744.569157313302;5759.193712880284 04:59:16 INFO - PROCESS | 1667 | |5;yui.html;678.9015592739053;691.2534462780196;701.6106061136071;746.7871656577877;784.0943710014931;15229.238538073094;15330.69338613227;16186.514404567883;16372;16426.83573164268;12652.936735316334;13988.720225595482;14724.705505889902;14737.115773053625;14864.405423783062;692.7125243024165;876.0407353947418;900.3922352412112;919.9586018629178;920.5121285718579;768.9346405555534;794.5947566740946;883.8983516895576;917.7705573606598;923.7690577355661;795.8806179073127;936.625349860058;972.7081875437341;972.7373609125532;981.7398389426808;925.6621333213396;954.8663187153804;957.9089986451276;964.9614015439374;965.2760429677742;682.011083928306;686.9828254293633;698.4517154034058;716.1585137463512;720.4236610711463;15769.974951628108;15789.21053947298;15952.920235398864;15971.281292341873;15989.040657560583;12740;14848.331825067899;14850.480233191895;14901.180435076067;15006.849931500654;10479.161667066617;12006.339651319175;12325.137240393176;12474.875251247462;12494.062945279049;3822.4075268333454;3853.903652408684;4034.495688038995;4054.1283624020975;4054.310767169559;112998;113131;114290;114324.4283778584;114453;11412.714682133013;11601;11604.535818567332;11643.301401915744;11875.762484750257;23694.644580331307;23938.64092038621;23943.28170154898;23964.04143834262;23976.760232397803;7624.885626715603;8017.959910200411;8077.474964127313;8167.509949403055;8448.535330556879;4481.193385190697;4484.596386325246;4566.223741963874;4575.885602859955;4706.317583950308;3547.0600290922926;3685.4287585424304;3686.594474607791;3692.926141477209;3693.759905606181;670.7618795327588;671.1543455246425;673.0644404278057;680.9182898052266;681.9556728812611;627.159606127791;638.8211300835774;663.4559661077869;664.2304919581356;668.2882729892649;7351.301626345488;8276.586170691562;8482.151784821468;8683.175098365646;8694.608742606599;516.2411255454476;571.2188978252057;620.500497099839;642.7782415066795;655.8983357579583;4339.34909763538;4371.803268852909;4418.911621767611;4485.349624304523;4590.701604395704;148558;149464;150511;152389;153171;80438;92197.53901230585;109270;112626;129312;114584.42707786574;114990;115481.8451815471;116970.9999999983;118030;9586.568604412958;9654.56554455037;9711.4757038728;9749;9768.56041478121;57521.561960949635;58977;59230;60112;61303.693481532304;77506;85546.5913273595;87194.69207961758;94477;96109;13233.602991910258;13430.93284533571;13570.59288221355;13667.658308542366;13889.722205556034;11940.52237910474;14579.198144101752;14916.701665966837;15022.62443438879;15759.921200393925;11342.829857552308;12768.553100641433;13779.379927903468;13983.370748316554;14432.567022989328;10382.896171038194;12272.202306850026;12595.622131336075;12719.236845789283;13015.893649039937;13590.388432520758;13673.384697688827;13700.65748356299;13727.519536816164;14164;11067.446627668423;11189.776204476027;11219.71950701239;11299.277487588715;11594.362310073027;10761.545702204727;11340.432978351215;11430.771384572428;11541.596044138414;11570 04:59:16 INFO - PROCESS | 1667 | __end_tp_report 04:59:16 INFO - PROCESS | 1667 | __start_cc_report 04:59:16 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,426 04:59:16 INFO - PROCESS | 1667 | __end_cc_report 04:59:16 INFO - PROCESS | 1667 | __startTimestamp1464955156848__endTimestamp 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | ------- Summary: start ------- 04:59:16 INFO - PROCESS | 1667 | Number of tests: 6 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5782.62 Median:6080.06 stddev:796.87 (13.1%) stddev-sans-first:769.43 04:59:16 INFO - PROCESS | 1667 | Values: 3027.0 3506.6 3516.7 3532.7 3578.9 5828.1 5845.4 5883.4 5973.1 6042.7 5404.2 5836.8 5878.6 5922.5 5937.1 5241.4 5316.7 5390.6 5396.8 5436.7 146.7 5188.0 5302.3 5356.3 5382.7 5274.8 5277.8 5308.7 5326.3 5353.4 5302.8 5303.5 5328.2 5356.2 5363.8 5297.5 5310.0 5313.4 5329.4 5350.1 5277.9 5683.1 5834.9 5839.6 5883.3 5688.1 5977.5 6217.6 6273.9 6283.7 6227.5 6245.7 6260.7 6299.2 6328.7 5791.5 5942.4 5965.6 6000.4 6080.1 5400.0 6274.9 6301.3 6328.0 6346.0 6251.9 6288.5 6340.1 6356.9 6365.6 5238.3 6233.2 6310.9 6312.1 6314.8 6254.2 6292.8 6300.4 6315.7 6328.3 6291.5 6321.7 6330.7 6333.9 6334.9 4377.8 4590.9 4910.7 4921.9 5019.3 4411.0 4456.9 4458.7 4461.2 4505.8 5814.2 5959.1 5975.3 6006.4 6034.6 6181.8 6232.3 6239.5 6265.0 6290.2 5903.1 5935.4 5946.8 5983.5 6003.6 4951.3 5505.6 5536.2 5556.8 5566.9 6291.1 6373.9 6445.4 6473.9 6492.9 6270.4 6304.0 6310.1 6310.8 6345.6 6289.8 6498.1 6519.9 6526.9 6534.7 6254.4 6288.8 6294.2 6303.5 6338.5 5289.9 6251.4 6300.4 6314.6 6329.4 6105.3 6117.7 6208.3 6283.9 6287.9 5278.4 6191.2 6226.2 6268.0 6278.9 5619.1 6134.1 6214.9 6215.7 6261.7 5114.7 6096.8 6165.4 6184.8 6293.9 6187.8 6220.5 6225.3 6229.3 6231.6 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:75287.48 Median:41443.05 stddev:170562.40 (411.6%) stddev-sans-first:170948.43 04:59:16 INFO - PROCESS | 1667 | Values: 494.2 498.9 501.5 504.8 505.1 38619.0 41828.4 41882.6 41905.6 41930.6 42657.8 43283.8 43406.1 43444.9 43570.0 9422.0 9639.4 9648.6 9667.4 9671.5 8148.0 9487.7 9515.1 9554.8 9586.1 9477.4 9543.7 9549.8 9584.3 9587.2 8104.0 9500.5 9565.6 9577.9 9607.8 9292.6 9338.4 9402.6 9486.1 9492.8 40019.8 40224.0 40289.0 40782.8 40828.4 3012.4 3114.6 3156.6 3219.3 3752.5 22653.9 32072.8 32254.4 32568.3 32601.5 3225.5 3369.8 3384.6 3387.1 3392.9 492843.0 526911.0 535070.3 535400.6 543712.0 58021.4 59251.4 59477.8 59587.4 59621.5 60452.9 60559.4 60584.4 60851.7 60969.8 14245.2 16282.8 16369.6 16572.8 16613.8 11479.8 11751.5 12702.0 12996.9 13009.9 16526.7 16689.0 16694.4 16732.6 16748.5 5494.6 6385.2 6451.3 6460.2 6540.8 929.0 972.3 973.9 1011.0 1158.6 27557.3 28751.7 30204.7 30458.7 30569.8 1082.9 1086.3 1095.6 1096.3 1170.3 14861.8 15593.5 15627.9 15662.7 15733.9 653156.0 677815.0 723377.0 732948.0 926484.0 60941.4 61378.4 61849.1 61901.7 61932.4 1344.7 1437.6 1463.5 1473.7 1477.9 30780.2 30960.7 31487.5 31582.7 31740.0 51151.0 51552.0 52192.2 52204.7 52281.5 608151.0 611902.0 612036.0 612064.0 613230.0 25527.6 25625.9 26021.7 26288.3 26394.7 40181.2 47365.0 49596.8 49815.5 49944.3 35665.0 39592.4 40907.8 41619.6 42065.0 40530.8 40841.7 41046.6 41377.2 41508.9 38879.6 39061.2 39799.0 39882.0 39926.0 34940.8 35116.6 35516.3 35622.5 35740.7 25505.0 25671.4 25931.4 26140.3 26347.3 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34284.33 Median:26115.11 stddev:93619.20 (358.5%) stddev-sans-first:93847.19 04:59:16 INFO - PROCESS | 1667 | Values: 369.9 380.0 380.4 384.7 398.0 19079.3 20632.5 21207.0 21261.0 21291.6 20718.5 21435.0 21614.7 21632.0 21855.7 5254.0 5686.2 5931.1 5943.6 5953.5 5846.4 5863.7 5900.5 5900.6 5924.1 5469.0 5895.1 5907.5 5955.4 5975.3 5842.9 5844.2 5897.8 6089.5 6120.9 5792.6 5837.9 5856.5 5871.1 5873.9 25145.0 25265.2 26016.0 26042.6 26187.6 2128.6 2155.3 2206.1 2303.5 2455.5 9720.6 10419.3 12549.5 12880.5 14259.7 1858.7 1873.2 1878.5 1908.2 2123.9 73479.6 114826.0 120218.0 123330.4 124753.0 9310.6 10189.6 11818.0 11834.6 12013.5 14618.9 14715.3 14724.4 14740.3 14771.2 8569.1 8578.9 8669.8 8685.1 8717.0 7066.4 8403.7 8564.5 8655.4 8675.9 4465.8 4602.2 4623.5 4629.2 4683.7 1854.1 1945.6 1986.4 1993.2 1995.7 845.1 891.9 902.6 994.3 1019.5 16785.7 19647.3 21103.8 21418.1 21467.5 928.7 930.6 954.6 972.5 984.9 7745.6 11407.8 11432.0 11464.9 11515.8 511893.0 519084.0 528088.0 607857.0 608059.0 122542.0 132776.0 140405.0 140533.0 143078.3 997.1 1002.1 1032.0 1048.5 1055.9 15537.4 19189.0 19389.8 19469.4 19658.5 56170.0 56764.0 58540.0 58580.0 58861.8 79961.0 83155.6 89199.0 89730.1 90735.0 6984.8 8092.3 8153.8 8169.9 8257.2 11378.7 13072.4 13160.0 13242.9 13243.7 8994.6 11210.7 11453.1 11453.1 11554.9 9849.3 11347.6 11360.5 11637.5 11681.7 9849.4 11026.0 11770.0 11788.9 11807.6 5695.4 7503.4 7559.0 7572.3 8488.2 8381.9 11312.9 11630.0 11679.2 11851.3 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5691.98 Median:4839.84 stddev:3212.55 (66.4%) stddev-sans-first:3206.41 04:59:16 INFO - PROCESS | 1667 | Values: 1539.5 1594.0 1597.4 1935.4 1956.7 8015.0 8603.1 8618.3 9109.0 9152.0 8711.5 8784.9 8916.5 8941.6 8952.1 2327.6 2330.1 2359.9 2360.9 2364.6 2327.6 2346.1 2395.5 2399.3 2408.4 2343.8 2387.7 2402.4 2403.4 2407.6 2330.3 2353.4 2357.4 2366.4 2371.6 2276.7 2293.8 2305.6 2311.1 2319.5 8840.2 8885.7 8944.8 8946.7 8953.6 4297.5 4619.3 4789.7 5356.3 5416.0 3547.4 3604.6 3668.3 3693.6 3713.7 1073.2 1088.3 1098.1 1100.6 1102.2 11783.5 11810.8 11935.5 11950.4 11954.6 4716.9 4736.2 4831.9 4854.0 4863.1 10047.3 10093.8 10099.0 10117.4 10122.4 5309.6 5318.4 5419.5 5423.2 5439.4 4435.6 4756.0 4914.3 4929.1 4948.6 3934.9 3956.6 3987.9 4070.8 4090.8 1295.7 1322.2 1323.5 1328.6 1331.7 800.8 819.8 873.5 932.4 970.9 5190.9 5664.6 5805.1 5928.6 5950.7 429.2 434.2 447.5 448.8 450.5 3491.7 3943.5 4012.4 4037.3 4051.9 8961.7 10179.5 10513.9 10699.5 10709.8 10644.3 10646.9 10680.0 10681.8 10683.0 8049.9 8201.8 8277.6 8329.3 8348.2 5725.8 5746.7 5778.8 5833.6 5867.4 9385.8 9743.6 9762.6 9936.7 9942.5 9719.3 10042.1 10279.0 10280.2 10302.0 6692.2 7032.0 7402.1 7450.8 7552.5 8076.4 8476.4 8510.2 8575.8 8593.4 6632.4 6674.6 7310.9 7430.9 7541.6 7479.8 7483.4 7498.9 7534.0 7562.5 6952.7 6952.7 6963.1 7057.3 7078.6 4847.8 4939.6 4963.9 4977.9 5012.6 4942.0 4987.9 5029.5 5092.1 5139.4 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4602.30 Median:5052.57 stddev:1445.20 (28.6%) stddev-sans-first:1412.68 04:59:16 INFO - PROCESS | 1667 | Values: 297.8 306.9 313.0 314.5 315.9 4627.8 4737.7 5104.1 5209.3 5390.9 4613.7 5118.8 5214.2 5407.5 5560.5 3610.7 3755.6 3935.2 4052.9 4148.0 3644.3 3781.5 4035.5 4079.4 4219.9 4134.8 4141.3 4146.3 4159.5 4200.8 3931.3 4152.6 4169.5 4170.5 4213.5 3574.8 3641.4 3661.8 3675.4 3716.6 4819.0 5232.8 5262.4 5311.0 5319.6 4643.0 4658.2 4693.8 4705.0 4714.6 4927.9 5290.5 5370.3 5763.1 5803.7 3179.4 3879.4 3899.2 3910.9 3928.6 5198.9 5209.9 5458.4 5813.3 6119.7 3949.3 3963.6 3980.9 3997.8 4023.5 3851.2 3937.2 4013.2 4068.9 4085.6 4110.3 4642.0 4670.2 4706.7 4709.3 4948.0 5166.2 5364.5 5804.7 5868.6 1057.1 1311.9 1329.7 1354.0 1370.5 787.1 801.0 810.8 824.9 832.3 4469.7 4482.8 4616.9 4620.9 4641.0 4758.4 5026.9 5099.3 5106.5 5112.2 4182.8 4367.1 4582.4 4636.0 4661.0 2591.0 2729.4 3182.3 3198.7 3213.9 6260.4 6293.7 6311.3 6338.9 6355.4 5336.5 5563.7 6055.5 6256.4 6295.5 6345.7 6345.8 6359.8 6373.4 6403.7 5226.7 5459.0 5925.9 6039.6 6045.8 5886.5 5902.6 5947.2 5965.8 6012.2 5843.2 6073.8 6237.7 6366.4 6388.8 3562.6 3755.6 3852.6 3971.7 3983.6 5125.6 5314.4 5555.9 5973.8 6025.0 4985.8 5505.3 5516.9 5601.8 5768.6 5068.6 5553.9 5787.0 5807.7 5825.5 5204.2 5383.1 5583.7 5762.1 5792.8 5036.6 5684.2 5705.5 5766.7 5834.7 4776.0 5666.5 5736.4 5744.6 5759.2 04:59:16 INFO - PROCESS | 1667 | 04:59:16 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:24457.38 Median:3689.76 stddev:38653.48 (1047.6%) stddev-sans-first:38720.68 04:59:16 INFO - PROCESS | 1667 | Values: 678.9 691.3 701.6 746.8 784.1 15229.2 15330.7 16186.5 16372.0 16426.8 12652.9 13988.7 14724.7 14737.1 14864.4 692.7 876.0 900.4 920.0 920.5 768.9 794.6 883.9 917.8 923.8 795.9 936.6 972.7 972.7 981.7 925.7 954.9 957.9 965.0 965.3 682.0 687.0 698.5 716.2 720.4 15770.0 15789.2 15952.9 15971.3 15989.0 12740.0 14848.3 14850.5 14901.2 15006.8 10479.2 12006.3 12325.1 12474.9 12494.1 3822.4 3853.9 4034.5 4054.1 4054.3 112998.0 113131.0 114290.0 114324.4 114453.0 11412.7 11601.0 11604.5 11643.3 11875.8 23694.6 23938.6 23943.3 23964.0 23976.8 7624.9 8018.0 8077.5 8167.5 8448.5 4481.2 4484.6 4566.2 4575.9 4706.3 3547.1 3685.4 3686.6 3692.9 3693.8 670.8 671.2 673.1 680.9 682.0 627.2 638.8 663.5 664.2 668.3 7351.3 8276.6 8482.2 8683.2 8694.6 516.2 571.2 620.5 642.8 655.9 4339.3 4371.8 4418.9 4485.3 4590.7 148558.0 149464.0 150511.0 152389.0 153171.0 80438.0 92197.5 109270.0 112626.0 129312.0 114584.4 114990.0 115481.8 116971.0 118030.0 9586.6 9654.6 9711.5 9749.0 9768.6 57521.6 58977.0 59230.0 60112.0 61303.7 77506.0 85546.6 87194.7 94477.0 96109.0 13233.6 13430.9 13570.6 13667.7 13889.7 11940.5 14579.2 14916.7 15022.6 15759.9 11342.8 12768.6 13779.4 13983.4 14432.6 10382.9 12272.2 12595.6 12719.2 13015.9 13590.4 13673.4 13700.7 13727.5 14164.0 11067.4 11189.8 11219.7 11299.3 11594.4 10761.5 11340.4 11430.8 11541.6 11570.0 04:59:16 INFO - PROCESS | 1667 | -------- Summary: end -------- 04:59:16 INFO - PROCESS | 1667 | 04:59:17 INFO - TEST-INFO | 1667: exit 0 04:59:17 INFO - TEST-OK | dromaeo_css | took 1112237ms 04:59:17 INFO - TEST-START | kraken 04:59:17 INFO - operating with platform_type : mac_ 04:59:17 INFO - Initialising browser for kraken test... 04:59:17 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpmes2Nx/profile http://localhost:49270/getInfo.html) 04:59:20 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 04:59:20 INFO - PROCESS | 1679 | colorDepth:24 04:59:20 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 04:59:20 INFO - PROCESS | 1679 | __metrics 04:59:20 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 04:59:21 INFO - TEST-INFO | 1679: exit 0 04:59:21 INFO - Browser initialized. 04:59:21 INFO - Running cycle 1/1 for kraken test... 04:59:21 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpmes2Nx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:33 INFO - PROCESS | 1681 | RSS: Main: 253153280 04:59:33 INFO - PROCESS | 1681 | 04:59:35 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 04:59:36 INFO - PROCESS | 1681 | RSS: Main: 265801728 04:59:36 INFO - PROCESS | 1681 | 04:59:39 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 04:59:40 INFO - PROCESS | 1681 | RSS: Main: 269762560 04:59:40 INFO - PROCESS | 1681 | 04:59:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 04:59:44 INFO - PROCESS | 1681 | RSS: Main: 270315520 04:59:44 INFO - PROCESS | 1681 | 04:59:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 04:59:46 INFO - PROCESS | 1681 | RSS: Main: 271228928 04:59:46 INFO - PROCESS | 1681 | 04:59:48 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:59:49 INFO - PROCESS | 1681 | RSS: Main: 271892480 04:59:49 INFO - PROCESS | 1681 | 04:59:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:53 INFO - PROCESS | 1681 | RSS: Main: 272203776 04:59:53 INFO - PROCESS | 1681 | 04:59:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:56 INFO - PROCESS | 1681 | RSS: Main: 272388096 04:59:56 INFO - PROCESS | 1681 | 04:59:59 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:00:00 INFO - PROCESS | 1681 | RSS: Main: 272441344 05:00:00 INFO - PROCESS | 1681 | 05:00:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:00:02 INFO - PROCESS | 1681 | RSS: Main: 272396288 05:00:02 INFO - PROCESS | 1681 | 05:00:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:00:05 INFO - PROCESS | 1681 | RSS: Main: 272302080 05:00:05 INFO - PROCESS | 1681 | 05:00:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:00:08 INFO - PROCESS | 1681 | RSS: Main: 272363520 05:00:08 INFO - PROCESS | 1681 | 05:00:11 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:00:11 INFO - PROCESS | 1681 | RSS: Main: 272404480 05:00:11 INFO - PROCESS | 1681 | 05:00:14 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:00:15 INFO - PROCESS | 1681 | RSS: Main: 272334848 05:00:15 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:00:17 INFO - PROCESS | 1681 | RSS: Main: 273326080 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | __start_tp_report 05:00:17 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 05:00:17 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 05:00:17 INFO - PROCESS | 1681 | |i|pagename|runs| 05:00:17 INFO - PROCESS | 1681 | |0;ai-astar;116;110;115;120;115;118;137;150;116;117 05:00:17 INFO - PROCESS | 1681 | |1;audio-beat-detection;153;120;143;161;122;144;149;146;201;106 05:00:17 INFO - PROCESS | 1681 | |2;audio-dft;195;203;210;233;199;194;197;194;200;196 05:00:17 INFO - PROCESS | 1681 | |3;audio-fft;60;114;85;155;55;61;81;84;144;64 05:00:17 INFO - PROCESS | 1681 | |4;audio-oscillator;69;78;125;68;72;85;110;69;75;113 05:00:17 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;91;89;91;86;135;121;92;92;121;87 05:00:17 INFO - PROCESS | 1681 | |6;imaging-darkroom;149;140;97;103;150;99;98;108;152;124 05:00:17 INFO - PROCESS | 1681 | |7;imaging-desaturate;106;80;144;104;84;146;145;93;92;91 05:00:17 INFO - PROCESS | 1681 | |8;json-parse-financial;56;57;55;57;65;81;85;84;82;61 05:00:17 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;66;73;75;76;86;82;75;70;88;72 05:00:17 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;91;89;88;86;86;87;86;87;116;109 05:00:17 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;138;162;138;148;156;148;159;149;159;159 05:00:17 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;168;168;169;164;167;164;165;169;165;188 05:00:17 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;69;67;67;69;66;69;65;68;65 05:00:17 INFO - PROCESS | 1681 | __end_tp_report 05:00:17 INFO - PROCESS | 1681 | __start_cc_report 05:00:17 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1099 05:00:17 INFO - PROCESS | 1681 | __end_cc_report 05:00:17 INFO - PROCESS | 1681 | __startTimestamp1464955217330__endTimestamp 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | ------- Summary: start ------- 05:00:17 INFO - PROCESS | 1681 | Number of tests: 14 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:121.40 Median:117.50 stddev:12.31 (10.5%) stddev-sans-first:12.90 05:00:17 INFO - PROCESS | 1681 | Values: 116.0 110.0 115.0 120.0 115.0 118.0 137.0 150.0 116.0 117.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:147.50 stddev:26.14 (17.7%) stddev-sans-first:27.55 05:00:17 INFO - PROCESS | 1681 | Values: 153.0 120.0 143.0 161.0 122.0 144.0 149.0 146.0 201.0 106.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:202.10 Median:199.50 stddev:11.91 (6.0%) stddev-sans-first:12.35 05:00:17 INFO - PROCESS | 1681 | Values: 195.0 203.0 210.0 233.0 199.0 194.0 197.0 194.0 200.0 196.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:90.30 Median:62.50 stddev:35.75 (57.2%) stddev-sans-first:36.19 05:00:17 INFO - PROCESS | 1681 | Values: 60.0 114.0 85.0 155.0 55.0 61.0 81.0 84.0 144.0 64.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:70.50 stddev:21.37 (30.3%) stddev-sans-first:21.71 05:00:17 INFO - PROCESS | 1681 | Values: 69.0 78.0 125.0 68.0 72.0 85.0 110.0 69.0 75.0 113.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:90.00 stddev:17.89 (19.9%) stddev-sans-first:18.64 05:00:17 INFO - PROCESS | 1681 | Values: 91.0 89.0 91.0 86.0 135.0 121.0 92.0 92.0 121.0 87.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:122.00 Median:151.00 stddev:23.64 (15.7%) stddev-sans-first:22.96 05:00:17 INFO - PROCESS | 1681 | Values: 149.0 140.0 97.0 103.0 150.0 99.0 98.0 108.0 152.0 124.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:82.00 stddev:26.37 (32.2%) stddev-sans-first:27.95 05:00:17 INFO - PROCESS | 1681 | Values: 106.0 80.0 144.0 104.0 84.0 146.0 145.0 93.0 92.0 91.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:73.00 stddev:13.00 (17.8%) stddev-sans-first:13.01 05:00:17 INFO - PROCESS | 1681 | Values: 56.0 57.0 55.0 57.0 65.0 81.0 85.0 84.0 82.0 61.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:75.50 stddev:7.01 (9.3%) stddev-sans-first:6.37 05:00:17 INFO - PROCESS | 1681 | Values: 66.0 73.0 75.0 76.0 86.0 82.0 75.0 70.0 88.0 72.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:87.00 stddev:10.78 (12.4%) stddev-sans-first:11.42 05:00:17 INFO - PROCESS | 1681 | Values: 91.0 89.0 88.0 86.0 86.0 87.0 86.0 87.0 116.0 109.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:157.50 stddev:8.78 (5.6%) stddev-sans-first:7.82 05:00:17 INFO - PROCESS | 1681 | Values: 138.0 162.0 138.0 148.0 156.0 148.0 159.0 149.0 159.0 159.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:168.00 stddev:7.06 (4.2%) stddev-sans-first:7.48 05:00:17 INFO - PROCESS | 1681 | Values: 168.0 168.0 169.0 164.0 167.0 164.0 165.0 169.0 165.0 188.0 05:00:17 INFO - PROCESS | 1681 | 05:00:17 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.57 (2.3%) stddev-sans-first:1.64 05:00:17 INFO - PROCESS | 1681 | Values: 68.0 69.0 67.0 67.0 69.0 66.0 69.0 65.0 68.0 65.0 05:00:17 INFO - PROCESS | 1681 | -------- Summary: end -------- 05:00:17 INFO - PROCESS | 1681 | 05:00:18 INFO - TEST-INFO | 1681: exit 0 05:00:18 INFO - TEST-OK | kraken | took 60805ms 05:00:18 INFO - SUITE-END | took 1173s 05:00:18 INFO - Completed test suite (00:19:33) 05:00:18 INFO - javascript benchmark 05:00:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7392.324744548752, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3026.9848650756744, 3506.614272430033, 3516.718662507, 3532.6997205237553, 3578.910527236819, 5828.096645020023, 5845.4446827551365, 5883.352831188566, 5973.074173503103, 6042.728077236524, 5404.190046017558, 5836.795712150076, 5878.588498805086, 5922.496587790032, 5937.050071988483, 5241.39723931748, 5316.680999140045, 5390.56875449965, 5396.78412863485, 5436.673799572019, 146.7070412152835, 5187.974060129713, 5302.284191634138, 5356.330458692663, 5382.6770393776305, 5274.8417547473255, 5277.8416647500635, 5308.654937429074, 5326.280952071479, 5353.437889021675, 5302.840914772563, 5303.469653034705, 5328.20076988451, 5356.1697936819855, 5363.758630861621, 5297.549708274802, 5310.0, 5313.415524292324, 5329.413764485904, 5350.117230656976, 5277.920831187535, 5683.147527870811, 5834.854128646775, 5839.649621022752, 5883.294004719461, 5688.089905615123, 5977.521798256173, 6217.595856269328, 6273.905891411632, 6283.654399008053, 6227.498394076296, 6245.687715614201, 6260.718267677966, 6299.181106456132, 6328.715207815613, 5791.536677065825, 5942.435468630473, 5965.642061476326, 6000.369961154103, 6080.0575910733905, 5399.973000134974, 6274.905876411857, 6301.306856245809, 6327.96836015817, 6346.0, 6251.874962500725, 6288.496920246369, 6340.144080549159, 6356.936430635635, 6365.649889256136, 5238.292830467914, 6233.2208473940755, 6310.905336419957, 6312.116303717483, 6314.778982735582, 6254.15568898202, 6292.811215663537, 6300.432961033529, 6315.747370105144, 6328.272248691367, 6291.5281353898645, 6321.747130114836, 6330.715117819709, 6333.904991425133, 6334.873302533923, 4377.796106070856, 4590.931136032962, 4910.6808057476865, 4921.926171107436, 5019.347484826949, 4410.977945110254, 4456.866294011184, 4458.710183838105, 4461.219286624828, 4505.797239124247, 5814.156947242626, 5959.135925290809, 5975.25309336333, 6006.429389208106, 6034.607750496291, 6181.84545386369, 6232.283287422005, 6239.500839932794, 6265.0, 6290.150829638032, 5903.085021821625, 5935.376785437467, 5946.791862284799, 5983.461488466059, 6003.6397816131175, 4951.282064100686, 5505.587080968863, 5536.197251398525, 5556.805511807148, 5566.888662226814, 6291.087792270187, 6373.936260637334, 6445.4199122079235, 6473.935260647428, 6492.870142597028, 6270.404311590391, 6303.968480157753, 6310.05349197624, 6310.7791227306825, 6345.587536810092, 6289.779857705142, 6498.057781621733, 6519.902201467076, 6526.902096468461, 6534.673266336569, 6254.3745625437095, 6288.779892703734, 6294.181756371826, 6303.527235457451, 6338.492920566436, 5289.894202116013, 6251.437370636664, 6300.401461861115, 6314.62112273265, 6329.367063293818, 6105.267367915879, 6117.736059405397, 6208.255009398901, 6283.905741413791, 6287.905681414691, 5278.36659600835, 6191.1641928339095, 6226.221722284715, 6268.0, 6278.937210627835, 5619.129034999418, 6134.110553969738, 6214.937850621436, 6215.720292586935, 6261.749530018748, 5114.667546609458, 6096.829073522031, 6165.3526379731275, 6184.81445556634, 6293.874122517615, 6187.752489900354, 6220.533459990609, 6225.284092329259, 6229.283632382153, 6231.594946328474], "value": 5734.819970697423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.16486138426035, 498.85283841266823, 501.5486062543711, 504.7526711911163, 505.1185680986674, 38619.0, 41828.372574411405, 41882.581174188286, 41905.58094419055, 41930.58069419305, 42657.7867110664, 43283.78358108213, 43406.13187736244, 43444.913877153085, 43570.0, 9421.952890235556, 9639.373440726366, 9648.565814538328, 9667.419954802699, 9671.468069256189, 8148.0, 9487.715368538955, 9515.096065873731, 9554.808903821919, 9586.13724764771, 9477.43135411874, 9543.713688589318, 9549.809003819919, 9584.329096963216, 9587.185089267421, 8103.95948020259, 9500.524973751319, 9565.617375304979, 9577.904220957771, 9607.759806004906, 9292.628294868266, 9338.439693618404, 9402.62389504419, 9486.051394860528, 9492.762680933032, 40019.79990100031, 40224.0, 40289.0, 40782.77651670454, 40828.38757418641, 3012.448588976679, 3114.5795317632283, 3156.6054243219596, 3219.307848812493, 3752.5496940367057, 22653.886730566406, 32072.839635801905, 32254.354912901847, 32568.348633027443, 32601.510977335358, 3225.451673215559, 3369.7978121312553, 3384.6107697614843, 3387.0685561470546, 3392.86428542858, 492843.0, 526911.0, 535070.3246483782, 535400.645993539, 543712.0, 58021.419785801605, 59251.407485925454, 59477.810443790884, 59587.40412595905, 59621.50946226379, 60452.86150677154, 60559.39440605538, 60584.394156058755, 60851.69574152189, 60969.78060438766, 14245.21651309188, 16282.75575866363, 16369.59076023085, 16572.75140872888, 16613.750793738105, 11479.770404591862, 11751.47118379675, 12702.0, 12996.870031299755, 13009.934950325378, 16526.66946661084, 16689.0, 16694.4156954506, 16732.58168545796, 16748.497545073667, 5494.5549230592005, 6385.233771947396, 6451.258105317855, 6460.192475940507, 6540.771073012517, 928.9907100929039, 972.2562239886493, 973.9318247722733, 1011.039512463163, 1158.6350299655583, 27557.31106722348, 28751.712482874904, 30204.697953020626, 30458.69541304603, 30569.847150764548, 1082.9099837314059, 1086.2830531848942, 1095.5891540672249, 1096.3038470571244, 1170.303669316755, 14861.777073343908, 15593.454229102153, 15627.921860390625, 15662.686746265239, 15733.921330393276, 653156.0, 677815.0, 723377.0, 732948.0, 926484.0, 60941.39058609357, 61378.38621613816, 61849.072263916074, 61901.690491547255, 61932.38067619356, 1344.7243315120377, 1437.6118448018976, 1463.5024091808837, 1473.7325899726227, 1477.8891583131308, 30780.230494236926, 30960.69039309578, 31487.52768708517, 31582.684173157973, 31740.0, 51151.0, 51552.0, 52192.21711674404, 52204.73897630488, 52281.47718522918, 608151.0, 611902.0, 612036.0, 612064.0, 613230.0, 25527.6170857441, 25625.871870640527, 26021.739782601933, 26288.34279143037, 26394.73605263923, 40181.1963760729, 47365.0, 49596.75201623969, 49815.51660350586, 49944.25083623676, 35665.0, 39592.40611390889, 40907.77276681704, 41619.58380416157, 42065.0, 40530.79734601308, 40841.695892218624, 41046.58953410428, 41377.1724565501, 41508.92455377158, 38879.63921262742, 39061.21877562376, 39799.0, 39882.00294992532, 39926.0, 34940.82529587336, 35116.64883351134, 35516.28967420585, 35622.465663014555, 35740.749073783336, 25505.0, 25671.379151752863, 25931.351716206493, 26140.34649133787, 26347.341316467242], "value": 20121.663652769654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.8520591763294, 379.99620003799964, 380.41416219022705, 384.70954429405805, 398.02285389369104, 19079.332223372185, 20632.48418789531, 21207.0, 21261.0, 21291.57416851664, 20718.48203794906, 21435.0, 21614.675779863315, 21632.026558804853, 21855.672164917538, 5254.0, 5686.232358631594, 5931.051031834886, 5943.613665111776, 5953.523718102541, 5846.415358464162, 5863.736131874055, 5900.52795776339, 5900.64596124234, 5924.081767326072, 5469.0, 5895.086261629433, 5907.4978626816555, 5955.434233747787, 5975.282966044081, 5842.941570584282, 5844.1818145459665, 5897.793577224798, 6089.543284253698, 6120.908186377208, 5792.565557583156, 5837.9124313135335, 5856.502197313233, 5871.148683440877, 5873.85315367115, 25145.0, 25265.242042738748, 26016.0, 26042.609360859602, 26187.607185892226, 2128.595566842295, 2155.277981876073, 2206.090037412366, 2303.4817166137655, 2455.533448644752, 9720.562574684156, 10419.270651054429, 12549.498020079185, 12880.549180778627, 14259.714805703932, 1858.6933156029238, 1873.175802646831, 1878.4985918711, 1908.1604094198647, 2123.883186424746, 73479.63260183718, 114826.0, 120218.0, 123330.38334808269, 124753.0, 9310.627574896927, 10189.643362482277, 11818.0, 11834.644960651194, 12013.459394327276, 14618.926905365617, 14715.337809798584, 14724.411023558938, 14740.262986850614, 14771.187584682732, 8569.143085691505, 8578.914210857936, 8669.783255418664, 8685.131486851264, 8716.951516393643, 7066.434685225169, 8403.663853445794, 8564.52895090776, 8655.394122411371, 8675.913240867636, 4465.843695470643, 4602.171609110393, 4623.491415944243, 4629.189891768927, 4683.7423941682855, 1854.147092337513, 1945.5525229197365, 1986.3941497843296, 1993.2126809910005, 1995.7305763722002, 845.1421806866036, 891.8617614269799, 902.6299217320998, 994.3238597753452, 1019.4902548725637, 16785.66428671444, 19647.312344068174, 21103.788962110488, 21418.143274269165, 21467.463313416978, 928.7445952363154, 930.6044930904338, 954.6324665003884, 972.4635076578988, 984.8571957066185, 7745.6127193640095, 11407.828882566768, 11432.0, 11464.885351146548, 11515.76968460626, 511893.0, 519084.0, 528088.0, 607857.0, 608059.0, 122542.0, 132776.0, 140405.0, 140533.0, 143078.2846085763, 997.1424574865609, 1002.0730823987782, 1031.9948400257952, 1048.5124417146064, 1055.8996895294936, 15537.378504859678, 19189.040547972825, 19389.836609803457, 19469.415917522496, 19658.50853728668, 56170.0, 56764.0, 58540.0, 58580.0, 58861.822763543634, 79961.0, 83155.58422208093, 89199.0, 89730.10269897217, 90735.0, 6984.825379365557, 8092.312153467021, 8153.8369232616205, 8169.937908071913, 8257.174282571936, 11378.715532111764, 13072.411741471466, 13160.0, 13242.933785331012, 13243.66890827737, 8994.550272486218, 11210.719732006764, 11453.083753299883, 11453.141014423785, 11554.884451155716, 9849.310548261554, 11347.602833900773, 11360.488778004845, 11637.5344986203, 11681.707957301136, 9849.409035457895, 11026.0, 11769.99595309606, 11788.941055295012, 11807.586734464252, 5695.401982791747, 7503.362214211853, 7559.0, 7572.280633339824, 8488.236058754741, 8381.916180838114, 11312.886871131182, 11630.0, 11679.240849344766, 11851.348175850066], "value": 9657.364997127548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.4534940096266, 1593.9760903586446, 1597.4249270262703, 1935.3710044235627, 1956.7162761399607, 8014.95992520038, 8603.139686031394, 8618.310535157188, 9109.0, 9151.954240228797, 8711.520866352348, 8784.93554014806, 8916.509591972439, 8941.597628106734, 8952.104789521045, 2327.627579587266, 2330.0912644068867, 2359.917402890899, 2360.9291721248305, 2364.586197415454, 2327.592671282527, 2346.143657564985, 2395.4788709169716, 2399.2562305685206, 2408.4219787251022, 2343.7773411525877, 2387.7015373078366, 2402.4354276744953, 2403.375122468153, 2407.626817843237, 2330.3125577954543, 2353.364591560278, 2357.3635118518077, 2366.3965688749345, 2371.5612611666897, 2276.658501224813, 2293.8050265727434, 2305.631099024148, 2311.144876395744, 2319.478117423583, 8840.248578870804, 8885.733427997171, 8944.776380590472, 8946.731598052069, 8953.641854325819, 4297.484301883794, 4619.330197121399, 4789.688670236458, 5356.276902618136, 5416.0, 3547.3614749345106, 3604.567451905762, 3668.321360548307, 3693.649103335179, 3713.6657700806786, 1073.1575713065206, 1088.3198001249218, 1098.1050443888205, 1100.6037826382496, 1102.1513434655271, 11783.528658853636, 11810.822837657443, 11935.462904169246, 11950.402479876058, 11954.58158964432, 4716.9056618867435, 4736.242201247784, 4831.9275210871865, 4854.0, 4863.148948933993, 10047.346922450164, 10093.798124037477, 10099.0, 10117.443540605338, 10122.392656440637, 5309.601779866525, 5318.388385335658, 5419.539339156137, 5423.159410291411, 5439.401665816757, 4435.6229720473475, 4756.0, 4914.287428322873, 4929.137400954819, 4948.62885283598, 3934.9213015740097, 3956.643902048829, 3987.860424885115, 4070.8371665133654, 4090.795460226977, 1295.7473292707832, 1322.2000689582856, 1323.4639970811836, 1328.581496828496, 1331.73365326935, 800.7757827808223, 819.815541503157, 873.4628203654706, 932.4032619123766, 970.8980557041409, 5190.896182076337, 5664.631798933056, 5805.129230615357, 5928.644281343047, 5950.702464876739, 429.1695569073852, 434.218406867637, 447.47421779409063, 448.8429049832533, 450.4976950699946, 3491.720662347057, 3943.526776786804, 4012.3981402789814, 4037.2934736421007, 4051.8784436466362, 8961.731148065568, 10179.491025448699, 10513.894861051445, 10699.518521666543, 10709.839352409721, 10644.308119972176, 10646.893531064745, 10679.999999999845, 10681.83977240342, 10683.03852653265, 8049.919500804917, 8201.753947381589, 8277.62750676221, 8329.292010179082, 8348.206920342436, 5725.799597014085, 5746.741396637078, 5778.768849245983, 5833.620814647034, 5867.413258674099, 9385.765355866159, 9743.610255589698, 9762.60949562038, 9936.701898943043, 9942.453165075991, 9719.319647624598, 10042.14641755459, 10279.0, 10280.177585793268, 10302.0, 6692.163479565054, 7031.964840175767, 7402.1117465904435, 7450.813729656802, 7552.509086909334, 8076.434649574473, 8476.364272679697, 8510.191531804468, 8575.828483430172, 8593.398462107842, 6632.436242919406, 6674.599524028574, 7310.926890731024, 7430.851382972419, 7541.622918854145, 7479.813004674709, 7483.363914067365, 7498.887516687145, 7533.962330188314, 7562.470627056274, 6952.652367381712, 6952.652367381712, 6963.1296087989, 7057.329553692391, 7078.575285482888, 4847.75761211931, 4939.5554400104165, 4963.851084467472, 4977.925331119964, 5012.649114561946, 4942.0, 4987.925181122214, 5029.5221953914315, 5092.108880945893, 5139.408967968582], "value": 4502.520724365327, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.80047368263274, 306.9186665533634, 312.9859156337965, 314.5266374106969, 315.8562853901475, 4627.814887404504, 4737.73942433166, 5104.065162531545, 5209.270702101709, 5390.8921821563645, 4613.731223913425, 5118.773333599886, 5214.217867319904, 5407.486288802567, 5560.499555040045, 3610.729195310349, 3755.6432138946893, 3935.1736135411597, 4052.8581499647516, 4147.9792601037025, 3644.268972238179, 3781.4894989176382, 4035.535913369971, 4079.4288799568085, 4219.873403797891, 4134.754217938918, 4141.316682747358, 4146.274401979642, 4159.502579071532, 4200.831966721327, 3931.2727145478034, 4152.647025002879, 4169.520505141917, 4170.478690163714, 4213.494380674339, 3574.767640103385, 3641.362761516724, 3661.761985470936, 3675.3751862183494, 3716.572594151653, 4818.951810481885, 5232.764525596357, 5262.394824595182, 5311.0, 5319.5744340452675, 4643.0, 4658.208104622222, 4693.835715749967, 4704.95295047052, 4714.599259062984, 4927.901441971177, 5290.497402746733, 5370.301860758115, 5763.1355296705415, 5803.7098145093, 3179.4277030134567, 3879.4180872869297, 3899.20636507207, 3910.902227444308, 3928.624981256566, 5198.896022079537, 5209.947900520984, 5458.426865179178, 5813.256023193026, 6119.714859879431, 3949.2693851636964, 3963.583823698528, 3980.8805735827973, 3997.7801220932547, 4023.4769479967417, 3851.2463588534333, 3937.1928754605237, 4013.2174226026227, 4068.898277543085, 4085.611866872642, 4110.260153172458, 4641.953580464152, 4670.1827180244, 4706.670533062721, 4709.340692303081, 4948.0, 5166.173412254097, 5364.497940576644, 5804.738786754606, 5868.647881127146, 1057.1489950589848, 1311.9176679239665, 1329.7008173160962, 1354.0453979944116, 1370.4792178971927, 787.1262898183012, 800.9547540459639, 810.8337790752881, 824.8699281983601, 832.3216578488513, 4469.709468884577, 4482.798274077674, 4616.8614941551805, 4620.861374158781, 4640.97679511607, 4758.357621721023, 5026.89946201074, 5099.3370861787735, 5106.54463477907, 5112.182050871843, 4182.769947652909, 4367.148406060851, 4582.381378513925, 4635.953640463552, 4660.976695116503, 2591.041314713568, 2729.3586007288272, 3182.315802102513, 3198.6801319867827, 3213.903582892517, 6260.373962603703, 6293.748250069946, 6311.305756366796, 6338.862263179663, 6355.3644635536075, 5336.491848831043, 5563.66618002913, 6055.515558755289, 6256.405641464054, 6295.464885484785, 6345.682715864281, 6345.841353966188, 6359.823432664879, 6373.3945275198785, 6403.743850246031, 5226.71253081069, 5459.00746227632, 5925.881482370414, 6039.607425517327, 6045.75816967334, 5886.470217680429, 5902.61632993854, 5947.197128387786, 5965.821025369246, 6012.188354572077, 5843.2403787507355, 6073.787417440369, 6237.688115594111, 6366.440222145604, 6388.8083357499345, 3562.590302115186, 3755.6291953437344, 3852.6339997700175, 3971.72197946141, 3983.570022286345, 5125.589952803842, 5314.441983591667, 5555.944440555542, 5973.761049557969, 6024.969875150597, 4985.750712464434, 5505.311836020497, 5516.9448305516435, 5601.80393686219, 5768.596198266081, 5068.569171620454, 5553.944460555343, 5786.971065144816, 5807.738651760596, 5825.50483208932, 5204.219367094966, 5383.1117865553315, 5583.664980101044, 5762.1068734346245, 5792.768289268382, 5036.56457909494, 5684.186327767781, 5705.543556515552, 5766.740496677743, 5834.679092649775, 4775.952240477551, 5666.518345940642, 5736.42635736423, 5744.569157313302, 5759.193712880284], "value": 4133.191345117769, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.9015592739053, 691.2534462780196, 701.6106061136071, 746.7871656577877, 784.0943710014931, 15229.238538073094, 15330.69338613227, 16186.514404567883, 16372.0, 16426.83573164268, 12652.936735316334, 13988.720225595482, 14724.705505889902, 14737.115773053625, 14864.405423783062, 692.7125243024165, 876.0407353947418, 900.3922352412112, 919.9586018629178, 920.5121285718579, 768.9346405555534, 794.5947566740946, 883.8983516895576, 917.7705573606598, 923.7690577355661, 795.8806179073127, 936.625349860058, 972.7081875437341, 972.7373609125532, 981.7398389426808, 925.6621333213396, 954.8663187153804, 957.9089986451276, 964.9614015439374, 965.2760429677742, 682.011083928306, 686.9828254293633, 698.4517154034058, 716.1585137463512, 720.4236610711463, 15769.974951628108, 15789.21053947298, 15952.920235398864, 15971.281292341873, 15989.040657560583, 12740.0, 14848.331825067899, 14850.480233191895, 14901.180435076067, 15006.849931500654, 10479.161667066617, 12006.339651319175, 12325.137240393176, 12474.875251247462, 12494.062945279049, 3822.4075268333454, 3853.903652408684, 4034.495688038995, 4054.1283624020975, 4054.310767169559, 112998.0, 113131.0, 114290.0, 114324.4283778584, 114453.0, 11412.714682133013, 11601.0, 11604.535818567332, 11643.301401915744, 11875.762484750257, 23694.644580331307, 23938.64092038621, 23943.28170154898, 23964.04143834262, 23976.760232397803, 7624.885626715603, 8017.959910200411, 8077.474964127313, 8167.509949403055, 8448.535330556879, 4481.193385190697, 4484.596386325246, 4566.223741963874, 4575.885602859955, 4706.317583950308, 3547.0600290922926, 3685.4287585424304, 3686.594474607791, 3692.926141477209, 3693.759905606181, 670.7618795327588, 671.1543455246425, 673.0644404278057, 680.9182898052266, 681.9556728812611, 627.159606127791, 638.8211300835774, 663.4559661077869, 664.2304919581356, 668.2882729892649, 7351.301626345488, 8276.586170691562, 8482.151784821468, 8683.175098365646, 8694.608742606599, 516.2411255454476, 571.2188978252057, 620.500497099839, 642.7782415066795, 655.8983357579583, 4339.34909763538, 4371.803268852909, 4418.911621767611, 4485.349624304523, 4590.701604395704, 148558.0, 149464.0, 150511.0, 152389.0, 153171.0, 80438.0, 92197.53901230585, 109270.0, 112626.0, 129312.0, 114584.42707786574, 114990.0, 115481.8451815471, 116970.9999999983, 118030.0, 9586.568604412958, 9654.56554455037, 9711.4757038728, 9749.0, 9768.56041478121, 57521.561960949635, 58977.0, 59230.0, 60112.0, 61303.693481532304, 77506.0, 85546.5913273595, 87194.69207961758, 94477.0, 96109.0, 13233.602991910258, 13430.93284533571, 13570.59288221355, 13667.658308542366, 13889.722205556034, 11940.52237910474, 14579.198144101752, 14916.701665966837, 15022.62443438879, 15759.921200393925, 11342.829857552308, 12768.553100641433, 13779.379927903468, 13983.370748316554, 14432.567022989328, 10382.896171038194, 12272.202306850026, 12595.622131336075, 12719.236845789283, 13015.893649039937, 13590.388432520758, 13673.384697688827, 13700.65748356299, 13727.519536816164, 14164.0, 11067.446627668423, 11189.776204476027, 11219.71950701239, 11299.277487588715, 11594.362310073027, 10761.545702204727, 11340.432978351215, 11430.771384572428, 11541.596044138414, 11570.0], "value": 7867.851742998064, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 115.0, 120.0, 115.0, 118.0, 137.0, 150.0, 116.0, 117.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 143.0, 161.0, 122.0, 144.0, 149.0, 146.0, 201.0, 106.0], "value": 144.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 210.0, 233.0, 199.0, 194.0, 197.0, 194.0, 200.0, 196.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 114.0, 85.0, 155.0, 55.0, 61.0, 81.0, 84.0, 144.0, 64.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 125.0, 68.0, 72.0, 85.0, 110.0, 69.0, 75.0, 113.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 86.0, 135.0, 121.0, 92.0, 92.0, 121.0, 87.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 140.0, 97.0, 103.0, 150.0, 99.0, 98.0, 108.0, 152.0, 124.0], "value": 122.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 80.0, 144.0, 104.0, 84.0, 146.0, 145.0, 93.0, 92.0, 91.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 55.0, 57.0, 65.0, 81.0, 85.0, 84.0, 82.0, 61.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 75.0, 76.0, 86.0, 82.0, 75.0, 70.0, 88.0, 72.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 88.0, 86.0, 86.0, 87.0, 86.0, 87.0, 116.0, 109.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 162.0, 138.0, 148.0, 156.0, 148.0, 159.0, 149.0, 159.0, 159.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 169.0, 164.0, 167.0, 164.0, 165.0, 169.0, 165.0, 188.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 67.0, 69.0, 66.0, 69.0, 65.0, 68.0, 65.0], "value": 67.3, "unit": "score"}]}]} 05:00:18 INFO - Return code: 0 05:00:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:00:18 ERROR - # TBPL SUCCESS # 05:00:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:00:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:00:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:00:18 INFO - 2016-06-03 05:00:18.683 screenresolution[1686:10285] starting screenresolution argv=screenresolution get 05:00:18 INFO - 2016-06-03 05:00:18.686 screenresolution[1686:10285] Display 0: 1600x1200x32@60 05:00:18 INFO - 2016-06-03 05:00:18.692 screenresolution[1687:10288] starting screenresolution argv=screenresolution list 05:00:18 INFO - Available Modes on Display 0 05:00:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:00:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:00:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:00:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:00:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:00:18 INFO - Intel Iris: 05:00:18 INFO - Chipset Model: Intel Iris 05:00:18 INFO - Type: GPU 05:00:18 INFO - Bus: Built-In 05:00:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:00:18 INFO - Vendor: Intel (0x8086) 05:00:18 INFO - Device ID: 0x0a2e 05:00:18 INFO - Revision ID: 0x0009 05:00:18 INFO - Displays: 05:00:18 INFO - Display: 05:00:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:00:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:00:18 INFO - Display Serial Number: 1600x1200 60 05:00:18 INFO - Main Display: Yes 05:00:18 INFO - Mirror: Off 05:00:18 INFO - Online: Yes 05:00:18 INFO - Rotation: Supported 05:00:18 INFO - Return code: 0 05:00:18 INFO - Running post-action listener: _resource_record_post_action 05:00:18 INFO - ##### 05:00:18 INFO - ##### Finished run-tests step (success) 05:00:18 INFO - ##### 05:00:18 INFO - Running post-run listener: _resource_record_post_run 05:00:18 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 37676544; Write bytes: 700967424; Read time: 387; Write time: 2409 05:00:18 INFO - TinderboxPrint: CPU usage
54.7% 05:00:18 INFO - TinderboxPrint: I/O read bytes / time
37,676,544 / 387 05:00:18 INFO - TinderboxPrint: I/O write bytes / time
700,967,424 / 2,409 05:00:18 INFO - TinderboxPrint: CPU idle
2,192.2 (45.3%) 05:00:18 INFO - TinderboxPrint: CPU system
978.5 (20.2%) 05:00:18 INFO - TinderboxPrint: CPU user
1,667.0 (34.5%) 05:00:18 INFO - TinderboxPrint: Swap in / out
438,124,544 / 0 05:00:18 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395700736; Write bytes: 390514688; Read time: 29536; Write time: 744 05:00:18 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 35853824; Write bytes: 297030144; Read time: 366; Write time: 1634 05:00:19 INFO - Running post-run listener: _upload_blobber_files 05:00:19 INFO - Blob upload gear active. 05:00:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:00:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:00:19 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:00:19 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:00:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:00:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:00:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:00:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:20 INFO - (blobuploader) - INFO - Done attempting. 05:00:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:00:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:00:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:21 INFO - (blobuploader) - INFO - Done attempting. 05:00:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:00:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:00:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:24 INFO - (blobuploader) - INFO - Done attempting. 05:00:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:00:24 INFO - Return code: 0 05:00:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:00:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:00:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7822cf1332435b97f9be99007cd325a53dfcc850855be1d478d8fcfae42a209bb013bd0e5f149f6ea5b7f07eb54f9a080f30aa6b0fb11d41fcd10c382dfd07f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7836412ee9ae47129b43a3bd1673f2eeaec36b827b0393358dd1d34bb05a89cb709c90f117361984e4051954edcfec902559b63179c2b2618648bfbcac5a772e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b05d4507fc9cb3e802bb31023038eb158ff43f720e56e647e4ab0c774291bc27ee40655e830d13716cd7a8073f1f6b4d37a2638d570bc5d457f4133cc2f2940"} 05:00:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:00:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:00:24 INFO - Contents: 05:00:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7822cf1332435b97f9be99007cd325a53dfcc850855be1d478d8fcfae42a209bb013bd0e5f149f6ea5b7f07eb54f9a080f30aa6b0fb11d41fcd10c382dfd07f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7836412ee9ae47129b43a3bd1673f2eeaec36b827b0393358dd1d34bb05a89cb709c90f117361984e4051954edcfec902559b63179c2b2618648bfbcac5a772e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b05d4507fc9cb3e802bb31023038eb158ff43f720e56e647e4ab0c774291bc27ee40655e830d13716cd7a8073f1f6b4d37a2638d570bc5d457f4133cc2f2940"} 05:00:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:00:24 INFO - Copying logs to upload dir... 05:00:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:00:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.219068 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-06-03 05:00:24.175524) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 05:00:24.178776) ========= 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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7822cf1332435b97f9be99007cd325a53dfcc850855be1d478d8fcfae42a209bb013bd0e5f149f6ea5b7f07eb54f9a080f30aa6b0fb11d41fcd10c382dfd07f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7836412ee9ae47129b43a3bd1673f2eeaec36b827b0393358dd1d34bb05a89cb709c90f117361984e4051954edcfec902559b63179c2b2618648bfbcac5a772e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b05d4507fc9cb3e802bb31023038eb158ff43f720e56e647e4ab0c774291bc27ee40655e830d13716cd7a8073f1f6b4d37a2638d570bc5d457f4133cc2f2940"} build_url:https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/QlYvADVUSQmWgGzdmuyzMw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7822cf1332435b97f9be99007cd325a53dfcc850855be1d478d8fcfae42a209bb013bd0e5f149f6ea5b7f07eb54f9a080f30aa6b0fb11d41fcd10c382dfd07f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7836412ee9ae47129b43a3bd1673f2eeaec36b827b0393358dd1d34bb05a89cb709c90f117361984e4051954edcfec902559b63179c2b2618648bfbcac5a772e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b05d4507fc9cb3e802bb31023038eb158ff43f720e56e647e4ab0c774291bc27ee40655e830d13716cd7a8073f1f6b4d37a2638d570bc5d457f4133cc2f2940"}' ========= master_lag: 0.17 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 05:00:24.359937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:00:24.363378) ========= 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.wPo4Fqt7Q3/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.mZXyqyqEJp/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005168 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:00:24.423520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 05:00:24.423833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 05:00:24.425055) ========= ========= Total master_lag: 0.41 =========