builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1464255715.65 results: success (0) buildid: 20160526012938 builduid: 3c7e2e01a36d4d12938f41112d3d3058 revision: ebf96465a8de20345f7b8d176d827ec538ecbd09 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.645994) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.646481) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.646766) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.669564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.670071) ========= 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.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-26 02:41:55-- 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% 236M=0s 2016-05-26 02:41:55 (236 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.794384) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.794719) ========= 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.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.024857 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:55.843367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-26 02:41:55.843738) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ebf96465a8de20345f7b8d176d827ec538ecbd09 --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 ebf96465a8de20345f7b8d176d827ec538ecbd09 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-26 02:41:55,925 truncating revision to first 12 chars 2016-05-26 02:41:55,925 Setting DEBUG logging. 2016-05-26 02:41:55,925 attempt 1/10 2016-05-26 02:41:55,925 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ebf96465a8de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 02:41:58,973 unpacking tar archive at: fx-team-ebf96465a8de/testing/mozharness/ program finished with exit code 0 elapsedTime=3.335513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-26 02:41:59.200805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:59.201383) ========= script_repo_revision: ebf96465a8de20345f7b8d176d827ec538ecbd09 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:59.202055) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:59.202645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:59.220049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-26 02:41:59.220539) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:41:59 INFO - MultiFileLogger online at 20160526 02:41:59 in /builds/slave/test 02:41:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:41:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:41:59 INFO - {'append_to_log': False, 02:41:59 INFO - 'base_work_dir': '/builds/slave/test', 02:41:59 INFO - 'blob_upload_branch': 'Fx-Team', 02:41:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:41:59 INFO - 'branch': 'Fx-Team', 02:41:59 INFO - 'buildbot_json_path': 'buildprops.json', 02:41:59 INFO - 'config_files': ('talos/mac_config.py',), 02:41:59 INFO - 'default_actions': ('clobber', 02:41:59 INFO - 'read-buildbot-config', 02:41:59 INFO - 'download-and-extract', 02:41:59 INFO - 'populate-webroot', 02:41:59 INFO - 'create-virtualenv', 02:41:59 INFO - 'install', 02:41:59 INFO - 'run-tests'), 02:41:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:41:59 INFO - 'download_minidump_stackwalk': True, 02:41:59 INFO - 'download_symbols': 'ondemand', 02:41:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:41:59 INFO - 'tooltool.py': '/tools/tooltool.py', 02:41:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:41:59 INFO - '/tools/misc-python/virtualenv.py')}, 02:41:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:41:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:41:59 INFO - 'installer_path': 'installer.exe', 02:41:59 INFO - 'log_level': 'info', 02:41:59 INFO - 'log_name': 'talos', 02:41:59 INFO - 'log_to_console': True, 02:41:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:41:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:41:59 INFO - 'opt_config_files': (), 02:41:59 INFO - 'pip_index': False, 02:41:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:59 INFO - 'cmd': ('bash', 02:41:59 INFO - '-c', 02:41:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:59 INFO - 'enabled': True, 02:41:59 INFO - 'halt_on_failure': False, 02:41:59 INFO - 'name': 'check_screen_resolution'},), 02:41:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:59 INFO - 'cmd': ('bash', 02:41:59 INFO - '-c', 02:41:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:59 INFO - 'enabled': True, 02:41:59 INFO - 'halt_on_failure': False, 02:41:59 INFO - 'name': 'check_screen_resolution'},), 02:41:59 INFO - 'run_cmd_checks_enabled': True, 02:41:59 INFO - 'sps_profile': False, 02:41:59 INFO - 'sps_profile_interval': 0, 02:41:59 INFO - 'suite': 'dromaeojs', 02:41:59 INFO - 'system_bits': '32', 02:41:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:41:59 INFO - 'title': 't-yosemite-r7-0228', 02:41:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:41:59 INFO - 'use_talos_json': True, 02:41:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:41:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:41:59 INFO - 'work_dir': 'build'} 02:41:59 INFO - ##### 02:41:59 INFO - ##### Running clobber step. 02:41:59 INFO - ##### 02:41:59 INFO - Running pre-action listener: _resource_record_pre_action 02:41:59 INFO - Running main action method: clobber 02:41:59 INFO - rmtree: /builds/slave/test/build 02:41:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:42:01 INFO - Running post-action listener: _resource_record_post_action 02:42:01 INFO - ##### 02:42:01 INFO - ##### Finished clobber step (success) 02:42:01 INFO - ##### 02:42:01 INFO - ##### 02:42:01 INFO - ##### Running read-buildbot-config step. 02:42:01 INFO - ##### 02:42:01 INFO - Running pre-action listener: _resource_record_pre_action 02:42:01 INFO - Running main action method: read_buildbot_config 02:42:01 INFO - Using buildbot properties: 02:42:01 INFO - { 02:42:01 INFO - "project": "", 02:42:01 INFO - "product": "firefox", 02:42:01 INFO - "script_repo_revision": "production", 02:42:01 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:42:01 INFO - "repository": "", 02:42:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:42:01 INFO - "buildid": "20160526012938", 02:42:01 INFO - "pgo_build": "False", 02:42:01 INFO - "basedir": "/builds/slave/test", 02:42:01 INFO - "buildnumber": 24, 02:42:01 INFO - "slavename": "t-yosemite-r7-0228", 02:42:01 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:42:01 INFO - "platform": "yosemite_r7", 02:42:01 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:42:01 INFO - "branch": "fx-team", 02:42:01 INFO - "repo_path": "integration/fx-team", 02:42:01 INFO - "revision": "ebf96465a8de20345f7b8d176d827ec538ecbd09", 02:42:01 INFO - "stage_platform": "macosx64", 02:42:01 INFO - "builduid": "3c7e2e01a36d4d12938f41112d3d3058", 02:42:01 INFO - "slavebuilddir": "test" 02:42:01 INFO - } 02:42:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:42:01 INFO - Running post-action listener: _resource_record_post_action 02:42:01 INFO - ##### 02:42:01 INFO - ##### Finished read-buildbot-config step (success) 02:42:01 INFO - ##### 02:42:01 INFO - ##### 02:42:01 INFO - ##### Running download-and-extract step. 02:42:01 INFO - ##### 02:42:01 INFO - Running pre-action listener: _resource_record_pre_action 02:42:01 INFO - Running main action method: download_and_extract 02:42:01 INFO - mkdir: /builds/slave/test/build/tests 02:42: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')]} 02:42:01 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:42:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:42:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:42:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:02 INFO - Downloaded 1199 bytes. 02:42:02 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:42:02 INFO - Using the following test package requirements: 02:42:02 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:42:02 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:42:02 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:42:02 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'jsshell-mac.zip'], 02:42:02 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:42:02 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:42:02 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:42:02 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:42:02 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:42:02 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:42:02 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:42:02 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:02 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:42:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:42:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:42:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:04 INFO - Downloaded 18622392 bytes. 02:42:04 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'] 02:42:04 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 02:42:07 INFO - Return code: 0 02:42:07 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 02:42: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')]} 02:42:07 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:42:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:42:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:42:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:09 INFO - Downloaded 18622392 bytes. 02:42:09 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'] 02:42:09 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 02:42:12 INFO - Return code: 0 02:42:12 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')]} 02:42:12 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:42:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:42:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:42:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:14 INFO - Downloaded 10834034 bytes. 02:42:14 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'] 02:42:14 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 02:42:14 INFO - Return code: 0 02:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:14 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:42:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:42:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:42:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:42:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:42:18 INFO - Downloaded 110500006 bytes. 02:42:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:42:18 INFO - mkdir: /builds/slave/test/properties 02:42:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:42:18 INFO - Writing to file /builds/slave/test/properties/build_url 02:42:18 INFO - Contents: 02:42:18 INFO - build_url:https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:42:20 INFO - Running post-action listener: _resource_record_post_action 02:42:20 INFO - Running post-action listener: set_extra_try_arguments 02:42:20 INFO - ##### 02:42:20 INFO - ##### Finished download-and-extract step (success) 02:42:20 INFO - ##### 02:42:20 INFO - ##### 02:42:20 INFO - ##### Running populate-webroot step. 02:42:20 INFO - ##### 02:42:20 INFO - Running pre-action listener: _resource_record_pre_action 02:42:20 INFO - Running main action method: populate_webroot 02:42:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:42:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:42:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:42:20 INFO - '--tppagecycles', 02:42:20 INFO - '7'], 02:42:20 INFO - 'tests': ['tsvgm']}}, 02:42:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:42:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:42:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:42:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:42:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['damp', 'tps']}, 02:42:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'tests': ['damp', 'tps']}, 02:42:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['dromaeo_dom']}, 02:42:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:42:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'other-e10s': {'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['a11yr', 02:42:20 INFO - 'ts_paint', 02:42:20 INFO - 'tpaint', 02:42:20 INFO - 'sessionrestore', 02:42:20 INFO - 'sessionrestore_no_auto_restore', 02:42:20 INFO - 'tabpaint']}, 02:42:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['tsvgx', 02:42:20 INFO - 'tsvgr_opacity', 02:42:20 INFO - 'tart', 02:42:20 INFO - 'tscrollx', 02:42:20 INFO - 'cart']}, 02:42:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:42:20 INFO - 'tsvgr_opacity', 02:42:20 INFO - 'tart', 02:42:20 INFO - 'tscrollx', 02:42:20 INFO - 'cart']}, 02:42:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'talos_options': ['--disable-e10s'], 02:42:20 INFO - 'tests': ['tp5o']}, 02:42:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'tests': ['tp5o']}, 02:42:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'talos_options': ['--disable-e10s', 02:42:20 INFO - '--xperf_path', 02:42:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:20 INFO - 'tests': ['tp5n']}, 02:42:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:42:20 INFO - 'talos_options': ['--xperf_path', 02:42:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:20 INFO - 'tests': ['tp5n']}}, 02:42:20 INFO - 'talos.zip': {'path': '', 02:42:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:42:20 INFO - Running post-action listener: _resource_record_post_action 02:42:20 INFO - ##### 02:42:20 INFO - ##### Finished populate-webroot step (success) 02:42:20 INFO - ##### 02:42:20 INFO - ##### 02:42:20 INFO - ##### Running create-virtualenv step. 02:42:20 INFO - ##### 02:42:20 INFO - Running pre-action listener: _resource_record_pre_action 02:42:20 INFO - Running main action method: create_virtualenv 02:42:20 INFO - Creating virtualenv /builds/slave/test/build/venv 02:42:20 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 02:42:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:42:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:42:20 INFO - Using real prefix '/tools/python27' 02:42:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:42:21 INFO - Installing distribute.............................................................................................................................................................................................done. 02:42:23 INFO - Installing pip.................done. 02:42:23 INFO - Return code: 0 02:42:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:42:23 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')]} 02:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:23 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')]} 02:42:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:23 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:23 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 02:42:23 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 02:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:23 INFO - 'HOME': '/Users/cltbld', 02:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:23 INFO - 'LOGNAME': 'cltbld', 02:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:23 INFO - 'MOZ_NO_REMOTE': '1', 02:42:23 INFO - 'NO_EM_RESTART': '1', 02:42:23 INFO - 'PAGER': '/bin/cat', 02:42:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:23 INFO - 'PWD': '/builds/slave/test', 02:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:23 INFO - 'SHELL': '/bin/bash', 02:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:23 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:23 INFO - 'USER': 'cltbld', 02:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:23 INFO - 'XPC_FLAGS': '0x0', 02:42:23 INFO - 'XPC_SERVICE_NAME': '0', 02:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:24 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 02:42:24 INFO - Cleaning up... 02:42:24 INFO - Return code: 0 02:42:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:24 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:24 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 02:42:24 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 02:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:24 INFO - 'HOME': '/Users/cltbld', 02:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:24 INFO - 'LOGNAME': 'cltbld', 02:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:24 INFO - 'MOZ_NO_REMOTE': '1', 02:42:24 INFO - 'NO_EM_RESTART': '1', 02:42:24 INFO - 'PAGER': '/bin/cat', 02:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:24 INFO - 'PWD': '/builds/slave/test', 02:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:24 INFO - 'SHELL': '/bin/bash', 02:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:24 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:24 INFO - 'USER': 'cltbld', 02:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:24 INFO - 'XPC_FLAGS': '0x0', 02:42:24 INFO - 'XPC_SERVICE_NAME': '0', 02:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:24 INFO - Downloading/unpacking psutil>=3.1.1 02:42:24 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 02:42:24 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 02:42:24 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 02:42:24 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 02:42:24 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 02:42:24 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 02:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:42:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:26 INFO - Installing collected packages: psutil 02:42:26 INFO - Running setup.py install for psutil 02:42:26 INFO - building 'psutil._psutil_osx' extension 02:42:26 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 02:42:27 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 02:42:27 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 02:42:27 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 02:42:27 INFO - building 'psutil._psutil_posix' extension 02:42:27 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 02:42:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:42:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:42:27 INFO - ^ 02:42:27 INFO - 1 warning generated. 02:42:27 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 02:42:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:27 INFO - Successfully installed psutil 02:42:27 INFO - Cleaning up... 02:42:27 INFO - Return code: 0 02:42:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:42:27 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')]} 02:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:27 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')]} 02:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:27 INFO - 'HOME': '/Users/cltbld', 02:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:27 INFO - 'LOGNAME': 'cltbld', 02:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:27 INFO - 'MOZ_NO_REMOTE': '1', 02:42:27 INFO - 'NO_EM_RESTART': '1', 02:42:27 INFO - 'PAGER': '/bin/cat', 02:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:27 INFO - 'PWD': '/builds/slave/test', 02:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:27 INFO - 'SHELL': '/bin/bash', 02:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:27 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:27 INFO - 'USER': 'cltbld', 02:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:27 INFO - 'XPC_FLAGS': '0x0', 02:42:27 INFO - 'XPC_SERVICE_NAME': '0', 02:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:42:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:42:31 INFO - Installing collected packages: mozsystemmonitor 02:42:31 INFO - Running setup.py install for mozsystemmonitor 02:42:31 INFO - Successfully installed mozsystemmonitor 02:42:31 INFO - Cleaning up... 02:42:31 INFO - Return code: 0 02:42:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:31 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:31 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 02:42:31 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 02:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:31 INFO - 'HOME': '/Users/cltbld', 02:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:31 INFO - 'LOGNAME': 'cltbld', 02:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:31 INFO - 'MOZ_NO_REMOTE': '1', 02:42:31 INFO - 'NO_EM_RESTART': '1', 02:42:31 INFO - 'PAGER': '/bin/cat', 02:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:31 INFO - 'PWD': '/builds/slave/test', 02:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:31 INFO - 'SHELL': '/bin/bash', 02:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:31 INFO - 'USER': 'cltbld', 02:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:31 INFO - 'XPC_FLAGS': '0x0', 02:42:31 INFO - 'XPC_SERVICE_NAME': '0', 02:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:32 INFO - Downloading/unpacking blobuploader==1.2.4 02:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:42:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:42:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:35 INFO - Downloading docopt-0.6.1.tar.gz 02:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:42:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:42:35 INFO - Running setup.py install for blobuploader 02:42:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:42:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:42:36 INFO - Running setup.py install for requests 02:42:36 INFO - Running setup.py install for docopt 02:42:36 INFO - Successfully installed blobuploader requests docopt 02:42:36 INFO - Cleaning up... 02:42:36 INFO - Return code: 0 02:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42: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')]} 02:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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')]} 02:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:36 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:36 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 02:42:36 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 02:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:36 INFO - 'HOME': '/Users/cltbld', 02:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:36 INFO - 'LOGNAME': 'cltbld', 02:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:36 INFO - 'MOZ_NO_REMOTE': '1', 02:42:36 INFO - 'NO_EM_RESTART': '1', 02:42:36 INFO - 'PAGER': '/bin/cat', 02:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:36 INFO - 'PWD': '/builds/slave/test', 02:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:36 INFO - 'SHELL': '/bin/bash', 02:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:36 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:36 INFO - 'USER': 'cltbld', 02:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:36 INFO - 'XPC_FLAGS': '0x0', 02:42:36 INFO - 'XPC_SERVICE_NAME': '0', 02:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-BwyvbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-z0m1Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yzCAHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-cgIlYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-HkJ_ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PhkAh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RYvIgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-sLyu3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-2OxKZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-X_UBO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-a3Zx8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-FqXUgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-AN37Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Pctze9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-sP544U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-82DWqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-u9nOUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:42:38 INFO - Running setup.py install for manifestparser 02:42:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:42:38 INFO - Running setup.py install for mozcrash 02:42:39 INFO - Running setup.py install for mozdebug 02:42:39 INFO - Running setup.py install for mozdevice 02:42:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Running setup.py install for mozfile 02:42:39 INFO - Running setup.py install for mozhttpd 02:42:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Running setup.py install for mozinfo 02:42:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Running setup.py install for mozInstall 02:42:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:42:39 INFO - Running setup.py install for mozleak 02:42:40 INFO - Running setup.py install for mozlog 02:42:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Running setup.py install for moznetwork 02:42:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Running setup.py install for mozprocess 02:42:40 INFO - Running setup.py install for mozprofile 02:42:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Running setup.py install for mozrunner 02:42:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:42:40 INFO - Running setup.py install for mozscreenshot 02:42:41 INFO - Running setup.py install for moztest 02:42:41 INFO - Running setup.py install for mozversion 02:42:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:42:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:42:41 INFO - Cleaning up... 02:42:41 INFO - Return code: 0 02:42:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:41 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')]} 02:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:41 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')]} 02:42:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:41 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:41 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 02:42:41 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 02:42:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:41 INFO - 'HOME': '/Users/cltbld', 02:42:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:41 INFO - 'LOGNAME': 'cltbld', 02:42:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:41 INFO - 'MOZ_NO_REMOTE': '1', 02:42:41 INFO - 'NO_EM_RESTART': '1', 02:42:41 INFO - 'PAGER': '/bin/cat', 02:42:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:41 INFO - 'PWD': '/builds/slave/test', 02:42:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:41 INFO - 'SHELL': '/bin/bash', 02:42:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:41 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:41 INFO - 'USER': 'cltbld', 02:42:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:41 INFO - 'XPC_FLAGS': '0x0', 02:42:41 INFO - 'XPC_SERVICE_NAME': '0', 02:42:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:41 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jCx3G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:41 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)) 02:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:41 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-noBxS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:41 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)) 02:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:41 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-BbbSir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:41 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)) 02:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:41 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-l3xxkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-H1HBAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-8YaT6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-JFAzXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-u3Rg3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ejGC7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-iQz4Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-bhq5oK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-YVSV7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:42 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)) 02:42:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:42 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RBHJDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:43 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)) 02:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-rgFrnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:43 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)) 02:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-l8iSc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:43 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)) 02:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Y5TXyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:43 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)) 02:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:43 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-1ymxHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:43 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)) 02:42:43 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)) 02:42:43 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)) 02:42:43 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)) 02:42:43 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)) 02:42:43 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)) 02:42:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:45 INFO - Downloading blessings-1.6.tar.gz 02:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:42:45 INFO - Installing collected packages: blessings 02:42:45 INFO - Running setup.py install for blessings 02:42:46 INFO - Successfully installed blessings 02:42:46 INFO - Cleaning up... 02:42:46 INFO - Return code: 0 02:42:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:42:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:46 INFO - Reading from file tmpfile_stdout 02:42:46 INFO - Current package versions: 02:42:46 INFO - blessings == 1.6 02:42:46 INFO - blobuploader == 1.2.4 02:42:46 INFO - docopt == 0.6.1 02:42:46 INFO - manifestparser == 1.1 02:42:46 INFO - mozInstall == 1.12 02:42:46 INFO - mozcrash == 0.17 02:42:46 INFO - mozdebug == 0.1 02:42:46 INFO - mozdevice == 0.48 02:42:46 INFO - mozfile == 1.2 02:42:46 INFO - mozhttpd == 0.7 02:42:46 INFO - mozinfo == 0.9 02:42:46 INFO - mozleak == 0.1 02:42:46 INFO - mozlog == 3.2 02:42:46 INFO - moznetwork == 0.27 02:42:46 INFO - mozprocess == 0.22 02:42:46 INFO - mozprofile == 0.28 02:42:46 INFO - mozrunner == 6.11 02:42:46 INFO - mozscreenshot == 0.1 02:42:46 INFO - mozsystemmonitor == 0.3 02:42:46 INFO - moztest == 0.7 02:42:46 INFO - mozversion == 1.4 02:42:46 INFO - psutil == 3.1.1 02:42:46 INFO - requests == 1.2.3 02:42:46 INFO - wsgiref == 0.1.2 02:42:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:46 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 02:42:46 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 02:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:46 INFO - 'HOME': '/Users/cltbld', 02:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:46 INFO - 'LOGNAME': 'cltbld', 02:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:46 INFO - 'MOZ_NO_REMOTE': '1', 02:42:46 INFO - 'NO_EM_RESTART': '1', 02:42:46 INFO - 'PAGER': '/bin/cat', 02:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:46 INFO - 'PWD': '/builds/slave/test', 02:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:46 INFO - 'SHELL': '/bin/bash', 02:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:46 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:46 INFO - 'USER': 'cltbld', 02:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:46 INFO - 'XPC_FLAGS': '0x0', 02:42:46 INFO - 'XPC_SERVICE_NAME': '0', 02:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 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)) 02:42:46 INFO - Cleaning up... 02:42:46 INFO - Return code: 0 02:42:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:46 INFO - 'HOME': '/Users/cltbld', 02:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:46 INFO - 'LOGNAME': 'cltbld', 02:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:46 INFO - 'MOZ_NO_REMOTE': '1', 02:42:46 INFO - 'NO_EM_RESTART': '1', 02:42:46 INFO - 'PAGER': '/bin/cat', 02:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:46 INFO - 'PWD': '/builds/slave/test', 02:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:46 INFO - 'SHELL': '/bin/bash', 02:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:46 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:46 INFO - 'USER': 'cltbld', 02:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:46 INFO - 'XPC_FLAGS': '0x0', 02:42:46 INFO - 'XPC_SERVICE_NAME': '0', 02:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:47 INFO - Downloading/unpacking jsonschema 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:42:49 INFO - Downloading/unpacking functools32 (from jsonschema) 02:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:42:49 INFO - warning: no files found matching '*.txt' 02:42:49 INFO - no previously-included directories found matching 'build' 02:42:49 INFO - no previously-included directories found matching 'dist' 02:42:49 INFO - no previously-included directories found matching '.git*' 02:42:49 INFO - Installing collected packages: jsonschema, functools32 02:42:50 INFO - Running setup.py install for functools32 02:42:50 INFO - warning: no files found matching '*.txt' 02:42:50 INFO - no previously-included directories found matching 'build' 02:42:50 INFO - no previously-included directories found matching 'dist' 02:42:50 INFO - no previously-included directories found matching '.git*' 02:42:50 INFO - Successfully installed jsonschema functools32 02:42:50 INFO - Cleaning up... 02:42:50 INFO - Return code: 0 02:42:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:42:50 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')]} 02:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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')]} 02:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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 0x107ad5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107adb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff56358c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, '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.xKLtXogKeU/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.xuvhED2ubW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 02:42:50 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 02:42:50 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 02:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:50 INFO - 'HOME': '/Users/cltbld', 02:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:50 INFO - 'LOGNAME': 'cltbld', 02:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:50 INFO - 'MOZ_NO_REMOTE': '1', 02:42:50 INFO - 'NO_EM_RESTART': '1', 02:42:50 INFO - 'PAGER': '/bin/cat', 02:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:50 INFO - 'PWD': '/builds/slave/test', 02:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:50 INFO - 'SHELL': '/bin/bash', 02:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:42:50 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:50 INFO - 'USER': 'cltbld', 02:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:50 INFO - 'XPC_FLAGS': '0x0', 02:42:50 INFO - 'XPC_SERVICE_NAME': '0', 02:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:50 INFO - Downloading/unpacking flake8 02:42:50 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 02:42:50 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 02:42:50 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 02:42:50 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 02:42:50 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 02:42:50 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 02:42:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:42:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:42:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:53 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 02:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:42:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:42:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:42:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:42:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:42:54 INFO - no previously-included directories found matching 'docs/_build' 02:42:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:42:54 INFO - Running setup.py install for pep8 02:42:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:42:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:42:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:42:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:42:54 INFO - no previously-included directories found matching 'docs/_build' 02:42:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:42:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:42:54 INFO - Cleaning up... 02:42:54 INFO - Return code: 0 02:42:54 INFO - Running post-action listener: _resource_record_post_action 02:42:54 INFO - Running post-action listener: _start_resource_monitoring 02:42:54 INFO - Starting resource monitoring. 02:42:54 INFO - ##### 02:42:54 INFO - ##### Finished create-virtualenv step (success) 02:42:54 INFO - ##### 02:42:54 INFO - ##### 02:42:54 INFO - ##### Running install step. 02:42:54 INFO - ##### 02:42:54 INFO - Running pre-action listener: _resource_record_pre_action 02:42:54 INFO - Running main action method: install 02:42:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:54 INFO - Reading from file tmpfile_stdout 02:42:54 INFO - Detecting whether we're running mozinstall >=1.0... 02:42:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:42:54 INFO - Reading from file tmpfile_stdout 02:42:54 INFO - Output received: 02:42:54 INFO - Usage: mozinstall [options] installer 02:42:54 INFO - Options: 02:42:54 INFO - -h, --help show this help message and exit 02:42:54 INFO - -d DEST, --destination=DEST 02:42:54 INFO - Directory to install application into. [default: 02:42:54 INFO - "/builds/slave/test"] 02:42:54 INFO - --app=APP Application being installed. [default: firefox] 02:42:55 INFO - mkdir: /builds/slave/test/build/application 02:42:55 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'] 02:42:55 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 02:43:28 INFO - Reading from file tmpfile_stdout 02:43:28 INFO - Output received: 02:43:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:43:28 INFO - Running post-action listener: _resource_record_post_action 02:43:28 INFO - ##### 02:43:28 INFO - ##### Finished install step (success) 02:43:28 INFO - ##### 02:43:28 INFO - ##### 02:43:28 INFO - ##### Running run-tests step. 02:43:28 INFO - ##### 02:43:28 INFO - Running pre-action listener: _resource_record_pre_action 02:43:28 INFO - Running main action method: run_tests 02:43:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:28 INFO - 2016-05-26 02:43:28.963 screenresolution[1644:7344] starting screenresolution argv=screenresolution get 02:43:28 INFO - 2016-05-26 02:43:28.966 screenresolution[1644:7344] Display 0: 1600x1200x32@60 02:43:28 INFO - 2016-05-26 02:43:28.972 screenresolution[1645:7346] starting screenresolution argv=screenresolution list 02:43:28 INFO - Available Modes on Display 0 02:43:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:29 INFO - Intel Iris: 02:43:29 INFO - Chipset Model: Intel Iris 02:43:29 INFO - Type: GPU 02:43:29 INFO - Bus: Built-In 02:43:29 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:29 INFO - Vendor: Intel (0x8086) 02:43:29 INFO - Device ID: 0x0a2e 02:43:29 INFO - Revision ID: 0x0009 02:43:29 INFO - Displays: 02:43:29 INFO - Display: 02:43:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:29 INFO - Display Serial Number: 1600x1200 60 02:43:29 INFO - Main Display: Yes 02:43:29 INFO - Mirror: Off 02:43:29 INFO - Online: Yes 02:43:29 INFO - Rotation: Supported 02:43:29 INFO - Return code: 0 02:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:43:29 INFO - Python 2.7.3 02:43:29 INFO - Return code: 0 02:43:29 INFO - grabbing minidump binary from tooltool 02:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:29 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 0x107979d30>, '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 0x10779ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107970b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:43:29 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 02:43:29 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 02:43:29 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 02:43:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:43:29 INFO - Return code: 0 02:43:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:43:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:43:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:43:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:43:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:43:30 INFO - Return code: 0 02:43:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:43:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKLtXogKeU/Render', 02:43:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:30 INFO - 'HOME': '/Users/cltbld', 02:43:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:30 INFO - 'LOGNAME': 'cltbld', 02:43:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:43:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:43:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:30 INFO - 'MOZ_NO_REMOTE': '1', 02:43:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:43:30 INFO - 'NO_EM_RESTART': '1', 02:43:30 INFO - 'PAGER': '/bin/cat', 02:43:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:30 INFO - 'PWD': '/builds/slave/test', 02:43:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:43:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:30 INFO - 'SHELL': '/bin/bash', 02:43:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xuvhED2ubW/Listeners', 02:43:30 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 02:43:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:30 INFO - 'USER': 'cltbld', 02:43:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:30 INFO - 'XPC_FLAGS': '0x0', 02:43:30 INFO - 'XPC_SERVICE_NAME': '0', 02:43:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:43:30 INFO - mozversion application_buildid: 20160526012938 02:43:30 INFO - mozversion application_changeset: ebf96465a8de20345f7b8d176d827ec538ecbd09 02:43:30 INFO - mozversion application_display_name: Nightly 02:43:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:43:30 INFO - mozversion application_name: Firefox 02:43:30 INFO - mozversion application_remotingname: firefox 02:43:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:43:30 INFO - mozversion application_vendor: Mozilla 02:43:30 INFO - mozversion application_version: 49.0a1 02:43:30 INFO - mozversion platform_buildid: 20160526012938 02:43:30 INFO - mozversion platform_changeset: ebf96465a8de20345f7b8d176d827ec538ecbd09 02:43:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:43:30 INFO - mozversion platform_version: 49.0a1 02:43:30 INFO - using testdate: 1464255810 02:43:30 INFO - actual date: 1464255810 02:43:30 INFO - starting webserver on 'localhost:49275' 02:43:30 INFO - SUITE-START | Running 2 tests 02:43:30 INFO - TEST-START | dromaeo_css 02:43:30 INFO - operating with platform_type : mac_ 02:43:30 INFO - Initialising browser for dromaeo_css test... 02:43:30 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp7UiTK7/profile http://localhost:49275/getInfo.html) 02:43:32 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 02:43:32 INFO - PROCESS | 1663 | colorDepth:24 02:43:32 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 02:43:32 INFO - PROCESS | 1663 | __metrics 02:43:33 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:43:33 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:43:33 INFO - TEST-INFO | 1663: exit 0 02:43:33 INFO - Browser initialized. 02:43:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:43:33 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp7UiTK7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:43:45 INFO - PROCESS | 1665 | RSS: Main: 241016832 02:43:45 INFO - PROCESS | 1665 | 02:44:36 INFO - PROCESS | 1665 | 1464255876628 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] 02:46:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 02:46:49 INFO - PROCESS | 1665 | RSS: Main: 363753472 02:46:49 INFO - PROCESS | 1665 | 02:49:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 02:49:52 INFO - PROCESS | 1665 | RSS: Main: 372633600 02:49:52 INFO - PROCESS | 1665 | 02:52:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 02:52:55 INFO - PROCESS | 1665 | RSS: Main: 372060160 02:52:55 INFO - PROCESS | 1665 | 02:55:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 02:55:58 INFO - PROCESS | 1665 | RSS: Main: 371380224 02:55:58 INFO - PROCESS | 1665 | 02:59:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 02:59:02 INFO - PROCESS | 1665 | RSS: Main: 338739200 02:59:02 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:02:05 INFO - PROCESS | 1665 | RSS: Main: 378376192 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | __start_tp_report 03:02:05 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 03:02:05 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 03:02:05 INFO - PROCESS | 1665 | |i|pagename|runs| 03:02:05 INFO - PROCESS | 1665 | |0;dojo.html;2939.162338733461;3478.0956951192684;3513.1392808761857;3517.085557754983;3552.1297282165856;5629.437056294369;5632.802851900185;5683.658980461175;5726.169705392721;5744.138379243111;5714.657120572779;5760.308762948442;5833.125031245305;5877.735501902414;5888.2934047914205;5131.153359695646;5161.122609156434;5191.377034755816;5222.608304377168;5317.255584218213;5299.5230429261355;5324.600654950875;5346.812861549846;5347.358317001966;5365.302510673607;5143.614228932845;5200.947990520084;5219.660722053054;5223.947760522384;5251.10731175701;5187;5212.843614691566;5227.189785583203;5233.581313494911;5235.738213089368;5154.664946778447;5183.352080989876;5195;5251.186066159751;5264.210368444726;5551.805686800943;5637.238972738669;5709.457601527848;5717.399673034313;5725.971370143165;6141.048137538689;6181.598196117283;6255.40573645503;6284.837305098571;6291.653959032252;6316.778912738078;6336.2396512418345;6354.2692590352235;6365.981442969149;6375.2987171411105;5810.389909059572;5842.824715258549;5884.823455296262;5885.264341957255;5889.998700220973;5264.52619264268;6315.305316415191;6326.114343991845;6330.715117819709;6364.49084073273;6168.753249870045;6171.9691401542705;6200.56596038282;6208;6218;6330.113784070234;6353.269374022047;6356.713947872357;6357.11000459936;6358.936410635927;6110.480609148272;6122.032243765608;6144.986077297285;6189.721462534197;6212.565120441616;6236.532260080512;6277.435030847246;6282.2147231596055;6289.371062893674;6312.116303717483;4862.246351815475;4905.337779399807;4909.533594308535;4924.532169443897;4929.383827021622;4284.335927931175;4366.6506679465565;4367.0174210802315;4449.377087207794;4458.643308535375;5717.62835415705;5725.026745453283;5729.627574207663;5771.538276937835;5774.711264436845;6317.810465686037;6325;6347.1114044033875;6362.141110950055;6369.681515924185;5490.313710786152;5542.168674698827;5556.555475561945;5576.275084239023;5581.134924086773;5260.815871444481;5265.289185959847;5279.049771041249;5289.312389389354;5289.550388217045;6289.213848268966;6331.493480521639;6345.524085693683;6346;6364.554481186365;6343.3022367538615;6373.075903993988;6381.553291269566;6385;6390.105385245976;6307.117003619589;6398.0402939559435;6400.647964362005;6406;6409.391107844747;6117.480014198843;6140.20177376956;6145.108959200801;6149.754009839557;6270.12218289449;6264.843378915563;6285.779997700059;6286.559940804283;6295.685215739103;6314.273858506147;5857.47282744538;6090.908636370369;6091.847703807441;6095.75616975316;6097.390260973867;5952.821415357546;6042.939570604413;6049.606775559574;6071.726772295346;6097.013809262604;5964.045752679551;5965.582409231312;5979.790707325223;6005.849853753691;6009.579329446897;6106.236720409949;6140.4473597376455;6141.692915354125;6157.84605384869;6158;5812.128180772918;5875.94124058771;5921.3782552831335;5950.2264705587995;5988.191594134736 03:02:05 INFO - PROCESS | 1665 | |1;ext.html;488.1408720651653;516.7287174233527;529.0318716748351;532.8854296326289;549.5905550364979;26873.865630671844;30696.38607227854;42475;43595.78202108985;43682.563174368246;44460.77769611155;44889.102217955624;44960;44979.32531012037;45161.774191129;9585.760355991104;9802.068803463659;9808.16630586401;9822;9830;8402.033766116883;8637.481751094923;10858;11866.347350895741;11908.940455297754;11859.64421067365;11948.924596786288;11964.1625086244;11974.820377694341;11983.281003139797;8674.956625216897;8894.955525222333;11807.409629518532;11921.986631136277;11932.761344773142;8750.86873696895;8821.735347939572;11555.711107222303;11874.762504749942;11898.286102833858;41692.37461438081;42120.41976432007;42208.57791422108;42327.9418014549;42372.364414533804;3278.49183376577;3419.743519236067;3490.89527314181;3744.400895856677;3994.0414300567945;29552.852235738897;30168.849155754302;30992.380152396825;31088.8445557773;31148.221294467592;2716.9184924452297;2732.303262668027;2770.252031951382;2841.218664867178;2868.3976364963382;520909;526088;526257.3687131578;526567;527110;61819.690901545204;62372.68813655902;62639;62674;62790.68604657039;65904.34095658982;66133.66933165303;66189;66274.33725662778;66280.6685966567;13612.65968350799;17136.314547417962;17149.742753858704;17158.227879745442;17177.88343757652;10701.78596428067;11081.445927703582;11851.644450666494;12097;13204.669883252996;17155.399561015303;17342.913285433744;17398.913005435144;17407.216675249645;17431.73852392215;6128.387161283836;7532.472726909174;7532.924670753332;7588.317051465284;7604.8479030419085;713.3045280851129;956.1538038835541;962.4177372689562;982.5480279071564;1003.7390278527636;29454.263643409085;29514.114576562737;30067.548986765218;30248.84875575652;30289.788408463857;1090.138790355626;1163.6567212672282;1197.7724232395817;1228.9139760216879;1235.8269842222096;15024.399024038916;15127;15190.924045379703;15603.53189404297;15649;671814;678392;682675;683654;685570;61093.38906610966;61146;61300.693496533124;61301;61344.69327653422;1182.2374568403332;1307.1176955554963;1462.8317743459638;1467.515719812438;1477.2392218007735;26317.605235921095;29905.355205463908;30456.23859403444;30984.915527956415;31841.5223771639;41024.58975410327;50617;51594.74202628963;51664.48335516747;51687;523643;526074;526211;526496;527402;33941;39974.80012599919;40930.79534602308;41517.37723934204;42433.72698819041;47099.29351059668;47262.29106563473;47432.57702268937;48775;48893;33008.67965281458;35527;36606.45090323594;38537;38919.221615568094;31150.37699245957;37821.67624133142;37902.8104859474;38555.22889542137;38680.41979370255;37276.813615931744;37749.245015099;37751.2449751009;37825.86522404332;38633.42049869198;27500.724992749816;32163.356732864744;33182.83860064886;33408.83295583507;33956.66043339634;24421.14525991582;24740.505189895743;24815.379615509755;25273.87363063173;25277.62083568785 03:02:05 INFO - PROCESS | 1665 | |2;jquery.html;335.9445691460909;375.32066958804563;375.75200367757276;380.09726898615787;389.4703203643043;20108.597828043432;20234.6964795528;21371.57256854862;21414.357569272906;21529.354119376403;11630.302181869094;12189.51241950321;12200;12206.755864882696;16626.66746665066;5001.999999999991;5487.890242195153;5509.201165830952;5513.145462453306;5527.226188333617;3866.130120722829;4112;5860.560457965648;6165.845853853667;6186.133941248228;6083.391660833903;6157.107219453176;6171.413715697024;6176.227971503562;6189.659568723719;4858.82994095205;5966.492848107916;5972.372900845392;6112.938870611304;6142.109394137848;3760.4359346098304;3922.5489068757174;3978.3634618461197;3986.940195897064;5492.780288788444;11264.380459074748;13139;13155.342232888413;13539.05226634136;13660;2059.742532183477;2061.6082944240698;2109.694094356325;2144.549644574641;2151.7848215178356;9505.524723763854;10262.7434314142;10289.890572432634;12256.877431225663;14246.572602821932;1693.6951348757218;1700.2325696471796;1759.0852756566642;1760.8938547486034;1761.806201317854;110427;127152.47607530445;128829;129779;129943.35028324892;11487.827682584768;11716.179867409199;11759.76480470403;11808.280391676595;11919.463624136935;14664.266786660624;14820.555383338517;14839.10965342083;14872.256387180598;14894.106353618818;8004.63979120941;8738.082501337396;8830.792301924519;8925.73222803317;8936.374453788172;8779.517126558101;8780.517071561126;8813.559322033872;8903.866442003375;8911.955440222757;4513.954860451354;4580.954190458119;4590.88522786933;4608.792604332813;4679.4618618858585;1749.6850566897826;1783.170825566118;1808.109035642486;1823.1450242507835;1907.3038341005433;682.3517658224706;899.3165194452264;928.6563971330651;991.5240684471349;1010.1111022300329;16473.34106635745;20125.798742012394;21194.364169074954;21405.357839264536;21419.357419277447;909.6043221198668;929.5816882402946;963.9036096390305;967.3228739882111;1006.642641862143;8710.69512567057;10735.892641073646;10971.067459265892;11305.773884522263;11329.886701132884;441536.58463415597;511455;515481;518290;532467;122378;126307.7369226296;126388.36805815912;128797;129322;1008.4907121903539;1016.420640235059;1030.922680798943;1042.6819819954835;1044.5351818440872;18505.35231266899;18725.53186170302;18817.341393051178;18856.171574263422;19000.23999040023;34239.315213696085;38444;50809;51122.233166503276;51721.7413912928;55636;71807;72114.63942680253;72736;72959.63520182365;8121.553314567519;8198.508089514648;8249.628766705393;8393.496390216607;8561.700340488052;12003.879961200277;12561.748765024466;12664.366781660696;12819.935900320439;13044.152130111512;10783.24517283814;10970.616028438966;11138.554457821596;11194.552217911192;11297.71755706114;10718.73203169927;10911.563537458731;10917.344959302467;10967.067799237155;11069.391183484986;11114.444277785917;11118.944405278246;11197.272177308449;11331.32012079278;11415.315081095161;7912;8033.0360356757565;8272.090070092165;8280.917190828015;8319.043310019426;8031.678732850621;9741;10901.563937442414;11158.330500170016;11310.660680179608 03:02:05 INFO - PROCESS | 1665 | |3;mootools.html;1400.012990841457;1560.914149721765;1587.841215878412;1833.5966087460754;1981.782003979562;7739.767806965786;8429.325653947684;8651.394402391827;8661.523616201106;8772.385932984693;8452.661893524251;8670.263027642657;8674.26268767154;8676.8286281352;8687;2043.8773673579633;2331.9506222199993;2339.3800642829706;2365.349528879555;2373.7982271506944;2213.1258153029544;2233.4081468410805;2248.651459023854;2248.932532024042;2254.4363909022745;2324.790768830805;2347.072906202049;2360.657704632827;2379.821513386503;2381.3570336009266;2328.0571368595747;2339.6841426407473;2350.165691179632;2351.494428697838;2352.141468364052;2340.32130682102;2344.8124150068124;2346.6831977682964;2346.741858395575;2349.060375849657;8503.914960850374;8609.440386374928;8683.218510334036;8692.739217823475;8770;5190.870228244286;5343.465653434664;5344.545713614348;5344.893102137974;5351.866203344908;3378.6114596821435;3492.511048453219;3522.401191797375;3523.94714079289;3553.662402071799;993.8360170571847;1005.7183988483237;1012.1143999000874;1013.9594416223342;1023.6775415744091;9381.859272110923;11433.942830285878;11438.599649012245;11441.199116061878;11449.713757156054;4142.860713303866;4297.914041719148;4443.600075993176;4501.977490112528;4592;9043.18611324984;9064.728058158265;9077.68228112013;9077.68228112013;9079.046700096394;5205.947940520622;5225.294585230945;5231.581473482112;5233.685978841206;5234.659747116425;4395.846145384896;4565.429321334833;4710.3405523226775;4791.281307803857;4886.120498310338;3749.83125759336;3768.698504119664;3839.2705385976574;3876.7286289959434;3883.8640647577763;1154.8036833738286;1156.4969238381332;1163.6334554615269;1172.2146162071433;1194.5580135349976;564.9180868774005;736.1828370508731;888.5246393179618;916.5325683901258;966.5022513405602;5205.349331333583;5701.543876489871;5866.1494083357675;5887.8233652990475;6052.585582661205;875.4309698695771;939.4691999020531;947.6493697332031;962.831504486712;975.7853272280087;3304.7025767681025;3658.8170591470853;3818.4726109555845;3912.980435097806;3948.1116748731306;10134.695959121238;10522.631707890187;10638.946805265925;10648.467576621293;10650.414227217578;10178;10544.156467482737;10564.735881602868;10615.203859710553;10625.203109766799;8048.637811298506;8143.592820358958;8207.507549547166;8218.917810821935;8221.835563288701;4766;4890.92663610039;5535.5848311376;5657.094864821609;5729.684867332337;8761.123887611187;9804.754881127743;9816.705498835046;9842.704718858446;9939.900600993897;7712.884306735291;8601.139886011599;9416.670416535662;10045.899541004788;10158.695239142837;7406.925930740624;7476.327130558276;7556.282153195438;7570.848583028199;7574.091109066947;7927.5243485391065;8137.471064380797;8182.1817818217705;8188.344932405513;8195.918040819515;7372.668229929774;7380.114386273682;7395.260473952561;7408.148062972827;7492.250774922464;7267.527610705287;7281.672324745505;7371.152317483558;7405.074365704287;7413.666385012795;6621.172353455818;6676.566023208476;6757.628330441677;6759.324067593201;6763.7632682855865;4756.429228492603;4778.163821331323;4785.521447855187;4849.515048495122;4993.275974983534;4652.30215467669;4826.6621336506105;4872.756362181947;4875.463698993037;4918.48355922623 03:02:05 INFO - PROCESS | 1665 | |4;prototype.html;243.80739216019342;297.7811308688114;314.23013616639224;316.69597186700764;318.29021282539935;4602.493725690171;5079.060373830844;5272.86817829554;5396.676199428027;5469.152281396385;4477.261251893434;4710.98713776538;5223.451537588546;5423.566114710824;5449.683884349156;3956.544997325301;4133.81397837098;4140.565240649726;4148.6266236038755;4160.6061969240345;3002.4595572796998;3883.922101675604;3968.9206215875665;4129.772862492562;4150.854720084798;3204.3431096625254;3475.8088305143206;4051.4327994080854;4081.571434999327;4147.81334839933;3864.42033694948;3873.8378486454235;4064.1668457966343;4069.491313585802;4085.7140000199997;3016.3364059906785;3574.8212589370687;3585.6195364784485;3586.5158203642436;3586.7668601540818;4542.772861356919;4552.385427967215;4738.167069589034;4860.902781944376;5127.359080114986;4044.312466880608;4127.979360103211;4321.746693458925;4561.452625684906;4751.287306903992;4475.30632751924;5022.59819214462;5160.729279117177;5686.5735069869925;5743.913841292384;3145.6316502321565;3376.5272861799367;3385.18755498681;3575.1955809942815;4015.7188996770237;5044.596432285409;5940.524758019348;5959.612625179349;5994.760209591567;6040.758369665208;3446.2418267981;4056.3307054336296;4057;4060.3503439449546;4070.4708387909973;3986.7807270599815;4102.651274641689;4130.173965206971;4154.813033413504;4167.97916010418;3979.422983667352;4677.906441871211;4724.976375118103;4740.644451666139;4741.834035808799;4651.627869770478;4787.497312782178;5324.946750532445;5678.630888992202;5746.396628354046;1137.2721458266717;1228.74054094552;1283.2044132637825;1314.5333406640505;1412.985870141306;828.8396245256689;903.9005709371963;957.3203025851584;967.434051080126;976.6229616241066;4513.88715282114;4832.44426890905;4914.960291479068;5263.315768950012;5391.083515802323;5483.616146869681;5652.7173641318595;5732.627379220421;5799.507041901401;5808.82573522795;4966.975165124151;5227;5256.316678831728;5261.868453288698;5266.236395722599;2510.0587279770084;2526;2565.8588777617038;2573.4302075733785;2600.7919366450637;4879.463259041502;4962.528559786814;5146.860415146757;5165.535101840853;5236.978789136158;4410.956440056415;4734.431868175841;4783.425988881357;4881.926771098437;5018.071656743514;4650.860733946858;4696.624270058387;4709.551033462581;4766.521408867439;5193.584513238932;4453.888652783707;4496.9100617986805;4635.397398338195;4728.787204575871;5396.406395296436;4989.750512474435;5841.50347220491;5847.795327163529;5870.5010074142465;5888.793892213924;4827.503473923095;5564.970480461127;6190.535709821871;6190.7214175363115;6193.38066193377;3716.4239542870896;3910.237503686754;3912.7847968360875;3938.9409158863214;3941.980290098531;5585.832425027255;5787.9131813021995;5790.783935373619;5813.273340832396;5927.733252003756;5224.2947202127225;5256.58072320479;5573.331200255995;5602.943970560243;5786.623869448473;5421.593380496557;5721.026245945142;5725.971370143123;5739.8565035874435;5787.479126878601;5568.192612071146;5595.692236926845;5602.887942241051;5605.915911261252;5626.831195064155;5560.691388623763;5707.461373948178;5720.19917211599;5727.513161381329;5747.4252574742195;4950.227872391472;5177.145770947752;5225.503577160163;5619.634723742944;5637.126245431965 03:02:05 INFO - PROCESS | 1665 | |5;yui.html;631.1826185090307;638.2500561839838;670.1991120610872;682.8600136971918;731.9277258815836;14509.201993890332;14994.475193368235;15218.84781152191;15219.239038048081;15239.161846098474;13667.521636742717;13838.238896860668;13866.37601307941;13880;13894;697.6581475077227;715.4097869257857;743.4721347843038;882.4484697064335;901.1394118616705;890.7016149589895;893.2407453664366;897.1745993685793;897.3225214962727;902.4044130873625;764.3083009876071;971;972.1056627902346;973.0415540692412;975.5951280218736;932.580338847518;964.1274646444979;966.7728083900278;967.260046064761;967.9032096790335;716.9928300716978;717.8887272472775;718.0557566799641;718.8885722712936;720.1358369956074;14837.48068817597;14856.702865942621;14875.851241487555;14881.479148229868;14887.478938237218;12248.81626775599;12353.567625133168;12367.381630918508;12374.133810633259;12599.811002834966;9477.763055923588;10700.892991070068;10881.082810188325;11008.779824403468;11064.944675276653;3988.4017397390335;4071.6742660587083;4077.429159917614;4083.836646534135;4208.621224089847;77600;78558.6072069636;80458.5977070111;80821.59589202075;81168;9265;9428.434293942386;9432.905670943202;9672.75818104553;9705.126538611417;21531.923403829747;21534.78465215359;21637.675434868488;21638;21693.566128677652;7186.065811444479;7240.167380751176;7302.96348518254;7364;7398.556086634819;3953.525576930787;3997.400389941532;4000.2199571083943;4004.65960393364;4015.056461731491;3379.2396710740377;3490.5811302643847;3493.3013397320638;3495.94756078659;3507;620.8230654263514;632.0235236559434;645.5126379583478;653.2324518690519;708.553611224925;501.87329445395324;565.6945249565189;590.5423296944903;601.0354472060809;609.4027852704374;7760.883586746203;7801.414893882981;7845.568493732785;8041.557714325651;8118.391120665856;568.1307599372966;577.1400613086471;587.3627114580643;596.1802521532892;603.6167033933397;3718.3864662330952;3761.755485893408;3785.1483416231677;3846.4614953906475;4001.3597824347967;65455;83570.58214708888;85163;85871.57064214638;90533;75471.62264188753;77721;77893.83159252616;78283.43433131305;78751.21248787439;67116.66441667858;68412.657936711;68852.655736722;68888.999999999;69669.65165174141;7294.562326260441;7383;7455.888161677471;7524.736634217776;7805.765827025199;39928.00179995524;40173;41940.580594194886;42801;43158;55469.445305546426;56125.71937140288;57560.42439575551;58637;59096.1135582958;10763.380520979697;11042.944785276344;11109.555617775199;11261.21171517986;11372.147088968195;11770.882291176978;11878.762424751283;11885.108616853599;11901.880981190077;11925.463354149259;8925.776855578662;11501.309921404409;11584.652460426201;11589.304641721523;11664.94167529157;11181.888181118411;11260.380679062402;11352.318860868374;11359;11758.647240582795;8092.433529652868;11124.833127502932;11177.385243811668;11344.432778361213;11400;8958;9086.273098152264;9237;9367.718968430958;9429.717108486757;9022.188003079755;9098.772530686785;9172.862407063765;9177.357584968988;9205.76985575366 03:02:05 INFO - PROCESS | 1665 | __end_tp_report 03:02:05 INFO - PROCESS | 1665 | __start_cc_report 03:02:05 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1183 03:02:05 INFO - PROCESS | 1665 | __end_cc_report 03:02:05 INFO - PROCESS | 1665 | __startTimestamp1464256925813__endTimestamp 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | ------- Summary: start ------- 03:02:05 INFO - PROCESS | 1665 | Number of tests: 6 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5762.63 Median:5964.05 stddev:655.69 (11.0%) stddev-sans-first:619.16 03:02:05 INFO - PROCESS | 1665 | Values: 2939.2 3478.1 3513.1 3517.1 3552.1 5629.4 5632.8 5683.7 5726.2 5744.1 5714.7 5760.3 5833.1 5877.7 5888.3 5131.2 5161.1 5191.4 5222.6 5317.3 5299.5 5324.6 5346.8 5347.4 5365.3 5143.6 5200.9 5219.7 5223.9 5251.1 5187.0 5212.8 5227.2 5233.6 5235.7 5154.7 5183.4 5195.0 5251.2 5264.2 5551.8 5637.2 5709.5 5717.4 5726.0 6141.0 6181.6 6255.4 6284.8 6291.7 6316.8 6336.2 6354.3 6366.0 6375.3 5810.4 5842.8 5884.8 5885.3 5890.0 5264.5 6315.3 6326.1 6330.7 6364.5 6168.8 6172.0 6200.6 6208.0 6218.0 6330.1 6353.3 6356.7 6357.1 6358.9 6110.5 6122.0 6145.0 6189.7 6212.6 6236.5 6277.4 6282.2 6289.4 6312.1 4862.2 4905.3 4909.5 4924.5 4929.4 4284.3 4366.7 4367.0 4449.4 4458.6 5717.6 5725.0 5729.6 5771.5 5774.7 6317.8 6325.0 6347.1 6362.1 6369.7 5490.3 5542.2 5556.6 5576.3 5581.1 5260.8 5265.3 5279.0 5289.3 5289.6 6289.2 6331.5 6345.5 6346.0 6364.6 6343.3 6373.1 6381.6 6385.0 6390.1 6307.1 6398.0 6400.6 6406.0 6409.4 6117.5 6140.2 6145.1 6149.8 6270.1 6264.8 6285.8 6286.6 6295.7 6314.3 5857.5 6090.9 6091.8 6095.8 6097.4 5952.8 6042.9 6049.6 6071.7 6097.0 5964.0 5965.6 5979.8 6005.8 6009.6 6106.2 6140.4 6141.7 6157.8 6158.0 5812.1 5875.9 5921.4 5950.2 5988.2 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:71485.37 Median:37864.34 stddev:155480.55 (410.6%) stddev-sans-first:155825.33 03:02:05 INFO - PROCESS | 1665 | Values: 488.1 516.7 529.0 532.9 549.6 26873.9 30696.4 42475.0 43595.8 43682.6 44460.8 44889.1 44960.0 44979.3 45161.8 9585.8 9802.1 9808.2 9822.0 9830.0 8402.0 8637.5 10858.0 11866.3 11908.9 11859.6 11948.9 11964.2 11974.8 11983.3 8675.0 8895.0 11807.4 11922.0 11932.8 8750.9 8821.7 11555.7 11874.8 11898.3 41692.4 42120.4 42208.6 42327.9 42372.4 3278.5 3419.7 3490.9 3744.4 3994.0 29552.9 30168.8 30992.4 31088.8 31148.2 2716.9 2732.3 2770.3 2841.2 2868.4 520909.0 526088.0 526257.4 526567.0 527110.0 61819.7 62372.7 62639.0 62674.0 62790.7 65904.3 66133.7 66189.0 66274.3 66280.7 13612.7 17136.3 17149.7 17158.2 17177.9 10701.8 11081.4 11851.6 12097.0 13204.7 17155.4 17342.9 17398.9 17407.2 17431.7 6128.4 7532.5 7532.9 7588.3 7604.8 713.3 956.2 962.4 982.5 1003.7 29454.3 29514.1 30067.5 30248.8 30289.8 1090.1 1163.7 1197.8 1228.9 1235.8 15024.4 15127.0 15190.9 15603.5 15649.0 671814.0 678392.0 682675.0 683654.0 685570.0 61093.4 61146.0 61300.7 61301.0 61344.7 1182.2 1307.1 1462.8 1467.5 1477.2 26317.6 29905.4 30456.2 30984.9 31841.5 41024.6 50617.0 51594.7 51664.5 51687.0 523643.0 526074.0 526211.0 526496.0 527402.0 33941.0 39974.8 40930.8 41517.4 42433.7 47099.3 47262.3 47432.6 48775.0 48893.0 33008.7 35527.0 36606.5 38537.0 38919.2 31150.4 37821.7 37902.8 38555.2 38680.4 37276.8 37749.2 37751.2 37825.9 38633.4 27500.7 32163.4 33182.8 33408.8 33956.7 24421.1 24740.5 24815.4 25273.9 25277.6 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:31519.01 Median:21282.97 stddev:85394.71 (401.2%) stddev-sans-first:85602.17 03:02:05 INFO - PROCESS | 1665 | Values: 335.9 375.3 375.8 380.1 389.5 20108.6 20234.7 21371.6 21414.4 21529.4 11630.3 12189.5 12200.0 12206.8 16626.7 5002.0 5487.9 5509.2 5513.1 5527.2 3866.1 4112.0 5860.6 6165.8 6186.1 6083.4 6157.1 6171.4 6176.2 6189.7 4858.8 5966.5 5972.4 6112.9 6142.1 3760.4 3922.5 3978.4 3986.9 5492.8 11264.4 13139.0 13155.3 13539.1 13660.0 2059.7 2061.6 2109.7 2144.5 2151.8 9505.5 10262.7 10289.9 12256.9 14246.6 1693.7 1700.2 1759.1 1760.9 1761.8 110427.0 127152.5 128829.0 129779.0 129943.4 11487.8 11716.2 11759.8 11808.3 11919.5 14664.3 14820.6 14839.1 14872.3 14894.1 8004.6 8738.1 8830.8 8925.7 8936.4 8779.5 8780.5 8813.6 8903.9 8912.0 4514.0 4581.0 4590.9 4608.8 4679.5 1749.7 1783.2 1808.1 1823.1 1907.3 682.4 899.3 928.7 991.5 1010.1 16473.3 20125.8 21194.4 21405.4 21419.4 909.6 929.6 963.9 967.3 1006.6 8710.7 10735.9 10971.1 11305.8 11329.9 441536.6 511455.0 515481.0 518290.0 532467.0 122378.0 126307.7 126388.4 128797.0 129322.0 1008.5 1016.4 1030.9 1042.7 1044.5 18505.4 18725.5 18817.3 18856.2 19000.2 34239.3 38444.0 50809.0 51122.2 51721.7 55636.0 71807.0 72114.6 72736.0 72959.6 8121.6 8198.5 8249.6 8393.5 8561.7 12003.9 12561.7 12664.4 12819.9 13044.2 10783.2 10970.6 11138.6 11194.6 11297.7 10718.7 10911.6 10917.3 10967.1 11069.4 11114.4 11118.9 11197.3 11331.3 11415.3 7912.0 8033.0 8272.1 8280.9 8319.0 8031.7 9741.0 10901.6 11158.3 11310.7 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5542.23 Median:4955.88 stddev:3089.33 (62.3%) stddev-sans-first:3082.32 03:02:05 INFO - PROCESS | 1665 | Values: 1400.0 1560.9 1587.8 1833.6 1981.8 7739.8 8429.3 8651.4 8661.5 8772.4 8452.7 8670.3 8674.3 8676.8 8687.0 2043.9 2332.0 2339.4 2365.3 2373.8 2213.1 2233.4 2248.7 2248.9 2254.4 2324.8 2347.1 2360.7 2379.8 2381.4 2328.1 2339.7 2350.2 2351.5 2352.1 2340.3 2344.8 2346.7 2346.7 2349.1 8503.9 8609.4 8683.2 8692.7 8770.0 5190.9 5343.5 5344.5 5344.9 5351.9 3378.6 3492.5 3522.4 3523.9 3553.7 993.8 1005.7 1012.1 1014.0 1023.7 9381.9 11433.9 11438.6 11441.2 11449.7 4142.9 4297.9 4443.6 4502.0 4592.0 9043.2 9064.7 9077.7 9077.7 9079.0 5205.9 5225.3 5231.6 5233.7 5234.7 4395.8 4565.4 4710.3 4791.3 4886.1 3749.8 3768.7 3839.3 3876.7 3883.9 1154.8 1156.5 1163.6 1172.2 1194.6 564.9 736.2 888.5 916.5 966.5 5205.3 5701.5 5866.1 5887.8 6052.6 875.4 939.5 947.6 962.8 975.8 3304.7 3658.8 3818.5 3913.0 3948.1 10134.7 10522.6 10638.9 10648.5 10650.4 10178.0 10544.2 10564.7 10615.2 10625.2 8048.6 8143.6 8207.5 8218.9 8221.8 4766.0 4890.9 5535.6 5657.1 5729.7 8761.1 9804.8 9816.7 9842.7 9939.9 7712.9 8601.1 9416.7 10045.9 10158.7 7406.9 7476.3 7556.3 7570.8 7574.1 7927.5 8137.5 8182.2 8188.3 8195.9 7372.7 7380.1 7395.3 7408.1 7492.3 7267.5 7281.7 7371.2 7405.1 7413.7 6621.2 6676.6 6757.6 6759.3 6763.8 4756.4 4778.2 4785.5 4849.5 4993.3 4652.3 4826.7 4872.8 4875.5 4918.5 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4448.74 Median:4807.50 stddev:1377.80 (28.7%) stddev-sans-first:1345.03 03:02:05 INFO - PROCESS | 1665 | Values: 243.8 297.8 314.2 316.7 318.3 4602.5 5079.1 5272.9 5396.7 5469.2 4477.3 4711.0 5223.5 5423.6 5449.7 3956.5 4133.8 4140.6 4148.6 4160.6 3002.5 3883.9 3968.9 4129.8 4150.9 3204.3 3475.8 4051.4 4081.6 4147.8 3864.4 3873.8 4064.2 4069.5 4085.7 3016.3 3574.8 3585.6 3586.5 3586.8 4542.8 4552.4 4738.2 4860.9 5127.4 4044.3 4128.0 4321.7 4561.5 4751.3 4475.3 5022.6 5160.7 5686.6 5743.9 3145.6 3376.5 3385.2 3575.2 4015.7 5044.6 5940.5 5959.6 5994.8 6040.8 3446.2 4056.3 4057.0 4060.4 4070.5 3986.8 4102.7 4130.2 4154.8 4168.0 3979.4 4677.9 4725.0 4740.6 4741.8 4651.6 4787.5 5324.9 5678.6 5746.4 1137.3 1228.7 1283.2 1314.5 1413.0 828.8 903.9 957.3 967.4 976.6 4513.9 4832.4 4915.0 5263.3 5391.1 5483.6 5652.7 5732.6 5799.5 5808.8 4967.0 5227.0 5256.3 5261.9 5266.2 2510.1 2526.0 2565.9 2573.4 2600.8 4879.5 4962.5 5146.9 5165.5 5237.0 4411.0 4734.4 4783.4 4881.9 5018.1 4650.9 4696.6 4709.6 4766.5 5193.6 4453.9 4496.9 4635.4 4728.8 5396.4 4989.8 5841.5 5847.8 5870.5 5888.8 4827.5 5565.0 6190.5 6190.7 6193.4 3716.4 3910.2 3912.8 3938.9 3942.0 5585.8 5787.9 5790.8 5813.3 5927.7 5224.3 5256.6 5573.3 5602.9 5786.6 5421.6 5721.0 5726.0 5739.9 5787.5 5568.2 5595.7 5602.9 5605.9 5626.8 5560.7 5707.5 5720.2 5727.5 5747.4 4950.2 5177.1 5225.5 5619.6 5637.1 03:02:05 INFO - PROCESS | 1665 | 03:02:05 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17337.25 Median:606.51 stddev:23995.22 (3956.3%) stddev-sans-first:24029.75 03:02:05 INFO - PROCESS | 1665 | Values: 631.2 638.3 670.2 682.9 731.9 14509.2 14994.5 15218.8 15219.2 15239.2 13667.5 13838.2 13866.4 13880.0 13894.0 697.7 715.4 743.5 882.4 901.1 890.7 893.2 897.2 897.3 902.4 764.3 971.0 972.1 973.0 975.6 932.6 964.1 966.8 967.3 967.9 717.0 717.9 718.1 718.9 720.1 14837.5 14856.7 14875.9 14881.5 14887.5 12248.8 12353.6 12367.4 12374.1 12599.8 9477.8 10700.9 10881.1 11008.8 11064.9 3988.4 4071.7 4077.4 4083.8 4208.6 77600.0 78558.6 80458.6 80821.6 81168.0 9265.0 9428.4 9432.9 9672.8 9705.1 21531.9 21534.8 21637.7 21638.0 21693.6 7186.1 7240.2 7303.0 7364.0 7398.6 3953.5 3997.4 4000.2 4004.7 4015.1 3379.2 3490.6 3493.3 3495.9 3507.0 620.8 632.0 645.5 653.2 708.6 501.9 565.7 590.5 601.0 609.4 7760.9 7801.4 7845.6 8041.6 8118.4 568.1 577.1 587.4 596.2 603.6 3718.4 3761.8 3785.1 3846.5 4001.4 65455.0 83570.6 85163.0 85871.6 90533.0 75471.6 77721.0 77893.8 78283.4 78751.2 67116.7 68412.7 68852.7 68889.0 69669.7 7294.6 7383.0 7455.9 7524.7 7805.8 39928.0 40173.0 41940.6 42801.0 43158.0 55469.4 56125.7 57560.4 58637.0 59096.1 10763.4 11042.9 11109.6 11261.2 11372.1 11770.9 11878.8 11885.1 11901.9 11925.5 8925.8 11501.3 11584.7 11589.3 11664.9 11181.9 11260.4 11352.3 11359.0 11758.6 8092.4 11124.8 11177.4 11344.4 11400.0 8958.0 9086.3 9237.0 9367.7 9429.7 9022.2 9098.8 9172.9 9177.4 9205.8 03:02:05 INFO - PROCESS | 1665 | -------- Summary: end -------- 03:02:05 INFO - PROCESS | 1665 | 03:02:06 INFO - TEST-INFO | 1665: exit 0 03:02:06 INFO - TEST-OK | dromaeo_css | took 1115731ms 03:02:06 INFO - TEST-START | kraken 03:02:06 INFO - operating with platform_type : mac_ 03:02:06 INFO - Initialising browser for kraken test... 03:02:06 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpluVrrF/profile http://localhost:49275/getInfo.html) 03:02:08 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 03:02:08 INFO - PROCESS | 1675 | colorDepth:24 03:02:08 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 03:02:08 INFO - PROCESS | 1675 | __metrics 03:02:08 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:02:08 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:02:09 INFO - TEST-INFO | 1675: exit 0 03:02:09 INFO - Browser initialized. 03:02:09 INFO - Running cycle 1/1 for kraken test... 03:02:09 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpluVrrF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:21 INFO - PROCESS | 1676 | RSS: Main: 241627136 03:02:21 INFO - PROCESS | 1676 | 03:02:24 INFO - PROCESS | 1676 | 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) 03:02:24 INFO - PROCESS | 1676 | RSS: Main: 289595392 03:02:24 INFO - PROCESS | 1676 | 03:02:27 INFO - PROCESS | 1676 | 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) 03:02:28 INFO - PROCESS | 1676 | RSS: Main: 297766912 03:02:28 INFO - PROCESS | 1676 | 03:02:31 INFO - PROCESS | 1676 | 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) 03:02:32 INFO - PROCESS | 1676 | RSS: Main: 387162112 03:02:32 INFO - PROCESS | 1676 | 03:02:34 INFO - PROCESS | 1676 | 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) 03:02:35 INFO - PROCESS | 1676 | RSS: Main: 287059968 03:02:35 INFO - PROCESS | 1676 | 03:02:37 INFO - PROCESS | 1676 | 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) 03:02:37 INFO - PROCESS | 1676 | RSS: Main: 291598336 03:02:37 INFO - PROCESS | 1676 | 03:02:40 INFO - PROCESS | 1676 | 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) 03:02:41 INFO - PROCESS | 1676 | RSS: Main: 328781824 03:02:41 INFO - PROCESS | 1676 | 03:02:44 INFO - PROCESS | 1676 | 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) 03:02:44 INFO - PROCESS | 1676 | RSS: Main: 329580544 03:02:44 INFO - PROCESS | 1676 | 03:02:47 INFO - PROCESS | 1676 | 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) 03:02:48 INFO - PROCESS | 1676 | RSS: Main: 328564736 03:02:48 INFO - PROCESS | 1676 | 03:02:49 INFO - PROCESS | 1676 | 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) 03:02:50 INFO - PROCESS | 1676 | RSS: Main: 315019264 03:02:50 INFO - PROCESS | 1676 | 03:02:52 INFO - PROCESS | 1676 | 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) 03:02:52 INFO - PROCESS | 1676 | RSS: Main: 322486272 03:02:52 INFO - PROCESS | 1676 | 03:02:55 INFO - PROCESS | 1676 | 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) 03:02:55 INFO - PROCESS | 1676 | RSS: Main: 324722688 03:02:55 INFO - PROCESS | 1676 | 03:02:58 INFO - PROCESS | 1676 | 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) 03:02:59 INFO - PROCESS | 1676 | RSS: Main: 329306112 03:02:59 INFO - PROCESS | 1676 | 03:03:02 INFO - PROCESS | 1676 | 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) 03:03:02 INFO - PROCESS | 1676 | RSS: Main: 327041024 03:03:02 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | 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) 03:03:04 INFO - PROCESS | 1676 | RSS: Main: 373944320 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | __start_tp_report 03:03:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:03:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:03:04 INFO - PROCESS | 1676 | |i|pagename|runs| 03:03:04 INFO - PROCESS | 1676 | |0;ai-astar;110;107;111;111;112;111;111;110;110;111 03:03:04 INFO - PROCESS | 1676 | |1;audio-beat-detection;153;120;115;168;117;118;138;117;147;117 03:03:04 INFO - PROCESS | 1676 | |2;audio-dft;183;195;261;202;179;177;180;181;181;182 03:03:04 INFO - PROCESS | 1676 | |3;audio-fft;82;58;59;132;58;72;90;91;81;55 03:03:04 INFO - PROCESS | 1676 | |4;audio-oscillator;86;69;100;70;78;81;125;101;86;97 03:03:04 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;86;89;85;86;84;88;86;88;84 03:03:04 INFO - PROCESS | 1676 | |6;imaging-darkroom;94;93;93;93;93;93;93;93;93;93 03:03:04 INFO - PROCESS | 1676 | |7;imaging-desaturate;87;108;133;109;85;84;85;86;87;86 03:03:04 INFO - PROCESS | 1676 | |8;json-parse-financial;55;55;57;56;56;57;57;76;58;55 03:03:04 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;64;70;69;68;72;77;73;69;69;69 03:03:04 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;87;87;87;91;87;86;86;86;86;87 03:03:04 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;123;123;142;127;136;150;145;150;175;143 03:03:04 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;168;168;207;225;168;164;168;165;161;164 03:03:04 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;66;66;67;67;67;68;77;85;87;81 03:03:04 INFO - PROCESS | 1676 | __end_tp_report 03:03:04 INFO - PROCESS | 1676 | __start_cc_report 03:03:04 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2028 03:03:04 INFO - PROCESS | 1676 | __end_cc_report 03:03:04 INFO - PROCESS | 1676 | __startTimestamp1464256984861__endTimestamp 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:03:04 INFO - PROCESS | 1676 | Number of tests: 14 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.35 (1.2%) stddev-sans-first:1.42 03:03:04 INFO - PROCESS | 1676 | Values: 110.0 107.0 111.0 111.0 112.0 111.0 111.0 110.0 110.0 111.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:129.00 stddev:19.13 (14.8%) stddev-sans-first:18.55 03:03:04 INFO - PROCESS | 1676 | Values: 153.0 120.0 115.0 168.0 117.0 118.0 138.0 117.0 147.0 117.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:192.10 Median:182.50 stddev:25.45 (13.9%) stddev-sans-first:26.78 03:03:04 INFO - PROCESS | 1676 | Values: 183.0 195.0 261.0 202.0 179.0 177.0 180.0 181.0 181.0 182.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:77.80 Median:76.50 stddev:23.52 (30.7%) stddev-sans-first:24.90 03:03:04 INFO - PROCESS | 1676 | Values: 82.0 58.0 59.0 132.0 58.0 72.0 90.0 91.0 81.0 55.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:79.50 stddev:16.96 (21.3%) stddev-sans-first:17.94 03:03:04 INFO - PROCESS | 1676 | Values: 86.0 69.0 100.0 70.0 78.0 81.0 125.0 101.0 86.0 97.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.79 03:03:04 INFO - PROCESS | 1676 | Values: 87.0 86.0 89.0 85.0 86.0 84.0 88.0 86.0 88.0 84.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 03:03:04 INFO - PROCESS | 1676 | Values: 94.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:85.50 stddev:16.40 (19.2%) stddev-sans-first:17.14 03:03:04 INFO - PROCESS | 1676 | Values: 87.0 108.0 133.0 109.0 85.0 84.0 85.0 86.0 87.0 86.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:57.00 stddev:6.34 (11.1%) stddev-sans-first:6.62 03:03:04 INFO - PROCESS | 1676 | Values: 55.0 55.0 57.0 56.0 56.0 57.0 57.0 76.0 58.0 55.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:69.50 stddev:3.43 (4.9%) stddev-sans-first:2.87 03:03:04 INFO - PROCESS | 1676 | Values: 64.0 70.0 69.0 68.0 72.0 77.0 73.0 69.0 69.0 69.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.58 03:03:04 INFO - PROCESS | 1676 | Values: 87.0 87.0 87.0 91.0 87.0 86.0 86.0 86.0 86.0 87.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:144.00 stddev:15.66 (10.9%) stddev-sans-first:15.13 03:03:04 INFO - PROCESS | 1676 | Values: 123.0 123.0 142.0 127.0 136.0 150.0 145.0 150.0 175.0 143.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:168.00 stddev:21.73 (12.9%) stddev-sans-first:22.87 03:03:04 INFO - PROCESS | 1676 | Values: 168.0 168.0 207.0 225.0 168.0 164.0 168.0 165.0 161.0 164.0 03:03:04 INFO - PROCESS | 1676 | 03:03:04 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.50 stddev:8.50 (11.7%) stddev-sans-first:8.62 03:03:04 INFO - PROCESS | 1676 | Values: 66.0 66.0 67.0 67.0 67.0 68.0 77.0 85.0 87.0 81.0 03:03:04 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:03:04 INFO - PROCESS | 1676 | 03:03:05 INFO - TEST-INFO | 1676: exit 0 03:03:05 INFO - TEST-OK | kraken | took 59244ms 03:03:05 INFO - SUITE-END | took 1174s 03:03:06 INFO - Completed test suite (00:19:35) 03:03:06 INFO - javascript benchmark 03:03:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2939.162338733461, 3478.0956951192684, 3513.1392808761857, 3517.085557754983, 3552.1297282165856, 5629.437056294369, 5632.802851900185, 5683.658980461175, 5726.169705392721, 5744.138379243111, 5714.657120572779, 5760.308762948442, 5833.125031245305, 5877.735501902414, 5888.2934047914205, 5131.153359695646, 5161.122609156434, 5191.377034755816, 5222.608304377168, 5317.255584218213, 5299.5230429261355, 5324.600654950875, 5346.812861549846, 5347.358317001966, 5365.302510673607, 5143.614228932845, 5200.947990520084, 5219.660722053054, 5223.947760522384, 5251.10731175701, 5187.0, 5212.843614691566, 5227.189785583203, 5233.581313494911, 5235.738213089368, 5154.664946778447, 5183.352080989876, 5195.0, 5251.186066159751, 5264.210368444726, 5551.805686800943, 5637.238972738669, 5709.457601527848, 5717.399673034313, 5725.971370143165, 6141.048137538689, 6181.598196117283, 6255.40573645503, 6284.837305098571, 6291.653959032252, 6316.778912738078, 6336.2396512418345, 6354.2692590352235, 6365.981442969149, 6375.2987171411105, 5810.389909059572, 5842.824715258549, 5884.823455296262, 5885.264341957255, 5889.998700220973, 5264.52619264268, 6315.305316415191, 6326.114343991845, 6330.715117819709, 6364.49084073273, 6168.753249870045, 6171.9691401542705, 6200.56596038282, 6208.0, 6218.0, 6330.113784070234, 6353.269374022047, 6356.713947872357, 6357.11000459936, 6358.936410635927, 6110.480609148272, 6122.032243765608, 6144.986077297285, 6189.721462534197, 6212.565120441616, 6236.532260080512, 6277.435030847246, 6282.2147231596055, 6289.371062893674, 6312.116303717483, 4862.246351815475, 4905.337779399807, 4909.533594308535, 4924.532169443897, 4929.383827021622, 4284.335927931175, 4366.6506679465565, 4367.0174210802315, 4449.377087207794, 4458.643308535375, 5717.62835415705, 5725.026745453283, 5729.627574207663, 5771.538276937835, 5774.711264436845, 6317.810465686037, 6325.0, 6347.1114044033875, 6362.141110950055, 6369.681515924185, 5490.313710786152, 5542.168674698827, 5556.555475561945, 5576.275084239023, 5581.134924086773, 5260.815871444481, 5265.289185959847, 5279.049771041249, 5289.312389389354, 5289.550388217045, 6289.213848268966, 6331.493480521639, 6345.524085693683, 6346.0, 6364.554481186365, 6343.3022367538615, 6373.075903993988, 6381.553291269566, 6385.0, 6390.105385245976, 6307.117003619589, 6398.0402939559435, 6400.647964362005, 6406.0, 6409.391107844747, 6117.480014198843, 6140.20177376956, 6145.108959200801, 6149.754009839557, 6270.12218289449, 6264.843378915563, 6285.779997700059, 6286.559940804283, 6295.685215739103, 6314.273858506147, 5857.47282744538, 6090.908636370369, 6091.847703807441, 6095.75616975316, 6097.390260973867, 5952.821415357546, 6042.939570604413, 6049.606775559574, 6071.726772295346, 6097.013809262604, 5964.045752679551, 5965.582409231312, 5979.790707325223, 6005.849853753691, 6009.579329446897, 6106.236720409949, 6140.4473597376455, 6141.692915354125, 6157.84605384869, 6158.0, 5812.128180772918, 5875.94124058771, 5921.3782552831335, 5950.2264705587995, 5988.191594134736], "value": 5719.727569877696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.1408720651653, 516.7287174233527, 529.0318716748351, 532.8854296326289, 549.5905550364979, 26873.865630671844, 30696.38607227854, 42475.0, 43595.78202108985, 43682.563174368246, 44460.77769611155, 44889.102217955624, 44960.0, 44979.32531012037, 45161.774191129, 9585.760355991104, 9802.068803463659, 9808.16630586401, 9822.0, 9830.0, 8402.033766116883, 8637.481751094923, 10858.0, 11866.347350895741, 11908.940455297754, 11859.64421067365, 11948.924596786288, 11964.1625086244, 11974.820377694341, 11983.281003139797, 8674.956625216897, 8894.955525222333, 11807.409629518532, 11921.986631136277, 11932.761344773142, 8750.86873696895, 8821.735347939572, 11555.711107222303, 11874.762504749942, 11898.286102833858, 41692.37461438081, 42120.41976432007, 42208.57791422108, 42327.9418014549, 42372.364414533804, 3278.49183376577, 3419.743519236067, 3490.89527314181, 3744.400895856677, 3994.0414300567945, 29552.852235738897, 30168.849155754302, 30992.380152396825, 31088.8445557773, 31148.221294467592, 2716.9184924452297, 2732.303262668027, 2770.252031951382, 2841.218664867178, 2868.3976364963382, 520909.0, 526088.0, 526257.3687131578, 526567.0, 527110.0, 61819.690901545204, 62372.68813655902, 62639.0, 62674.0, 62790.68604657039, 65904.34095658982, 66133.66933165303, 66189.0, 66274.33725662778, 66280.6685966567, 13612.65968350799, 17136.314547417962, 17149.742753858704, 17158.227879745442, 17177.88343757652, 10701.78596428067, 11081.445927703582, 11851.644450666494, 12097.0, 13204.669883252996, 17155.399561015303, 17342.913285433744, 17398.913005435144, 17407.216675249645, 17431.73852392215, 6128.387161283836, 7532.472726909174, 7532.924670753332, 7588.317051465284, 7604.8479030419085, 713.3045280851129, 956.1538038835541, 962.4177372689562, 982.5480279071564, 1003.7390278527636, 29454.263643409085, 29514.114576562737, 30067.548986765218, 30248.84875575652, 30289.788408463857, 1090.138790355626, 1163.6567212672282, 1197.7724232395817, 1228.9139760216879, 1235.8269842222096, 15024.399024038916, 15127.0, 15190.924045379703, 15603.53189404297, 15649.0, 671814.0, 678392.0, 682675.0, 683654.0, 685570.0, 61093.38906610966, 61146.0, 61300.693496533124, 61301.0, 61344.69327653422, 1182.2374568403332, 1307.1176955554963, 1462.8317743459638, 1467.515719812438, 1477.2392218007735, 26317.605235921095, 29905.355205463908, 30456.23859403444, 30984.915527956415, 31841.5223771639, 41024.58975410327, 50617.0, 51594.74202628963, 51664.48335516747, 51687.0, 523643.0, 526074.0, 526211.0, 526496.0, 527402.0, 33941.0, 39974.80012599919, 40930.79534602308, 41517.37723934204, 42433.72698819041, 47099.29351059668, 47262.29106563473, 47432.57702268937, 48775.0, 48893.0, 33008.67965281458, 35527.0, 36606.45090323594, 38537.0, 38919.221615568094, 31150.37699245957, 37821.67624133142, 37902.8104859474, 38555.22889542137, 38680.41979370255, 37276.813615931744, 37749.245015099, 37751.2449751009, 37825.86522404332, 38633.42049869198, 27500.724992749816, 32163.356732864744, 33182.83860064886, 33408.83295583507, 33956.66043339634, 24421.14525991582, 24740.505189895743, 24815.379615509755, 25273.87363063173, 25277.62083568785], "value": 20407.03076479802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.9445691460909, 375.32066958804563, 375.75200367757276, 380.09726898615787, 389.4703203643043, 20108.597828043432, 20234.6964795528, 21371.57256854862, 21414.357569272906, 21529.354119376403, 11630.302181869094, 12189.51241950321, 12200.0, 12206.755864882696, 16626.66746665066, 5001.999999999991, 5487.890242195153, 5509.201165830952, 5513.145462453306, 5527.226188333617, 3866.130120722829, 4112.0, 5860.560457965648, 6165.845853853667, 6186.133941248228, 6083.391660833903, 6157.107219453176, 6171.413715697024, 6176.227971503562, 6189.659568723719, 4858.82994095205, 5966.492848107916, 5972.372900845392, 6112.938870611304, 6142.109394137848, 3760.4359346098304, 3922.5489068757174, 3978.3634618461197, 3986.940195897064, 5492.780288788444, 11264.380459074748, 13139.0, 13155.342232888413, 13539.05226634136, 13660.0, 2059.742532183477, 2061.6082944240698, 2109.694094356325, 2144.549644574641, 2151.7848215178356, 9505.524723763854, 10262.7434314142, 10289.890572432634, 12256.877431225663, 14246.572602821932, 1693.6951348757218, 1700.2325696471796, 1759.0852756566642, 1760.8938547486034, 1761.806201317854, 110427.0, 127152.47607530445, 128829.0, 129779.0, 129943.35028324892, 11487.827682584768, 11716.179867409199, 11759.76480470403, 11808.280391676595, 11919.463624136935, 14664.266786660624, 14820.555383338517, 14839.10965342083, 14872.256387180598, 14894.106353618818, 8004.63979120941, 8738.082501337396, 8830.792301924519, 8925.73222803317, 8936.374453788172, 8779.517126558101, 8780.517071561126, 8813.559322033872, 8903.866442003375, 8911.955440222757, 4513.954860451354, 4580.954190458119, 4590.88522786933, 4608.792604332813, 4679.4618618858585, 1749.6850566897826, 1783.170825566118, 1808.109035642486, 1823.1450242507835, 1907.3038341005433, 682.3517658224706, 899.3165194452264, 928.6563971330651, 991.5240684471349, 1010.1111022300329, 16473.34106635745, 20125.798742012394, 21194.364169074954, 21405.357839264536, 21419.357419277447, 909.6043221198668, 929.5816882402946, 963.9036096390305, 967.3228739882111, 1006.642641862143, 8710.69512567057, 10735.892641073646, 10971.067459265892, 11305.773884522263, 11329.886701132884, 441536.58463415597, 511455.0, 515481.0, 518290.0, 532467.0, 122378.0, 126307.7369226296, 126388.36805815912, 128797.0, 129322.0, 1008.4907121903539, 1016.420640235059, 1030.922680798943, 1042.6819819954835, 1044.5351818440872, 18505.35231266899, 18725.53186170302, 18817.341393051178, 18856.171574263422, 19000.23999040023, 34239.315213696085, 38444.0, 50809.0, 51122.233166503276, 51721.7413912928, 55636.0, 71807.0, 72114.63942680253, 72736.0, 72959.63520182365, 8121.553314567519, 8198.508089514648, 8249.628766705393, 8393.496390216607, 8561.700340488052, 12003.879961200277, 12561.748765024466, 12664.366781660696, 12819.935900320439, 13044.152130111512, 10783.24517283814, 10970.616028438966, 11138.554457821596, 11194.552217911192, 11297.71755706114, 10718.73203169927, 10911.563537458731, 10917.344959302467, 10967.067799237155, 11069.391183484986, 11114.444277785917, 11118.944405278246, 11197.272177308449, 11331.32012079278, 11415.315081095161, 7912.0, 8033.0360356757565, 8272.090070092165, 8280.917190828015, 8319.043310019426, 8031.678732850621, 9741.0, 10901.563937442414, 11158.330500170016, 11310.660680179608], "value": 9048.569573640607, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.012990841457, 1560.914149721765, 1587.841215878412, 1833.5966087460754, 1981.782003979562, 7739.767806965786, 8429.325653947684, 8651.394402391827, 8661.523616201106, 8772.385932984693, 8452.661893524251, 8670.263027642657, 8674.26268767154, 8676.8286281352, 8687.0, 2043.8773673579633, 2331.9506222199993, 2339.3800642829706, 2365.349528879555, 2373.7982271506944, 2213.1258153029544, 2233.4081468410805, 2248.651459023854, 2248.932532024042, 2254.4363909022745, 2324.790768830805, 2347.072906202049, 2360.657704632827, 2379.821513386503, 2381.3570336009266, 2328.0571368595747, 2339.6841426407473, 2350.165691179632, 2351.494428697838, 2352.141468364052, 2340.32130682102, 2344.8124150068124, 2346.6831977682964, 2346.741858395575, 2349.060375849657, 8503.914960850374, 8609.440386374928, 8683.218510334036, 8692.739217823475, 8770.0, 5190.870228244286, 5343.465653434664, 5344.545713614348, 5344.893102137974, 5351.866203344908, 3378.6114596821435, 3492.511048453219, 3522.401191797375, 3523.94714079289, 3553.662402071799, 993.8360170571847, 1005.7183988483237, 1012.1143999000874, 1013.9594416223342, 1023.6775415744091, 9381.859272110923, 11433.942830285878, 11438.599649012245, 11441.199116061878, 11449.713757156054, 4142.860713303866, 4297.914041719148, 4443.600075993176, 4501.977490112528, 4592.0, 9043.18611324984, 9064.728058158265, 9077.68228112013, 9077.68228112013, 9079.046700096394, 5205.947940520622, 5225.294585230945, 5231.581473482112, 5233.685978841206, 5234.659747116425, 4395.846145384896, 4565.429321334833, 4710.3405523226775, 4791.281307803857, 4886.120498310338, 3749.83125759336, 3768.698504119664, 3839.2705385976574, 3876.7286289959434, 3883.8640647577763, 1154.8036833738286, 1156.4969238381332, 1163.6334554615269, 1172.2146162071433, 1194.5580135349976, 564.9180868774005, 736.1828370508731, 888.5246393179618, 916.5325683901258, 966.5022513405602, 5205.349331333583, 5701.543876489871, 5866.1494083357675, 5887.8233652990475, 6052.585582661205, 875.4309698695771, 939.4691999020531, 947.6493697332031, 962.831504486712, 975.7853272280087, 3304.7025767681025, 3658.8170591470853, 3818.4726109555845, 3912.980435097806, 3948.1116748731306, 10134.695959121238, 10522.631707890187, 10638.946805265925, 10648.467576621293, 10650.414227217578, 10178.0, 10544.156467482737, 10564.735881602868, 10615.203859710553, 10625.203109766799, 8048.637811298506, 8143.592820358958, 8207.507549547166, 8218.917810821935, 8221.835563288701, 4766.0, 4890.92663610039, 5535.5848311376, 5657.094864821609, 5729.684867332337, 8761.123887611187, 9804.754881127743, 9816.705498835046, 9842.704718858446, 9939.900600993897, 7712.884306735291, 8601.139886011599, 9416.670416535662, 10045.899541004788, 10158.695239142837, 7406.925930740624, 7476.327130558276, 7556.282153195438, 7570.848583028199, 7574.091109066947, 7927.5243485391065, 8137.471064380797, 8182.1817818217705, 8188.344932405513, 8195.918040819515, 7372.668229929774, 7380.114386273682, 7395.260473952561, 7408.148062972827, 7492.250774922464, 7267.527610705287, 7281.672324745505, 7371.152317483558, 7405.074365704287, 7413.666385012795, 6621.172353455818, 6676.566023208476, 6757.628330441677, 6759.324067593201, 6763.7632682855865, 4756.429228492603, 4778.163821331323, 4785.521447855187, 4849.515048495122, 4993.275974983534, 4652.30215467669, 4826.6621336506105, 4872.756362181947, 4875.463698993037, 4918.48355922623], "value": 4455.886540628009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [243.80739216019342, 297.7811308688114, 314.23013616639224, 316.69597186700764, 318.29021282539935, 4602.493725690171, 5079.060373830844, 5272.86817829554, 5396.676199428027, 5469.152281396385, 4477.261251893434, 4710.98713776538, 5223.451537588546, 5423.566114710824, 5449.683884349156, 3956.544997325301, 4133.81397837098, 4140.565240649726, 4148.6266236038755, 4160.6061969240345, 3002.4595572796998, 3883.922101675604, 3968.9206215875665, 4129.772862492562, 4150.854720084798, 3204.3431096625254, 3475.8088305143206, 4051.4327994080854, 4081.571434999327, 4147.81334839933, 3864.42033694948, 3873.8378486454235, 4064.1668457966343, 4069.491313585802, 4085.7140000199997, 3016.3364059906785, 3574.8212589370687, 3585.6195364784485, 3586.5158203642436, 3586.7668601540818, 4542.772861356919, 4552.385427967215, 4738.167069589034, 4860.902781944376, 5127.359080114986, 4044.312466880608, 4127.979360103211, 4321.746693458925, 4561.452625684906, 4751.287306903992, 4475.30632751924, 5022.59819214462, 5160.729279117177, 5686.5735069869925, 5743.913841292384, 3145.6316502321565, 3376.5272861799367, 3385.18755498681, 3575.1955809942815, 4015.7188996770237, 5044.596432285409, 5940.524758019348, 5959.612625179349, 5994.760209591567, 6040.758369665208, 3446.2418267981, 4056.3307054336296, 4057.0, 4060.3503439449546, 4070.4708387909973, 3986.7807270599815, 4102.651274641689, 4130.173965206971, 4154.813033413504, 4167.97916010418, 3979.422983667352, 4677.906441871211, 4724.976375118103, 4740.644451666139, 4741.834035808799, 4651.627869770478, 4787.497312782178, 5324.946750532445, 5678.630888992202, 5746.396628354046, 1137.2721458266717, 1228.74054094552, 1283.2044132637825, 1314.5333406640505, 1412.985870141306, 828.8396245256689, 903.9005709371963, 957.3203025851584, 967.434051080126, 976.6229616241066, 4513.88715282114, 4832.44426890905, 4914.960291479068, 5263.315768950012, 5391.083515802323, 5483.616146869681, 5652.7173641318595, 5732.627379220421, 5799.507041901401, 5808.82573522795, 4966.975165124151, 5227.0, 5256.316678831728, 5261.868453288698, 5266.236395722599, 2510.0587279770084, 2526.0, 2565.8588777617038, 2573.4302075733785, 2600.7919366450637, 4879.463259041502, 4962.528559786814, 5146.860415146757, 5165.535101840853, 5236.978789136158, 4410.956440056415, 4734.431868175841, 4783.425988881357, 4881.926771098437, 5018.071656743514, 4650.860733946858, 4696.624270058387, 4709.551033462581, 4766.521408867439, 5193.584513238932, 4453.888652783707, 4496.9100617986805, 4635.397398338195, 4728.787204575871, 5396.406395296436, 4989.750512474435, 5841.50347220491, 5847.795327163529, 5870.5010074142465, 5888.793892213924, 4827.503473923095, 5564.970480461127, 6190.535709821871, 6190.7214175363115, 6193.38066193377, 3716.4239542870896, 3910.237503686754, 3912.7847968360875, 3938.9409158863214, 3941.980290098531, 5585.832425027255, 5787.9131813021995, 5790.783935373619, 5813.273340832396, 5927.733252003756, 5224.2947202127225, 5256.58072320479, 5573.331200255995, 5602.943970560243, 5786.623869448473, 5421.593380496557, 5721.026245945142, 5725.971370143123, 5739.8565035874435, 5787.479126878601, 5568.192612071146, 5595.692236926845, 5602.887942241051, 5605.915911261252, 5626.831195064155, 5560.691388623763, 5707.461373948178, 5720.19917211599, 5727.513161381329, 5747.4252574742195, 4950.227872391472, 5177.145770947752, 5225.503577160163, 5619.634723742944, 5637.126245431965], "value": 4012.4824740174295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.1826185090307, 638.2500561839838, 670.1991120610872, 682.8600136971918, 731.9277258815836, 14509.201993890332, 14994.475193368235, 15218.84781152191, 15219.239038048081, 15239.161846098474, 13667.521636742717, 13838.238896860668, 13866.37601307941, 13880.0, 13894.0, 697.6581475077227, 715.4097869257857, 743.4721347843038, 882.4484697064335, 901.1394118616705, 890.7016149589895, 893.2407453664366, 897.1745993685793, 897.3225214962727, 902.4044130873625, 764.3083009876071, 971.0, 972.1056627902346, 973.0415540692412, 975.5951280218736, 932.580338847518, 964.1274646444979, 966.7728083900278, 967.260046064761, 967.9032096790335, 716.9928300716978, 717.8887272472775, 718.0557566799641, 718.8885722712936, 720.1358369956074, 14837.48068817597, 14856.702865942621, 14875.851241487555, 14881.479148229868, 14887.478938237218, 12248.81626775599, 12353.567625133168, 12367.381630918508, 12374.133810633259, 12599.811002834966, 9477.763055923588, 10700.892991070068, 10881.082810188325, 11008.779824403468, 11064.944675276653, 3988.4017397390335, 4071.6742660587083, 4077.429159917614, 4083.836646534135, 4208.621224089847, 77600.0, 78558.6072069636, 80458.5977070111, 80821.59589202075, 81168.0, 9265.0, 9428.434293942386, 9432.905670943202, 9672.75818104553, 9705.126538611417, 21531.923403829747, 21534.78465215359, 21637.675434868488, 21638.0, 21693.566128677652, 7186.065811444479, 7240.167380751176, 7302.96348518254, 7364.0, 7398.556086634819, 3953.525576930787, 3997.400389941532, 4000.2199571083943, 4004.65960393364, 4015.056461731491, 3379.2396710740377, 3490.5811302643847, 3493.3013397320638, 3495.94756078659, 3507.0, 620.8230654263514, 632.0235236559434, 645.5126379583478, 653.2324518690519, 708.553611224925, 501.87329445395324, 565.6945249565189, 590.5423296944903, 601.0354472060809, 609.4027852704374, 7760.883586746203, 7801.414893882981, 7845.568493732785, 8041.557714325651, 8118.391120665856, 568.1307599372966, 577.1400613086471, 587.3627114580643, 596.1802521532892, 603.6167033933397, 3718.3864662330952, 3761.755485893408, 3785.1483416231677, 3846.4614953906475, 4001.3597824347967, 65455.0, 83570.58214708888, 85163.0, 85871.57064214638, 90533.0, 75471.62264188753, 77721.0, 77893.83159252616, 78283.43433131305, 78751.21248787439, 67116.66441667858, 68412.657936711, 68852.655736722, 68888.999999999, 69669.65165174141, 7294.562326260441, 7383.0, 7455.888161677471, 7524.736634217776, 7805.765827025199, 39928.00179995524, 40173.0, 41940.580594194886, 42801.0, 43158.0, 55469.445305546426, 56125.71937140288, 57560.42439575551, 58637.0, 59096.1135582958, 10763.380520979697, 11042.944785276344, 11109.555617775199, 11261.21171517986, 11372.147088968195, 11770.882291176978, 11878.762424751283, 11885.108616853599, 11901.880981190077, 11925.463354149259, 8925.776855578662, 11501.309921404409, 11584.652460426201, 11589.304641721523, 11664.94167529157, 11181.888181118411, 11260.380679062402, 11352.318860868374, 11359.0, 11758.647240582795, 8092.433529652868, 11124.833127502932, 11177.385243811668, 11344.432778361213, 11400.0, 8958.0, 9086.273098152264, 9237.0, 9367.718968430958, 9429.717108486757, 9022.188003079755, 9098.772530686785, 9172.862407063765, 9177.357584968988, 9205.76985575366], "value": 6732.514042524721, "unit": "score"}], "name": "dromaeo_css", "value": 7091.1705078434925, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 111.0, 111.0, 112.0, 111.0, 111.0, 110.0, 110.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 115.0, 168.0, 117.0, 118.0, 138.0, 117.0, 147.0, 117.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 195.0, 261.0, 202.0, 179.0, 177.0, 180.0, 181.0, 181.0, 182.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 132.0, 58.0, 72.0, 90.0, 91.0, 81.0, 55.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 100.0, 70.0, 78.0, 81.0, 125.0, 101.0, 86.0, 97.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 85.0, 86.0, 84.0, 88.0, 86.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 108.0, 133.0, 109.0, 85.0, 84.0, 85.0, 86.0, 87.0, 86.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 56.0, 56.0, 57.0, 57.0, 76.0, 58.0, 55.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 69.0, 68.0, 72.0, 77.0, 73.0, 69.0, 69.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 91.0, 87.0, 86.0, 86.0, 86.0, 86.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 142.0, 127.0, 136.0, 150.0, 145.0, 150.0, 175.0, 143.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 207.0, 225.0, 168.0, 164.0, 168.0, 165.0, 161.0, 164.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 67.0, 68.0, 77.0, 85.0, 87.0, 81.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1480.5, "alertThreshold": 2.0}]} 03:03:06 INFO - Return code: 0 03:03:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:03:06 ERROR - # TBPL SUCCESS # 03:03:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:03:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:03:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:03:06 INFO - 2016-05-26 03:03:06.151 screenresolution[1680:9581] starting screenresolution argv=screenresolution get 03:03:06 INFO - 2016-05-26 03:03:06.154 screenresolution[1680:9581] Display 0: 1600x1200x32@60 03:03:06 INFO - 2016-05-26 03:03:06.160 screenresolution[1681:9584] starting screenresolution argv=screenresolution list 03:03:06 INFO - Available Modes on Display 0 03:03:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:03:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:03:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:03:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:03:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:03:06 INFO - Intel Iris: 03:03:06 INFO - Chipset Model: Intel Iris 03:03:06 INFO - Type: GPU 03:03:06 INFO - Bus: Built-In 03:03:06 INFO - VRAM (Dynamic, Max): 1536 MB 03:03:06 INFO - Vendor: Intel (0x8086) 03:03:06 INFO - Device ID: 0x0a2e 03:03:06 INFO - Revision ID: 0x0009 03:03:06 INFO - Displays: 03:03:06 INFO - Display: 03:03:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:03:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:03:06 INFO - Display Serial Number: 1600x1200 60 03:03:06 INFO - Main Display: Yes 03:03:06 INFO - Mirror: Off 03:03:06 INFO - Online: Yes 03:03:06 INFO - Rotation: Supported 03:03:06 INFO - Return code: 0 03:03:06 INFO - Running post-action listener: _resource_record_post_action 03:03:06 INFO - ##### 03:03:06 INFO - ##### Finished run-tests step (success) 03:03:06 INFO - ##### 03:03:06 INFO - Running post-run listener: _resource_record_post_run 03:03:06 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36832768; Write bytes: 694327808; Read time: 242; Write time: 1908 03:03:06 INFO - TinderboxPrint: CPU usage
53.7% 03:03:06 INFO - TinderboxPrint: I/O read bytes / time
36,832,768 / 242 03:03:06 INFO - TinderboxPrint: I/O write bytes / time
694,327,808 / 1,908 03:03:06 INFO - TinderboxPrint: CPU idle
2,241.5 (46.3%) 03:03:06 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 03:03:06 INFO - TinderboxPrint: CPU user
1,633.8 (33.7%) 03:03:06 INFO - TinderboxPrint: Swap in / out
434,122,752 / 0 03:03:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393345536; Write bytes: 387360256; Read time: 29064; Write time: 787 03:03:06 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35018240; Write bytes: 293762048; Read time: 224; Write time: 1081 03:03:06 INFO - Running post-run listener: _upload_blobber_files 03:03:06 INFO - Blob upload gear active. 03:03:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:03:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:03:06 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'] 03:03:06 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 03:03:07 INFO - (blobuploader) - INFO - Open directory for files ... 03:03:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:03:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:03:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:08 INFO - (blobuploader) - INFO - Done attempting. 03:03:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:03:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:03:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:09 INFO - (blobuploader) - INFO - Done attempting. 03:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:11 INFO - (blobuploader) - INFO - Done attempting. 03:03:11 INFO - (blobuploader) - INFO - Iteration through files over. 03:03:11 INFO - Return code: 0 03:03:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:03:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:03:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13ba6da4df25a5b9ac1c74b8a9736f875d74cbe6bea964dd1ba2a4227bec5a6692057ed8f78475e95ad52d5b73838031fd84046e2ef65ade3fa281801769197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/762cd0e920d87ed89dc15b4dd806b34eccefc1fe9c1ddb966bfdac124749b4cdaf133e9417017b906ca403201d6c0ac655be18ff912ea6923456770390f4dabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e093b4ebb3cec618693c3daa2035c7195d0f5a1ca3ce4059fd19e8366d8dfb618b69f2cc053d8d1fcf361113ac89510bcd7e44491338fce798cb6f224f4d7510"} 03:03:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:03:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:03:11 INFO - Contents: 03:03:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13ba6da4df25a5b9ac1c74b8a9736f875d74cbe6bea964dd1ba2a4227bec5a6692057ed8f78475e95ad52d5b73838031fd84046e2ef65ade3fa281801769197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/762cd0e920d87ed89dc15b4dd806b34eccefc1fe9c1ddb966bfdac124749b4cdaf133e9417017b906ca403201d6c0ac655be18ff912ea6923456770390f4dabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e093b4ebb3cec618693c3daa2035c7195d0f5a1ca3ce4059fd19e8366d8dfb618b69f2cc053d8d1fcf361113ac89510bcd7e44491338fce798cb6f224f4d7510"} 03:03:11 INFO - Running post-run listener: copy_logs_to_upload_dir 03:03:11 INFO - Copying logs to upload dir... 03:03:11 INFO - mkdir: /builds/slave/test/build/upload/logs 03:03:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.519456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-26 03:03:11.781405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 03:03:11.784841) ========= 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.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13ba6da4df25a5b9ac1c74b8a9736f875d74cbe6bea964dd1ba2a4227bec5a6692057ed8f78475e95ad52d5b73838031fd84046e2ef65ade3fa281801769197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/762cd0e920d87ed89dc15b4dd806b34eccefc1fe9c1ddb966bfdac124749b4cdaf133e9417017b906ca403201d6c0ac655be18ff912ea6923456770390f4dabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e093b4ebb3cec618693c3daa2035c7195d0f5a1ca3ce4059fd19e8366d8dfb618b69f2cc053d8d1fcf361113ac89510bcd7e44491338fce798cb6f224f4d7510"} build_url:https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010951 build_url: 'https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13ba6da4df25a5b9ac1c74b8a9736f875d74cbe6bea964dd1ba2a4227bec5a6692057ed8f78475e95ad52d5b73838031fd84046e2ef65ade3fa281801769197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/762cd0e920d87ed89dc15b4dd806b34eccefc1fe9c1ddb966bfdac124749b4cdaf133e9417017b906ca403201d6c0ac655be18ff912ea6923456770390f4dabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e093b4ebb3cec618693c3daa2035c7195d0f5a1ca3ce4059fd19e8366d8dfb618b69f2cc053d8d1fcf361113ac89510bcd7e44491338fce798cb6f224f4d7510"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 03:03:11.814801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 03:03:11.815125) ========= 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.xKLtXogKeU/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.xuvhED2ubW/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.004355 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 03:03:11.863252) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 03:03:11.863584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 03:03:11.863922) ========= ========= Total master_lag: 0.17 =========