builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0258 starttime: 1464192063.52 results: success (0) buildid: 20160525063710 builduid: 67e7610a129d4ea4b040c5ef98e6020f revision: d6d4e8417d2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.523484) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.523939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.524216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.546544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.547040) ========= 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.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-05-25 09:01:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-05-25 09:01:03 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.664013) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.664453) ========= 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.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.024978 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:03.714534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 09:01:03.714906) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d6d4e8417d2f --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 d6d4e8417d2f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-05-25 09:01:03,160 Setting DEBUG logging. 2016-05-25 09:01:03,160 attempt 1/10 2016-05-25 09:01:03,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d6d4e8417d2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 09:01:04,162 unpacking tar archive at: mozilla-central-d6d4e8417d2f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.250449 ========= master_lag: 0.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 09:01:05.511194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:05.511516) ========= script_repo_revision: d6d4e8417d2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:05.511890) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:05.512171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 09:01:05.530417) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-25 09:01:05.530807) ========= /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.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:01:05 INFO - MultiFileLogger online at 20160525 09:01:05 in /builds/slave/test 09:01:05 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 09:01:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:05 INFO - {'append_to_log': False, 09:01:05 INFO - 'base_work_dir': '/builds/slave/test', 09:01:05 INFO - 'blob_upload_branch': 'Firefox', 09:01:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:05 INFO - 'branch': 'Firefox', 09:01:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:05 INFO - 'config_files': ('talos/mac_config.py',), 09:01:05 INFO - 'default_actions': ('clobber', 09:01:05 INFO - 'read-buildbot-config', 09:01:05 INFO - 'download-and-extract', 09:01:05 INFO - 'populate-webroot', 09:01:05 INFO - 'create-virtualenv', 09:01:05 INFO - 'install', 09:01:05 INFO - 'run-tests'), 09:01:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:05 INFO - 'download_minidump_stackwalk': True, 09:01:05 INFO - 'download_symbols': 'ondemand', 09:01:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:05 INFO - 'installer_path': 'installer.exe', 09:01:05 INFO - 'log_level': 'info', 09:01:05 INFO - 'log_name': 'talos', 09:01:05 INFO - 'log_to_console': True, 09:01:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:05 INFO - 'opt_config_files': (), 09:01:05 INFO - 'pip_index': False, 09:01:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:05 INFO - 'cmd': ('bash', 09:01:05 INFO - '-c', 09:01:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:05 INFO - 'enabled': True, 09:01:05 INFO - 'halt_on_failure': False, 09:01:05 INFO - 'name': 'check_screen_resolution'},), 09:01:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:05 INFO - 'cmd': ('bash', 09:01:05 INFO - '-c', 09:01:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:05 INFO - 'enabled': True, 09:01:05 INFO - 'halt_on_failure': False, 09:01:05 INFO - 'name': 'check_screen_resolution'},), 09:01:05 INFO - 'run_cmd_checks_enabled': True, 09:01:05 INFO - 'sps_profile': False, 09:01:05 INFO - 'sps_profile_interval': 0, 09:01:05 INFO - 'suite': 'dromaeojs-e10s', 09:01:05 INFO - 'system_bits': '32', 09:01:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:05 INFO - 'title': 't-yosemite-r7-0258', 09:01:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:05 INFO - 'use_talos_json': True, 09:01:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:05 INFO - 'work_dir': 'build'} 09:01:05 INFO - ##### 09:01:05 INFO - ##### Running clobber step. 09:01:05 INFO - ##### 09:01:05 INFO - Running pre-action listener: _resource_record_pre_action 09:01:05 INFO - Running main action method: clobber 09:01:05 INFO - rmtree: /builds/slave/test/build 09:01:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:06 INFO - Running post-action listener: _resource_record_post_action 09:01:06 INFO - ##### 09:01:06 INFO - ##### Finished clobber step (success) 09:01:06 INFO - ##### 09:01:06 INFO - ##### 09:01:06 INFO - ##### Running read-buildbot-config step. 09:01:06 INFO - ##### 09:01:06 INFO - Running pre-action listener: _resource_record_pre_action 09:01:06 INFO - Running main action method: read_buildbot_config 09:01:06 INFO - Using buildbot properties: 09:01:06 INFO - { 09:01:06 INFO - "project": "", 09:01:06 INFO - "product": "firefox", 09:01:06 INFO - "script_repo_revision": "production", 09:01:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:01:06 INFO - "repository": "", 09:01:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:01:06 INFO - "buildid": "20160525063710", 09:01:06 INFO - "stage_platform": "macosx64", 09:01:06 INFO - "basedir": "/builds/slave/test", 09:01:06 INFO - "nightly_build": "True", 09:01:06 INFO - "buildnumber": 15, 09:01:06 INFO - "slavename": "t-yosemite-r7-0258", 09:01:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:01:06 INFO - "platform": "yosemite_r7", 09:01:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:01:06 INFO - "branch": "mozilla-central", 09:01:06 INFO - "repo_path": "mozilla-central", 09:01:06 INFO - "revision": "d6d4e8417d2f", 09:01:06 INFO - "pgo_build": "False", 09:01:06 INFO - "builduid": "67e7610a129d4ea4b040c5ef98e6020f", 09:01:06 INFO - "slavebuilddir": "test" 09:01:06 INFO - } 09:01:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:01:06 INFO - Running post-action listener: _resource_record_post_action 09:01:06 INFO - ##### 09:01:06 INFO - ##### Finished read-buildbot-config step (success) 09:01:06 INFO - ##### 09:01:06 INFO - ##### 09:01:06 INFO - ##### Running download-and-extract step. 09:01:06 INFO - ##### 09:01:06 INFO - Running pre-action listener: _resource_record_pre_action 09:01:06 INFO - Running main action method: download_and_extract 09:01:06 INFO - mkdir: /builds/slave/test/build/tests 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:06 INFO - https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:01:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:07 INFO - Downloaded 1199 bytes. 09:01:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:07 INFO - Using the following test package requirements: 09:01:07 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:01:07 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:07 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:01:07 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'jsshell-mac.zip'], 09:01:07 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:01:07 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:01:07 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:01:07 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:01:07 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:01:07 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:07 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:07 INFO - https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:08 INFO - Downloaded 18634889 bytes. 09:01:08 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'] 09:01:08 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 09:01:11 INFO - Return code: 0 09:01:11 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 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:11 INFO - https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:13 INFO - Downloaded 18634889 bytes. 09:01:13 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'] 09:01:13 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 09:01:16 INFO - Return code: 0 09:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:16 INFO - https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:01:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:01:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:01:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:17 INFO - Downloaded 10834034 bytes. 09:01:17 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'] 09:01:17 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 09:01:18 INFO - Return code: 0 09:01: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')]} 09:01:18 INFO - https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:01:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:01:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BAg7vywdRCODrIvvcRjl_A/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 09:01:21 INFO - Downloaded 110643849 bytes. 09:01:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:21 INFO - mkdir: /builds/slave/test/properties 09:01:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:21 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:21 INFO - Contents: 09:01:21 INFO - build_url:https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:22 INFO - Running post-action listener: _resource_record_post_action 09:01:22 INFO - Running post-action listener: set_extra_try_arguments 09:01:22 INFO - ##### 09:01:22 INFO - ##### Finished download-and-extract step (success) 09:01:22 INFO - ##### 09:01:22 INFO - ##### 09:01:22 INFO - ##### Running populate-webroot step. 09:01:22 INFO - ##### 09:01:22 INFO - Running pre-action listener: _resource_record_pre_action 09:01:22 INFO - Running main action method: populate_webroot 09:01:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:22 INFO - '--tppagecycles', 09:01:22 INFO - '7'], 09:01:22 INFO - 'tests': ['tsvgm']}}, 09:01:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['damp', 'tps']}, 09:01:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'tests': ['damp', 'tps']}, 09:01:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['dromaeo_dom']}, 09:01:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['a11yr', 09:01:22 INFO - 'ts_paint', 09:01:22 INFO - 'tpaint', 09:01:22 INFO - 'sessionrestore', 09:01:22 INFO - 'sessionrestore_no_auto_restore', 09:01:22 INFO - 'tabpaint']}, 09:01:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['tsvgx', 09:01:22 INFO - 'tsvgr_opacity', 09:01:22 INFO - 'tart', 09:01:22 INFO - 'tscrollx', 09:01:22 INFO - 'cart']}, 09:01:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:22 INFO - 'tsvgr_opacity', 09:01:22 INFO - 'tart', 09:01:22 INFO - 'tscrollx', 09:01:22 INFO - 'cart']}, 09:01:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'talos_options': ['--disable-e10s'], 09:01:22 INFO - 'tests': ['tp5o']}, 09:01:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'tests': ['tp5o']}, 09:01:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'talos_options': ['--disable-e10s', 09:01:22 INFO - '--xperf_path', 09:01:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:22 INFO - 'tests': ['tp5n']}, 09:01:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:22 INFO - 'talos_options': ['--xperf_path', 09:01:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:22 INFO - 'tests': ['tp5n']}}, 09:01:22 INFO - 'talos.zip': {'path': '', 09:01:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:22 INFO - Running post-action listener: _resource_record_post_action 09:01:22 INFO - ##### 09:01:22 INFO - ##### Finished populate-webroot step (success) 09:01:22 INFO - ##### 09:01:22 INFO - ##### 09:01:22 INFO - ##### Running create-virtualenv step. 09:01:22 INFO - ##### 09:01:22 INFO - Running pre-action listener: _resource_record_pre_action 09:01:22 INFO - Running main action method: create_virtualenv 09:01:22 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:01:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:22 INFO - Using real prefix '/tools/python27' 09:01:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:23 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:25 INFO - Installing pip.................done. 09:01:25 INFO - Return code: 0 09:01:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:25 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:25 INFO - 'HOME': '/Users/cltbld', 09:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:25 INFO - 'LOGNAME': 'cltbld', 09:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:25 INFO - 'MOZ_NO_REMOTE': '1', 09:01:25 INFO - 'NO_EM_RESTART': '1', 09:01:25 INFO - 'PAGER': '/bin/cat', 09:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:25 INFO - 'PWD': '/builds/slave/test', 09:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:25 INFO - 'SHELL': '/bin/bash', 09:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:25 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:25 INFO - 'USER': 'cltbld', 09:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:25 INFO - 'XPC_FLAGS': '0x0', 09:01:25 INFO - 'XPC_SERVICE_NAME': '0', 09:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:01:26 INFO - Cleaning up... 09:01:26 INFO - Return code: 0 09:01:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:26 INFO - 'HOME': '/Users/cltbld', 09:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:26 INFO - 'LOGNAME': 'cltbld', 09:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:26 INFO - 'MOZ_NO_REMOTE': '1', 09:01:26 INFO - 'NO_EM_RESTART': '1', 09:01:26 INFO - 'PAGER': '/bin/cat', 09:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:26 INFO - 'PWD': '/builds/slave/test', 09:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:26 INFO - 'SHELL': '/bin/bash', 09:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:26 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:26 INFO - 'USER': 'cltbld', 09:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:26 INFO - 'XPC_FLAGS': '0x0', 09:01:26 INFO - 'XPC_SERVICE_NAME': '0', 09:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:26 INFO - Downloading/unpacking psutil>=3.1.1 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:29 INFO - Installing collected packages: psutil 09:01:29 INFO - Running setup.py install for psutil 09:01:29 INFO - building 'psutil._psutil_osx' extension 09:01:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:01:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:01:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:01:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:01:30 INFO - building 'psutil._psutil_posix' extension 09:01:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:01:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:30 INFO - ^ 09:01:30 INFO - 1 warning generated. 09:01:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:01:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:30 INFO - Successfully installed psutil 09:01:30 INFO - Cleaning up... 09:01:30 INFO - Return code: 0 09:01:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:30 INFO - 'HOME': '/Users/cltbld', 09:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:30 INFO - 'LOGNAME': 'cltbld', 09:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:30 INFO - 'MOZ_NO_REMOTE': '1', 09:01:30 INFO - 'NO_EM_RESTART': '1', 09:01:30 INFO - 'PAGER': '/bin/cat', 09:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:30 INFO - 'PWD': '/builds/slave/test', 09:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:30 INFO - 'SHELL': '/bin/bash', 09:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:30 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:30 INFO - 'USER': 'cltbld', 09:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:30 INFO - 'XPC_FLAGS': '0x0', 09:01:30 INFO - 'XPC_SERVICE_NAME': '0', 09:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:33 INFO - Installing collected packages: mozsystemmonitor 09:01:33 INFO - Running setup.py install for mozsystemmonitor 09:01:33 INFO - Successfully installed mozsystemmonitor 09:01:33 INFO - Cleaning up... 09:01:33 INFO - Return code: 0 09:01:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:33 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:33 INFO - 'HOME': '/Users/cltbld', 09:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:33 INFO - 'LOGNAME': 'cltbld', 09:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:33 INFO - 'MOZ_NO_REMOTE': '1', 09:01:33 INFO - 'NO_EM_RESTART': '1', 09:01:33 INFO - 'PAGER': '/bin/cat', 09:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:33 INFO - 'PWD': '/builds/slave/test', 09:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:33 INFO - 'SHELL': '/bin/bash', 09:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:33 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:33 INFO - 'USER': 'cltbld', 09:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:33 INFO - 'XPC_FLAGS': '0x0', 09:01:33 INFO - 'XPC_SERVICE_NAME': '0', 09:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:37 INFO - Downloading docopt-0.6.1.tar.gz 09:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:37 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:37 INFO - Running setup.py install for blobuploader 09:01:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:37 INFO - Running setup.py install for requests 09:01:38 INFO - Running setup.py install for docopt 09:01:38 INFO - Successfully installed blobuploader requests docopt 09:01:38 INFO - Cleaning up... 09:01:38 INFO - Return code: 0 09:01:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:38 INFO - 'HOME': '/Users/cltbld', 09:01:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:38 INFO - 'LOGNAME': 'cltbld', 09:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:38 INFO - 'MOZ_NO_REMOTE': '1', 09:01:38 INFO - 'NO_EM_RESTART': '1', 09:01:38 INFO - 'PAGER': '/bin/cat', 09:01:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:38 INFO - 'PWD': '/builds/slave/test', 09:01:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:38 INFO - 'SHELL': '/bin/bash', 09:01:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:38 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:38 INFO - 'USER': 'cltbld', 09:01:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:38 INFO - 'XPC_FLAGS': '0x0', 09:01:38 INFO - 'XPC_SERVICE_NAME': '0', 09:01:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-o6gSeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-BV0lq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-NWEd2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:38 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-KIeMfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QziKlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eXyANu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-XRM2xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-zdQuNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YRDs64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-HD56Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ifQaBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QhzO9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-dl89GV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:39 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-DVm_bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:40 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-1Oo5pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:40 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vdsKHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:40 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6n9icB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:40 INFO - Running setup.py install for manifestparser 09:01:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Running setup.py install for mozcrash 09:01:40 INFO - Running setup.py install for mozdebug 09:01:40 INFO - Running setup.py install for mozdevice 09:01:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Running setup.py install for mozfile 09:01:41 INFO - Running setup.py install for mozhttpd 09:01:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozinfo 09:01:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozInstall 09:01:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozleak 09:01:41 INFO - Running setup.py install for mozlog 09:01:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for moznetwork 09:01:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozprocess 09:01:42 INFO - Running setup.py install for mozprofile 09:01:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozrunner 09:01:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozscreenshot 09:01:42 INFO - Running setup.py install for moztest 09:01:42 INFO - Running setup.py install for mozversion 09:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:42 INFO - Cleaning up... 09:01:42 INFO - Return code: 0 09:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:42 INFO - 'HOME': '/Users/cltbld', 09:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:42 INFO - 'LOGNAME': 'cltbld', 09:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:42 INFO - 'MOZ_NO_REMOTE': '1', 09:01:42 INFO - 'NO_EM_RESTART': '1', 09:01:42 INFO - 'PAGER': '/bin/cat', 09:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:42 INFO - 'PWD': '/builds/slave/test', 09:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:42 INFO - 'SHELL': '/bin/bash', 09:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:42 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:42 INFO - 'USER': 'cltbld', 09:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:42 INFO - 'XPC_FLAGS': '0x0', 09:01:42 INFO - 'XPC_SERVICE_NAME': '0', 09:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-LYQ3zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-REeHbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6UhHAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-vdTA5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GG4GOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-P5pF3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QOATQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:43 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-43UVzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-FthCN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-RdUpK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:44 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)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-heQyKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-iorLAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-IKAJ32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-npEo20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-KA5s1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-DetfCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:44 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Bav8rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:47 INFO - Downloading blessings-1.6.tar.gz 09:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:47 INFO - Installing collected packages: blessings 09:01:47 INFO - Running setup.py install for blessings 09:01:47 INFO - Successfully installed blessings 09:01:47 INFO - Cleaning up... 09:01:47 INFO - Return code: 0 09:01:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:48 INFO - Reading from file tmpfile_stdout 09:01:48 INFO - Current package versions: 09:01:48 INFO - blessings == 1.6 09:01:48 INFO - blobuploader == 1.2.4 09:01:48 INFO - docopt == 0.6.1 09:01:48 INFO - manifestparser == 1.1 09:01:48 INFO - mozInstall == 1.12 09:01:48 INFO - mozcrash == 0.17 09:01:48 INFO - mozdebug == 0.1 09:01:48 INFO - mozdevice == 0.48 09:01:48 INFO - mozfile == 1.2 09:01:48 INFO - mozhttpd == 0.7 09:01:48 INFO - mozinfo == 0.9 09:01:48 INFO - mozleak == 0.1 09:01:48 INFO - mozlog == 3.2 09:01:48 INFO - moznetwork == 0.27 09:01:48 INFO - mozprocess == 0.22 09:01:48 INFO - mozprofile == 0.28 09:01:48 INFO - mozrunner == 6.11 09:01:48 INFO - mozscreenshot == 0.1 09:01:48 INFO - mozsystemmonitor == 0.3 09:01:48 INFO - moztest == 0.7 09:01:48 INFO - mozversion == 1.4 09:01:48 INFO - psutil == 3.1.1 09:01:48 INFO - requests == 1.2.3 09:01:48 INFO - wsgiref == 0.1.2 09:01:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 INFO - Downloading/unpacking jsonschema 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:51 INFO - Downloading/unpacking functools32 (from jsonschema) 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:51 INFO - warning: no files found matching '*.txt' 09:01:51 INFO - no previously-included directories found matching 'build' 09:01:51 INFO - no previously-included directories found matching 'dist' 09:01:51 INFO - no previously-included directories found matching '.git*' 09:01:51 INFO - Installing collected packages: jsonschema, functools32 09:01:51 INFO - Running setup.py install for functools32 09:01:51 INFO - warning: no files found matching '*.txt' 09:01:51 INFO - no previously-included directories found matching 'build' 09:01:51 INFO - no previously-included directories found matching 'dist' 09:01:51 INFO - no previously-included directories found matching '.git*' 09:01:51 INFO - Successfully installed jsonschema functools32 09:01:51 INFO - Cleaning up... 09:01:51 INFO - Return code: 0 09:01:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:51 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 0x105585a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10558b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fece1c6dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, '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.xweQwLupKj/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.pmwZPVffzR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:51 INFO - 'HOME': '/Users/cltbld', 09:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:51 INFO - 'LOGNAME': 'cltbld', 09:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:51 INFO - 'MOZ_NO_REMOTE': '1', 09:01:51 INFO - 'NO_EM_RESTART': '1', 09:01:51 INFO - 'PAGER': '/bin/cat', 09:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:51 INFO - 'PWD': '/builds/slave/test', 09:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:51 INFO - 'SHELL': '/bin/bash', 09:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:01:51 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:51 INFO - 'USER': 'cltbld', 09:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:51 INFO - 'XPC_FLAGS': '0x0', 09:01:51 INFO - 'XPC_SERVICE_NAME': '0', 09:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:52 INFO - Downloading/unpacking flake8 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:01:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:01:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:55 INFO - no previously-included directories found matching 'docs/_build' 09:01:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:01:55 INFO - Running setup.py install for pep8 09:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:55 INFO - no previously-included directories found matching 'docs/_build' 09:01:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:01:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:01:56 INFO - Cleaning up... 09:01:56 INFO - Return code: 0 09:01:56 INFO - Running post-action listener: _resource_record_post_action 09:01:56 INFO - Running post-action listener: _start_resource_monitoring 09:01:56 INFO - Starting resource monitoring. 09:01:56 INFO - ##### 09:01:56 INFO - ##### Finished create-virtualenv step (success) 09:01:56 INFO - ##### 09:01:56 INFO - ##### 09:01:56 INFO - ##### Running install step. 09:01:56 INFO - ##### 09:01:56 INFO - Running pre-action listener: _resource_record_pre_action 09:01:56 INFO - Running main action method: install 09:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:56 INFO - Reading from file tmpfile_stdout 09:01:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:56 INFO - Reading from file tmpfile_stdout 09:01:56 INFO - Output received: 09:01:56 INFO - Usage: mozinstall [options] installer 09:01:56 INFO - Options: 09:01:56 INFO - -h, --help show this help message and exit 09:01:56 INFO - -d DEST, --destination=DEST 09:01:56 INFO - Directory to install application into. [default: 09:01:56 INFO - "/builds/slave/test"] 09:01:56 INFO - --app=APP Application being installed. [default: firefox] 09:01:56 INFO - mkdir: /builds/slave/test/build/application 09:01:56 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'] 09:01:56 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 09:02:30 INFO - Reading from file tmpfile_stdout 09:02:30 INFO - Output received: 09:02:30 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:02:30 INFO - Running post-action listener: _resource_record_post_action 09:02:30 INFO - ##### 09:02:30 INFO - ##### Finished install step (success) 09:02:30 INFO - ##### 09:02:30 INFO - ##### 09:02:30 INFO - ##### Running run-tests step. 09:02:30 INFO - ##### 09:02:30 INFO - Running pre-action listener: _resource_record_pre_action 09:02:30 INFO - Running main action method: run_tests 09:02:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:30 INFO - 2016-05-25 09:02:30.233 screenresolution[1658:7439] starting screenresolution argv=screenresolution get 09:02:30 INFO - 2016-05-25 09:02:30.236 screenresolution[1658:7439] Display 0: 1600x1200x32@60 09:02:30 INFO - 2016-05-25 09:02:30.241 screenresolution[1659:7441] starting screenresolution argv=screenresolution list 09:02:30 INFO - Available Modes on Display 0 09:02:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:30 INFO - Intel Iris: 09:02:30 INFO - Chipset Model: Intel Iris 09:02:30 INFO - Type: GPU 09:02:30 INFO - Bus: Built-In 09:02:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:30 INFO - Vendor: Intel (0x8086) 09:02:30 INFO - Device ID: 0x0a2e 09:02:30 INFO - Revision ID: 0x0009 09:02:30 INFO - Displays: 09:02:30 INFO - Display: 09:02:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:30 INFO - Display Serial Number: 1600x1200 60 09:02:30 INFO - Main Display: Yes 09:02:30 INFO - Mirror: Off 09:02:30 INFO - Online: Yes 09:02:30 INFO - Rotation: Supported 09:02:30 INFO - Return code: 0 09:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:30 INFO - Python 2.7.3 09:02:30 INFO - Return code: 0 09:02:30 INFO - grabbing minidump binary from tooltool 09:02: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')]} 09:02:30 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 0x105429d30>, '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 0x10524ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105420b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:02:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:02:30 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 09:02:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:30 INFO - Return code: 0 09:02:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:02:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:02:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:02:31 INFO - Return code: 0 09:02:31 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 09:02:31 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 09:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xweQwLupKj/Render', 09:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:31 INFO - 'HOME': '/Users/cltbld', 09:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:31 INFO - 'LOGNAME': 'cltbld', 09:02:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:31 INFO - 'MOZ_NO_REMOTE': '1', 09:02:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:31 INFO - 'NO_EM_RESTART': '1', 09:02:31 INFO - 'PAGER': '/bin/cat', 09:02:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:31 INFO - 'PWD': '/builds/slave/test', 09:02:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:31 INFO - 'SHELL': '/bin/bash', 09:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pmwZPVffzR/Listeners', 09:02:31 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 09:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:31 INFO - 'USER': 'cltbld', 09:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:31 INFO - 'XPC_FLAGS': '0x0', 09:02:31 INFO - 'XPC_SERVICE_NAME': '0', 09:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:31 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 09:02:31 INFO - mozversion application_buildid: 20160525063710 09:02:31 INFO - mozversion application_changeset: d6d4e8417d2fd71fdf47c319b7a217f6ace9d5a5 09:02:31 INFO - mozversion application_display_name: Nightly 09:02:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:31 INFO - mozversion application_name: Firefox 09:02:31 INFO - mozversion application_remotingname: firefox 09:02:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:31 INFO - mozversion application_vendor: Mozilla 09:02:31 INFO - mozversion application_version: 49.0a1 09:02:31 INFO - mozversion platform_buildid: 20160525063710 09:02:31 INFO - mozversion platform_changeset: d6d4e8417d2fd71fdf47c319b7a217f6ace9d5a5 09:02:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:31 INFO - mozversion platform_version: 49.0a1 09:02:31 INFO - using testdate: 1464192151 09:02:31 INFO - actual date: 1464192151 09:02:31 INFO - starting webserver on 'localhost:49272' 09:02:31 INFO - SUITE-START | Running 2 tests 09:02:31 INFO - TEST-START | dromaeo_css 09:02:31 INFO - operating with platform_type : mac_ 09:02:31 INFO - Initialising browser for dromaeo_css test... 09:02:31 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmprnwg_g/profile http://localhost:49272/getInfo.html) 09:02:35 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 09:02:35 INFO - PROCESS | 1677 | colorDepth:24 09:02:35 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 09:02:35 INFO - PROCESS | 1677 | __metrics 09:02:35 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:02:35 INFO - TEST-INFO | 1677: exit 0 09:02:35 INFO - Browser initialized. 09:02:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:35 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmprnwg_g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:47 INFO - PROCESS | 1682 | RSS: Main: 251740160 09:02:47 INFO - PROCESS | 1682 | 09:03:37 INFO - PROCESS | 1682 | 1464192217632 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:05:47 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:05:48 INFO - PROCESS | 1682 | RSS: Main: 263958528 09:05:48 INFO - PROCESS | 1682 | 09:08:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:08:51 INFO - PROCESS | 1682 | RSS: Main: 275841024 09:08:51 INFO - PROCESS | 1682 | 09:11:53 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:11:54 INFO - PROCESS | 1682 | RSS: Main: 277667840 09:11:54 INFO - PROCESS | 1682 | 09:14:56 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:14:56 INFO - PROCESS | 1682 | RSS: Main: 279404544 09:14:56 INFO - PROCESS | 1682 | 09:17:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:18:00 INFO - PROCESS | 1682 | RSS: Main: 282722304 09:18:00 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:21:02 INFO - PROCESS | 1682 | RSS: Main: 283250688 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | __start_tp_report 09:21:02 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 09:21:02 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 09:21:02 INFO - PROCESS | 1682 | |i|pagename|runs| 09:21:02 INFO - PROCESS | 1682 | |0;dojo.html;3050.977922395997;3097.1173215633535;3425.1094715373997;3485.1809824691177;3540.3981323175058;4256.489221293441;4701.412323459567;5487.094629386152;5613.270274864267;5737.741801618927;5343.973280133604;5621.971890140544;5662.292213473316;5684.886302273952;5737.942620573793;4269.957300426987;5144.588432925367;5195.324607800981;5352.304200453955;5424.566034717213;34.32419199337744;4906.607471402279;5155.226715992593;5201.1418116011;5249.343832020997;5198.454162312941;5218.660787048849;5232.2674825524455;5241.868953276179;5243.606729495303;5230.895382092337;5258.027264955996;5279.656822306538;5290.232916227164;5324.946750532523;4529.935465165683;4705.3647757552635;5120.6927584344685;5132.255822905695;5184.248283998837;4796.208625576775;5007.524285192901;5600.971995139998;5637.985162670718;5695.914561281584;6095.408112664863;6136.365905528322;6198.501406487258;6215.2541694996435;6219.813405597839;5291.841244762663;6132.01899668756;6170.629762214281;6221.720022598994;6236.906446403263;5873.471387575139;5899.46904778572;5908.615939963888;5917.822465326047;5925.525957923399;6114.206128133634;6121.8493044937295;6230.6573138476915;6274.403931626488;6289.905651415233;5070.467600901926;6171;6176.598521096114;6182.907256391158;6204.720787564571;6192;6212.378762123752;6220.440160385587;6229.719662615194;6277.905831412532;6097.524678803529;6110.144579758838;6148.723307451175;6218.533609979269;6252;6114.571104522756;6160.383961603804;6190.628562286277;6191.349908259658;6194.039923811816;4300.817275249331;4840.467548569654;4911.066897289504;4931.3835770528685;4970.204767237297;4341.934870976938;4375;4396.010897548026;4431.933520997188;4458.866234012985;4781.593564546983;4996.275540046673;5706.749790193033;5800.564957628195;5840.561957853178;6231.532635052389;6262.2172228471445;6262.624242545462;6272;6272.1219029335925;5730.169125476866;5824.155497452845;5860.2967643883;5886.0288052471715;5927.881442371215;4498.3252512123445;5383.81156659515;5403.594730395236;5409.675419474844;5415.593830462731;5295.841124766263;6169.784057557964;6366.172397588284;6377.617342959437;6382.489400847921;6166.982447896047;6201.1938448003275;6211.223597050369;6226.439620434183;6247.5001999840815;6348.71430785625;6360.204974378203;6364.140840986593;6379.681015949277;6380.649064301508;5188.455212202815;6108.236470441195;6158;6215.844603884939;6221.035739460391;6177.444030037318;6247.562670613195;6279.560430769802;6280.780172693934;6285.59143655661;4718.457377401505;5860.94139058621;6144.662043587646;6191.19514463117;6211.440970312694;5920.496757775465;5995.700214989321;6033.879322413615;6180.81457556274;6194.256689197326;4713.31656909753;5965.940340596539;5998.730057147526;6039.607425517503;6064.3935606439;5178.32681751385;5686.857828554319;6077.6353418795015;6157.907631385622;6164.352742962104;5370.892582148257;5903.881922361615;5977.252843394575;5996.520278377807;6034.2155519782145 09:21:02 INFO - PROCESS | 1682 | |1;ext.html;495.09397802022323;510.6706174517436;511.46551853313287;541.3963430774687;577.6765011593507;32640.347193056125;42146.578534214685;42489.57510424902;42601.57398426015;42613.78693106534;37983.620163798354;41086.7945660272;43674;43841.903952401204;44206;9529.809403811902;9703.466309352983;9744.22046236303;9791.06984836443;9794.265430092735;9626.855597166013;9674.129328360446;9704.951475242613;9711.562979665896;9746.31775775696;9717.708468745948;9742.123208911196;9767.218622510218;9782.706518804447;9796;9609.663661771805;9619.711408657751;9636.71089867305;9660.275479339043;9666;9572.47351395673;9646.180074693659;9685.982971788002;9688.370255933341;9710.514474276259;39962.000949976195;40889;40915;41423.75728728143;43457;2643.4977354302623;3068.4783586790077;3125.6092988376454;3153.8738450462183;3294.390537750524;29299.853500732574;29601.407971840443;29614.70385296141;29952.70047299521;30166;2702.391961808597;2748.2030211238716;2752.325680208342;2864.3555200079813;2880.251134704971;382891;388335;390641;393849;396688;64500;64716.676416617614;64723.67638161779;64741;64962.67518662376;36029.27941441156;36318;36366.09084772849;38285.42571861424;67756;17153.142342882806;17264.827351726573;17269.222884970208;17451.825481745273;17489;10495.632652857266;12136.45385957634;13145.868541314656;13166;13253;16304.184790760652;20446.79553204449;20454.488637784176;20612.278570249968;20641.380758577267;7451.478396512301;8464.957675211585;8483.490990540588;8487.108853570286;8498.192671696179;885.6324625280437;967.2165545907837;994.2841154368842;999.7900440907346;1082.8538147350007;30312;30482.54276185859;30559.694403056128;30871.845640772102;30944.535831962537;1046.1839764983324;1067.4609322292347;1078.8651418572679;1176.6409796684843;1221.065884598283;15422.383104675911;15496.767548486781;16091;16444.177791110396;16567.668646627;602725;695136;698332;700592;701997;53981;61970.38029619736;64202;64315.356846431874;64695;1367.6033950154542;1443.5813614052017;1451.8330392004843;1482.2959094430275;1487.263804416799;27766.66799984015;28871;30711.385772283982;31411.84294078515;31516.842415787774;54173.18740218979;54339.45660543344;54986;55038.724806377315;55117.72441137768;658049;659509;660303;660805;664802;26830;27002.594961075993;27288.72711272941;27349.589756153277;28008.019719309726;47896.5210347906;48247.793805155154;48632.5136748628;49257.507424925294;49504.50495494999;39322;39657.40513892352;39826.60173398229;40540.797296013334;40757.98105047397;38189.854304370914;38436.42345364878;38556;39086.02284942899;39307.80346098251;32965.50551741774;38035.809820950715;38543;38812.6118738809;39315.80342098271;33309.83345083259;35279;35311.470327945615;36128.45807312945;36361.818190908874;24211.75788242095;25257.74742252554;25349.493010139326;25775.484490309715;26087.478250434506 09:21:02 INFO - PROCESS | 1682 | |2;jquery.html;386.5631836025291;388.8347452332759;390.3598099117447;391.82563759127197;415.46820070310014;14521.712933925304;16143.596410089754;16946.91526542367;19002.334918277847;20577.897110514445;17341.479755607354;17412.912935435335;20261.290854820083;20703.896480517575;21022.684659730116;5771.3074431068335;5845.152452894327;6034.396560343975;6059.212302400681;6068.6965651717455;5216.6869987800665;5998.490128339096;6052.697365131748;6065.727042283108;6068.211132552762;5045.243213517983;5714.628549144292;5839.240898683165;5996.370381109986;6032.638041717489;5937.22816033915;5942.554308426864;5961.791337303173;5963.61236519627;5971.850703732398;4995.100881841267;5160.174372100483;5199.558037566811;5215.478452154792;5217.243499692561;24201.031958721807;24637.753622463726;25277.620835687478;25696.614550781567;25723.35691607706;2085.8644188127873;2133.7332833395826;2201.8678879267136;2256.3419218185963;2484.763947424992;14911.403543858232;15082;15242.771358429633;15295.311710973034;15495.767563486557;1747.9038652874087;1769.6991511443086;1827.6253368059515;1840.4938641873591;1847.8798878072882;115747.84252157455;120270.3986480062;120661.39669301685;122064;123393.38303308426;10647.94676026615;11059.72350691239;11170.106391488662;11248.943755281334;11283.435828208556;13861.376238069312;13970.441182352592;14064.85935140656;14151.009429339847;14156.433742650179;7177.354038136593;7687.692492300245;8638.26574741154;8743.907011623547;8839;8594.613242404106;8755;8757.255633271243;8893.599788009555;8917.509536975398;5274.472552744772;5862.5896187267335;6809.557378770364;6813.625250611165;6833.350831670983;1865.8414034806922;1925.768907731053;1926.8843869367884;1929.5851391950516;1932.352661858265;906.1120102299783;906.5648488725449;939.8496240601471;991.3506653142204;1060.5121644043672;17450;20392.388228353175;20451.386458406272;20498.282560110623;20540.178392864418;971.7667759737612;976.7069879036261;981.6073570571829;1007.2194049611463;1030.304544432505;10696.411697356565;10701.411422371844;10983.780324393467;11012.063974562092;11027.558897644005;585196.0740196272;620332;621381.8930905317;622570;635797;106671.8665626683;136961;137019;141685.5831441693;143656.12687746185;1065.3394895165047;1068.8022715797601;1070.1813112968584;1083.462494953976;1095.7260684828793;18519.35182268668;18650.53373665615;18733.250669973047;18856;18888.15003324826;51907.96184076373;52428;53686.194707080205;55489.722551386985;56136;60590.697046514484;81799.5910020446;82381.58809205916;82547.5872620657;85921.57039214764;5262.315898933114;6035.456808887221;7493.400527957641;7804.726834560764;8436.70471533518;10150.390976541135;10160.732492353378;11164.38595877202;12343.629691109281;12600.936995314965;9062.365634405529;10597.682069537927;10680.89319106799;11093.833592495957;11150.721231969266;10645.840312395476;10981.286216395909;11036.337819730841;11101.38942358178;11125;10974.945125274642;11128.276662016942;11178.27341222818;11209.383483908137;11217.214794964275;7765.72819951299;8048.678052877819;8123.75628731139;8131.552764598004;8135.105138434886;9617.326787125114;9906.801863962824;10924.2899211551;11023.779524409627;11285.435728213391 09:21:02 INFO - PROCESS | 1682 | |3;mootools.html;1554.463710020043;1589.6661701042783;1840.227104616061;1936.3126090237956;1956.9510762230952;7799.337056350217;8296;8352.916470835306;8782.253508451773;8787.736367908958;8514.531700756455;8595.570221488917;8596.527191004492;8746.693865714702;8765.079666635007;2384.3085505203558;2393.5930891748403;2401.135591187171;2411.963820542693;2414.61366181411;2375.9406014849683;2382.154335211001;2395.8921848516857;2399.8680072596;2411.7105947286263;2362.870042147681;2377.583922813509;2387.8328517003815;2397.6643269942133;2398.8560686358787;2283.5547068321684;2290.862548247102;2299.31020693792;2308.988455057714;2383.487550176703;2036.297477370305;2306.1467257114805;2318.710161229846;2322.3381336319067;2326.150954901466;8003.679852805881;8167.897333859972;8661.047284798668;8791.560421978875;8792.912070879273;5199.350081239845;5297;5330.93381323733;5361.544268737162;5362.678239305654;3012.9454690858024;3118.0957522318727;3257.1368587324378;3356;3496.9825150874085;881.4755220643707;993.9304248702593;1008.5814387029448;1028.6605420211313;1032.8502367156796;11328.09375249978;11425.14311426638;11440.885591144066;11452.94273528627;11474.368909709963;4071;4529.252673308867;4745.240761478147;4756.120117778222;4759.357486739315;9646.469444180497;9715.757106072404;9795.559199835881;9832.950835245922;9839.065288797696;5235.502627250406;5251.133562962144;5310.8937821244135;5327.7336133193185;5335.759890804923;4264.061906380592;4699.013207226452;4831.444383895897;4905.484924082992;4961.950380496221;3886.1256217351433;3947.4473573699706;3978.462907507507;4008.1382502762044;4044.170944956336;1167.7080729817546;1276.3363051213316;1289.4842063174806;1304.5368894042479;1308.1431662261234;790.2768966395798;857;878.4685265414332;880.7357792662303;899.9550022498848;4115.1975364803575;5355.3305836770405;5543.307086614173;5607.52336051432;5619.831405057855;958.3435346787472;960.351762560269;961.6730311694058;968.2205824311357;1006.864073350103;3568.660977207161;3653.4337177737493;3672.540932383452;3712.2204337088974;3834.424836274581;10288.639897603698;10602;10612.522436490377;10640.574377024986;10654.360738355723;9000.50497222659;10329.276950613385;10437.582496700046;10533.157347412189;10541.789164216672;6594.86810263792;6787.966060169668;8212.425130240827;8216.342692584698;8223.712170074139;5386.919196211981;5592.69240191777;5623.100303951348;5660.349059858005;5673.687947162945;9618.374805637612;9876.160526355341;9898.653547125816;9932.453715045453;9967.202623790225;9567.138957493858;9756.463394513086;9969.102780749767;10039.899601003897;10066.597336106473;6442.613443193423;6782.728690852508;7148.070750802362;7420.443466739908;7428.814279643052;8126.55303958288;8142.7557173284895;8167.44818484486;8171.959140204499;8240.340772738047;7211.062561866924;7310.9634451829525;7333.1200255969625;7404.444666650131;7468.551886886804;6968.477364197604;7191.208967013729;7194.928050719635;7201.675924583511;7218.314260145278;6842.55523390978;6850.965745171442;6900.309969003059;6957.130358705162;6966.651667416711;4503.617192538671;4623.745693986729;4639.559241872017;4641.419822522184;4777.761111944458;4467.2182368086105;4692.319613655933;4762.78567464458;4763.214069678603;4789.832355867528 09:21:02 INFO - PROCESS | 1682 | |4;prototype.html;310.6520696819562;312.10426077158553;315.19467759873544;318.4490830862609;318.8979526551504;4934;5341.545968592664;5481.848811749537;5520.309961254843;5601.5518758499275;4433.91132177357;4879.365682461275;5348.609361565992;5449.536789372898;5466.753996070176;3696.669199088326;3977.840886364549;4200.663946884257;4230.682698797587;4256.914861702764;3530.258645684409;4221.493420789509;4226.746395216282;4237.194932962742;4257.8296868125235;3527.7177825774006;4181.60274773896;4207.3493698969605;4253.702240843142;4264.42430271914;4099.364598487225;4244.596763307481;4244.61798438139;4264.808083636244;4264.933766558361;3103.643081045686;3626.9455958160647;3647.7993710345922;3657.7439579229463;3678.6505281998434;4389.517153113155;5282.683039017633;5284.682919024871;5366.785328586851;5394.951852203531;4434.977825110886;4697.859064228044;4820.493848145964;4821.421429428456;4824.879378015543;4821.879453013667;5529.751161197756;5750.712464376807;5767.88464230713;5797.376734514342;3469.61834198238;3730.645588669072;3765.585785563586;3880.9611903881164;3921.5882332354977;4966.55301022906;5122.462141475129;5174.818881339173;5491.917621235724;5876.82463507296;3085.5988721466065;3249.675032496779;3301.290222602104;3746.138388170736;3772.603876592973;3658.6158453362546;3837.829462014057;4031.314676505001;4044.2518134145275;4044.6359827615656;4077.347624380085;4463.411221491527;4686.835960741358;4740.881477963078;4744.312074749142;4916.483769204254;5390.54180394663;5431.321084864392;5683.920055189501;5913.290405151323;1153.423288355822;1216.7809794236946;1264.8482182138202;1371.1430356027483;1385.6189547874415;737.2222305467783;827.668932427034;846.4582667093065;854.0946596607616;874.61079819481;5062.620303477254;5085.567726743268;5195.11683013881;5274.707696614354;5392.892142157135;4675.901163226639;5214.947850521522;5498.477644623835;5587.38538760736;5915.792947246826;4730.716157030589;4863.392075990501;5060.5698515625845;5259.368875734936;5329.333833270841;2642.502834821076;2844.2604922720243;3010.6838781928022;3144.9528257076163;3177.0778678899396;5374.74827729226;5986;6000.609960352563;6317.305096439388;6347.904781428282;5698.487136157766;5841.50347220491;6155.645757933247;6180.3201647818705;6180.99999999991;5979.521638268928;6241.157443745127;6282.528810339243;6347.714352854225;6468.091912885616;4881.8535443936735;5540.91688624679;5616.017196990592;5974.1934838796205;6139.508839292756;5564.888702226014;5903.793367232126;5932.940670593239;5941.37615550361;5957.6127551709;5146.639735218649;5389.75746091419;5577.191307260506;6154.569180157346;6347.936520634735;3508.386032444363;3686.741928065117;3941.6058394160355;3954.2486927483687;3954.9011274717213;5350.946490535045;5370.19447082925;5512.255845460972;5891.9116213255975;5942.851428714143;4822.577339684732;5240.397354304151;5644.88710225785;5820.970895145497;5854.180414742024;5791.189233507228;5800.186909349047;5806.883862322815;5861.765529378948;5898.1152827076285;4762.904741905073;5480.616356854982;5762.481376676119;5783.843231353662;5805.506531944832;5432.7555260012605;5549.833504994856;5735.856603584777;5905.99598068338;5978.312494063239;4970.975145124251;5201.947980520146;5746.597738158289;5849.415058494116;5892.085072351417 09:21:02 INFO - PROCESS | 1682 | |5;yui.html;659.3340725866874;723.6851969393311;739.6338812287917;757.2768006553741;764.0602059466859;12398.566050188245;14422.278886055694;14641.633959151026;15161.924190379033;15180.2409879506;11128.165387595942;12040.518379264819;14097.154170749762;14147.787783183236;14158.64603384916;708.0901042160824;877.0265005843524;888.1518150166607;892.0722448653426;894.2979760887705;715.595688436032;851.7870532366908;878.6353663229752;888.5290795878162;893.2005854760018;897.5915958239018;899.1278459893925;899.2581120575519;931.8695382646434;939.4316438554679;891.9018907920124;893.8882639670041;908.2234689340651;932.4451951089092;939.7133874168376;663.7643636509018;667.9866402671968;678.375894177358;699.4718987164658;702.0872865275173;14187.361568729431;14563.781543276858;14563.927180364137;14584.416623335053;15128.319225634763;12226.510939562408;12281.631551053482;12331.568395106218;12409.379531023504;12521;9860.65487707934;9905.405675659484;9921.702348929544;9968.700938971842;10285.971402859654;3415.9829200853837;3435.845386957593;3456.844442000116;3470.323286959044;3483.5645544306963;64072;76106;80998.59500702517;83586.58206708988;83654;7529.397648188132;9291.349605527683;9333.63965997742;9343.439393636403;9460.952695236618;21359.14563417446;21504.78495215028;21787.891060544596;21794.891025545086;21806.018729157226;7334.3765779908235;7408.444366672414;7414.597426780073;7443.9627801861725;7474.215207403253;3279.885204017848;3905.3165696003084;3931.2334094851803;3984;3995.6603688686787;3466.3587236361354;3527.94708079379;3565.180008598037;3567.0369000369756;3568.91077723059;586.2320360327984;636.1285039495828;637.9202599675041;644.6969924135649;645.2901808011151;486.7250003747942;500.4520050544648;527.6438404077232;544.2598066629381;578.4244676546779;7608.733694320672;7625.466217364842;7656.272654097852;7819.569923654253;7926.771350440691;502.51005269861594;564.6894208185481;581.776016233753;590.3889474393982;611.4832966143601;3612.277544491112;3659.5425571803526;3705.40713485841;3753.5120434343908;3954.6045395460224;76037.23962760302;78517.21482785213;86137.70793438103;90077;90133.54933225423;59395.109073363936;76921.46157076827;78654;79405.80891286636;79740.60129699315;69904.60190796155;71251.0950067693;71314;71882.56234875378;73793.15517112223;7863.213678632091;7901.854231136568;7910.050793904768;7982.481138725964;8050.5169689818795;38595.80702096584;42907;44332;44524;45419.772901135286;59911.80176396535;59939.70030149821;60263.49341266503;60499.39500604937;60670.089948649926;10824.134069274282;10908.672739817817;11014.44927753593;11050.717397386234;11314.83027754568;11875.346855923008;11959.880401195876;12025.398730063636;12229.69425764363;12399.194052386567;8986.955065224633;10921.726956826144;10966.067884229611;11031.944840275748;11139;9989.250806189419;11120.666380008613;11462.369569673414;11525.423728813357;11663.59177428786;10904.236703430684;10968.067714244378;11073.169512286771;11078.058365039062;11108.57324448592;9191;9398.95300523493;9660.082292182231;9691.854622180814;9719.515972701121;6844.965775171092;9140.223081038186;9328;9484.715458536255;9563.852337719518 09:21:02 INFO - PROCESS | 1682 | __end_tp_report 09:21:02 INFO - PROCESS | 1682 | __start_cc_report 09:21:02 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,421 09:21:02 INFO - PROCESS | 1682 | __end_cc_report 09:21:02 INFO - PROCESS | 1682 | __startTimestamp1464193262812__endTimestamp 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | ------- Summary: start ------- 09:21:02 INFO - PROCESS | 1682 | Number of tests: 6 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#0] dojo.html Cycles:165 Average:5609.37 Median:5908.62 stddev:829.67 (14.0%) stddev-sans-first:807.57 09:21:02 INFO - PROCESS | 1682 | Values: 3051.0 3097.1 3425.1 3485.2 3540.4 4256.5 4701.4 5487.1 5613.3 5737.7 5344.0 5622.0 5662.3 5684.9 5737.9 4270.0 5144.6 5195.3 5352.3 5424.6 34.3 4906.6 5155.2 5201.1 5249.3 5198.5 5218.7 5232.3 5241.9 5243.6 5230.9 5258.0 5279.7 5290.2 5324.9 4529.9 4705.4 5120.7 5132.3 5184.2 4796.2 5007.5 5601.0 5638.0 5695.9 6095.4 6136.4 6198.5 6215.3 6219.8 5291.8 6132.0 6170.6 6221.7 6236.9 5873.5 5899.5 5908.6 5917.8 5925.5 6114.2 6121.8 6230.7 6274.4 6289.9 5070.5 6171.0 6176.6 6182.9 6204.7 6192.0 6212.4 6220.4 6229.7 6277.9 6097.5 6110.1 6148.7 6218.5 6252.0 6114.6 6160.4 6190.6 6191.3 6194.0 4300.8 4840.5 4911.1 4931.4 4970.2 4341.9 4375.0 4396.0 4431.9 4458.9 4781.6 4996.3 5706.7 5800.6 5840.6 6231.5 6262.2 6262.6 6272.0 6272.1 5730.2 5824.2 5860.3 5886.0 5927.9 4498.3 5383.8 5403.6 5409.7 5415.6 5295.8 6169.8 6366.2 6377.6 6382.5 6167.0 6201.2 6211.2 6226.4 6247.5 6348.7 6360.2 6364.1 6379.7 6380.6 5188.5 6108.2 6158.0 6215.8 6221.0 6177.4 6247.6 6279.6 6280.8 6285.6 4718.5 5860.9 6144.7 6191.2 6211.4 5920.5 5995.7 6033.9 6180.8 6194.3 4713.3 5965.9 5998.7 6039.6 6064.4 5178.3 5686.9 6077.6 6157.9 6164.4 5370.9 5903.9 5977.3 5996.5 6034.2 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#1] ext.html Cycles:180 Average:70960.95 Median:216585.50 stddev:158852.80 (73.3%) stddev-sans-first:159210.31 09:21:02 INFO - PROCESS | 1682 | Values: 495.1 510.7 511.5 541.4 577.7 32640.3 42146.6 42489.6 42601.6 42613.8 37983.6 41086.8 43674.0 43841.9 44206.0 9529.8 9703.5 9744.2 9791.1 9794.3 9626.9 9674.1 9705.0 9711.6 9746.3 9717.7 9742.1 9767.2 9782.7 9796.0 9609.7 9619.7 9636.7 9660.3 9666.0 9572.5 9646.2 9686.0 9688.4 9710.5 39962.0 40889.0 40915.0 41423.8 43457.0 2643.5 3068.5 3125.6 3153.9 3294.4 29299.9 29601.4 29614.7 29952.7 30166.0 2702.4 2748.2 2752.3 2864.4 2880.3 382891.0 388335.0 390641.0 393849.0 396688.0 64500.0 64716.7 64723.7 64741.0 64962.7 36029.3 36318.0 36366.1 38285.4 67756.0 17153.1 17264.8 17269.2 17451.8 17489.0 10495.6 12136.5 13145.9 13166.0 13253.0 16304.2 20446.8 20454.5 20612.3 20641.4 7451.5 8465.0 8483.5 8487.1 8498.2 885.6 967.2 994.3 999.8 1082.9 30312.0 30482.5 30559.7 30871.8 30944.5 1046.2 1067.5 1078.9 1176.6 1221.1 15422.4 15496.8 16091.0 16444.2 16567.7 602725.0 695136.0 698332.0 700592.0 701997.0 53981.0 61970.4 64202.0 64315.4 64695.0 1367.6 1443.6 1451.8 1482.3 1487.3 27766.7 28871.0 30711.4 31411.8 31516.8 54173.2 54339.5 54986.0 55038.7 55117.7 658049.0 659509.0 660303.0 660805.0 664802.0 26830.0 27002.6 27288.7 27349.6 28008.0 47896.5 48247.8 48632.5 49257.5 49504.5 39322.0 39657.4 39826.6 40540.8 40758.0 38189.9 38436.4 38556.0 39086.0 39307.8 32965.5 38035.8 38543.0 38812.6 39315.8 33309.8 35279.0 35311.5 36128.5 36361.8 24211.8 25257.7 25349.5 25775.5 26087.5 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#2] jquery.html Cycles:180 Average:35718.25 Median:11578.21 stddev:103099.80 (890.5%) stddev-sans-first:103354.89 09:21:02 INFO - PROCESS | 1682 | Values: 386.6 388.8 390.4 391.8 415.5 14521.7 16143.6 16946.9 19002.3 20577.9 17341.5 17412.9 20261.3 20703.9 21022.7 5771.3 5845.2 6034.4 6059.2 6068.7 5216.7 5998.5 6052.7 6065.7 6068.2 5045.2 5714.6 5839.2 5996.4 6032.6 5937.2 5942.6 5961.8 5963.6 5971.9 4995.1 5160.2 5199.6 5215.5 5217.2 24201.0 24637.8 25277.6 25696.6 25723.4 2085.9 2133.7 2201.9 2256.3 2484.8 14911.4 15082.0 15242.8 15295.3 15495.8 1747.9 1769.7 1827.6 1840.5 1847.9 115747.8 120270.4 120661.4 122064.0 123393.4 10647.9 11059.7 11170.1 11248.9 11283.4 13861.4 13970.4 14064.9 14151.0 14156.4 7177.4 7687.7 8638.3 8743.9 8839.0 8594.6 8755.0 8757.3 8893.6 8917.5 5274.5 5862.6 6809.6 6813.6 6833.4 1865.8 1925.8 1926.9 1929.6 1932.4 906.1 906.6 939.8 991.4 1060.5 17450.0 20392.4 20451.4 20498.3 20540.2 971.8 976.7 981.6 1007.2 1030.3 10696.4 10701.4 10983.8 11012.1 11027.6 585196.1 620332.0 621381.9 622570.0 635797.0 106671.9 136961.0 137019.0 141685.6 143656.1 1065.3 1068.8 1070.2 1083.5 1095.7 18519.4 18650.5 18733.3 18856.0 18888.2 51908.0 52428.0 53686.2 55489.7 56136.0 60590.7 81799.6 82381.6 82547.6 85921.6 5262.3 6035.5 7493.4 7804.7 8436.7 10150.4 10160.7 11164.4 12343.6 12600.9 9062.4 10597.7 10680.9 11093.8 11150.7 10645.8 10981.3 11036.3 11101.4 11125.0 10974.9 11128.3 11178.3 11209.4 11217.2 7765.7 8048.7 8123.8 8131.6 8135.1 9617.3 9906.8 10924.3 11023.8 11285.4 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#3] mootools.html Cycles:180 Average:5556.05 Median:5080.65 stddev:3115.40 (61.3%) stddev-sans-first:3109.63 09:21:02 INFO - PROCESS | 1682 | Values: 1554.5 1589.7 1840.2 1936.3 1957.0 7799.3 8296.0 8352.9 8782.3 8787.7 8514.5 8595.6 8596.5 8746.7 8765.1 2384.3 2393.6 2401.1 2412.0 2414.6 2375.9 2382.2 2395.9 2399.9 2411.7 2362.9 2377.6 2387.8 2397.7 2398.9 2283.6 2290.9 2299.3 2309.0 2383.5 2036.3 2306.1 2318.7 2322.3 2326.2 8003.7 8167.9 8661.0 8791.6 8792.9 5199.4 5297.0 5330.9 5361.5 5362.7 3012.9 3118.1 3257.1 3356.0 3497.0 881.5 993.9 1008.6 1028.7 1032.9 11328.1 11425.1 11440.9 11452.9 11474.4 4071.0 4529.3 4745.2 4756.1 4759.4 9646.5 9715.8 9795.6 9833.0 9839.1 5235.5 5251.1 5310.9 5327.7 5335.8 4264.1 4699.0 4831.4 4905.5 4962.0 3886.1 3947.4 3978.5 4008.1 4044.2 1167.7 1276.3 1289.5 1304.5 1308.1 790.3 857.0 878.5 880.7 900.0 4115.2 5355.3 5543.3 5607.5 5619.8 958.3 960.4 961.7 968.2 1006.9 3568.7 3653.4 3672.5 3712.2 3834.4 10288.6 10602.0 10612.5 10640.6 10654.4 9000.5 10329.3 10437.6 10533.2 10541.8 6594.9 6788.0 8212.4 8216.3 8223.7 5386.9 5592.7 5623.1 5660.3 5673.7 9618.4 9876.2 9898.7 9932.5 9967.2 9567.1 9756.5 9969.1 10039.9 10066.6 6442.6 6782.7 7148.1 7420.4 7428.8 8126.6 8142.8 8167.4 8172.0 8240.3 7211.1 7311.0 7333.1 7404.4 7468.6 6968.5 7191.2 7194.9 7201.7 7218.3 6842.6 6851.0 6900.3 6957.1 6966.7 4503.6 4623.7 4639.6 4641.4 4777.8 4467.2 4692.3 4762.8 4763.2 4789.8 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#4] prototype.html Cycles:180 Average:4621.27 Median:5198.53 stddev:1453.29 (28.0%) stddev-sans-first:1420.90 09:21:02 INFO - PROCESS | 1682 | Values: 310.7 312.1 315.2 318.4 318.9 4934.0 5341.5 5481.8 5520.3 5601.6 4433.9 4879.4 5348.6 5449.5 5466.8 3696.7 3977.8 4200.7 4230.7 4256.9 3530.3 4221.5 4226.7 4237.2 4257.8 3527.7 4181.6 4207.3 4253.7 4264.4 4099.4 4244.6 4244.6 4264.8 4264.9 3103.6 3626.9 3647.8 3657.7 3678.7 4389.5 5282.7 5284.7 5366.8 5395.0 4435.0 4697.9 4820.5 4821.4 4824.9 4821.9 5529.8 5750.7 5767.9 5797.4 3469.6 3730.6 3765.6 3881.0 3921.6 4966.6 5122.5 5174.8 5491.9 5876.8 3085.6 3249.7 3301.3 3746.1 3772.6 3658.6 3837.8 4031.3 4044.3 4044.6 4077.3 4463.4 4686.8 4740.9 4744.3 4916.5 5390.5 5431.3 5683.9 5913.3 1153.4 1216.8 1264.8 1371.1 1385.6 737.2 827.7 846.5 854.1 874.6 5062.6 5085.6 5195.1 5274.7 5392.9 4675.9 5214.9 5498.5 5587.4 5915.8 4730.7 4863.4 5060.6 5259.4 5329.3 2642.5 2844.3 3010.7 3145.0 3177.1 5374.7 5986.0 6000.6 6317.3 6347.9 5698.5 5841.5 6155.6 6180.3 6181.0 5979.5 6241.2 6282.5 6347.7 6468.1 4881.9 5540.9 5616.0 5974.2 6139.5 5564.9 5903.8 5932.9 5941.4 5957.6 5146.6 5389.8 5577.2 6154.6 6347.9 3508.4 3686.7 3941.6 3954.2 3954.9 5350.9 5370.2 5512.3 5891.9 5942.9 4822.6 5240.4 5644.9 5821.0 5854.2 5791.2 5800.2 5806.9 5861.8 5898.1 4762.9 5480.6 5762.5 5783.8 5805.5 5432.8 5549.8 5735.9 5906.0 5978.3 4971.0 5201.9 5746.6 5849.4 5892.1 09:21:02 INFO - PROCESS | 1682 | 09:21:02 INFO - PROCESS | 1682 | [#5] yui.html Cycles:180 Average:17372.30 Median:623.81 stddev:24257.90 (3888.7%) stddev-sans-first:24293.49 09:21:02 INFO - PROCESS | 1682 | Values: 659.3 723.7 739.6 757.3 764.1 12398.6 14422.3 14641.6 15161.9 15180.2 11128.2 12040.5 14097.2 14147.8 14158.6 708.1 877.0 888.2 892.1 894.3 715.6 851.8 878.6 888.5 893.2 897.6 899.1 899.3 931.9 939.4 891.9 893.9 908.2 932.4 939.7 663.8 668.0 678.4 699.5 702.1 14187.4 14563.8 14563.9 14584.4 15128.3 12226.5 12281.6 12331.6 12409.4 12521.0 9860.7 9905.4 9921.7 9968.7 10286.0 3416.0 3435.8 3456.8 3470.3 3483.6 64072.0 76106.0 80998.6 83586.6 83654.0 7529.4 9291.3 9333.6 9343.4 9461.0 21359.1 21504.8 21787.9 21794.9 21806.0 7334.4 7408.4 7414.6 7444.0 7474.2 3279.9 3905.3 3931.2 3984.0 3995.7 3466.4 3527.9 3565.2 3567.0 3568.9 586.2 636.1 637.9 644.7 645.3 486.7 500.5 527.6 544.3 578.4 7608.7 7625.5 7656.3 7819.6 7926.8 502.5 564.7 581.8 590.4 611.5 3612.3 3659.5 3705.4 3753.5 3954.6 76037.2 78517.2 86137.7 90077.0 90133.5 59395.1 76921.5 78654.0 79405.8 79740.6 69904.6 71251.1 71314.0 71882.6 73793.2 7863.2 7901.9 7910.1 7982.5 8050.5 38595.8 42907.0 44332.0 44524.0 45419.8 59911.8 59939.7 60263.5 60499.4 60670.1 10824.1 10908.7 11014.4 11050.7 11314.8 11875.3 11959.9 12025.4 12229.7 12399.2 8987.0 10921.7 10966.1 11031.9 11139.0 9989.3 11120.7 11462.4 11525.4 11663.6 10904.2 10968.1 11073.2 11078.1 11108.6 9191.0 9399.0 9660.1 9691.9 9719.5 6845.0 9140.2 9328.0 9484.7 9563.9 09:21:02 INFO - PROCESS | 1682 | -------- Summary: end -------- 09:21:02 INFO - PROCESS | 1682 | 09:21:03 INFO - TEST-INFO | 1682: exit 0 09:21:03 INFO - TEST-OK | dromaeo_css | took 1111448ms 09:21:03 INFO - TEST-START | kraken 09:21:03 INFO - operating with platform_type : mac_ 09:21:03 INFO - Initialising browser for kraken test... 09:21:03 INFO - TEST-INFO | started process 1803 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpc4oru3/profile http://localhost:49272/getInfo.html) 09:21:06 INFO - PROCESS | 1803 | __metrics Screen width/height:1600/1200 09:21:06 INFO - PROCESS | 1803 | colorDepth:24 09:21:06 INFO - PROCESS | 1803 | Browser inner width/height: 1024/689 09:21:06 INFO - PROCESS | 1803 | __metrics 09:21:06 ERROR - PROCESS | 1803 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:21:07 INFO - TEST-INFO | 1803: exit 0 09:21:07 INFO - Browser initialized. 09:21:07 INFO - Running cycle 1/1 for kraken test... 09:21:07 INFO - TEST-INFO | started process 1806 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpc4oru3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:19 INFO - PROCESS | 1806 | RSS: Main: 252256256 09:21:19 INFO - PROCESS | 1806 | 09:21:21 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:22 INFO - PROCESS | 1806 | RSS: Main: 263987200 09:21:22 INFO - PROCESS | 1806 | 09:21:25 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:21:26 INFO - PROCESS | 1806 | RSS: Main: 269119488 09:21:26 INFO - PROCESS | 1806 | 09:21:29 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:21:30 INFO - PROCESS | 1806 | RSS: Main: 269426688 09:21:30 INFO - PROCESS | 1806 | 09:21:32 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:21:32 INFO - PROCESS | 1806 | RSS: Main: 270020608 09:21:32 INFO - PROCESS | 1806 | 09:21:34 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:35 INFO - PROCESS | 1806 | RSS: Main: 270675968 09:21:35 INFO - PROCESS | 1806 | 09:21:38 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:39 INFO - PROCESS | 1806 | RSS: Main: 271069184 09:21:39 INFO - PROCESS | 1806 | 09:21:42 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:42 INFO - PROCESS | 1806 | RSS: Main: 271396864 09:21:42 INFO - PROCESS | 1806 | 09:21:45 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:21:46 INFO - PROCESS | 1806 | RSS: Main: 270848000 09:21:46 INFO - PROCESS | 1806 | 09:21:48 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:48 INFO - PROCESS | 1806 | RSS: Main: 270536704 09:21:48 INFO - PROCESS | 1806 | 09:21:50 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:51 INFO - PROCESS | 1806 | RSS: Main: 270581760 09:21:51 INFO - PROCESS | 1806 | 09:21:53 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:54 INFO - PROCESS | 1806 | RSS: Main: 271175680 09:21:54 INFO - PROCESS | 1806 | 09:21:57 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:57 INFO - PROCESS | 1806 | RSS: Main: 271036416 09:21:57 INFO - PROCESS | 1806 | 09:22:00 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:01 INFO - PROCESS | 1806 | RSS: Main: 270925824 09:22:01 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:22:03 INFO - PROCESS | 1806 | RSS: Main: 272461824 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | __start_tp_report 09:22:03 INFO - PROCESS | 1806 | _x_x_mozilla_page_load 09:22:03 INFO - PROCESS | 1806 | _x_x_mozilla_page_load_details 09:22:03 INFO - PROCESS | 1806 | |i|pagename|runs| 09:22:03 INFO - PROCESS | 1806 | |0;ai-astar;123;107;117;117;117;115;116;115;129;158 09:22:03 INFO - PROCESS | 1806 | |1;audio-beat-detection;178;122;132;159;124;145;165;153;174;146 09:22:03 INFO - PROCESS | 1806 | |2;audio-dft;190;197;207;235;192;197;193;193;232;248 09:22:03 INFO - PROCESS | 1806 | |3;audio-fft;68;100;101;165;59;59;58;61;124;57 09:22:03 INFO - PROCESS | 1806 | |4;audio-oscillator;80;101;164;100;84;76;114;70;73;115 09:22:03 INFO - PROCESS | 1806 | |5;imaging-gaussian-blur;88;85;88;85;124;129;89;129;135;87 09:22:03 INFO - PROCESS | 1806 | |6;imaging-darkroom;115;152;151;96;102;95;95;142;116;152 09:22:03 INFO - PROCESS | 1806 | |7;imaging-desaturate;143;125;89;146;112;89;92;91;91;90 09:22:03 INFO - PROCESS | 1806 | |8;json-parse-financial;60;60;58;56;61;61;61;75;60;58 09:22:03 INFO - PROCESS | 1806 | |9;json-stringify-tinderbox;60;62;69;94;103;64;88;96;70;87 09:22:03 INFO - PROCESS | 1806 | |10;stanford-crypto-aes;97;109;111;106;88;86;87;87;90;86 09:22:03 INFO - PROCESS | 1806 | |11;stanford-crypto-ccm;125;152;150;152;149;143;147;142;180;142 09:22:03 INFO - PROCESS | 1806 | |12;stanford-crypto-pbkdf2;165;147;213;226;166;168;165;189;229;175 09:22:03 INFO - PROCESS | 1806 | |13;stanford-crypto-sha256-iterative;67;68;67;67;66;63;66;65;68;65 09:22:03 INFO - PROCESS | 1806 | __end_tp_report 09:22:03 INFO - PROCESS | 1806 | __start_cc_report 09:22:03 INFO - PROCESS | 1806 | _x_x_mozilla_cycle_collect,1081 09:22:03 INFO - PROCESS | 1806 | __end_cc_report 09:22:03 INFO - PROCESS | 1806 | __startTimestamp1464193323503__endTimestamp 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | ------- Summary: start ------- 09:22:03 INFO - PROCESS | 1806 | Number of tests: 14 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#0] ai-astar Cycles:10 Average:121.40 Median:117.00 stddev:14.05 (12.0%) stddev-sans-first:14.89 09:22:03 INFO - PROCESS | 1806 | Values: 123.0 107.0 117.0 117.0 117.0 115.0 116.0 115.0 129.0 158.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:156.00 stddev:19.72 (12.6%) stddev-sans-first:18.08 09:22:03 INFO - PROCESS | 1806 | Values: 178.0 122.0 132.0 159.0 124.0 145.0 165.0 153.0 174.0 146.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#2] audio-dft Cycles:10 Average:208.40 Median:202.00 stddev:21.54 (10.7%) stddev-sans-first:21.80 09:22:03 INFO - PROCESS | 1806 | Values: 190.0 197.0 207.0 235.0 192.0 197.0 193.0 193.0 232.0 248.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#3] audio-fft Cycles:10 Average:85.20 Median:58.50 stddev:36.71 (62.8%) stddev-sans-first:38.41 09:22:03 INFO - PROCESS | 1806 | Values: 68.0 100.0 101.0 165.0 59.0 59.0 58.0 61.0 124.0 57.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:71.50 stddev:28.53 (39.9%) stddev-sans-first:29.53 09:22:03 INFO - PROCESS | 1806 | Values: 80.0 101.0 164.0 100.0 84.0 76.0 114.0 70.0 73.0 115.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#5] imaging-gaussian-blur Cycles:10 Average:103.90 Median:86.00 stddev:22.01 (25.6%) stddev-sans-first:22.58 09:22:03 INFO - PROCESS | 1806 | Values: 88.0 85.0 88.0 85.0 124.0 129.0 89.0 129.0 135.0 87.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#6] imaging-darkroom Cycles:10 Average:121.60 Median:152.00 stddev:25.07 (16.5%) stddev-sans-first:26.48 09:22:03 INFO - PROCESS | 1806 | Values: 115.0 152.0 151.0 96.0 102.0 95.0 95.0 142.0 116.0 152.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#7] imaging-desaturate Cycles:10 Average:106.80 Median:89.50 stddev:23.19 (25.9%) stddev-sans-first:20.57 09:22:03 INFO - PROCESS | 1806 | Values: 143.0 125.0 89.0 146.0 112.0 89.0 92.0 91.0 91.0 90.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:60.50 stddev:5.19 (8.6%) stddev-sans-first:5.49 09:22:03 INFO - PROCESS | 1806 | Values: 60.0 60.0 58.0 56.0 61.0 61.0 61.0 75.0 60.0 58.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:78.50 stddev:15.95 (20.3%) stddev-sans-first:15.31 09:22:03 INFO - PROCESS | 1806 | Values: 60.0 62.0 69.0 94.0 103.0 64.0 88.0 96.0 70.0 87.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:87.00 stddev:10.22 (11.7%) stddev-sans-first:10.81 09:22:03 INFO - PROCESS | 1806 | Values: 97.0 109.0 111.0 106.0 88.0 86.0 87.0 87.0 90.0 86.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:149.50 stddev:13.69 (9.2%) stddev-sans-first:11.67 09:22:03 INFO - PROCESS | 1806 | Values: 125.0 152.0 150.0 152.0 149.0 143.0 147.0 142.0 180.0 142.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:182.00 stddev:28.69 (15.8%) stddev-sans-first:29.56 09:22:03 INFO - PROCESS | 1806 | Values: 165.0 147.0 213.0 226.0 166.0 168.0 165.0 189.0 229.0 175.0 09:22:03 INFO - PROCESS | 1806 | 09:22:03 INFO - PROCESS | 1806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.55 (2.3%) stddev-sans-first:1.62 09:22:03 INFO - PROCESS | 1806 | Values: 67.0 68.0 67.0 67.0 66.0 63.0 66.0 65.0 68.0 65.0 09:22:03 INFO - PROCESS | 1806 | -------- Summary: end -------- 09:22:03 INFO - PROCESS | 1806 | 09:22:04 INFO - TEST-INFO | 1806: exit 0 09:22:04 INFO - TEST-OK | kraken | took 60920ms 09:22:04 INFO - SUITE-END | took 1172s 09:22:04 INFO - Completed test suite (00:19:32) 09:22:04 INFO - javascript benchmark 09:22:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7142.586465682874, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3050.977922395997, 3097.1173215633535, 3425.1094715373997, 3485.1809824691177, 3540.3981323175058, 4256.489221293441, 4701.412323459567, 5487.094629386152, 5613.270274864267, 5737.741801618927, 5343.973280133604, 5621.971890140544, 5662.292213473316, 5684.886302273952, 5737.942620573793, 4269.957300426987, 5144.588432925367, 5195.324607800981, 5352.304200453955, 5424.566034717213, 34.32419199337744, 4906.607471402279, 5155.226715992593, 5201.1418116011, 5249.343832020997, 5198.454162312941, 5218.660787048849, 5232.2674825524455, 5241.868953276179, 5243.606729495303, 5230.895382092337, 5258.027264955996, 5279.656822306538, 5290.232916227164, 5324.946750532523, 4529.935465165683, 4705.3647757552635, 5120.6927584344685, 5132.255822905695, 5184.248283998837, 4796.208625576775, 5007.524285192901, 5600.971995139998, 5637.985162670718, 5695.914561281584, 6095.408112664863, 6136.365905528322, 6198.501406487258, 6215.2541694996435, 6219.813405597839, 5291.841244762663, 6132.01899668756, 6170.629762214281, 6221.720022598994, 6236.906446403263, 5873.471387575139, 5899.46904778572, 5908.615939963888, 5917.822465326047, 5925.525957923399, 6114.206128133634, 6121.8493044937295, 6230.6573138476915, 6274.403931626488, 6289.905651415233, 5070.467600901926, 6171.0, 6176.598521096114, 6182.907256391158, 6204.720787564571, 6192.0, 6212.378762123752, 6220.440160385587, 6229.719662615194, 6277.905831412532, 6097.524678803529, 6110.144579758838, 6148.723307451175, 6218.533609979269, 6252.0, 6114.571104522756, 6160.383961603804, 6190.628562286277, 6191.349908259658, 6194.039923811816, 4300.817275249331, 4840.467548569654, 4911.066897289504, 4931.3835770528685, 4970.204767237297, 4341.934870976938, 4375.0, 4396.010897548026, 4431.933520997188, 4458.866234012985, 4781.593564546983, 4996.275540046673, 5706.749790193033, 5800.564957628195, 5840.561957853178, 6231.532635052389, 6262.2172228471445, 6262.624242545462, 6272.0, 6272.1219029335925, 5730.169125476866, 5824.155497452845, 5860.2967643883, 5886.0288052471715, 5927.881442371215, 4498.3252512123445, 5383.81156659515, 5403.594730395236, 5409.675419474844, 5415.593830462731, 5295.841124766263, 6169.784057557964, 6366.172397588284, 6377.617342959437, 6382.489400847921, 6166.982447896047, 6201.1938448003275, 6211.223597050369, 6226.439620434183, 6247.5001999840815, 6348.71430785625, 6360.204974378203, 6364.140840986593, 6379.681015949277, 6380.649064301508, 5188.455212202815, 6108.236470441195, 6158.0, 6215.844603884939, 6221.035739460391, 6177.444030037318, 6247.562670613195, 6279.560430769802, 6280.780172693934, 6285.59143655661, 4718.457377401505, 5860.94139058621, 6144.662043587646, 6191.19514463117, 6211.440970312694, 5920.496757775465, 5995.700214989321, 6033.879322413615, 6180.81457556274, 6194.256689197326, 4713.31656909753, 5965.940340596539, 5998.730057147526, 6039.607425517503, 6064.3935606439, 5178.32681751385, 5686.857828554319, 6077.6353418795015, 6157.907631385622, 6164.352742962104, 5370.892582148257, 5903.881922361615, 5977.252843394575, 5996.520278377807, 6034.2155519782145], "value": 5559.744616536917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.09397802022323, 510.6706174517436, 511.46551853313287, 541.3963430774687, 577.6765011593507, 32640.347193056125, 42146.578534214685, 42489.57510424902, 42601.57398426015, 42613.78693106534, 37983.620163798354, 41086.7945660272, 43674.0, 43841.903952401204, 44206.0, 9529.809403811902, 9703.466309352983, 9744.22046236303, 9791.06984836443, 9794.265430092735, 9626.855597166013, 9674.129328360446, 9704.951475242613, 9711.562979665896, 9746.31775775696, 9717.708468745948, 9742.123208911196, 9767.218622510218, 9782.706518804447, 9796.0, 9609.663661771805, 9619.711408657751, 9636.71089867305, 9660.275479339043, 9666.0, 9572.47351395673, 9646.180074693659, 9685.982971788002, 9688.370255933341, 9710.514474276259, 39962.000949976195, 40889.0, 40915.0, 41423.75728728143, 43457.0, 2643.4977354302623, 3068.4783586790077, 3125.6092988376454, 3153.8738450462183, 3294.390537750524, 29299.853500732574, 29601.407971840443, 29614.70385296141, 29952.70047299521, 30166.0, 2702.391961808597, 2748.2030211238716, 2752.325680208342, 2864.3555200079813, 2880.251134704971, 382891.0, 388335.0, 390641.0, 393849.0, 396688.0, 64500.0, 64716.676416617614, 64723.67638161779, 64741.0, 64962.67518662376, 36029.27941441156, 36318.0, 36366.09084772849, 38285.42571861424, 67756.0, 17153.142342882806, 17264.827351726573, 17269.222884970208, 17451.825481745273, 17489.0, 10495.632652857266, 12136.45385957634, 13145.868541314656, 13166.0, 13253.0, 16304.184790760652, 20446.79553204449, 20454.488637784176, 20612.278570249968, 20641.380758577267, 7451.478396512301, 8464.957675211585, 8483.490990540588, 8487.108853570286, 8498.192671696179, 885.6324625280437, 967.2165545907837, 994.2841154368842, 999.7900440907346, 1082.8538147350007, 30312.0, 30482.54276185859, 30559.694403056128, 30871.845640772102, 30944.535831962537, 1046.1839764983324, 1067.4609322292347, 1078.8651418572679, 1176.6409796684843, 1221.065884598283, 15422.383104675911, 15496.767548486781, 16091.0, 16444.177791110396, 16567.668646627, 602725.0, 695136.0, 698332.0, 700592.0, 701997.0, 53981.0, 61970.38029619736, 64202.0, 64315.356846431874, 64695.0, 1367.6033950154542, 1443.5813614052017, 1451.8330392004843, 1482.2959094430275, 1487.263804416799, 27766.66799984015, 28871.0, 30711.385772283982, 31411.84294078515, 31516.842415787774, 54173.18740218979, 54339.45660543344, 54986.0, 55038.724806377315, 55117.72441137768, 658049.0, 659509.0, 660303.0, 660805.0, 664802.0, 26830.0, 27002.594961075993, 27288.72711272941, 27349.589756153277, 28008.019719309726, 47896.5210347906, 48247.793805155154, 48632.5136748628, 49257.507424925294, 49504.50495494999, 39322.0, 39657.40513892352, 39826.60173398229, 40540.797296013334, 40757.98105047397, 38189.854304370914, 38436.42345364878, 38556.0, 39086.02284942899, 39307.80346098251, 32965.50551741774, 38035.809820950715, 38543.0, 38812.6118738809, 39315.80342098271, 33309.83345083259, 35279.0, 35311.470327945615, 36128.45807312945, 36361.818190908874, 24211.75788242095, 25257.74742252554, 25349.493010139326, 25775.484490309715, 26087.478250434506], "value": 20058.356539966164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.5631836025291, 388.8347452332759, 390.3598099117447, 391.82563759127197, 415.46820070310014, 14521.712933925304, 16143.596410089754, 16946.91526542367, 19002.334918277847, 20577.897110514445, 17341.479755607354, 17412.912935435335, 20261.290854820083, 20703.896480517575, 21022.684659730116, 5771.3074431068335, 5845.152452894327, 6034.396560343975, 6059.212302400681, 6068.6965651717455, 5216.6869987800665, 5998.490128339096, 6052.697365131748, 6065.727042283108, 6068.211132552762, 5045.243213517983, 5714.628549144292, 5839.240898683165, 5996.370381109986, 6032.638041717489, 5937.22816033915, 5942.554308426864, 5961.791337303173, 5963.61236519627, 5971.850703732398, 4995.100881841267, 5160.174372100483, 5199.558037566811, 5215.478452154792, 5217.243499692561, 24201.031958721807, 24637.753622463726, 25277.620835687478, 25696.614550781567, 25723.35691607706, 2085.8644188127873, 2133.7332833395826, 2201.8678879267136, 2256.3419218185963, 2484.763947424992, 14911.403543858232, 15082.0, 15242.771358429633, 15295.311710973034, 15495.767563486557, 1747.9038652874087, 1769.6991511443086, 1827.6253368059515, 1840.4938641873591, 1847.8798878072882, 115747.84252157455, 120270.3986480062, 120661.39669301685, 122064.0, 123393.38303308426, 10647.94676026615, 11059.72350691239, 11170.106391488662, 11248.943755281334, 11283.435828208556, 13861.376238069312, 13970.441182352592, 14064.85935140656, 14151.009429339847, 14156.433742650179, 7177.354038136593, 7687.692492300245, 8638.26574741154, 8743.907011623547, 8839.0, 8594.613242404106, 8755.0, 8757.255633271243, 8893.599788009555, 8917.509536975398, 5274.472552744772, 5862.5896187267335, 6809.557378770364, 6813.625250611165, 6833.350831670983, 1865.8414034806922, 1925.768907731053, 1926.8843869367884, 1929.5851391950516, 1932.352661858265, 906.1120102299783, 906.5648488725449, 939.8496240601471, 991.3506653142204, 1060.5121644043672, 17450.0, 20392.388228353175, 20451.386458406272, 20498.282560110623, 20540.178392864418, 971.7667759737612, 976.7069879036261, 981.6073570571829, 1007.2194049611463, 1030.304544432505, 10696.411697356565, 10701.411422371844, 10983.780324393467, 11012.063974562092, 11027.558897644005, 585196.0740196272, 620332.0, 621381.8930905317, 622570.0, 635797.0, 106671.8665626683, 136961.0, 137019.0, 141685.5831441693, 143656.12687746185, 1065.3394895165047, 1068.8022715797601, 1070.1813112968584, 1083.462494953976, 1095.7260684828793, 18519.35182268668, 18650.53373665615, 18733.250669973047, 18856.0, 18888.15003324826, 51907.96184076373, 52428.0, 53686.194707080205, 55489.722551386985, 56136.0, 60590.697046514484, 81799.5910020446, 82381.58809205916, 82547.5872620657, 85921.57039214764, 5262.315898933114, 6035.456808887221, 7493.400527957641, 7804.726834560764, 8436.70471533518, 10150.390976541135, 10160.732492353378, 11164.38595877202, 12343.629691109281, 12600.936995314965, 9062.365634405529, 10597.682069537927, 10680.89319106799, 11093.833592495957, 11150.721231969266, 10645.840312395476, 10981.286216395909, 11036.337819730841, 11101.38942358178, 11125.0, 10974.945125274642, 11128.276662016942, 11178.27341222818, 11209.383483908137, 11217.214794964275, 7765.72819951299, 8048.678052877819, 8123.75628731139, 8131.552764598004, 8135.105138434886, 9617.326787125114, 9906.801863962824, 10924.2899211551, 11023.779524409627, 11285.435728213391], "value": 9633.204751347543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.463710020043, 1589.6661701042783, 1840.227104616061, 1936.3126090237956, 1956.9510762230952, 7799.337056350217, 8296.0, 8352.916470835306, 8782.253508451773, 8787.736367908958, 8514.531700756455, 8595.570221488917, 8596.527191004492, 8746.693865714702, 8765.079666635007, 2384.3085505203558, 2393.5930891748403, 2401.135591187171, 2411.963820542693, 2414.61366181411, 2375.9406014849683, 2382.154335211001, 2395.8921848516857, 2399.8680072596, 2411.7105947286263, 2362.870042147681, 2377.583922813509, 2387.8328517003815, 2397.6643269942133, 2398.8560686358787, 2283.5547068321684, 2290.862548247102, 2299.31020693792, 2308.988455057714, 2383.487550176703, 2036.297477370305, 2306.1467257114805, 2318.710161229846, 2322.3381336319067, 2326.150954901466, 8003.679852805881, 8167.897333859972, 8661.047284798668, 8791.560421978875, 8792.912070879273, 5199.350081239845, 5297.0, 5330.93381323733, 5361.544268737162, 5362.678239305654, 3012.9454690858024, 3118.0957522318727, 3257.1368587324378, 3356.0, 3496.9825150874085, 881.4755220643707, 993.9304248702593, 1008.5814387029448, 1028.6605420211313, 1032.8502367156796, 11328.09375249978, 11425.14311426638, 11440.885591144066, 11452.94273528627, 11474.368909709963, 4071.0, 4529.252673308867, 4745.240761478147, 4756.120117778222, 4759.357486739315, 9646.469444180497, 9715.757106072404, 9795.559199835881, 9832.950835245922, 9839.065288797696, 5235.502627250406, 5251.133562962144, 5310.8937821244135, 5327.7336133193185, 5335.759890804923, 4264.061906380592, 4699.013207226452, 4831.444383895897, 4905.484924082992, 4961.950380496221, 3886.1256217351433, 3947.4473573699706, 3978.462907507507, 4008.1382502762044, 4044.170944956336, 1167.7080729817546, 1276.3363051213316, 1289.4842063174806, 1304.5368894042479, 1308.1431662261234, 790.2768966395798, 857.0, 878.4685265414332, 880.7357792662303, 899.9550022498848, 4115.1975364803575, 5355.3305836770405, 5543.307086614173, 5607.52336051432, 5619.831405057855, 958.3435346787472, 960.351762560269, 961.6730311694058, 968.2205824311357, 1006.864073350103, 3568.660977207161, 3653.4337177737493, 3672.540932383452, 3712.2204337088974, 3834.424836274581, 10288.639897603698, 10602.0, 10612.522436490377, 10640.574377024986, 10654.360738355723, 9000.50497222659, 10329.276950613385, 10437.582496700046, 10533.157347412189, 10541.789164216672, 6594.86810263792, 6787.966060169668, 8212.425130240827, 8216.342692584698, 8223.712170074139, 5386.919196211981, 5592.69240191777, 5623.100303951348, 5660.349059858005, 5673.687947162945, 9618.374805637612, 9876.160526355341, 9898.653547125816, 9932.453715045453, 9967.202623790225, 9567.138957493858, 9756.463394513086, 9969.102780749767, 10039.899601003897, 10066.597336106473, 6442.613443193423, 6782.728690852508, 7148.070750802362, 7420.443466739908, 7428.814279643052, 8126.55303958288, 8142.7557173284895, 8167.44818484486, 8171.959140204499, 8240.340772738047, 7211.062561866924, 7310.9634451829525, 7333.1200255969625, 7404.444666650131, 7468.551886886804, 6968.477364197604, 7191.208967013729, 7194.928050719635, 7201.675924583511, 7218.314260145278, 6842.55523390978, 6850.965745171442, 6900.309969003059, 6957.130358705162, 6966.651667416711, 4503.617192538671, 4623.745693986729, 4639.559241872017, 4641.419822522184, 4777.761111944458, 4467.2182368086105, 4692.319613655933, 4762.78567464458, 4763.214069678603, 4789.832355867528], "value": 4481.630405304798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.6520696819562, 312.10426077158553, 315.19467759873544, 318.4490830862609, 318.8979526551504, 4934.0, 5341.545968592664, 5481.848811749537, 5520.309961254843, 5601.5518758499275, 4433.91132177357, 4879.365682461275, 5348.609361565992, 5449.536789372898, 5466.753996070176, 3696.669199088326, 3977.840886364549, 4200.663946884257, 4230.682698797587, 4256.914861702764, 3530.258645684409, 4221.493420789509, 4226.746395216282, 4237.194932962742, 4257.8296868125235, 3527.7177825774006, 4181.60274773896, 4207.3493698969605, 4253.702240843142, 4264.42430271914, 4099.364598487225, 4244.596763307481, 4244.61798438139, 4264.808083636244, 4264.933766558361, 3103.643081045686, 3626.9455958160647, 3647.7993710345922, 3657.7439579229463, 3678.6505281998434, 4389.517153113155, 5282.683039017633, 5284.682919024871, 5366.785328586851, 5394.951852203531, 4434.977825110886, 4697.859064228044, 4820.493848145964, 4821.421429428456, 4824.879378015543, 4821.879453013667, 5529.751161197756, 5750.712464376807, 5767.88464230713, 5797.376734514342, 3469.61834198238, 3730.645588669072, 3765.585785563586, 3880.9611903881164, 3921.5882332354977, 4966.55301022906, 5122.462141475129, 5174.818881339173, 5491.917621235724, 5876.82463507296, 3085.5988721466065, 3249.675032496779, 3301.290222602104, 3746.138388170736, 3772.603876592973, 3658.6158453362546, 3837.829462014057, 4031.314676505001, 4044.2518134145275, 4044.6359827615656, 4077.347624380085, 4463.411221491527, 4686.835960741358, 4740.881477963078, 4744.312074749142, 4916.483769204254, 5390.54180394663, 5431.321084864392, 5683.920055189501, 5913.290405151323, 1153.423288355822, 1216.7809794236946, 1264.8482182138202, 1371.1430356027483, 1385.6189547874415, 737.2222305467783, 827.668932427034, 846.4582667093065, 854.0946596607616, 874.61079819481, 5062.620303477254, 5085.567726743268, 5195.11683013881, 5274.707696614354, 5392.892142157135, 4675.901163226639, 5214.947850521522, 5498.477644623835, 5587.38538760736, 5915.792947246826, 4730.716157030589, 4863.392075990501, 5060.5698515625845, 5259.368875734936, 5329.333833270841, 2642.502834821076, 2844.2604922720243, 3010.6838781928022, 3144.9528257076163, 3177.0778678899396, 5374.74827729226, 5986.0, 6000.609960352563, 6317.305096439388, 6347.904781428282, 5698.487136157766, 5841.50347220491, 6155.645757933247, 6180.3201647818705, 6180.99999999991, 5979.521638268928, 6241.157443745127, 6282.528810339243, 6347.714352854225, 6468.091912885616, 4881.8535443936735, 5540.91688624679, 5616.017196990592, 5974.1934838796205, 6139.508839292756, 5564.888702226014, 5903.793367232126, 5932.940670593239, 5941.37615550361, 5957.6127551709, 5146.639735218649, 5389.75746091419, 5577.191307260506, 6154.569180157346, 6347.936520634735, 3508.386032444363, 3686.741928065117, 3941.6058394160355, 3954.2486927483687, 3954.9011274717213, 5350.946490535045, 5370.19447082925, 5512.255845460972, 5891.9116213255975, 5942.851428714143, 4822.577339684732, 5240.397354304151, 5644.88710225785, 5820.970895145497, 5854.180414742024, 5791.189233507228, 5800.186909349047, 5806.883862322815, 5861.765529378948, 5898.1152827076285, 4762.904741905073, 5480.616356854982, 5762.481376676119, 5783.843231353662, 5805.506531944832, 5432.7555260012605, 5549.833504994856, 5735.856603584777, 5905.99598068338, 5978.312494063239, 4970.975145124251, 5201.947980520146, 5746.597738158289, 5849.415058494116, 5892.085072351417], "value": 4151.293462612738, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.3340725866874, 723.6851969393311, 739.6338812287917, 757.2768006553741, 764.0602059466859, 12398.566050188245, 14422.278886055694, 14641.633959151026, 15161.924190379033, 15180.2409879506, 11128.165387595942, 12040.518379264819, 14097.154170749762, 14147.787783183236, 14158.64603384916, 708.0901042160824, 877.0265005843524, 888.1518150166607, 892.0722448653426, 894.2979760887705, 715.595688436032, 851.7870532366908, 878.6353663229752, 888.5290795878162, 893.2005854760018, 897.5915958239018, 899.1278459893925, 899.2581120575519, 931.8695382646434, 939.4316438554679, 891.9018907920124, 893.8882639670041, 908.2234689340651, 932.4451951089092, 939.7133874168376, 663.7643636509018, 667.9866402671968, 678.375894177358, 699.4718987164658, 702.0872865275173, 14187.361568729431, 14563.781543276858, 14563.927180364137, 14584.416623335053, 15128.319225634763, 12226.510939562408, 12281.631551053482, 12331.568395106218, 12409.379531023504, 12521.0, 9860.65487707934, 9905.405675659484, 9921.702348929544, 9968.700938971842, 10285.971402859654, 3415.9829200853837, 3435.845386957593, 3456.844442000116, 3470.323286959044, 3483.5645544306963, 64072.0, 76106.0, 80998.59500702517, 83586.58206708988, 83654.0, 7529.397648188132, 9291.349605527683, 9333.63965997742, 9343.439393636403, 9460.952695236618, 21359.14563417446, 21504.78495215028, 21787.891060544596, 21794.891025545086, 21806.018729157226, 7334.3765779908235, 7408.444366672414, 7414.597426780073, 7443.9627801861725, 7474.215207403253, 3279.885204017848, 3905.3165696003084, 3931.2334094851803, 3984.0, 3995.6603688686787, 3466.3587236361354, 3527.94708079379, 3565.180008598037, 3567.0369000369756, 3568.91077723059, 586.2320360327984, 636.1285039495828, 637.9202599675041, 644.6969924135649, 645.2901808011151, 486.7250003747942, 500.4520050544648, 527.6438404077232, 544.2598066629381, 578.4244676546779, 7608.733694320672, 7625.466217364842, 7656.272654097852, 7819.569923654253, 7926.771350440691, 502.51005269861594, 564.6894208185481, 581.776016233753, 590.3889474393982, 611.4832966143601, 3612.277544491112, 3659.5425571803526, 3705.40713485841, 3753.5120434343908, 3954.6045395460224, 76037.23962760302, 78517.21482785213, 86137.70793438103, 90077.0, 90133.54933225423, 59395.109073363936, 76921.46157076827, 78654.0, 79405.80891286636, 79740.60129699315, 69904.60190796155, 71251.0950067693, 71314.0, 71882.56234875378, 73793.15517112223, 7863.213678632091, 7901.854231136568, 7910.050793904768, 7982.481138725964, 8050.5169689818795, 38595.80702096584, 42907.0, 44332.0, 44524.0, 45419.772901135286, 59911.80176396535, 59939.70030149821, 60263.49341266503, 60499.39500604937, 60670.089948649926, 10824.134069274282, 10908.672739817817, 11014.44927753593, 11050.717397386234, 11314.83027754568, 11875.346855923008, 11959.880401195876, 12025.398730063636, 12229.69425764363, 12399.194052386567, 8986.955065224633, 10921.726956826144, 10966.067884229611, 11031.944840275748, 11139.0, 9989.250806189419, 11120.666380008613, 11462.369569673414, 11525.423728813357, 11663.59177428786, 10904.236703430684, 10968.067714244378, 11073.169512286771, 11078.058365039062, 11108.57324448592, 9191.0, 9398.95300523493, 9660.082292182231, 9691.854622180814, 9719.515972701121, 6844.965775171092, 9140.223081038186, 9328.0, 9484.715458536255, 9563.852337719518], "value": 6642.715747027483, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1628.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 107.0, 117.0, 117.0, 117.0, 115.0, 116.0, 115.0, 129.0, 158.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 122.0, 132.0, 159.0, 124.0, 145.0, 165.0, 153.0, 174.0, 146.0], "value": 149.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 197.0, 207.0, 235.0, 192.0, 197.0, 193.0, 193.0, 232.0, 248.0], "value": 208.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 100.0, 101.0, 165.0, 59.0, 59.0, 58.0, 61.0, 124.0, 57.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 101.0, 164.0, 100.0, 84.0, 76.0, 114.0, 70.0, 73.0, 115.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 124.0, 129.0, 89.0, 129.0, 135.0, 87.0], "value": 103.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 152.0, 151.0, 96.0, 102.0, 95.0, 95.0, 142.0, 116.0, 152.0], "value": 121.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 125.0, 89.0, 146.0, 112.0, 89.0, 92.0, 91.0, 91.0, 90.0], "value": 106.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 58.0, 56.0, 61.0, 61.0, 61.0, 75.0, 60.0, 58.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 69.0, 94.0, 103.0, 64.0, 88.0, 96.0, 70.0, 87.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 109.0, 111.0, 106.0, 88.0, 86.0, 87.0, 87.0, 90.0, 86.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 152.0, 150.0, 152.0, 149.0, 143.0, 147.0, 142.0, 180.0, 142.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 147.0, 213.0, 226.0, 166.0, 168.0, 165.0, 189.0, 229.0, 175.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 63.0, 66.0, 65.0, 68.0, 65.0], "value": 66.2, "unit": "score"}]}]} 09:22:04 INFO - Return code: 0 09:22:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:04 ERROR - # TBPL SUCCESS # 09:22:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:04 INFO - 2016-05-25 09:22:04.799 screenresolution[1817:10540] starting screenresolution argv=screenresolution get 09:22:04 INFO - 2016-05-25 09:22:04.802 screenresolution[1817:10540] Display 0: 1600x1200x32@60 09:22:04 INFO - 2016-05-25 09:22:04.807 screenresolution[1818:10543] starting screenresolution argv=screenresolution list 09:22:04 INFO - Available Modes on Display 0 09:22:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:05 INFO - Intel Iris: 09:22:05 INFO - Chipset Model: Intel Iris 09:22:05 INFO - Type: GPU 09:22:05 INFO - Bus: Built-In 09:22:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:05 INFO - Vendor: Intel (0x8086) 09:22:05 INFO - Device ID: 0x0a2e 09:22:05 INFO - Revision ID: 0x0009 09:22:05 INFO - Displays: 09:22:05 INFO - Display: 09:22:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:05 INFO - Display Serial Number: 1600x1200 60 09:22:05 INFO - Main Display: Yes 09:22:05 INFO - Mirror: Off 09:22:05 INFO - Online: Yes 09:22:05 INFO - Rotation: Supported 09:22:05 INFO - Return code: 0 09:22:05 INFO - Running post-action listener: _resource_record_post_action 09:22:05 INFO - ##### 09:22:05 INFO - ##### Finished run-tests step (success) 09:22:05 INFO - ##### 09:22:05 INFO - Running post-run listener: _resource_record_post_run 09:22:05 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 37733888; Write bytes: 700231168; Read time: 246; Write time: 1970 09:22:05 INFO - TinderboxPrint: CPU usage
54.6% 09:22:05 INFO - TinderboxPrint: I/O read bytes / time
37,733,888 / 246 09:22:05 INFO - TinderboxPrint: I/O write bytes / time
700,231,168 / 1,970 09:22:05 INFO - TinderboxPrint: CPU idle
2,194.0 (45.4%) 09:22:05 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 09:22:05 INFO - TinderboxPrint: CPU user
1,661.4 (34.4%) 09:22:05 INFO - TinderboxPrint: Swap in / out
435,187,712 / 0 09:22:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398242304; Write bytes: 399448064; Read time: 29810; Write time: 799 09:22:05 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 35919360; Write bytes: 299935232; Read time: 230; Write time: 1168 09:22:05 INFO - Running post-run listener: _upload_blobber_files 09:22:05 INFO - Blob upload gear active. 09:22:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:22:05 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'] 09:22:05 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 09:22:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:22:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:06 INFO - (blobuploader) - INFO - Done attempting. 09:22:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:22:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:07 INFO - (blobuploader) - INFO - Done attempting. 09:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:10 INFO - (blobuploader) - INFO - Done attempting. 09:22:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:10 INFO - Return code: 0 09:22:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05c66cd74aabb0fd88c59d912118a527a68c44b12d83646238236e58da0b3ba58d12b1f74aa555f7582786751b2e822939d998e51d24ba19631da54c0e941f17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f185166ee946b486fc347ac351bef88c62ee0bdae6b545e295bbe323c0c8b33abeca8c3cb599abb6aaa55e076d2eb88804abb126503b9e83e2a713975f31e667", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d0e7a303128c9a780d7a3e8e1133f48b164f8b82175df5b260d3b9f0ff025b17a9ad565b9484c82f04477dfc68895decfe2613ed33025f68eb9bff8f18d3c8"} 09:22:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:10 INFO - Contents: 09:22:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05c66cd74aabb0fd88c59d912118a527a68c44b12d83646238236e58da0b3ba58d12b1f74aa555f7582786751b2e822939d998e51d24ba19631da54c0e941f17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f185166ee946b486fc347ac351bef88c62ee0bdae6b545e295bbe323c0c8b33abeca8c3cb599abb6aaa55e076d2eb88804abb126503b9e83e2a713975f31e667", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d0e7a303128c9a780d7a3e8e1133f48b164f8b82175df5b260d3b9f0ff025b17a9ad565b9484c82f04477dfc68895decfe2613ed33025f68eb9bff8f18d3c8"} 09:22:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:10 INFO - Copying logs to upload dir... 09:22:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.688791 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-25 09:22:11.262360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 09:22:11.266378) ========= 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.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/05c66cd74aabb0fd88c59d912118a527a68c44b12d83646238236e58da0b3ba58d12b1f74aa555f7582786751b2e822939d998e51d24ba19631da54c0e941f17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f185166ee946b486fc347ac351bef88c62ee0bdae6b545e295bbe323c0c8b33abeca8c3cb599abb6aaa55e076d2eb88804abb126503b9e83e2a713975f31e667", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d0e7a303128c9a780d7a3e8e1133f48b164f8b82175df5b260d3b9f0ff025b17a9ad565b9484c82f04477dfc68895decfe2613ed33025f68eb9bff8f18d3c8"} build_url:https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/BAg7vywdRCODrIvvcRjl_A/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/05c66cd74aabb0fd88c59d912118a527a68c44b12d83646238236e58da0b3ba58d12b1f74aa555f7582786751b2e822939d998e51d24ba19631da54c0e941f17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f185166ee946b486fc347ac351bef88c62ee0bdae6b545e295bbe323c0c8b33abeca8c3cb599abb6aaa55e076d2eb88804abb126503b9e83e2a713975f31e667", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d0e7a303128c9a780d7a3e8e1133f48b164f8b82175df5b260d3b9f0ff025b17a9ad565b9484c82f04477dfc68895decfe2613ed33025f68eb9bff8f18d3c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 09:22:11.299998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:22:11.300333) ========= 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.xweQwLupKj/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.pmwZPVffzR/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005272 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 09:22:11.351748) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 09:22:11.352509) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 09:22:11.353312) ========= ========= Total master_lag: 0.70 =========