builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0046 starttime: 1463179997.34 results: success (0) buildid: 20160513135736 builduid: 39ad5e3a832143f19cbfc5e9ce344704 revision: edcd8d596806c757423acc2573c9047c569fb4f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.340369) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.340808) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.341101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.395564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.395868) ========= 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.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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-13 15:53:17-- 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% 170M=0s 2016-05-13 15:53:17 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.370849 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.793643) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.793959) ========= 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.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.028061 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:17.842698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 15:53:17.843030) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev edcd8d596806c757423acc2573c9047c569fb4f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev edcd8d596806c757423acc2573c9047c569fb4f8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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-13 15:53:18,032 truncating revision to first 12 chars 2016-05-13 15:53:18,032 Setting DEBUG logging. 2016-05-13 15:53:18,032 attempt 1/10 2016-05-13 15:53:18,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/edcd8d596806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 15:53:20,363 unpacking tar archive at: fx-team-edcd8d596806/testing/mozharness/ program finished with exit code 0 elapsedTime=2.579007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 15:53:20.441253) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:20.441620) ========= script_repo_revision: edcd8d596806c757423acc2573c9047c569fb4f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:20.442029) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:20.442396) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 15:53:20.457039) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-05-13 15:53:20.457435) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:53:20 INFO - MultiFileLogger online at 20160513 15:53:20 in /builds/slave/test 15:53:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:53:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:53:20 INFO - {'append_to_log': False, 15:53:20 INFO - 'base_work_dir': '/builds/slave/test', 15:53:20 INFO - 'blob_upload_branch': 'Fx-Team', 15:53:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:53:20 INFO - 'branch': 'Fx-Team', 15:53:20 INFO - 'buildbot_json_path': 'buildprops.json', 15:53:20 INFO - 'config_files': ('talos/mac_config.py',), 15:53:20 INFO - 'default_actions': ('clobber', 15:53:20 INFO - 'read-buildbot-config', 15:53:20 INFO - 'download-and-extract', 15:53:20 INFO - 'populate-webroot', 15:53:20 INFO - 'create-virtualenv', 15:53:20 INFO - 'install', 15:53:20 INFO - 'run-tests'), 15:53:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:53:20 INFO - 'download_minidump_stackwalk': True, 15:53:20 INFO - 'download_symbols': 'ondemand', 15:53:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:53:20 INFO - 'tooltool.py': '/tools/tooltool.py', 15:53:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:53:20 INFO - '/tools/misc-python/virtualenv.py')}, 15:53:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:53:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:53:20 INFO - 'installer_path': 'installer.exe', 15:53:20 INFO - 'log_level': 'info', 15:53:20 INFO - 'log_name': 'talos', 15:53:20 INFO - 'log_to_console': True, 15:53:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:53:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:53:20 INFO - 'opt_config_files': (), 15:53:20 INFO - 'pip_index': False, 15:53:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:53:20 INFO - 'cmd': ('bash', 15:53:20 INFO - '-c', 15:53:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:53:20 INFO - 'enabled': True, 15:53:20 INFO - 'halt_on_failure': False, 15:53:20 INFO - 'name': 'check_screen_resolution'},), 15:53:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:53:20 INFO - 'cmd': ('bash', 15:53:20 INFO - '-c', 15:53:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:53:20 INFO - 'enabled': True, 15:53:20 INFO - 'halt_on_failure': False, 15:53:20 INFO - 'name': 'check_screen_resolution'},), 15:53:20 INFO - 'run_cmd_checks_enabled': True, 15:53:20 INFO - 'sps_profile': False, 15:53:20 INFO - 'sps_profile_interval': 0, 15:53:20 INFO - 'suite': 'dromaeojs-e10s', 15:53:20 INFO - 'system_bits': '32', 15:53:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:53:20 INFO - 'title': 't-yosemite-r7-0046', 15:53:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:53:20 INFO - 'use_talos_json': True, 15:53:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:53:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:53:20 INFO - 'work_dir': 'build'} 15:53:20 INFO - ##### 15:53:20 INFO - ##### Running clobber step. 15:53:20 INFO - ##### 15:53:20 INFO - Running pre-action listener: _resource_record_pre_action 15:53:20 INFO - Running main action method: clobber 15:53:20 INFO - rmtree: /builds/slave/test/build 15:53:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:53:22 INFO - Running post-action listener: _resource_record_post_action 15:53:22 INFO - ##### 15:53:22 INFO - ##### Finished clobber step (success) 15:53:22 INFO - ##### 15:53:22 INFO - ##### 15:53:22 INFO - ##### Running read-buildbot-config step. 15:53:22 INFO - ##### 15:53:22 INFO - Running pre-action listener: _resource_record_pre_action 15:53:22 INFO - Running main action method: read_buildbot_config 15:53:22 INFO - Using buildbot properties: 15:53:22 INFO - { 15:53:22 INFO - "project": "", 15:53:22 INFO - "product": "firefox", 15:53:22 INFO - "script_repo_revision": "production", 15:53:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 15:53:22 INFO - "repository": "", 15:53:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 15:53:22 INFO - "buildid": "20160513135736", 15:53:22 INFO - "pgo_build": "False", 15:53:22 INFO - "basedir": "/builds/slave/test", 15:53:22 INFO - "buildnumber": 5, 15:53:22 INFO - "slavename": "t-yosemite-r7-0046", 15:53:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:53:22 INFO - "platform": "yosemite_r7", 15:53:22 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 15:53:22 INFO - "branch": "fx-team", 15:53:22 INFO - "repo_path": "integration/fx-team", 15:53:22 INFO - "revision": "edcd8d596806c757423acc2573c9047c569fb4f8", 15:53:22 INFO - "stage_platform": "macosx64", 15:53:22 INFO - "builduid": "39ad5e3a832143f19cbfc5e9ce344704", 15:53:22 INFO - "slavebuilddir": "test" 15:53:22 INFO - } 15:53:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:53:22 INFO - Running post-action listener: _resource_record_post_action 15:53:22 INFO - ##### 15:53:22 INFO - ##### Finished read-buildbot-config step (success) 15:53:22 INFO - ##### 15:53:22 INFO - ##### 15:53:22 INFO - ##### Running download-and-extract step. 15:53:22 INFO - ##### 15:53:22 INFO - Running pre-action listener: _resource_record_pre_action 15:53:22 INFO - Running main action method: download_and_extract 15:53:22 INFO - mkdir: /builds/slave/test/build/tests 15:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:22 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:53:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:53:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:53:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:53:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:53:36 INFO - Downloaded 1199 bytes. 15:53:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:53:36 INFO - Using the following test package requirements: 15:53:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:53:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:53:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:53:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'jsshell-mac.zip'], 15:53:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:53:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:53:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:53:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:53:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:53:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:53:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:53:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:53:36 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')]} 15:53:36 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:53:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:53:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:53:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:53:58 INFO - Downloaded 18153816 bytes. 15:53:58 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'] 15:53:58 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 15:54:01 INFO - Return code: 0 15:54:01 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 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:54:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:54:02 INFO - Downloaded 18153816 bytes. 15:54:02 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'] 15:54:02 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 15:54:06 INFO - Return code: 0 15:54: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')]} 15:54:06 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:54:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:54:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:54:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:54:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:54:07 INFO - Downloaded 10840239 bytes. 15:54:07 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'] 15:54:07 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 15:54:07 INFO - Return code: 0 15:54: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')]} 15:54:07 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:54:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:54:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 15:54:11 INFO - Downloaded 110063875 bytes. 15:54:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:11 INFO - mkdir: /builds/slave/test/properties 15:54:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:54:11 INFO - Writing to file /builds/slave/test/properties/build_url 15:54:11 INFO - Contents: 15:54:11 INFO - build_url:https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:13 INFO - Running post-action listener: _resource_record_post_action 15:54:13 INFO - Running post-action listener: set_extra_try_arguments 15:54:13 INFO - ##### 15:54:13 INFO - ##### Finished download-and-extract step (success) 15:54:13 INFO - ##### 15:54:13 INFO - ##### 15:54:13 INFO - ##### Running populate-webroot step. 15:54:13 INFO - ##### 15:54:13 INFO - Running pre-action listener: _resource_record_pre_action 15:54:13 INFO - Running main action method: populate_webroot 15:54:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:54:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:54:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:54:13 INFO - '--tppagecycles', 15:54:13 INFO - '7'], 15:54:13 INFO - 'tests': ['tsvgm']}}, 15:54:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['damp', 'tps']}, 15:54:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'tests': ['damp', 'tps']}, 15:54:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['dromaeo_dom']}, 15:54:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['a11yr', 15:54:13 INFO - 'ts_paint', 15:54:13 INFO - 'tpaint', 15:54:13 INFO - 'sessionrestore', 15:54:13 INFO - 'sessionrestore_no_auto_restore', 15:54:13 INFO - 'tabpaint']}, 15:54:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['tsvgx', 15:54:13 INFO - 'tsvgr_opacity', 15:54:13 INFO - 'tart', 15:54:13 INFO - 'tscrollx', 15:54:13 INFO - 'cart']}, 15:54:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:13 INFO - 'tsvgr_opacity', 15:54:13 INFO - 'tart', 15:54:13 INFO - 'tscrollx', 15:54:13 INFO - 'cart']}, 15:54:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'talos_options': ['--disable-e10s'], 15:54:13 INFO - 'tests': ['tp5o']}, 15:54:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'tests': ['tp5o']}, 15:54:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'talos_options': ['--disable-e10s', 15:54:13 INFO - '--xperf_path', 15:54:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:13 INFO - 'tests': ['tp5n']}, 15:54:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:13 INFO - 'talos_options': ['--xperf_path', 15:54:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:13 INFO - 'tests': ['tp5n']}}, 15:54:13 INFO - 'talos.zip': {'path': '', 15:54:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:54:13 INFO - Running post-action listener: _resource_record_post_action 15:54:13 INFO - ##### 15:54:13 INFO - ##### Finished populate-webroot step (success) 15:54:13 INFO - ##### 15:54:13 INFO - ##### 15:54:13 INFO - ##### Running create-virtualenv step. 15:54:13 INFO - ##### 15:54:13 INFO - Running pre-action listener: _resource_record_pre_action 15:54:13 INFO - Running main action method: create_virtualenv 15:54:13 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:13 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 15:54:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:54:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:13 INFO - Using real prefix '/tools/python27' 15:54:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:14 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:17 INFO - Installing pip.................done. 15:54:17 INFO - Return code: 0 15:54:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:54:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:17 INFO - 'HOME': '/Users/cltbld', 15:54:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:17 INFO - 'LOGNAME': 'cltbld', 15:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:17 INFO - 'MOZ_NO_REMOTE': '1', 15:54:17 INFO - 'NO_EM_RESTART': '1', 15:54:17 INFO - 'PAGER': '/bin/cat', 15:54:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:17 INFO - 'PWD': '/builds/slave/test', 15:54:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:17 INFO - 'SHELL': '/bin/bash', 15:54:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:17 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:17 INFO - 'USER': 'cltbld', 15:54:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:17 INFO - 'XPC_FLAGS': '0x0', 15:54:17 INFO - 'XPC_SERVICE_NAME': '0', 15:54:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:17 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 15:54:17 INFO - Cleaning up... 15:54:17 INFO - Return code: 0 15:54:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:54:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:17 INFO - 'HOME': '/Users/cltbld', 15:54:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:17 INFO - 'LOGNAME': 'cltbld', 15:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:17 INFO - 'MOZ_NO_REMOTE': '1', 15:54:17 INFO - 'NO_EM_RESTART': '1', 15:54:17 INFO - 'PAGER': '/bin/cat', 15:54:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:17 INFO - 'PWD': '/builds/slave/test', 15:54:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:17 INFO - 'SHELL': '/bin/bash', 15:54:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:17 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:17 INFO - 'USER': 'cltbld', 15:54:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:17 INFO - 'XPC_FLAGS': '0x0', 15:54:17 INFO - 'XPC_SERVICE_NAME': '0', 15:54:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:17 INFO - Downloading/unpacking psutil>=3.1.1 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:21 INFO - Installing collected packages: psutil 15:54:21 INFO - Running setup.py install for psutil 15:54:21 INFO - building 'psutil._psutil_osx' extension 15:54:21 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 15:54:21 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 15:54:21 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 15:54:22 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 15:54:22 INFO - building 'psutil._psutil_posix' extension 15:54:22 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 15:54:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:54:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:54:22 INFO - ^ 15:54:22 INFO - 1 warning generated. 15:54:22 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 15:54:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:22 INFO - Successfully installed psutil 15:54:22 INFO - Cleaning up... 15:54:22 INFO - Return code: 0 15:54:22 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 15:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:22 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:22 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.1'] in /builds/slave/test/build 15:54:22 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.1 15:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:22 INFO - 'HOME': '/Users/cltbld', 15:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:22 INFO - 'LOGNAME': 'cltbld', 15:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:22 INFO - 'MOZ_NO_REMOTE': '1', 15:54:22 INFO - 'NO_EM_RESTART': '1', 15:54:22 INFO - 'PAGER': '/bin/cat', 15:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:22 INFO - 'PWD': '/builds/slave/test', 15:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:22 INFO - 'SHELL': '/bin/bash', 15:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:22 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:22 INFO - 'USER': 'cltbld', 15:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:22 INFO - 'XPC_FLAGS': '0x0', 15:54:22 INFO - 'XPC_SERVICE_NAME': '0', 15:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:22 INFO - Downloading/unpacking mozsystemmonitor==0.1 15:54:22 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 15:54:22 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 15:54:22 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 15:54:22 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 15:54:22 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 15:54:22 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 15:54:25 INFO - Downloading mozsystemmonitor-0.1.tar.gz 15:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 15:54:25 INFO - Installing collected packages: mozsystemmonitor 15:54:25 INFO - Running setup.py install for mozsystemmonitor 15:54:25 INFO - Successfully installed mozsystemmonitor 15:54:25 INFO - Cleaning up... 15:54:25 INFO - Return code: 0 15:54:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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', '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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:54: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 blobuploader==1.2.4 15:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:25 INFO - 'HOME': '/Users/cltbld', 15:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:25 INFO - 'LOGNAME': 'cltbld', 15:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:25 INFO - 'MOZ_NO_REMOTE': '1', 15:54:25 INFO - 'NO_EM_RESTART': '1', 15:54:25 INFO - 'PAGER': '/bin/cat', 15:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:25 INFO - 'PWD': '/builds/slave/test', 15:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:25 INFO - 'SHELL': '/bin/bash', 15:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:25 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:25 INFO - 'USER': 'cltbld', 15:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:25 INFO - 'XPC_FLAGS': '0x0', 15:54:25 INFO - 'XPC_SERVICE_NAME': '0', 15:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:25 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 15:54:25 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 15:54:25 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 15:54:25 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 15:54:25 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 15:54:25 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 15:54:28 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:29 INFO - Downloading docopt-0.6.1.tar.gz 15:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:29 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:29 INFO - Running setup.py install for blobuploader 15:54:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:29 INFO - Running setup.py install for requests 15:54:30 INFO - Running setup.py install for docopt 15:54:30 INFO - Successfully installed blobuploader requests docopt 15:54:30 INFO - Cleaning up... 15:54:30 INFO - Return code: 0 15:54:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:30 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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:30 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 15:54:30 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 15:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:30 INFO - 'HOME': '/Users/cltbld', 15:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:30 INFO - 'LOGNAME': 'cltbld', 15:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:30 INFO - 'MOZ_NO_REMOTE': '1', 15:54:30 INFO - 'NO_EM_RESTART': '1', 15:54:30 INFO - 'PAGER': '/bin/cat', 15:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:30 INFO - 'PWD': '/builds/slave/test', 15:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:30 INFO - 'SHELL': '/bin/bash', 15:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:30 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:30 INFO - 'USER': 'cltbld', 15:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:30 INFO - 'XPC_FLAGS': '0x0', 15:54:30 INFO - 'XPC_SERVICE_NAME': '0', 15:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:30 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-nTxXfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:30 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-FtB511-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:30 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-GW6p9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:30 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-lGuBH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:30 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-tt5Bdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-NI0uwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-EjgTs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-SKJ62S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-GONUL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-TULSX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-UnGG4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-EIm9x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-XMhGXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-IID2RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:31 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-f5K4RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:32 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-AF5s9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:32 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-fMhaX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:32 INFO - Running setup.py install for manifestparser 15:54:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:32 INFO - Running setup.py install for mozcrash 15:54:32 INFO - Running setup.py install for mozdebug 15:54:32 INFO - Running setup.py install for mozdevice 15:54:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:54:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:54:32 INFO - Running setup.py install for mozfile 15:54:33 INFO - Running setup.py install for mozhttpd 15:54:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Running setup.py install for mozinfo 15:54:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Running setup.py install for mozInstall 15:54:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Running setup.py install for mozleak 15:54:33 INFO - Running setup.py install for mozlog 15:54:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Running setup.py install for moznetwork 15:54:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:33 INFO - Running setup.py install for mozprocess 15:54:34 INFO - Running setup.py install for mozprofile 15:54:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:34 INFO - Running setup.py install for mozrunner 15:54:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:34 INFO - Running setup.py install for mozscreenshot 15:54:34 INFO - Running setup.py install for moztest 15:54:34 INFO - Running setup.py install for mozversion 15:54:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:34 INFO - Cleaning up... 15:54:34 INFO - Return code: 0 15:54:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:34 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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:34 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 15:54:34 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 15:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:34 INFO - 'HOME': '/Users/cltbld', 15:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:34 INFO - 'LOGNAME': 'cltbld', 15:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:34 INFO - 'MOZ_NO_REMOTE': '1', 15:54:34 INFO - 'NO_EM_RESTART': '1', 15:54:34 INFO - 'PAGER': '/bin/cat', 15:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:34 INFO - 'PWD': '/builds/slave/test', 15:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:34 INFO - 'SHELL': '/bin/bash', 15:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:34 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:34 INFO - 'USER': 'cltbld', 15:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:34 INFO - 'XPC_FLAGS': '0x0', 15:54:34 INFO - 'XPC_SERVICE_NAME': '0', 15:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-7Zysu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-oacLXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-QLuBUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-WWqpb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-0Hnu0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-M2gxA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-g4o3k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-E84SOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:35 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)) 15:54:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:35 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-HCV1BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-uQE2EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Y7AYyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-v_ZVYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-MoJlDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-7OSdQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-tWAkrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-nYLXWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:36 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)) 15:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:36 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-z_rSg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:36 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)) 15:54:36 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)) 15:54:36 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)) 15:54:36 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)) 15:54:36 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)) 15:54:36 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)) 15:54:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54:39 INFO - Downloading blessings-1.6.tar.gz 15:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:54:39 INFO - Installing collected packages: blessings 15:54:39 INFO - Running setup.py install for blessings 15:54:39 INFO - Successfully installed blessings 15:54:39 INFO - Cleaning up... 15:54:39 INFO - Return code: 0 15:54:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:54:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:39 INFO - Reading from file tmpfile_stdout 15:54:39 INFO - Current package versions: 15:54:39 INFO - blessings == 1.6 15:54:39 INFO - blobuploader == 1.2.4 15:54:39 INFO - docopt == 0.6.1 15:54:39 INFO - manifestparser == 1.1 15:54:39 INFO - mozInstall == 1.12 15:54:39 INFO - mozcrash == 0.17 15:54:39 INFO - mozdebug == 0.1 15:54:39 INFO - mozdevice == 0.48 15:54:39 INFO - mozfile == 1.2 15:54:39 INFO - mozhttpd == 0.7 15:54:39 INFO - mozinfo == 0.9 15:54:39 INFO - mozleak == 0.1 15:54:39 INFO - mozlog == 3.2 15:54:39 INFO - moznetwork == 0.27 15:54:39 INFO - mozprocess == 0.22 15:54:39 INFO - mozprofile == 0.28 15:54:39 INFO - mozrunner == 6.11 15:54:39 INFO - mozscreenshot == 0.1 15:54:39 INFO - mozsystemmonitor == 0.1 15:54:39 INFO - moztest == 0.7 15:54:39 INFO - mozversion == 1.4 15:54:39 INFO - psutil == 3.1.1 15:54:39 INFO - requests == 1.2.3 15:54:39 INFO - wsgiref == 0.1.2 15:54:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:39 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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:39 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 15:54:39 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 15:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:40 INFO - 'HOME': '/Users/cltbld', 15:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:40 INFO - 'LOGNAME': 'cltbld', 15:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:40 INFO - 'MOZ_NO_REMOTE': '1', 15:54:40 INFO - 'NO_EM_RESTART': '1', 15:54:40 INFO - 'PAGER': '/bin/cat', 15:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:40 INFO - 'PWD': '/builds/slave/test', 15:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:40 INFO - 'SHELL': '/bin/bash', 15:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:40 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:40 INFO - 'USER': 'cltbld', 15:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:40 INFO - 'XPC_FLAGS': '0x0', 15:54:40 INFO - 'XPC_SERVICE_NAME': '0', 15:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 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)) 15:54:40 INFO - Cleaning up... 15:54:40 INFO - Return code: 0 15:54:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:54:40 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')]} 15:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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')]} 15:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:40 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 15:54:40 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 15:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:40 INFO - 'HOME': '/Users/cltbld', 15:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:40 INFO - 'LOGNAME': 'cltbld', 15:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:40 INFO - 'MOZ_NO_REMOTE': '1', 15:54:40 INFO - 'NO_EM_RESTART': '1', 15:54:40 INFO - 'PAGER': '/bin/cat', 15:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:40 INFO - 'PWD': '/builds/slave/test', 15:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:40 INFO - 'SHELL': '/bin/bash', 15:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:40 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:40 INFO - 'USER': 'cltbld', 15:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:40 INFO - 'XPC_FLAGS': '0x0', 15:54:40 INFO - 'XPC_SERVICE_NAME': '0', 15:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:40 INFO - Downloading/unpacking jsonschema 15:54:40 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 15:54:40 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 15:54:40 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 15:54:40 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 15:54:40 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 15:54:40 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 15:54:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:42 INFO - Downloading/unpacking functools32 (from jsonschema) 15:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:43 INFO - warning: no files found matching '*.txt' 15:54:43 INFO - no previously-included directories found matching 'build' 15:54:43 INFO - no previously-included directories found matching 'dist' 15:54:43 INFO - no previously-included directories found matching '.git*' 15:54:43 INFO - Installing collected packages: jsonschema, functools32 15:54:43 INFO - Running setup.py install for functools32 15:54:43 INFO - warning: no files found matching '*.txt' 15:54:43 INFO - no previously-included directories found matching 'build' 15:54:43 INFO - no previously-included directories found matching 'dist' 15:54:43 INFO - no previously-included directories found matching '.git*' 15:54:43 INFO - Successfully installed jsonschema functools32 15:54:43 INFO - Cleaning up... 15:54:43 INFO - Return code: 0 15:54:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10786ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107872570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98ad62400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, '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.DiHhmaa8OR/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.GJdPa9janq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 15:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:43 INFO - 'HOME': '/Users/cltbld', 15:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:43 INFO - 'LOGNAME': 'cltbld', 15:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:43 INFO - 'MOZ_NO_REMOTE': '1', 15:54:43 INFO - 'NO_EM_RESTART': '1', 15:54:43 INFO - 'PAGER': '/bin/cat', 15:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:43 INFO - 'PWD': '/builds/slave/test', 15:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:43 INFO - 'SHELL': '/bin/bash', 15:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:54:43 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:43 INFO - 'USER': 'cltbld', 15:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:43 INFO - 'XPC_FLAGS': '0x0', 15:54:43 INFO - 'XPC_SERVICE_NAME': '0', 15:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:43 INFO - Downloading/unpacking flake8 15:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:54:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:54:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:54:47 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 15:54:47 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 15:54:47 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 15:54:47 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 15:54:47 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 15:54:47 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 15:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:54:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:47 INFO - no previously-included directories found matching 'docs/_build' 15:54:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:54:47 INFO - Running setup.py install for pep8 15:54:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:54:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:54:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:54:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:54:47 INFO - no previously-included directories found matching 'docs/_build' 15:54:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:54:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:54:47 INFO - Cleaning up... 15:54:47 INFO - Return code: 0 15:54:47 INFO - Running post-action listener: _resource_record_post_action 15:54:47 INFO - Running post-action listener: _start_resource_monitoring 15:54:48 INFO - Starting resource monitoring. 15:54:48 INFO - ##### 15:54:48 INFO - ##### Finished create-virtualenv step (success) 15:54:48 INFO - ##### 15:54:48 INFO - ##### 15:54:48 INFO - ##### Running install step. 15:54:48 INFO - ##### 15:54:48 INFO - Running pre-action listener: _resource_record_pre_action 15:54:48 INFO - Running main action method: install 15:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:48 INFO - Reading from file tmpfile_stdout 15:54:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:48 INFO - Reading from file tmpfile_stdout 15:54:48 INFO - Output received: 15:54:48 INFO - Usage: mozinstall [options] installer 15:54:48 INFO - Options: 15:54:48 INFO - -h, --help show this help message and exit 15:54:48 INFO - -d DEST, --destination=DEST 15:54:48 INFO - Directory to install application into. [default: 15:54:48 INFO - "/builds/slave/test"] 15:54:48 INFO - --app=APP Application being installed. [default: firefox] 15:54:48 INFO - mkdir: /builds/slave/test/build/application 15:54:48 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'] 15:54:48 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 15:55:22 INFO - Reading from file tmpfile_stdout 15:55:22 INFO - Output received: 15:55:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:55:22 INFO - Running post-action listener: _resource_record_post_action 15:55:22 INFO - ##### 15:55:22 INFO - ##### Finished install step (success) 15:55:22 INFO - ##### 15:55:22 INFO - ##### 15:55:22 INFO - ##### Running run-tests step. 15:55:22 INFO - ##### 15:55:22 INFO - Running pre-action listener: _resource_record_pre_action 15:55:22 INFO - Running main action method: run_tests 15:55:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:55:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:55:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:55:22 INFO - 2016-05-13 15:55:22.499 screenresolution[1644:8192] starting screenresolution argv=screenresolution get 15:55:22 INFO - 2016-05-13 15:55:22.504 screenresolution[1644:8192] Display 0: 1600x1200x32@60 15:55:22 INFO - 2016-05-13 15:55:22.510 screenresolution[1645:8198] starting screenresolution argv=screenresolution list 15:55:22 INFO - Available Modes on Display 0 15:55:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:55:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:55:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:55:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:55:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:55:22 INFO - Intel Iris: 15:55:22 INFO - Chipset Model: Intel Iris 15:55:22 INFO - Type: GPU 15:55:22 INFO - Bus: Built-In 15:55:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:55:22 INFO - Vendor: Intel (0x8086) 15:55:22 INFO - Device ID: 0x0a2e 15:55:22 INFO - Revision ID: 0x0009 15:55:22 INFO - Displays: 15:55:22 INFO - Display: 15:55:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:55:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:55:22 INFO - Display Serial Number: 1600x1200 60 15:55:22 INFO - Main Display: Yes 15:55:22 INFO - Mirror: Off 15:55:22 INFO - Online: Yes 15:55:22 INFO - Rotation: Supported 15:55:22 INFO - Return code: 0 15:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:55:22 INFO - Python 2.7.3 15:55:22 INFO - Return code: 0 15:55:22 INFO - grabbing minidump binary from tooltool 15:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:22 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 0x107710d30>, '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 0x107534db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107707b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:55:22 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 15:55:22 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 15:55:22 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 15:55:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:55:22 INFO - Return code: 0 15:55:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:55:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:55:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:55:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:55:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:55:23 INFO - Return code: 0 15:55:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--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 15:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0046 --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 15:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DiHhmaa8OR/Render', 15:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:23 INFO - 'HOME': '/Users/cltbld', 15:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:23 INFO - 'LOGNAME': 'cltbld', 15:55:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:55:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:23 INFO - 'MOZ_NO_REMOTE': '1', 15:55:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:55:23 INFO - 'NO_EM_RESTART': '1', 15:55:23 INFO - 'PAGER': '/bin/cat', 15:55:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:23 INFO - 'PWD': '/builds/slave/test', 15:55:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:23 INFO - 'SHELL': '/bin/bash', 15:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GJdPa9janq/Listeners', 15:55:23 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 15:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:23 INFO - 'USER': 'cltbld', 15:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:23 INFO - 'XPC_FLAGS': '0x0', 15:55:23 INFO - 'XPC_SERVICE_NAME': '0', 15:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--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 15:55:24 INFO - mozversion application_buildid: 20160513135736 15:55:24 INFO - mozversion application_changeset: edcd8d596806c757423acc2573c9047c569fb4f8 15:55:24 INFO - mozversion application_display_name: Nightly 15:55:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:55:24 INFO - mozversion application_name: Firefox 15:55:24 INFO - mozversion application_remotingname: firefox 15:55:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:55:24 INFO - mozversion application_vendor: Mozilla 15:55:24 INFO - mozversion application_version: 49.0a1 15:55:24 INFO - mozversion platform_buildid: 20160513135736 15:55:24 INFO - mozversion platform_changeset: edcd8d596806c757423acc2573c9047c569fb4f8 15:55:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:55:24 INFO - mozversion platform_version: 49.0a1 15:55:24 INFO - using testdate: 1463180124 15:55:24 INFO - actual date: 1463180124 15:55:24 INFO - starting webserver on 'localhost:49275' 15:55:24 INFO - SUITE-START | Running 2 tests 15:55:24 INFO - TEST-START | dromaeo_css 15:55:24 INFO - operating with platform_type : mac_ 15:55:24 INFO - Initialising browser for dromaeo_css test... 15:55:24 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpgoucvo/profile http://localhost:49275/getInfo.html) 15:55:27 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:55:27 INFO - PROCESS | 1663 | colorDepth:24 15:55:27 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:55:27 INFO - PROCESS | 1663 | __metrics 15:55:27 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 15:55:28 INFO - TEST-INFO | 1663: exit 0 15:55:28 INFO - Browser initialized. 15:55:28 INFO - Running cycle 1/1 for dromaeo_css test... 15:55:28 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpgoucvo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:40 INFO - PROCESS | 1667 | RSS: Main: 249815040 15:55:40 INFO - PROCESS | 1667 | 15:56:29 INFO - PROCESS | 1667 | 1463180189950 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] 15:58:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:58:42 INFO - PROCESS | 1667 | RSS: Main: 261566464 15:58:42 INFO - PROCESS | 1667 | 16:01:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:01:45 INFO - PROCESS | 1667 | RSS: Main: 273379328 16:01:45 INFO - PROCESS | 1667 | 16:04:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:04:47 INFO - PROCESS | 1667 | RSS: Main: 275484672 16:04:47 INFO - PROCESS | 1667 | 16:07:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:07:50 INFO - PROCESS | 1667 | RSS: Main: 277106688 16:07:50 INFO - PROCESS | 1667 | 16:10:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:10:53 INFO - PROCESS | 1667 | RSS: Main: 279937024 16:10:53 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:13:56 INFO - PROCESS | 1667 | RSS: Main: 281137152 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | __start_tp_report 16:13:56 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 16:13:56 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 16:13:56 INFO - PROCESS | 1667 | |i|pagename|runs| 16:13:56 INFO - PROCESS | 1667 | |0;dojo.html;3450.9309813803707;3452.6202117767043;3461.948070778939;3525.5240542526753;3531.8940431787055;5780.624259423134;5869.720116995774;5928;5953.1665566820575;5975.014122669754;5717.942820571793;5840.474357307841;5864.501517371018;5878.617889837157;5883.205767221424;4698.2482802751565;4768.640937332861;5000;5224.29472021277;5389.595780316472;698.9168185856446;5215.660982036175;5287;5305.124654432014;5355.0896347621;5184.507471790155;5257.106291930362;5260.316158899357;5281.207818827168;5313.840584782462;5269.367675878881;5274.3670759509105;5292.391374991887;5293.417724050351;5300.363956325227;5131.307273518102;5189.974050129763;5287.497687719699;5304.336957880265;5324.733763311819;5683.2895888014;5693.316801983747;5757.165211044375;5769.798057067983;5773.538116950675;6287.056941458772;6294.055891616248;6323.177986861724;6334.144890439824;6336.96831515844;5273.156294992783;6256.311805701369;6268.87462250757;6321.968390158066;6350;5140.254663073833;5962.850928726816;5982.252218472691;5983.461488466059;5986.161937328778;6319.715612797435;6388.744450222032;6402.135711678957;6403.295637479873;6426.839329016812;6216.067589861466;6235.469985051322;6278.340774218733;6284.748610055546;6325.462335701517;5285.233641122093;6267.247930248308;6331.556791024676;6340.080688300171;6342.207224096988;6198.721057552421;6283.37166283368;6286.15136956505;6362.713677884506;6367.522435817332;6282.780102696383;6291.716872740738;6295.055741638699;6341.23905131378;6380.649064301508;4988.625853061035;4992.80028798844;4996.750162491933;5023.296738456619;5026.29631851541;4427.269500532376;4436.5785250401805;4452.933206001912;4524.276115821453;4558.247889098328;5834.212381328552;5943.197668314722;5988.760449582055;6008.609440386361;6099.664518451527;6297.27581328144;6324.936750632527;6330.3669633036325;6343.397377249154;6353.587016843982;5874.060150375919;6030.216071910623;6056.878862422727;6071.51427885777;6085.269767627913;5471.972640136773;5497.972510137423;5499.807506737245;5527.834164975057;5533.474319939599;6440.935590644127;6458.128152699325;6465.062565928009;6469.611823290617;6490.3509649036605;6248.375162483715;6309.085182648582;6316.273628532777;6340.30256671775;6379.138816259746;6333.683315834098;6381.776637817654;6432.9678351607945;6458.289588145206;6465.579737317059;6292.968535157295;6331.810045698637;6336.873262534816;6341.714622841891;6349.809505714836;5662.405447427961;6227.875442491216;6228.22147231596;6255.56211065221;6268.52986026059;6201.875962480635;6288.6855657217875;6300;6303.810885673437;6323.20959880015;5698.1737648041635;6082.482988945812;6292.779752708633;6295.433410993032;6302.905456418066;5894.233749612523;5980.5215582754145;6120.143179954899;6134.26388833325;6147.293061297829;6311.810645680637;6318.241810982711;6343.302236754045;6344.746210151727;6346.9682651586445;6136.5090792737365;6144.78493252734;6156.722947467285;6168.599041062316;6200.689965501617 16:13:56 INFO - PROCESS | 1667 | |1;ext.html;511.37612113221854;529.6901312732052;530.5172293213176;538.8114160043986;576.9365369809323;31609.68390316096;38415.42376864349;41297;41369;41456.585434145614;30219;35506.822465887635;41989.79005104978;42607.786961065154;42623;9574.042595740422;9609.951950240238;9683.564239609199;9742.415455072683;9769.218462522982;9614.855777163348;9665.275104367165;9686.273529485281;9701.951490242538;9744;8356;9650.324477286593;9716.65991690291;9756.317057805956;9756.707298781012;9638.084381983701;9689.418634881895;9710.563024663907;9721.659741909;9723.611055557769;9553.331266811327;9590;9667.903320966841;9678.419294842262;9736.805263894683;38763.8370848875;38881.61118388808;39091.41362879531;39241.80379098115;39364;3214.1964508872784;3348.782329148622;3372.140104273419;3603.225306559076;3885.30064588374;28592.714072859213;28852.71147288542;29185.41629167426;29201.561976570367;29632.555511667342;2726.168518601826;2737.1651646247888;2748.6426764520684;2843.70141135182;2882.1497658190883;393336;466050;468789.65605172096;470378;471694.2830571685;59804;60165.09752353718;60237.09644355338;60468.09297860535;60583;36064.45903311452;36495.4525682115;37034.25931481355;37687.4346884797;37807.243855122746;16551;16864.409745658842;17042.06268655236;17065.402710905306;17112.57218569546;12780.872191277967;13118;13155.934220329029;13174.670633234246;13203.207807531579;19403.611927761365;19613.62704610692;19652.901735491516;19731.605367892847;19758.308459203858;7686.962260094928;7687.654055567513;8196.713115040944;8341.45780524264;8436.620352084172;905.6332185464897;931.8182954323842;1021.4177918586334;1127.2165844737992;1127.768807394482;29752;29927.551086733718;30224.84887575548;30488;30634.927777528126;1141.395060617874;1173.4074292482412;1180.5808937827119;1224.8407706998212;1228.993855030719;15400.922995384952;15502.767458488132;15614.84385156134;15694.450694225647;15766.76349854753;632258.8387058127;640897;649131;650891;652179;59785.40214597886;59868.70065649644;60349.39650603526;60362.69818650879;60407;1243.5771837575269;1433.9139651620935;1456.9218778104128;1490.4559835659907;1499.235389951111;24687.629685554373;25883.482330352912;28089.57865632058;29223;30180.698193018667;50154.495365139104;51336;51718.67265972965;51725;51862.48137518727;357962;399084;477630;478759;480212;26406.471870562866;26497.470050599266;26545.805438754916;26745.93016279327;26750.197494075208;44653.66039018834;45222;45971;46794;47136.52863471459;37021.6297837029;37960;38220.80889595534;38861.80569097136;38937.6106238934;31773.523397149525;37634.81182594179;37846.43230351602;38311.42532861954;38457.23085538218;36930.63069369272;37429.4385584211;37709.245815083;37916.86249412522;38647.6135238644;29540.852295738383;31457.685423145474;32564.83717581397;33854.492182617774;33978.66021339755;24828.255152345457;25129.497410052063;25256.26395435376;25405.237842864743;25491.872540637178 16:13:56 INFO - PROCESS | 1667 | |2;jquery.html;387.84486205517806;391.95688474267826;394.63496265953984;395.99208015839685;414.2502071251036;15825.762613560777;16740.66518669626;20991;21031.47421314468;21058.157673693055;20756.169753209855;21000.579988400263;21105.94470276484;21706.674399884014;21883;5158.458361872015;6064.848378790543;6085.695715214244;6141.662208578527;6143.354947730491;5761.884762304751;5861.648301101947;5887.175795388649;6019.21750172477;6083.087536869483;5780.450857168562;5789.739461724233;5883.352831188566;5893.44012318829;5907.645541267516;5822.126680997863;5864.5894787364905;5886.381929897363;5914.083317085859;6056.878862422771;5247.711375874325;5258.684478931276;5259.053370393328;5276.868078298074;5303.734813259361;23757.643635345485;24652.260432187064;24830.130945417004;24899.5020099597;25130.748692513025;2064.9690254646193;2144.2602302205714;2173.402314363547;2234.7765223477686;2267.319804058776;10024.699259022169;10044.296899217057;10213.540390682363;13153.86846131536;13851.445942162301;1751.325739590267;1771.663383957057;1771.937982170618;1784.0633667324628;1786.857051435895;119024;121601.39199303946;121751;121818.3909080449;123751;10682.412467314218;11160.386178760084;11909.22590031645;12013;12091.758164836654;12934.41795119203;13663.248521331423;14509.564713058626;14793.556193314218;14822.258887055605;7546.811329716801;8615.439996400213;8672.306215502746;8762.386632935633;8955.955220223857;7869.095054068741;8396.160383961555;8735.694250701195;8839.690610828718;8850.73447796567;4535.31970204472;4560.224761790503;4620.561046700558;4637.350770892078;4753.073150735573;1965.3668867571944;2024.7772744998038;2036.2771216218325;2037.1036743832963;2065.731454910852;870.6038752367784;894.4677916639589;989.307484760656;998.2962011781822;1023.1661196125134;19759.110840012236;20770;20893.37319880406;20902.58194836125;20968.16127354889;906.3474298505066;909.9727008189633;996.6661168508485;1007.9848802267971;1065.717584840018;10202.438865862301;10941.835872461761;11022.338659680445;11049.784523702387;11168.776624467628;515957;525020;568688;582947;610678;111400.44299778611;134966.62583435493;139670.90493642603;139771.60228397587;140556;1014.1987829614521;1045.325764881647;1057.9471026448646;1068.5031460370965;1084.143526613966;18123;18316.450506484827;18446.26214951387;18498.445046648623;18623.44129676112;53133.734331328094;55091;55372.72313638567;56074.71962640161;56753;79073.20926790888;80107;80459.59770201112;82806;84648.57675711582;6354;7710.614469276626;7908.130105688485;8148.755537333889;8246.05170405387;10730.141588672732;12355.567555135527;12725.681857953625;12758.106932514635;12908.225506469642;10228.897711023092;10780;10861.02250797432;10877.728056798327;11052.889471105185;10840.078593319657;10844.186685998424;10872.293300935415;11037.448127593427;11108.389038602954;9808.754781130528;11011.889881101086;11049.447527623426;11437.142214333791;11533.538658453568;7985.72049978248;8012.5192488450875;8022.478538894953;8163.306118979954;8398.538080405637;9611.663591774255;11103.056240219672;11133.997940185422;11153.888461115284;11330.60342887995 16:13:56 INFO - PROCESS | 1667 | |3;mootools.html;1492.2389581313528;1589.7520446449537;1868.1593283022635;1977.070776734935;1997.1611922992342;8170.714025009126;8839;9019.54902254888;9325.347225694195;9345.439273643578;8908.910910890889;8976.910230897689;9017.278617710585;9158.221551168159;9164.954175229132;2440.6949131358624;2470.505898820234;2474.505098980202;2474.8020158387285;2484.776370126688;2405.3144853716694;2475.7029156501244;2481.727010028895;2482.6276058591175;2483.9751602484016;2429.101232543961;2452.1785201957364;2452.2765784093735;2481.305234534333;2486.204414587333;2420.8547487150827;2440.9755902440925;2449.5590793657134;2455.1284294075613;2467.444824914398;2438.7195472520534;2440.804735621146;2446.951060978788;2451.889664965081;2463.5935070713313;8966.551672416419;9142.588583513758;9166.770830729285;9182.816343673156;9200.30997675177;4661.254199328091;5450.991566560199;5594.664320140804;5603.3836278009385;5615.859603509904;3604.945925811115;3612.422012478017;3630.1209208549913;3678.9632103678887;3696.537932758405;974.4396971741277;985.014985014985;1013.3160116921049;1033.4161198922584;1039.6776999130218;11974.521019159223;12122.696932576757;12140.757184856342;12151.696207594792;12155.392230388534;4790.952090479051;4840;4970.527799859007;4978.925316120261;5023.472535383732;8320;9287.674931377369;10223.7444063899;10365;10370.637027703995;5445.646033007842;5458.563314934796;5497.917531237034;5561.221428999943;5599.776008959596;4408.559144085566;4533.727976321431;4566.178087944136;5021.874453138627;5068.771905264272;4167.4790651168605;4172.582741725803;4240.787960601958;4252.362145678173;4269.423627810206;1360.7006458579096;1375.21612680773;1377.4765589076287;1380.9102408343426;1391.074935168122;921.4563407589554;927.7402327348353;931.4318265857821;934.0426063285187;945.6406565505064;5868.3251426086545;5963.224780778471;6124.234470691164;6129.877402451926;6144.262688477322;430.39529461107196;439.72517176764524;439.9252127138394;440.6298709084385;446.39290564831805;4010.057636455431;4058.736182148151;4071.572484889103;4121.278776214151;4137.172565486854;10503.894961050444;10849.78300433987;10922.126229901589;10997.560097596166;11012;10824.242303038869;10893.183011274028;10942.343459392303;10950.507227174796;10966.013058824585;8161.020677518736;8272.1727827218;8340.958295208606;8359.456635318806;8381.371397145238;5887.646741195371;5915.881682366243;5971.492423144082;5997.790077347272;6009;9835.754106147404;10116.696499105039;10270.794584108127;10301.845472318071;10334.483275836328;9946.950265248628;10326.277160598986;10336.328138670962;10412.47937603132;10453.372797632166;7137.607431591312;7544.602058250936;7595.278448547379;7674.117476490052;7842.254985776342;8587.742367728979;8698.956125264756;8710.60802263887;8807.955960220157;8809.339299552432;7638.045244344457;7806.41451891099;7859.960700196462;7872.84254314899;7932.960335198287;7177.964110179416;7602.163761986288;7633.0458692663415;7730.652120654471;7738.845223095619;7209.3872020876715;7241.674124664508;7339.74310899116;7350.77947661571;7358.926410735824;4895.559399654048;5005.274235235943;5073.492650734897;5099.439061703136;5125.79496820123;5092.490750924878;5127.077126117186;5177.301064356264;5195.402528709095;5268.920966185434 16:13:56 INFO - PROCESS | 1667 | |4;prototype.html;279.6211133913547;283.58313279479165;313.95604615353847;323.1646194586994;326.5982841105439;4622.514635963222;4632.536746325366;4890.510948905112;5594.524465420434;5621.971890140554;4735.810567577293;5049.293098966147;5472.50747432731;5477.863053423666;5501.669899806005;3735.62643735627;3958.782266975315;4047.8380864765522;4056.371262454324;4101.036256479725;3122.2350524121507;3379.983100084496;3962.8507732757466;4050.230456213325;4127.958720412803;3763.6612704856557;4088.897777555555;4098.446709694198;4126.587341265865;4133.256013917508;3351.491828677105;3496.8110842313736;3954.762714237155;4083.9183216335805;4114.033202197481;2924.8245105293536;3454.222799870034;3544.9645503544893;3584.354816133095;3594.622564630702;4384.6188450638265;4841.515848415165;4979.30289759434;5095.745212739348;5156.639035267533;3764.8305826237884;4353.847615333448;4582.908341833178;4719.976400118012;4793.880152996168;5652.09566469367;5661.886762264731;5676.574256930747;5717.9714101429645;5724.4561766632105;4004.4593979812644;4016.357382818764;4020.577839326887;4032.4152997815445;4049.2667449471783;5733.31200255972;5977.356227037556;6007.279126504804;6007.909881351783;6063.211782468295;3594.263176048904;4058.640355480888;4076.307027805221;4085.89785255371;4087.693422993287;3669.743117981716;4105.665658660888;4136.544980052192;4143.212789569972;4152.522459917148;4038.9394159087637;4043.8989025274605;4716.834910778175;4753.334533165291;4759.881002974953;5482.122860342406;5591.189277554732;5724.685142317129;5734.197212390269;5736.254286942671;1228.3551135653854;1336.946522139123;1417.0859795431888;1424.1953296387508;1426.892983026277;814.722994181981;817.1868990354517;833.8457385383723;891.9464832109965;897.8742975983367;4440.045390241049;4522.479914809774;5216.191490319006;5362.329708786402;5372.355317361941;4665.46347170073;5319.255304257407;5757.366689664133;5846.7076646167525;5985.281766188085;5110.3356563646485;5216;5272.868178295574;5376.623636345418;5418.214358918013;2587.676540432446;3025.2739342558066;3064.4024415238814;3115.146487839732;3123.703248191418;5741.827745167651;6232.626042437469;6236.750529978841;6248.343923887926;6272.278687950853;5756.769729210869;6113.724882380304;6123.663198524124;6172.5679202456295;6183.659898705524;5940.940590594125;6191.1641928339095;6213.596116252429;6230.501559875199;6263.906041409383;4860.441049279378;5089.8727531812;5320.840374788762;5857.677827719516;5953.255843019622;5062.443131255633;5870.618409803349;5898.64608123514;5912.467877890838;5922.111683247547;5074.31496747949;5133.820316288912;5495.395506494203;6119.10307804582;6191.288001879841;3679.2273622539565;3860.536735591747;3971.563128055969;3984.621460961204;4076.470058892325;4745.694933893069;5726.685032323262;5868.911966320423;5957.821265362046;5976.970115149396;5757.006356694412;5760.423957604206;5767.452092051249;5778.162166485752;5792.333881603669;5128.8461346159675;5372.62391632582;5393.06078187381;5414.945850541444;5790.770553336912;5411.512963833274;5488.451154884479;5681.9147712783515;5709.942900571107;5764.769409223584;4921.753912304442;5499.725013749377;5673.971630141988;5810.854728631818;5836.182934389104;5255.421903590678;5572.387037425961;5698.034178208512;5704.828855134353;5723.6851973141875 16:13:56 INFO - PROCESS | 1667 | |5;yui.html;657.5988646925375;737.5648367463195;740.1155619035255;761.4099073218258;762.9198934111921;15100.47148349808;15214.391424343014;15412.537623871287;15457.227138643078;15602.375904963803;14092.506762263323;14278.500252491165;14926.925365373185;15619;15665.608359791011;697.2504557600599;881.3433991676178;886.534569351091;892.1702816380796;894.5124906925709;870.6430363550945;892.2772554231094;895;897.1253028297423;899.163777686751;924.912133347331;931.2643012020495;931.7950050988771;938.1697197979769;939.4410325856105;921.9769505762342;932.9300302477274;934.2899396458706;935.2751617496426;937.582775664826;668.8194187569378;677.069030083635;679.9898001529981;690.5373399822131;693.9791806245821;14906.776398353924;14931;14938.775918361232;15202.923985380003;15217.847821521755;12257.14200006;12376.319302438362;12424.192427492184;12490.562830300896;12660.493580256778;9791.853122203172;10044.94977525115;10139.036791504795;10398;10570.471476426224;2889.2632378743288;3035.8937437189593;3457.083872773717;3473.3053389321985;3505.3515099706383;82743.17256827415;84457;84822;84911.5754421224;85486.5725671374;9158.633654653873;9268.582913768896;9297.256219502424;9379.437233765995;9480.24158067353;21316.36050918444;21615.45961350979;21624.89187554052;21647;21798.019089141028;6129.478994285536;6544.854650436155;7188.4968052235845;7585.241475852371;7683.923160768432;3811.8475260989803;3904.6095390460728;3923.7449565778134;3940.5862384449792;4103.117829666636;2960.2303401115864;3019.788614796943;3400.183955850578;3488.5116083748294;3503.211777350076;594.4293478260918;635.4979566142788;658.785894584262;661.5468404143178;661.7319985405918;597.0864577196895;612.4396177497629;614.0911451052467;614.1678026274424;616.0789619518848;7425.443091768139;8120.75637730869;8300.460470069425;8353.37349698775;8442.451240669337;495.1434019146824;507.7936366487605;580.3847921203537;581.7004242814953;608.8477880529867;3776.811159442017;3874.341361968472;3912.7261091723876;3937.0551067743536;3953.78254196022;84806;85349;85361;87111.12888871157;87823.12176878277;75153;76467.61766191245;81464;84727;86336;52362;76741.61629191818;77278.22721772935;77676;79296;6350.7459701611415;7879.448438609243;8213.219744124299;8408.570273548108;8610.095939926468;45867.853303667674;47070;47288.763556182;47715.56853294268;47787.22127787193;65115.67442162948;65764;65828.0125798123;66053;66413;9081.636734530544;11011.44942752843;11218.943905280421;11326.433678316216;11849.585264515701;11937.462814173557;12079.637610871689;12097.395130243276;12099.33453660057;12150.696232594255;11321.490532925873;11540.769184616429;11662.416879156179;11732.413379330828;12195.512179513074;11329.773404531697;11578.189526733047;11642.068634509049;11873.150105708246;12411.813822792847;10444.686659399926;10764.251929300473;10892.891071089187;11207.215494915277;11433.656990290305;9392.530373481162;9507.809843803223;9618.711438656852;9666.661666841908;9852.014798520091;8385.051301894653;9320.834895638045;9363.157315841607;10004.149647280063;10089.495525223563 16:13:56 INFO - PROCESS | 1667 | __end_tp_report 16:13:56 INFO - PROCESS | 1667 | __start_cc_report 16:13:56 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,415 16:13:56 INFO - PROCESS | 1667 | __end_cc_report 16:13:56 INFO - PROCESS | 1667 | __startTimestamp1463181236307__endTimestamp 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | ------- Summary: start ------- 16:13:56 INFO - PROCESS | 1667 | Number of tests: 6 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5808.85 Median:6134.26 stddev:773.36 (12.6%) stddev-sans-first:753.29 16:13:56 INFO - PROCESS | 1667 | Values: 3450.9 3452.6 3461.9 3525.5 3531.9 5780.6 5869.7 5928.0 5953.2 5975.0 5717.9 5840.5 5864.5 5878.6 5883.2 4698.2 4768.6 5000.0 5224.3 5389.6 698.9 5215.7 5287.0 5305.1 5355.1 5184.5 5257.1 5260.3 5281.2 5313.8 5269.4 5274.4 5292.4 5293.4 5300.4 5131.3 5190.0 5287.5 5304.3 5324.7 5683.3 5693.3 5757.2 5769.8 5773.5 6287.1 6294.1 6323.2 6334.1 6337.0 5273.2 6256.3 6268.9 6322.0 6350.0 5140.3 5962.9 5982.3 5983.5 5986.2 6319.7 6388.7 6402.1 6403.3 6426.8 6216.1 6235.5 6278.3 6284.7 6325.5 5285.2 6267.2 6331.6 6340.1 6342.2 6198.7 6283.4 6286.2 6362.7 6367.5 6282.8 6291.7 6295.1 6341.2 6380.6 4988.6 4992.8 4996.8 5023.3 5026.3 4427.3 4436.6 4452.9 4524.3 4558.2 5834.2 5943.2 5988.8 6008.6 6099.7 6297.3 6324.9 6330.4 6343.4 6353.6 5874.1 6030.2 6056.9 6071.5 6085.3 5472.0 5498.0 5499.8 5527.8 5533.5 6440.9 6458.1 6465.1 6469.6 6490.4 6248.4 6309.1 6316.3 6340.3 6379.1 6333.7 6381.8 6433.0 6458.3 6465.6 6293.0 6331.8 6336.9 6341.7 6349.8 5662.4 6227.9 6228.2 6255.6 6268.5 6201.9 6288.7 6300.0 6303.8 6323.2 5698.2 6082.5 6292.8 6295.4 6302.9 5894.2 5980.5 6120.1 6134.3 6147.3 6311.8 6318.2 6343.3 6344.7 6347.0 6136.5 6144.8 6156.7 6168.6 6200.7 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:64575.45 Median:38266.12 stddev:139526.47 (364.6%) stddev-sans-first:139834.96 16:13:56 INFO - PROCESS | 1667 | Values: 511.4 529.7 530.5 538.8 576.9 31609.7 38415.4 41297.0 41369.0 41456.6 30219.0 35506.8 41989.8 42607.8 42623.0 9574.0 9610.0 9683.6 9742.4 9769.2 9614.9 9665.3 9686.3 9702.0 9744.0 8356.0 9650.3 9716.7 9756.3 9756.7 9638.1 9689.4 9710.6 9721.7 9723.6 9553.3 9590.0 9667.9 9678.4 9736.8 38763.8 38881.6 39091.4 39241.8 39364.0 3214.2 3348.8 3372.1 3603.2 3885.3 28592.7 28852.7 29185.4 29201.6 29632.6 2726.2 2737.2 2748.6 2843.7 2882.1 393336.0 466050.0 468789.7 470378.0 471694.3 59804.0 60165.1 60237.1 60468.1 60583.0 36064.5 36495.5 37034.3 37687.4 37807.2 16551.0 16864.4 17042.1 17065.4 17112.6 12780.9 13118.0 13155.9 13174.7 13203.2 19403.6 19613.6 19652.9 19731.6 19758.3 7687.0 7687.7 8196.7 8341.5 8436.6 905.6 931.8 1021.4 1127.2 1127.8 29752.0 29927.6 30224.8 30488.0 30634.9 1141.4 1173.4 1180.6 1224.8 1229.0 15400.9 15502.8 15614.8 15694.5 15766.8 632258.8 640897.0 649131.0 650891.0 652179.0 59785.4 59868.7 60349.4 60362.7 60407.0 1243.6 1433.9 1456.9 1490.5 1499.2 24687.6 25883.5 28089.6 29223.0 30180.7 50154.5 51336.0 51718.7 51725.0 51862.5 357962.0 399084.0 477630.0 478759.0 480212.0 26406.5 26497.5 26545.8 26745.9 26750.2 44653.7 45222.0 45971.0 46794.0 47136.5 37021.6 37960.0 38220.8 38861.8 38937.6 31773.5 37634.8 37846.4 38311.4 38457.2 36930.6 37429.4 37709.2 37916.9 38647.6 29540.9 31457.7 32564.8 33854.5 33978.7 24828.3 25129.5 25256.3 25405.2 25491.9 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34331.38 Median:11925.47 stddev:94458.49 (792.1%) stddev-sans-first:94689.09 16:13:56 INFO - PROCESS | 1667 | Values: 387.8 392.0 394.6 396.0 414.3 15825.8 16740.7 20991.0 21031.5 21058.2 20756.2 21000.6 21105.9 21706.7 21883.0 5158.5 6064.8 6085.7 6141.7 6143.4 5761.9 5861.6 5887.2 6019.2 6083.1 5780.5 5789.7 5883.4 5893.4 5907.6 5822.1 5864.6 5886.4 5914.1 6056.9 5247.7 5258.7 5259.1 5276.9 5303.7 23757.6 24652.3 24830.1 24899.5 25130.7 2065.0 2144.3 2173.4 2234.8 2267.3 10024.7 10044.3 10213.5 13153.9 13851.4 1751.3 1771.7 1771.9 1784.1 1786.9 119024.0 121601.4 121751.0 121818.4 123751.0 10682.4 11160.4 11909.2 12013.0 12091.8 12934.4 13663.2 14509.6 14793.6 14822.3 7546.8 8615.4 8672.3 8762.4 8956.0 7869.1 8396.2 8735.7 8839.7 8850.7 4535.3 4560.2 4620.6 4637.4 4753.1 1965.4 2024.8 2036.3 2037.1 2065.7 870.6 894.5 989.3 998.3 1023.2 19759.1 20770.0 20893.4 20902.6 20968.2 906.3 910.0 996.7 1008.0 1065.7 10202.4 10941.8 11022.3 11049.8 11168.8 515957.0 525020.0 568688.0 582947.0 610678.0 111400.4 134966.6 139670.9 139771.6 140556.0 1014.2 1045.3 1057.9 1068.5 1084.1 18123.0 18316.5 18446.3 18498.4 18623.4 53133.7 55091.0 55372.7 56074.7 56753.0 79073.2 80107.0 80459.6 82806.0 84648.6 6354.0 7710.6 7908.1 8148.8 8246.1 10730.1 12355.6 12725.7 12758.1 12908.2 10228.9 10780.0 10861.0 10877.7 11052.9 10840.1 10844.2 10872.3 11037.4 11108.4 9808.8 11011.9 11049.4 11437.1 11533.5 7985.7 8012.5 8022.5 8163.3 8398.5 9611.7 11103.1 11134.0 11153.9 11330.6 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5860.92 Median:4933.04 stddev:3286.05 (66.6%) stddev-sans-first:3278.87 16:13:56 INFO - PROCESS | 1667 | Values: 1492.2 1589.8 1868.2 1977.1 1997.2 8170.7 8839.0 9019.5 9325.3 9345.4 8908.9 8976.9 9017.3 9158.2 9165.0 2440.7 2470.5 2474.5 2474.8 2484.8 2405.3 2475.7 2481.7 2482.6 2484.0 2429.1 2452.2 2452.3 2481.3 2486.2 2420.9 2441.0 2449.6 2455.1 2467.4 2438.7 2440.8 2447.0 2451.9 2463.6 8966.6 9142.6 9166.8 9182.8 9200.3 4661.3 5451.0 5594.7 5603.4 5615.9 3604.9 3612.4 3630.1 3679.0 3696.5 974.4 985.0 1013.3 1033.4 1039.7 11974.5 12122.7 12140.8 12151.7 12155.4 4791.0 4840.0 4970.5 4978.9 5023.5 8320.0 9287.7 10223.7 10365.0 10370.6 5445.6 5458.6 5497.9 5561.2 5599.8 4408.6 4533.7 4566.2 5021.9 5068.8 4167.5 4172.6 4240.8 4252.4 4269.4 1360.7 1375.2 1377.5 1380.9 1391.1 921.5 927.7 931.4 934.0 945.6 5868.3 5963.2 6124.2 6129.9 6144.3 430.4 439.7 439.9 440.6 446.4 4010.1 4058.7 4071.6 4121.3 4137.2 10503.9 10849.8 10922.1 10997.6 11012.0 10824.2 10893.2 10942.3 10950.5 10966.0 8161.0 8272.2 8341.0 8359.5 8381.4 5887.6 5915.9 5971.5 5997.8 6009.0 9835.8 10116.7 10270.8 10301.8 10334.5 9947.0 10326.3 10336.3 10412.5 10453.4 7137.6 7544.6 7595.3 7674.1 7842.3 8587.7 8699.0 8710.6 8808.0 8809.3 7638.0 7806.4 7860.0 7872.8 7933.0 7178.0 7602.2 7633.0 7730.7 7738.8 7209.4 7241.7 7339.7 7350.8 7358.9 4895.6 5005.3 5073.5 5099.4 5125.8 5092.5 5127.1 5177.3 5195.4 5268.9 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4621.24 Median:5145.23 stddev:1452.18 (28.2%) stddev-sans-first:1419.22 16:13:56 INFO - PROCESS | 1667 | Values: 279.6 283.6 314.0 323.2 326.6 4622.5 4632.5 4890.5 5594.5 5622.0 4735.8 5049.3 5472.5 5477.9 5501.7 3735.6 3958.8 4047.8 4056.4 4101.0 3122.2 3380.0 3962.9 4050.2 4128.0 3763.7 4088.9 4098.4 4126.6 4133.3 3351.5 3496.8 3954.8 4083.9 4114.0 2924.8 3454.2 3545.0 3584.4 3594.6 4384.6 4841.5 4979.3 5095.7 5156.6 3764.8 4353.8 4582.9 4720.0 4793.9 5652.1 5661.9 5676.6 5718.0 5724.5 4004.5 4016.4 4020.6 4032.4 4049.3 5733.3 5977.4 6007.3 6007.9 6063.2 3594.3 4058.6 4076.3 4085.9 4087.7 3669.7 4105.7 4136.5 4143.2 4152.5 4038.9 4043.9 4716.8 4753.3 4759.9 5482.1 5591.2 5724.7 5734.2 5736.3 1228.4 1336.9 1417.1 1424.2 1426.9 814.7 817.2 833.8 891.9 897.9 4440.0 4522.5 5216.2 5362.3 5372.4 4665.5 5319.3 5757.4 5846.7 5985.3 5110.3 5216.0 5272.9 5376.6 5418.2 2587.7 3025.3 3064.4 3115.1 3123.7 5741.8 6232.6 6236.8 6248.3 6272.3 5756.8 6113.7 6123.7 6172.6 6183.7 5940.9 6191.2 6213.6 6230.5 6263.9 4860.4 5089.9 5320.8 5857.7 5953.3 5062.4 5870.6 5898.6 5912.5 5922.1 5074.3 5133.8 5495.4 6119.1 6191.3 3679.2 3860.5 3971.6 3984.6 4076.5 4745.7 5726.7 5868.9 5957.8 5977.0 5757.0 5760.4 5767.5 5778.2 5792.3 5128.8 5372.6 5393.1 5414.9 5790.8 5411.5 5488.5 5681.9 5709.9 5764.8 4921.8 5499.7 5674.0 5810.9 5836.2 5255.4 5572.4 5698.0 5704.8 5723.7 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18238.42 Median:614.13 stddev:25701.11 (4185.0%) stddev-sans-first:25739.30 16:13:56 INFO - PROCESS | 1667 | Values: 657.6 737.6 740.1 761.4 762.9 15100.5 15214.4 15412.5 15457.2 15602.4 14092.5 14278.5 14926.9 15619.0 15665.6 697.3 881.3 886.5 892.2 894.5 870.6 892.3 895.0 897.1 899.2 924.9 931.3 931.8 938.2 939.4 922.0 932.9 934.3 935.3 937.6 668.8 677.1 680.0 690.5 694.0 14906.8 14931.0 14938.8 15202.9 15217.8 12257.1 12376.3 12424.2 12490.6 12660.5 9791.9 10044.9 10139.0 10398.0 10570.5 2889.3 3035.9 3457.1 3473.3 3505.4 82743.2 84457.0 84822.0 84911.6 85486.6 9158.6 9268.6 9297.3 9379.4 9480.2 21316.4 21615.5 21624.9 21647.0 21798.0 6129.5 6544.9 7188.5 7585.2 7683.9 3811.8 3904.6 3923.7 3940.6 4103.1 2960.2 3019.8 3400.2 3488.5 3503.2 594.4 635.5 658.8 661.5 661.7 597.1 612.4 614.1 614.2 616.1 7425.4 8120.8 8300.5 8353.4 8442.5 495.1 507.8 580.4 581.7 608.8 3776.8 3874.3 3912.7 3937.1 3953.8 84806.0 85349.0 85361.0 87111.1 87823.1 75153.0 76467.6 81464.0 84727.0 86336.0 52362.0 76741.6 77278.2 77676.0 79296.0 6350.7 7879.4 8213.2 8408.6 8610.1 45867.9 47070.0 47288.8 47715.6 47787.2 65115.7 65764.0 65828.0 66053.0 66413.0 9081.6 11011.4 11218.9 11326.4 11849.6 11937.5 12079.6 12097.4 12099.3 12150.7 11321.5 11540.8 11662.4 11732.4 12195.5 11329.8 11578.2 11642.1 11873.2 12411.8 10444.7 10764.3 10892.9 11207.2 11433.7 9392.5 9507.8 9618.7 9666.7 9852.0 8385.1 9320.8 9363.2 10004.1 10089.5 16:13:56 INFO - PROCESS | 1667 | -------- Summary: end -------- 16:13:56 INFO - PROCESS | 1667 | 16:13:56 INFO - TEST-INFO | 1667: exit 0 16:13:56 INFO - TEST-OK | dromaeo_css | took 1112654ms 16:13:56 INFO - TEST-START | kraken 16:13:56 INFO - operating with platform_type : mac_ 16:13:56 INFO - Initialising browser for kraken test... 16:13:56 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpRMUlWp/profile http://localhost:49275/getInfo.html) 16:13:59 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 16:13:59 INFO - PROCESS | 1674 | colorDepth:24 16:13:59 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 16:13:59 INFO - PROCESS | 1674 | __metrics 16:13:59 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 16:14:00 INFO - TEST-INFO | 1674: exit 0 16:14:00 INFO - Browser initialized. 16:14:00 INFO - Running cycle 1/1 for kraken test... 16:14:00 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpRMUlWp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:12 INFO - PROCESS | 1677 | RSS: Main: 249655296 16:14:12 INFO - PROCESS | 1677 | 16:14:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:16 INFO - PROCESS | 1677 | RSS: Main: 261357568 16:14:16 INFO - PROCESS | 1677 | 16:14:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:14:19 INFO - PROCESS | 1677 | RSS: Main: 266072064 16:14:19 INFO - PROCESS | 1677 | 16:14:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:14:24 INFO - PROCESS | 1677 | RSS: Main: 266485760 16:14:24 INFO - PROCESS | 1677 | 16:14:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:14:27 INFO - PROCESS | 1677 | RSS: Main: 267370496 16:14:27 INFO - PROCESS | 1677 | 16:14:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:14:29 INFO - PROCESS | 1677 | RSS: Main: 267837440 16:14:29 INFO - PROCESS | 1677 | 16:14:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:14:33 INFO - PROCESS | 1677 | RSS: Main: 267993088 16:14:33 INFO - PROCESS | 1677 | 16:14:36 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:14:37 INFO - PROCESS | 1677 | RSS: Main: 268210176 16:14:37 INFO - PROCESS | 1677 | 16:14:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:14:41 INFO - PROCESS | 1677 | RSS: Main: 266964992 16:14:41 INFO - PROCESS | 1677 | 16:14:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:43 INFO - PROCESS | 1677 | RSS: Main: 267444224 16:14:43 INFO - PROCESS | 1677 | 16:14:45 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:46 INFO - PROCESS | 1677 | RSS: Main: 267001856 16:14:46 INFO - PROCESS | 1677 | 16:14:48 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:49 INFO - PROCESS | 1677 | RSS: Main: 267124736 16:14:49 INFO - PROCESS | 1677 | 16:14:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:52 INFO - PROCESS | 1677 | RSS: Main: 267239424 16:14:52 INFO - PROCESS | 1677 | 16:14:55 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:56 INFO - PROCESS | 1677 | RSS: Main: 267411456 16:14:56 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:14:58 INFO - PROCESS | 1677 | RSS: Main: 270102528 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | __start_tp_report 16:14:58 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 16:14:58 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 16:14:58 INFO - PROCESS | 1677 | |i|pagename|runs| 16:14:58 INFO - PROCESS | 1677 | |0;ai-astar;114;118;107;116;119;116;108;117;117;114 16:14:58 INFO - PROCESS | 1677 | |1;audio-beat-detection;152;123;179;243;120;139;143;147;181;155 16:14:58 INFO - PROCESS | 1677 | |2;audio-dft;216;246;271;238;229;259;217;230;280;250 16:14:58 INFO - PROCESS | 1677 | |3;audio-fft;72;79;78;173;48;74;54;69;144;49 16:14:58 INFO - PROCESS | 1677 | |4;audio-oscillator;81;91;146;81;84;82;122;85;81;121 16:14:58 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;99;80;133;119;90;86;92;90;95;86 16:14:58 INFO - PROCESS | 1677 | |6;imaging-darkroom;96;97;96;96;97;104;152;151;96;97 16:14:58 INFO - PROCESS | 1677 | |7;imaging-desaturate;119;85;148;149;92;119;143;145;121;90 16:14:58 INFO - PROCESS | 1677 | |8;json-parse-financial;54;56;80;87;86;76;86;138;61;57 16:14:58 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;68;67;72;70;72;73;72;70;67;69 16:14:58 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;84;87;87;82;87;88;86;86;84;86 16:14:58 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;138;137;136;144;135;133;138;146;241;149 16:14:58 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;167;171;165;169;165;167;167;165;166;167 16:14:58 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;65;67;66;69;62;65;67;67;71 16:14:58 INFO - PROCESS | 1677 | __end_tp_report 16:14:58 INFO - PROCESS | 1677 | __start_cc_report 16:14:58 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1079 16:14:58 INFO - PROCESS | 1677 | __end_cc_report 16:14:58 INFO - PROCESS | 1677 | __startTimestamp1463181298326__endTimestamp 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | ------- Summary: start ------- 16:14:58 INFO - PROCESS | 1677 | Number of tests: 14 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.50 stddev:4.06 (3.5%) stddev-sans-first:4.30 16:14:58 INFO - PROCESS | 1677 | Values: 114.0 118.0 107.0 116.0 119.0 116.0 108.0 117.0 117.0 114.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:158.20 Median:153.50 stddev:35.93 (23.4%) stddev-sans-first:38.03 16:14:58 INFO - PROCESS | 1677 | Values: 152.0 123.0 179.0 243.0 120.0 139.0 143.0 147.0 181.0 155.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:243.60 Median:248.00 stddev:21.75 (8.8%) stddev-sans-first:20.65 16:14:58 INFO - PROCESS | 1677 | Values: 216.0 246.0 271.0 238.0 229.0 259.0 217.0 230.0 280.0 250.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:84.00 Median:70.50 stddev:41.46 (58.8%) stddev-sans-first:43.75 16:14:58 INFO - PROCESS | 1677 | Values: 72.0 79.0 78.0 173.0 48.0 74.0 54.0 69.0 144.0 49.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:81.50 stddev:23.43 (28.8%) stddev-sans-first:24.09 16:14:58 INFO - PROCESS | 1677 | Values: 81.0 91.0 146.0 81.0 84.0 82.0 122.0 85.0 81.0 121.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:90.00 stddev:16.47 (18.3%) stddev-sans-first:17.46 16:14:58 INFO - PROCESS | 1677 | Values: 99.0 80.0 133.0 119.0 90.0 86.0 92.0 90.0 95.0 86.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:96.00 stddev:22.95 (23.9%) stddev-sans-first:23.91 16:14:58 INFO - PROCESS | 1677 | Values: 96.0 97.0 96.0 96.0 97.0 104.0 152.0 151.0 96.0 97.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:121.10 Median:148.50 stddev:25.12 (16.9%) stddev-sans-first:26.63 16:14:58 INFO - PROCESS | 1677 | Values: 119.0 85.0 148.0 149.0 92.0 119.0 143.0 145.0 121.0 90.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:78.10 Median:78.00 stddev:24.96 (32.0%) stddev-sans-first:24.90 16:14:58 INFO - PROCESS | 1677 | Values: 54.0 56.0 80.0 87.0 86.0 76.0 86.0 138.0 61.0 57.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:2.21 (3.1%) stddev-sans-first:2.22 16:14:58 INFO - PROCESS | 1677 | Values: 68.0 67.0 72.0 70.0 72.0 73.0 72.0 70.0 67.0 69.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.50 stddev:1.83 (2.1%) stddev-sans-first:1.83 16:14:58 INFO - PROCESS | 1677 | Values: 84.0 87.0 87.0 82.0 87.0 88.0 86.0 86.0 84.0 86.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:141.00 stddev:32.49 (23.0%) stddev-sans-first:34.18 16:14:58 INFO - PROCESS | 1677 | Values: 138.0 137.0 136.0 144.0 135.0 133.0 138.0 146.0 241.0 149.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:1.91 (1.1%) stddev-sans-first:2.03 16:14:58 INFO - PROCESS | 1677 | Values: 167.0 171.0 165.0 169.0 165.0 167.0 167.0 165.0 166.0 167.0 16:14:58 INFO - PROCESS | 1677 | 16:14:58 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:2.42 (3.6%) stddev-sans-first:2.55 16:14:58 INFO - PROCESS | 1677 | Values: 66.0 65.0 67.0 66.0 69.0 62.0 65.0 67.0 67.0 71.0 16:14:58 INFO - PROCESS | 1677 | -------- Summary: end -------- 16:14:58 INFO - PROCESS | 1677 | 16:14:59 INFO - TEST-INFO | 1677: exit 0 16:14:59 INFO - TEST-OK | kraken | took 62254ms 16:14:59 INFO - SUITE-END | took 1174s 16:14:59 INFO - Completed test suite (00:19:35) 16:14:59 INFO - javascript benchmark 16:14:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7221.6425013421485, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3450.9309813803707, 3452.6202117767043, 3461.948070778939, 3525.5240542526753, 3531.8940431787055, 5780.624259423134, 5869.720116995774, 5928.0, 5953.1665566820575, 5975.014122669754, 5717.942820571793, 5840.474357307841, 5864.501517371018, 5878.617889837157, 5883.205767221424, 4698.2482802751565, 4768.640937332861, 5000.0, 5224.29472021277, 5389.595780316472, 698.9168185856446, 5215.660982036175, 5287.0, 5305.124654432014, 5355.0896347621, 5184.507471790155, 5257.106291930362, 5260.316158899357, 5281.207818827168, 5313.840584782462, 5269.367675878881, 5274.3670759509105, 5292.391374991887, 5293.417724050351, 5300.363956325227, 5131.307273518102, 5189.974050129763, 5287.497687719699, 5304.336957880265, 5324.733763311819, 5683.2895888014, 5693.316801983747, 5757.165211044375, 5769.798057067983, 5773.538116950675, 6287.056941458772, 6294.055891616248, 6323.177986861724, 6334.144890439824, 6336.96831515844, 5273.156294992783, 6256.311805701369, 6268.87462250757, 6321.968390158066, 6350.0, 5140.254663073833, 5962.850928726816, 5982.252218472691, 5983.461488466059, 5986.161937328778, 6319.715612797435, 6388.744450222032, 6402.135711678957, 6403.295637479873, 6426.839329016812, 6216.067589861466, 6235.469985051322, 6278.340774218733, 6284.748610055546, 6325.462335701517, 5285.233641122093, 6267.247930248308, 6331.556791024676, 6340.080688300171, 6342.207224096988, 6198.721057552421, 6283.37166283368, 6286.15136956505, 6362.713677884506, 6367.522435817332, 6282.780102696383, 6291.716872740738, 6295.055741638699, 6341.23905131378, 6380.649064301508, 4988.625853061035, 4992.80028798844, 4996.750162491933, 5023.296738456619, 5026.29631851541, 4427.269500532376, 4436.5785250401805, 4452.933206001912, 4524.276115821453, 4558.247889098328, 5834.212381328552, 5943.197668314722, 5988.760449582055, 6008.609440386361, 6099.664518451527, 6297.27581328144, 6324.936750632527, 6330.3669633036325, 6343.397377249154, 6353.587016843982, 5874.060150375919, 6030.216071910623, 6056.878862422727, 6071.51427885777, 6085.269767627913, 5471.972640136773, 5497.972510137423, 5499.807506737245, 5527.834164975057, 5533.474319939599, 6440.935590644127, 6458.128152699325, 6465.062565928009, 6469.611823290617, 6490.3509649036605, 6248.375162483715, 6309.085182648582, 6316.273628532777, 6340.30256671775, 6379.138816259746, 6333.683315834098, 6381.776637817654, 6432.9678351607945, 6458.289588145206, 6465.579737317059, 6292.968535157295, 6331.810045698637, 6336.873262534816, 6341.714622841891, 6349.809505714836, 5662.405447427961, 6227.875442491216, 6228.22147231596, 6255.56211065221, 6268.52986026059, 6201.875962480635, 6288.6855657217875, 6300.0, 6303.810885673437, 6323.20959880015, 5698.1737648041635, 6082.482988945812, 6292.779752708633, 6295.433410993032, 6302.905456418066, 5894.233749612523, 5980.5215582754145, 6120.143179954899, 6134.26388833325, 6147.293061297829, 6311.810645680637, 6318.241810982711, 6343.302236754045, 6344.746210151727, 6346.9682651586445, 6136.5090792737365, 6144.78493252734, 6156.722947467285, 6168.599041062316, 6200.689965501617], "value": 5761.193289624058, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.37612113221854, 529.6901312732052, 530.5172293213176, 538.8114160043986, 576.9365369809323, 31609.68390316096, 38415.42376864349, 41297.0, 41369.0, 41456.585434145614, 30219.0, 35506.822465887635, 41989.79005104978, 42607.786961065154, 42623.0, 9574.042595740422, 9609.951950240238, 9683.564239609199, 9742.415455072683, 9769.218462522982, 9614.855777163348, 9665.275104367165, 9686.273529485281, 9701.951490242538, 9744.0, 8356.0, 9650.324477286593, 9716.65991690291, 9756.317057805956, 9756.707298781012, 9638.084381983701, 9689.418634881895, 9710.563024663907, 9721.659741909, 9723.611055557769, 9553.331266811327, 9590.0, 9667.903320966841, 9678.419294842262, 9736.805263894683, 38763.8370848875, 38881.61118388808, 39091.41362879531, 39241.80379098115, 39364.0, 3214.1964508872784, 3348.782329148622, 3372.140104273419, 3603.225306559076, 3885.30064588374, 28592.714072859213, 28852.71147288542, 29185.41629167426, 29201.561976570367, 29632.555511667342, 2726.168518601826, 2737.1651646247888, 2748.6426764520684, 2843.70141135182, 2882.1497658190883, 393336.0, 466050.0, 468789.65605172096, 470378.0, 471694.2830571685, 59804.0, 60165.09752353718, 60237.09644355338, 60468.09297860535, 60583.0, 36064.45903311452, 36495.4525682115, 37034.25931481355, 37687.4346884797, 37807.243855122746, 16551.0, 16864.409745658842, 17042.06268655236, 17065.402710905306, 17112.57218569546, 12780.872191277967, 13118.0, 13155.934220329029, 13174.670633234246, 13203.207807531579, 19403.611927761365, 19613.62704610692, 19652.901735491516, 19731.605367892847, 19758.308459203858, 7686.962260094928, 7687.654055567513, 8196.713115040944, 8341.45780524264, 8436.620352084172, 905.6332185464897, 931.8182954323842, 1021.4177918586334, 1127.2165844737992, 1127.768807394482, 29752.0, 29927.551086733718, 30224.84887575548, 30488.0, 30634.927777528126, 1141.395060617874, 1173.4074292482412, 1180.5808937827119, 1224.8407706998212, 1228.993855030719, 15400.922995384952, 15502.767458488132, 15614.84385156134, 15694.450694225647, 15766.76349854753, 632258.8387058127, 640897.0, 649131.0, 650891.0, 652179.0, 59785.40214597886, 59868.70065649644, 60349.39650603526, 60362.69818650879, 60407.0, 1243.5771837575269, 1433.9139651620935, 1456.9218778104128, 1490.4559835659907, 1499.235389951111, 24687.629685554373, 25883.482330352912, 28089.57865632058, 29223.0, 30180.698193018667, 50154.495365139104, 51336.0, 51718.67265972965, 51725.0, 51862.48137518727, 357962.0, 399084.0, 477630.0, 478759.0, 480212.0, 26406.471870562866, 26497.470050599266, 26545.805438754916, 26745.93016279327, 26750.197494075208, 44653.66039018834, 45222.0, 45971.0, 46794.0, 47136.52863471459, 37021.6297837029, 37960.0, 38220.80889595534, 38861.80569097136, 38937.6106238934, 31773.523397149525, 37634.81182594179, 37846.43230351602, 38311.42532861954, 38457.23085538218, 36930.63069369272, 37429.4385584211, 37709.245815083, 37916.86249412522, 38647.6135238644, 29540.852295738383, 31457.685423145474, 32564.83717581397, 33854.492182617774, 33978.66021339755, 24828.255152345457, 25129.497410052063, 25256.26395435376, 25405.237842864743, 25491.872540637178], "value": 19494.580860391026, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.84486205517806, 391.95688474267826, 394.63496265953984, 395.99208015839685, 414.2502071251036, 15825.762613560777, 16740.66518669626, 20991.0, 21031.47421314468, 21058.157673693055, 20756.169753209855, 21000.579988400263, 21105.94470276484, 21706.674399884014, 21883.0, 5158.458361872015, 6064.848378790543, 6085.695715214244, 6141.662208578527, 6143.354947730491, 5761.884762304751, 5861.648301101947, 5887.175795388649, 6019.21750172477, 6083.087536869483, 5780.450857168562, 5789.739461724233, 5883.352831188566, 5893.44012318829, 5907.645541267516, 5822.126680997863, 5864.5894787364905, 5886.381929897363, 5914.083317085859, 6056.878862422771, 5247.711375874325, 5258.684478931276, 5259.053370393328, 5276.868078298074, 5303.734813259361, 23757.643635345485, 24652.260432187064, 24830.130945417004, 24899.5020099597, 25130.748692513025, 2064.9690254646193, 2144.2602302205714, 2173.402314363547, 2234.7765223477686, 2267.319804058776, 10024.699259022169, 10044.296899217057, 10213.540390682363, 13153.86846131536, 13851.445942162301, 1751.325739590267, 1771.663383957057, 1771.937982170618, 1784.0633667324628, 1786.857051435895, 119024.0, 121601.39199303946, 121751.0, 121818.3909080449, 123751.0, 10682.412467314218, 11160.386178760084, 11909.22590031645, 12013.0, 12091.758164836654, 12934.41795119203, 13663.248521331423, 14509.564713058626, 14793.556193314218, 14822.258887055605, 7546.811329716801, 8615.439996400213, 8672.306215502746, 8762.386632935633, 8955.955220223857, 7869.095054068741, 8396.160383961555, 8735.694250701195, 8839.690610828718, 8850.73447796567, 4535.31970204472, 4560.224761790503, 4620.561046700558, 4637.350770892078, 4753.073150735573, 1965.3668867571944, 2024.7772744998038, 2036.2771216218325, 2037.1036743832963, 2065.731454910852, 870.6038752367784, 894.4677916639589, 989.307484760656, 998.2962011781822, 1023.1661196125134, 19759.110840012236, 20770.0, 20893.37319880406, 20902.58194836125, 20968.16127354889, 906.3474298505066, 909.9727008189633, 996.6661168508485, 1007.9848802267971, 1065.717584840018, 10202.438865862301, 10941.835872461761, 11022.338659680445, 11049.784523702387, 11168.776624467628, 515957.0, 525020.0, 568688.0, 582947.0, 610678.0, 111400.44299778611, 134966.62583435493, 139670.90493642603, 139771.60228397587, 140556.0, 1014.1987829614521, 1045.325764881647, 1057.9471026448646, 1068.5031460370965, 1084.143526613966, 18123.0, 18316.450506484827, 18446.26214951387, 18498.445046648623, 18623.44129676112, 53133.734331328094, 55091.0, 55372.72313638567, 56074.71962640161, 56753.0, 79073.20926790888, 80107.0, 80459.59770201112, 82806.0, 84648.57675711582, 6354.0, 7710.614469276626, 7908.130105688485, 8148.755537333889, 8246.05170405387, 10730.141588672732, 12355.567555135527, 12725.681857953625, 12758.106932514635, 12908.225506469642, 10228.897711023092, 10780.0, 10861.02250797432, 10877.728056798327, 11052.889471105185, 10840.078593319657, 10844.186685998424, 10872.293300935415, 11037.448127593427, 11108.389038602954, 9808.754781130528, 11011.889881101086, 11049.447527623426, 11437.142214333791, 11533.538658453568, 7985.72049978248, 8012.5192488450875, 8022.478538894953, 8163.306118979954, 8398.538080405637, 9611.663591774255, 11103.056240219672, 11133.997940185422, 11153.888461115284, 11330.60342887995], "value": 9594.597989677512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.2389581313528, 1589.7520446449537, 1868.1593283022635, 1977.070776734935, 1997.1611922992342, 8170.714025009126, 8839.0, 9019.54902254888, 9325.347225694195, 9345.439273643578, 8908.910910890889, 8976.910230897689, 9017.278617710585, 9158.221551168159, 9164.954175229132, 2440.6949131358624, 2470.505898820234, 2474.505098980202, 2474.8020158387285, 2484.776370126688, 2405.3144853716694, 2475.7029156501244, 2481.727010028895, 2482.6276058591175, 2483.9751602484016, 2429.101232543961, 2452.1785201957364, 2452.2765784093735, 2481.305234534333, 2486.204414587333, 2420.8547487150827, 2440.9755902440925, 2449.5590793657134, 2455.1284294075613, 2467.444824914398, 2438.7195472520534, 2440.804735621146, 2446.951060978788, 2451.889664965081, 2463.5935070713313, 8966.551672416419, 9142.588583513758, 9166.770830729285, 9182.816343673156, 9200.30997675177, 4661.254199328091, 5450.991566560199, 5594.664320140804, 5603.3836278009385, 5615.859603509904, 3604.945925811115, 3612.422012478017, 3630.1209208549913, 3678.9632103678887, 3696.537932758405, 974.4396971741277, 985.014985014985, 1013.3160116921049, 1033.4161198922584, 1039.6776999130218, 11974.521019159223, 12122.696932576757, 12140.757184856342, 12151.696207594792, 12155.392230388534, 4790.952090479051, 4840.0, 4970.527799859007, 4978.925316120261, 5023.472535383732, 8320.0, 9287.674931377369, 10223.7444063899, 10365.0, 10370.637027703995, 5445.646033007842, 5458.563314934796, 5497.917531237034, 5561.221428999943, 5599.776008959596, 4408.559144085566, 4533.727976321431, 4566.178087944136, 5021.874453138627, 5068.771905264272, 4167.4790651168605, 4172.582741725803, 4240.787960601958, 4252.362145678173, 4269.423627810206, 1360.7006458579096, 1375.21612680773, 1377.4765589076287, 1380.9102408343426, 1391.074935168122, 921.4563407589554, 927.7402327348353, 931.4318265857821, 934.0426063285187, 945.6406565505064, 5868.3251426086545, 5963.224780778471, 6124.234470691164, 6129.877402451926, 6144.262688477322, 430.39529461107196, 439.72517176764524, 439.9252127138394, 440.6298709084385, 446.39290564831805, 4010.057636455431, 4058.736182148151, 4071.572484889103, 4121.278776214151, 4137.172565486854, 10503.894961050444, 10849.78300433987, 10922.126229901589, 10997.560097596166, 11012.0, 10824.242303038869, 10893.183011274028, 10942.343459392303, 10950.507227174796, 10966.013058824585, 8161.020677518736, 8272.1727827218, 8340.958295208606, 8359.456635318806, 8381.371397145238, 5887.646741195371, 5915.881682366243, 5971.492423144082, 5997.790077347272, 6009.0, 9835.754106147404, 10116.696499105039, 10270.794584108127, 10301.845472318071, 10334.483275836328, 9946.950265248628, 10326.277160598986, 10336.328138670962, 10412.47937603132, 10453.372797632166, 7137.607431591312, 7544.602058250936, 7595.278448547379, 7674.117476490052, 7842.254985776342, 8587.742367728979, 8698.956125264756, 8710.60802263887, 8807.955960220157, 8809.339299552432, 7638.045244344457, 7806.41451891099, 7859.960700196462, 7872.84254314899, 7932.960335198287, 7177.964110179416, 7602.163761986288, 7633.0458692663415, 7730.652120654471, 7738.845223095619, 7209.3872020876715, 7241.674124664508, 7339.74310899116, 7350.77947661571, 7358.926410735824, 4895.559399654048, 5005.274235235943, 5073.492650734897, 5099.439061703136, 5125.79496820123, 5092.490750924878, 5127.077126117186, 5177.301064356264, 5195.402528709095, 5268.920966185434], "value": 4634.832105201132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.6211133913547, 283.58313279479165, 313.95604615353847, 323.1646194586994, 326.5982841105439, 4622.514635963222, 4632.536746325366, 4890.510948905112, 5594.524465420434, 5621.971890140554, 4735.810567577293, 5049.293098966147, 5472.50747432731, 5477.863053423666, 5501.669899806005, 3735.62643735627, 3958.782266975315, 4047.8380864765522, 4056.371262454324, 4101.036256479725, 3122.2350524121507, 3379.983100084496, 3962.8507732757466, 4050.230456213325, 4127.958720412803, 3763.6612704856557, 4088.897777555555, 4098.446709694198, 4126.587341265865, 4133.256013917508, 3351.491828677105, 3496.8110842313736, 3954.762714237155, 4083.9183216335805, 4114.033202197481, 2924.8245105293536, 3454.222799870034, 3544.9645503544893, 3584.354816133095, 3594.622564630702, 4384.6188450638265, 4841.515848415165, 4979.30289759434, 5095.745212739348, 5156.639035267533, 3764.8305826237884, 4353.847615333448, 4582.908341833178, 4719.976400118012, 4793.880152996168, 5652.09566469367, 5661.886762264731, 5676.574256930747, 5717.9714101429645, 5724.4561766632105, 4004.4593979812644, 4016.357382818764, 4020.577839326887, 4032.4152997815445, 4049.2667449471783, 5733.31200255972, 5977.356227037556, 6007.279126504804, 6007.909881351783, 6063.211782468295, 3594.263176048904, 4058.640355480888, 4076.307027805221, 4085.89785255371, 4087.693422993287, 3669.743117981716, 4105.665658660888, 4136.544980052192, 4143.212789569972, 4152.522459917148, 4038.9394159087637, 4043.8989025274605, 4716.834910778175, 4753.334533165291, 4759.881002974953, 5482.122860342406, 5591.189277554732, 5724.685142317129, 5734.197212390269, 5736.254286942671, 1228.3551135653854, 1336.946522139123, 1417.0859795431888, 1424.1953296387508, 1426.892983026277, 814.722994181981, 817.1868990354517, 833.8457385383723, 891.9464832109965, 897.8742975983367, 4440.045390241049, 4522.479914809774, 5216.191490319006, 5362.329708786402, 5372.355317361941, 4665.46347170073, 5319.255304257407, 5757.366689664133, 5846.7076646167525, 5985.281766188085, 5110.3356563646485, 5216.0, 5272.868178295574, 5376.623636345418, 5418.214358918013, 2587.676540432446, 3025.2739342558066, 3064.4024415238814, 3115.146487839732, 3123.703248191418, 5741.827745167651, 6232.626042437469, 6236.750529978841, 6248.343923887926, 6272.278687950853, 5756.769729210869, 6113.724882380304, 6123.663198524124, 6172.5679202456295, 6183.659898705524, 5940.940590594125, 6191.1641928339095, 6213.596116252429, 6230.501559875199, 6263.906041409383, 4860.441049279378, 5089.8727531812, 5320.840374788762, 5857.677827719516, 5953.255843019622, 5062.443131255633, 5870.618409803349, 5898.64608123514, 5912.467877890838, 5922.111683247547, 5074.31496747949, 5133.820316288912, 5495.395506494203, 6119.10307804582, 6191.288001879841, 3679.2273622539565, 3860.536735591747, 3971.563128055969, 3984.621460961204, 4076.470058892325, 4745.694933893069, 5726.685032323262, 5868.911966320423, 5957.821265362046, 5976.970115149396, 5757.006356694412, 5760.423957604206, 5767.452092051249, 5778.162166485752, 5792.333881603669, 5128.8461346159675, 5372.62391632582, 5393.06078187381, 5414.945850541444, 5790.770553336912, 5411.512963833274, 5488.451154884479, 5681.9147712783515, 5709.942900571107, 5764.769409223584, 4921.753912304442, 5499.725013749377, 5673.971630141988, 5810.854728631818, 5836.182934389104, 5255.421903590678, 5572.387037425961, 5698.034178208512, 5704.828855134353, 5723.6851973141875], "value": 4155.1443187885925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.5988646925375, 737.5648367463195, 740.1155619035255, 761.4099073218258, 762.9198934111921, 15100.47148349808, 15214.391424343014, 15412.537623871287, 15457.227138643078, 15602.375904963803, 14092.506762263323, 14278.500252491165, 14926.925365373185, 15619.0, 15665.608359791011, 697.2504557600599, 881.3433991676178, 886.534569351091, 892.1702816380796, 894.5124906925709, 870.6430363550945, 892.2772554231094, 895.0, 897.1253028297423, 899.163777686751, 924.912133347331, 931.2643012020495, 931.7950050988771, 938.1697197979769, 939.4410325856105, 921.9769505762342, 932.9300302477274, 934.2899396458706, 935.2751617496426, 937.582775664826, 668.8194187569378, 677.069030083635, 679.9898001529981, 690.5373399822131, 693.9791806245821, 14906.776398353924, 14931.0, 14938.775918361232, 15202.923985380003, 15217.847821521755, 12257.14200006, 12376.319302438362, 12424.192427492184, 12490.562830300896, 12660.493580256778, 9791.853122203172, 10044.94977525115, 10139.036791504795, 10398.0, 10570.471476426224, 2889.2632378743288, 3035.8937437189593, 3457.083872773717, 3473.3053389321985, 3505.3515099706383, 82743.17256827415, 84457.0, 84822.0, 84911.5754421224, 85486.5725671374, 9158.633654653873, 9268.582913768896, 9297.256219502424, 9379.437233765995, 9480.24158067353, 21316.36050918444, 21615.45961350979, 21624.89187554052, 21647.0, 21798.019089141028, 6129.478994285536, 6544.854650436155, 7188.4968052235845, 7585.241475852371, 7683.923160768432, 3811.8475260989803, 3904.6095390460728, 3923.7449565778134, 3940.5862384449792, 4103.117829666636, 2960.2303401115864, 3019.788614796943, 3400.183955850578, 3488.5116083748294, 3503.211777350076, 594.4293478260918, 635.4979566142788, 658.785894584262, 661.5468404143178, 661.7319985405918, 597.0864577196895, 612.4396177497629, 614.0911451052467, 614.1678026274424, 616.0789619518848, 7425.443091768139, 8120.75637730869, 8300.460470069425, 8353.37349698775, 8442.451240669337, 495.1434019146824, 507.7936366487605, 580.3847921203537, 581.7004242814953, 608.8477880529867, 3776.811159442017, 3874.341361968472, 3912.7261091723876, 3937.0551067743536, 3953.78254196022, 84806.0, 85349.0, 85361.0, 87111.12888871157, 87823.12176878277, 75153.0, 76467.61766191245, 81464.0, 84727.0, 86336.0, 52362.0, 76741.61629191818, 77278.22721772935, 77676.0, 79296.0, 6350.7459701611415, 7879.448438609243, 8213.219744124299, 8408.570273548108, 8610.095939926468, 45867.853303667674, 47070.0, 47288.763556182, 47715.56853294268, 47787.22127787193, 65115.67442162948, 65764.0, 65828.0125798123, 66053.0, 66413.0, 9081.636734530544, 11011.44942752843, 11218.943905280421, 11326.433678316216, 11849.585264515701, 11937.462814173557, 12079.637610871689, 12097.395130243276, 12099.33453660057, 12150.696232594255, 11321.490532925873, 11540.769184616429, 11662.416879156179, 11732.413379330828, 12195.512179513074, 11329.773404531697, 11578.189526733047, 11642.068634509049, 11873.150105708246, 12411.813822792847, 10444.686659399926, 10764.251929300473, 10892.891071089187, 11207.215494915277, 11433.656990290305, 9392.530373481162, 9507.809843803223, 9618.711438656852, 9666.661666841908, 9852.014798520091, 8385.051301894653, 9320.834895638045, 9363.157315841607, 10004.149647280063, 10089.495525223563], "value": 6834.375067073753, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1641.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 107.0, 116.0, 119.0, 116.0, 108.0, 117.0, 117.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 179.0, 243.0, 120.0, 139.0, 143.0, 147.0, 181.0, 155.0], "value": 158.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 246.0, 271.0, 238.0, 229.0, 259.0, 217.0, 230.0, 280.0, 250.0], "value": 243.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 78.0, 173.0, 48.0, 74.0, 54.0, 69.0, 144.0, 49.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 91.0, 146.0, 81.0, 84.0, 82.0, 122.0, 85.0, 81.0, 121.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 80.0, 133.0, 119.0, 90.0, 86.0, 92.0, 90.0, 95.0, 86.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 104.0, 152.0, 151.0, 96.0, 97.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 85.0, 148.0, 149.0, 92.0, 119.0, 143.0, 145.0, 121.0, 90.0], "value": 121.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 80.0, 87.0, 86.0, 76.0, 86.0, 138.0, 61.0, 57.0], "value": 78.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 70.0, 72.0, 73.0, 72.0, 70.0, 67.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 87.0, 82.0, 87.0, 88.0, 86.0, 86.0, 84.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 144.0, 135.0, 133.0, 138.0, 146.0, 241.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 165.0, 169.0, 165.0, 167.0, 167.0, 165.0, 166.0, 167.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 66.0, 69.0, 62.0, 65.0, 67.0, 67.0, 71.0], "value": 66.5, "unit": "score"}]}]} 16:14:59 INFO - Return code: 0 16:14:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:14:59 ERROR - # TBPL SUCCESS # 16:14:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:59 INFO - 2016-05-13 16:14:59.625 screenresolution[1682:10890] starting screenresolution argv=screenresolution get 16:14:59 INFO - 2016-05-13 16:14:59.627 screenresolution[1682:10890] Display 0: 1600x1200x32@60 16:14:59 INFO - 2016-05-13 16:14:59.633 screenresolution[1683:10893] starting screenresolution argv=screenresolution list 16:14:59 INFO - Available Modes on Display 0 16:14:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:59 INFO - Intel Iris: 16:14:59 INFO - Chipset Model: Intel Iris 16:14:59 INFO - Type: GPU 16:14:59 INFO - Bus: Built-In 16:14:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:59 INFO - Vendor: Intel (0x8086) 16:14:59 INFO - Device ID: 0x0a2e 16:14:59 INFO - Revision ID: 0x0009 16:14:59 INFO - Displays: 16:14:59 INFO - Display: 16:14:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:59 INFO - Display Serial Number: 1600x1200 60 16:14:59 INFO - Main Display: Yes 16:14:59 INFO - Mirror: Off 16:14:59 INFO - Online: Yes 16:14:59 INFO - Rotation: Supported 16:14:59 INFO - Return code: 0 16:14:59 INFO - Running post-action listener: _resource_record_post_action 16:14:59 INFO - ##### 16:14:59 INFO - ##### Finished run-tests step (success) 16:14:59 INFO - ##### 16:14:59 INFO - Running post-run listener: _resource_record_post_run 16:15:00 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 32439808; Write bytes: 698055168; Read time: 322; Write time: 2014 16:15:00 INFO - TinderboxPrint: CPU usage
54.7% 16:15:00 INFO - TinderboxPrint: I/O read bytes / time
32,439,808 / 322 16:15:00 INFO - TinderboxPrint: I/O write bytes / time
698,055,168 / 2,014 16:15:00 INFO - TinderboxPrint: CPU idle
2,194.4 (45.3%) 16:15:00 INFO - TinderboxPrint: CPU system
984.8 (20.3%) 16:15:00 INFO - TinderboxPrint: CPU user
1,666.4 (34.4%) 16:15:00 INFO - TinderboxPrint: Swap in / out
423,239,680 / 0 16:15:00 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 386699776; Write bytes: 380704256; Read time: 29962; Write time: 720 16:15:00 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30658048; Write bytes: 299064832; Read time: 303; Write time: 1244 16:15:00 INFO - Running post-run listener: _upload_blobber_files 16:15:00 INFO - Blob upload gear active. 16:15:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:00 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 16:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:15:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:15:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:15:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:02 INFO - (blobuploader) - INFO - Done attempting. 16:15:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:15:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:15:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:03 INFO - (blobuploader) - INFO - Done attempting. 16:15:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:05 INFO - (blobuploader) - INFO - Done attempting. 16:15:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:05 INFO - Return code: 0 16:15:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fddd128d894fddfc73d2a866fa581af6da2fc915d236a1c8dca2922b78c1f349ce42c884d6c8759e168bdd0ce8184294648a6ac3c3fcf684e9ac99c318ce9a17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/67a039b544b88d80f585462ce147fabcdb2016c84344407441579af9c49f7c613f3220ee04893eaa56bd9c90e5bbfce7d3236627e27577155daa581b6f679478", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d891d8f5b9803a1fe37442ce1cff6590bc80c1d8d1163167f9361f02255c0c10974adc85bf57c327b2611f4293379545aa9764ca08e9053fca69042668e4fe0d"} 16:15:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:05 INFO - Contents: 16:15:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fddd128d894fddfc73d2a866fa581af6da2fc915d236a1c8dca2922b78c1f349ce42c884d6c8759e168bdd0ce8184294648a6ac3c3fcf684e9ac99c318ce9a17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/67a039b544b88d80f585462ce147fabcdb2016c84344407441579af9c49f7c613f3220ee04893eaa56bd9c90e5bbfce7d3236627e27577155daa581b6f679478", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d891d8f5b9803a1fe37442ce1cff6590bc80c1d8d1163167f9361f02255c0c10974adc85bf57c327b2611f4293379545aa9764ca08e9053fca69042668e4fe0d"} 16:15:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:05 INFO - Copying logs to upload dir... 16:15:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.442128 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-05-13 16:15:05.989342) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 16:15:05.992484) ========= 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.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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/Fx-Team/sha512/fddd128d894fddfc73d2a866fa581af6da2fc915d236a1c8dca2922b78c1f349ce42c884d6c8759e168bdd0ce8184294648a6ac3c3fcf684e9ac99c318ce9a17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/67a039b544b88d80f585462ce147fabcdb2016c84344407441579af9c49f7c613f3220ee04893eaa56bd9c90e5bbfce7d3236627e27577155daa581b6f679478", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d891d8f5b9803a1fe37442ce1cff6590bc80c1d8d1163167f9361f02255c0c10974adc85bf57c327b2611f4293379545aa9764ca08e9053fca69042668e4fe0d"} build_url:https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011693 build_url: 'https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fddd128d894fddfc73d2a866fa581af6da2fc915d236a1c8dca2922b78c1f349ce42c884d6c8759e168bdd0ce8184294648a6ac3c3fcf684e9ac99c318ce9a17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/67a039b544b88d80f585462ce147fabcdb2016c84344407441579af9c49f7c613f3220ee04893eaa56bd9c90e5bbfce7d3236627e27577155daa581b6f679478", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d891d8f5b9803a1fe37442ce1cff6590bc80c1d8d1163167f9361f02255c0c10974adc85bf57c327b2611f4293379545aa9764ca08e9053fca69042668e4fe0d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 16:15:06.021626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:15:06.021960) ========= 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.DiHhmaa8OR/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.GJdPa9janq/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 16:15:06.071370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 16:15:06.071811) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 16:15:06.072372) ========= ========= Total master_lag: 0.22 =========