builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1464871988.97 results: success (0) buildid: 20160602030220 builduid: 8d834d93c2314782aa56f6ea25f5cfbd revision: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:08.971761) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:08.972183) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:08.972470) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.002449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.002704) ========= 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.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-02 05:53:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-06-02 05:53:09 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.487523 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.512363) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.512673) ========= 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.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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.025750 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.561260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:09.561623) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-02 05:53:09,645 truncating revision to first 12 chars 2016-06-02 05:53:09,645 Setting DEBUG logging. 2016-06-02 05:53:09,645 attempt 1/10 2016-06-02 05:53:09,645 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34a8be4346a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 05:53:10,342 unpacking tar archive at: mozilla-central-34a8be4346a9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.962859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:10.546562) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:10.547096) ========= script_repo_revision: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:10.547785) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:10.548273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:53:10.566284) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-06-02 05:53:10.566935) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:10 INFO - MultiFileLogger online at 20160602 05:53:10 in /builds/slave/test 05:53:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:53:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:53:10 INFO - {'append_to_log': False, 05:53:10 INFO - 'base_work_dir': '/builds/slave/test', 05:53:10 INFO - 'blob_upload_branch': 'Firefox', 05:53:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:53:10 INFO - 'branch': 'Firefox', 05:53:10 INFO - 'buildbot_json_path': 'buildprops.json', 05:53:10 INFO - 'config_files': ('talos/mac_config.py',), 05:53:10 INFO - 'default_actions': ('clobber', 05:53:10 INFO - 'read-buildbot-config', 05:53:10 INFO - 'download-and-extract', 05:53:10 INFO - 'populate-webroot', 05:53:10 INFO - 'create-virtualenv', 05:53:10 INFO - 'install', 05:53:10 INFO - 'run-tests'), 05:53:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:53:10 INFO - 'download_minidump_stackwalk': True, 05:53:10 INFO - 'download_symbols': 'ondemand', 05:53:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:53:10 INFO - 'tooltool.py': '/tools/tooltool.py', 05:53:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:53:10 INFO - '/tools/misc-python/virtualenv.py')}, 05:53:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:53:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:53:10 INFO - 'installer_path': 'installer.exe', 05:53:10 INFO - 'log_level': 'info', 05:53:10 INFO - 'log_name': 'talos', 05:53:10 INFO - 'log_to_console': True, 05:53:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:53:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:53:10 INFO - 'opt_config_files': (), 05:53:10 INFO - 'pip_index': False, 05:53:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:10 INFO - 'cmd': ('bash', 05:53:10 INFO - '-c', 05:53:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:10 INFO - 'enabled': True, 05:53:10 INFO - 'halt_on_failure': False, 05:53:10 INFO - 'name': 'check_screen_resolution'},), 05:53:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:10 INFO - 'cmd': ('bash', 05:53:10 INFO - '-c', 05:53:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:10 INFO - 'enabled': True, 05:53:10 INFO - 'halt_on_failure': False, 05:53:10 INFO - 'name': 'check_screen_resolution'},), 05:53:10 INFO - 'run_cmd_checks_enabled': True, 05:53:10 INFO - 'sps_profile': False, 05:53:10 INFO - 'sps_profile_interval': 0, 05:53:10 INFO - 'suite': 'dromaeojs', 05:53:10 INFO - 'system_bits': '32', 05:53:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:53:10 INFO - 'title': 't-yosemite-r7-0324', 05:53:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:53:10 INFO - 'use_talos_json': True, 05:53:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:53:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:53:10 INFO - 'work_dir': 'build'} 05:53:10 INFO - ##### 05:53:10 INFO - ##### Running clobber step. 05:53:10 INFO - ##### 05:53:10 INFO - Running pre-action listener: _resource_record_pre_action 05:53:10 INFO - Running main action method: clobber 05:53:10 INFO - rmtree: /builds/slave/test/build 05:53:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:53:12 INFO - Running post-action listener: _resource_record_post_action 05:53:12 INFO - ##### 05:53:12 INFO - ##### Finished clobber step (success) 05:53:12 INFO - ##### 05:53:12 INFO - ##### 05:53:12 INFO - ##### Running read-buildbot-config step. 05:53:12 INFO - ##### 05:53:12 INFO - Running pre-action listener: _resource_record_pre_action 05:53:12 INFO - Running main action method: read_buildbot_config 05:53:12 INFO - Using buildbot properties: 05:53:12 INFO - { 05:53:12 INFO - "project": "", 05:53:12 INFO - "product": "firefox", 05:53:12 INFO - "script_repo_revision": "production", 05:53:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:53:12 INFO - "repository": "", 05:53:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:53:12 INFO - "buildid": "20160602030220", 05:53:12 INFO - "stage_platform": "macosx64", 05:53:12 INFO - "basedir": "/builds/slave/test", 05:53:12 INFO - "nightly_build": "True", 05:53:12 INFO - "buildnumber": 5, 05:53:12 INFO - "slavename": "t-yosemite-r7-0324", 05:53:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:53:12 INFO - "platform": "yosemite_r7", 05:53:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:53:12 INFO - "branch": "mozilla-central", 05:53:12 INFO - "repo_path": "mozilla-central", 05:53:12 INFO - "revision": "34a8be4346a9231e472fc36b1d7c0531e0fbf7c5", 05:53:12 INFO - "pgo_build": "False", 05:53:12 INFO - "builduid": "8d834d93c2314782aa56f6ea25f5cfbd", 05:53:12 INFO - "slavebuilddir": "test" 05:53:12 INFO - } 05:53:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:53:12 INFO - Running post-action listener: _resource_record_post_action 05:53:12 INFO - ##### 05:53:12 INFO - ##### Finished read-buildbot-config step (success) 05:53:12 INFO - ##### 05:53:12 INFO - ##### 05:53:12 INFO - ##### Running download-and-extract step. 05:53:12 INFO - ##### 05:53:12 INFO - Running pre-action listener: _resource_record_pre_action 05:53:12 INFO - Running main action method: download_and_extract 05:53:12 INFO - mkdir: /builds/slave/test/build/tests 05:53: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')]} 05:53:12 INFO - https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:53:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:53:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:53:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:17 INFO - Downloaded 1199 bytes. 05:53:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:53:17 INFO - Using the following test package requirements: 05:53:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:53:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:53:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:53:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'jsshell-mac.zip'], 05:53:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:53:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:53:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:53:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:53:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:53:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:53:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:53:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:17 INFO - https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:53:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:53:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:53:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:20 INFO - Downloaded 18697223 bytes. 05:53:20 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'] 05:53:20 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 05:53:23 INFO - Return code: 0 05:53:23 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 05:53: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')]} 05:53:23 INFO - https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:53:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:53:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:53:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:27 INFO - Downloaded 18697223 bytes. 05:53:27 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'] 05:53:27 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 05:53:31 INFO - Return code: 0 05:53: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')]} 05:53:31 INFO - https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:53:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:53:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:53:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:32 INFO - Downloaded 10834055 bytes. 05:53:32 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'] 05:53:32 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 05:53:32 INFO - Return code: 0 05:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:32 INFO - https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:53:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:53:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:53:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HNwPjWBETnWF8PAaWndqhQ/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 05:53:36 INFO - Downloaded 111203260 bytes. 05:53:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:53:36 INFO - mkdir: /builds/slave/test/properties 05:53:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:53:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:53:36 INFO - Contents: 05:53:36 INFO - build_url:https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:53:38 INFO - Running post-action listener: _resource_record_post_action 05:53:38 INFO - Running post-action listener: set_extra_try_arguments 05:53:38 INFO - ##### 05:53:38 INFO - ##### Finished download-and-extract step (success) 05:53:38 INFO - ##### 05:53:38 INFO - ##### 05:53:38 INFO - ##### Running populate-webroot step. 05:53:38 INFO - ##### 05:53:38 INFO - Running pre-action listener: _resource_record_pre_action 05:53:38 INFO - Running main action method: populate_webroot 05:53:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:53:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:53:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:53:38 INFO - '--tppagecycles', 05:53:38 INFO - '7'], 05:53:38 INFO - 'tests': ['tsvgm']}}, 05:53:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:53:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:53:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:53:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:53:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['damp', 'tps']}, 05:53:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'tests': ['damp', 'tps']}, 05:53:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['dromaeo_dom']}, 05:53:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:53:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'other-e10s': {'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['a11yr', 05:53:38 INFO - 'ts_paint', 05:53:38 INFO - 'tpaint', 05:53:38 INFO - 'sessionrestore', 05:53:38 INFO - 'sessionrestore_no_auto_restore', 05:53:38 INFO - 'tabpaint']}, 05:53:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['tsvgx', 05:53:38 INFO - 'tsvgr_opacity', 05:53:38 INFO - 'tart', 05:53:38 INFO - 'tscrollx', 05:53:38 INFO - 'cart']}, 05:53:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:53:38 INFO - 'tsvgr_opacity', 05:53:38 INFO - 'tart', 05:53:38 INFO - 'tscrollx', 05:53:38 INFO - 'cart']}, 05:53:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'talos_options': ['--disable-e10s'], 05:53:38 INFO - 'tests': ['tp5o']}, 05:53:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'tests': ['tp5o']}, 05:53:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'talos_options': ['--disable-e10s', 05:53:38 INFO - '--xperf_path', 05:53:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:38 INFO - 'tests': ['tp5n']}, 05:53:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:38 INFO - 'talos_options': ['--xperf_path', 05:53:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:38 INFO - 'tests': ['tp5n']}}, 05:53:38 INFO - 'talos.zip': {'path': '', 05:53:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:53:38 INFO - Running post-action listener: _resource_record_post_action 05:53:38 INFO - ##### 05:53:38 INFO - ##### Finished populate-webroot step (success) 05:53:38 INFO - ##### 05:53:38 INFO - ##### 05:53:38 INFO - ##### Running create-virtualenv step. 05:53:38 INFO - ##### 05:53:38 INFO - Running pre-action listener: _resource_record_pre_action 05:53:38 INFO - Running main action method: create_virtualenv 05:53:38 INFO - Creating virtualenv /builds/slave/test/build/venv 05:53:38 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 05:53:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:53:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:53:38 INFO - Using real prefix '/tools/python27' 05:53:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:53:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:53:41 INFO - Installing pip.................done. 05:53:41 INFO - Return code: 0 05:53:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:41 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:41 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 05:53:41 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 05:53:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:41 INFO - 'HOME': '/Users/cltbld', 05:53:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:41 INFO - 'LOGNAME': 'cltbld', 05:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:41 INFO - 'MOZ_NO_REMOTE': '1', 05:53:41 INFO - 'NO_EM_RESTART': '1', 05:53:41 INFO - 'PAGER': '/bin/cat', 05:53:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:41 INFO - 'PWD': '/builds/slave/test', 05:53:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:41 INFO - 'SHELL': '/bin/bash', 05:53:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:41 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:41 INFO - 'USER': 'cltbld', 05:53:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:41 INFO - 'XPC_FLAGS': '0x0', 05:53:41 INFO - 'XPC_SERVICE_NAME': '0', 05:53:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:42 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 05:53:42 INFO - Cleaning up... 05:53:42 INFO - Return code: 0 05:53:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:42 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:42 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 05:53:42 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 05:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:42 INFO - 'HOME': '/Users/cltbld', 05:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:42 INFO - 'LOGNAME': 'cltbld', 05:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:42 INFO - 'MOZ_NO_REMOTE': '1', 05:53:42 INFO - 'NO_EM_RESTART': '1', 05:53:42 INFO - 'PAGER': '/bin/cat', 05:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:42 INFO - 'PWD': '/builds/slave/test', 05:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:42 INFO - 'SHELL': '/bin/bash', 05:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:42 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:42 INFO - 'USER': 'cltbld', 05:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:42 INFO - 'XPC_FLAGS': '0x0', 05:53:42 INFO - 'XPC_SERVICE_NAME': '0', 05:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:42 INFO - Downloading/unpacking psutil>=3.1.1 05:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:53:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:45 INFO - Installing collected packages: psutil 05:53:45 INFO - Running setup.py install for psutil 05:53:45 INFO - building 'psutil._psutil_osx' extension 05:53:45 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 05:53:46 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 05:53:46 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 05:53:46 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 05:53:46 INFO - building 'psutil._psutil_posix' extension 05:53:46 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 05:53:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:53:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:53:46 INFO - ^ 05:53:46 INFO - 1 warning generated. 05:53:46 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 05:53:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:46 INFO - Successfully installed psutil 05:53:46 INFO - Cleaning up... 05:53:46 INFO - Return code: 0 05:53:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:53: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 mozsystemmonitor==0.3 05:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:46 INFO - 'HOME': '/Users/cltbld', 05:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:46 INFO - 'LOGNAME': 'cltbld', 05:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:46 INFO - 'MOZ_NO_REMOTE': '1', 05:53:46 INFO - 'NO_EM_RESTART': '1', 05:53:46 INFO - 'PAGER': '/bin/cat', 05:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:46 INFO - 'PWD': '/builds/slave/test', 05:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:46 INFO - 'SHELL': '/bin/bash', 05:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:46 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:46 INFO - 'USER': 'cltbld', 05:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:46 INFO - 'XPC_FLAGS': '0x0', 05:53:46 INFO - 'XPC_SERVICE_NAME': '0', 05:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:53:49 INFO - Installing collected packages: mozsystemmonitor 05:53:49 INFO - Running setup.py install for mozsystemmonitor 05:53:49 INFO - Successfully installed mozsystemmonitor 05:53:49 INFO - Cleaning up... 05:53:49 INFO - Return code: 0 05:53:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:53:49 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')]} 05:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:49 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')]} 05:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:49 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:49 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 05:53:49 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 05:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:49 INFO - 'HOME': '/Users/cltbld', 05:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:49 INFO - 'LOGNAME': 'cltbld', 05:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:49 INFO - 'MOZ_NO_REMOTE': '1', 05:53:49 INFO - 'NO_EM_RESTART': '1', 05:53:49 INFO - 'PAGER': '/bin/cat', 05:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:49 INFO - 'PWD': '/builds/slave/test', 05:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:49 INFO - 'SHELL': '/bin/bash', 05:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:49 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:49 INFO - 'USER': 'cltbld', 05:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:49 INFO - 'XPC_FLAGS': '0x0', 05:53:49 INFO - 'XPC_SERVICE_NAME': '0', 05:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:50 INFO - Downloading/unpacking blobuploader==1.2.4 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:53:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:53 INFO - Downloading docopt-0.6.1.tar.gz 05:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:53:53 INFO - Installing collected packages: blobuploader, requests, docopt 05:53:53 INFO - Running setup.py install for blobuploader 05:53:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:53:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:53:53 INFO - Running setup.py install for requests 05:53:54 INFO - Running setup.py install for docopt 05:53:54 INFO - Successfully installed blobuploader requests docopt 05:53:54 INFO - Cleaning up... 05:53:54 INFO - Return code: 0 05:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:54 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')]} 05:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:54 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')]} 05:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:54 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:54 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 05:53:54 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 05:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:54 INFO - 'HOME': '/Users/cltbld', 05:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:54 INFO - 'LOGNAME': 'cltbld', 05:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:54 INFO - 'MOZ_NO_REMOTE': '1', 05:53:54 INFO - 'NO_EM_RESTART': '1', 05:53:54 INFO - 'PAGER': '/bin/cat', 05:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:54 INFO - 'PWD': '/builds/slave/test', 05:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:54 INFO - 'SHELL': '/bin/bash', 05:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:54 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:54 INFO - 'USER': 'cltbld', 05:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:54 INFO - 'XPC_FLAGS': '0x0', 05:53:54 INFO - 'XPC_SERVICE_NAME': '0', 05:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-lB2F2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-JwoFJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-K5Rzqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-4aByld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-CmKa4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-52J5DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-G21yRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-kAYMOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-UFa6li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-_DpqlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-kTrfxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-BDmtLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-bLANqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-2volom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-S2O46g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-_zFRIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-JPeSNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:53:56 INFO - Running setup.py install for manifestparser 05:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:53:56 INFO - Running setup.py install for mozcrash 05:53:56 INFO - Running setup.py install for mozdebug 05:53:57 INFO - Running setup.py install for mozdevice 05:53:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Running setup.py install for mozfile 05:53:57 INFO - Running setup.py install for mozhttpd 05:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Running setup.py install for mozinfo 05:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Running setup.py install for mozInstall 05:53:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:53:57 INFO - Running setup.py install for mozleak 05:53:57 INFO - Running setup.py install for mozlog 05:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for moznetwork 05:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for mozprocess 05:53:58 INFO - Running setup.py install for mozprofile 05:53:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for mozrunner 05:53:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:53:58 INFO - Running setup.py install for mozscreenshot 05:53:58 INFO - Running setup.py install for moztest 05:53:59 INFO - Running setup.py install for mozversion 05:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:53:59 INFO - Cleaning up... 05:53:59 INFO - Return code: 0 05:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:59 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')]} 05:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:59 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')]} 05:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:59 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:53:59 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 05:53:59 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 05:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:59 INFO - 'HOME': '/Users/cltbld', 05:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:59 INFO - 'LOGNAME': 'cltbld', 05:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:59 INFO - 'MOZ_NO_REMOTE': '1', 05:53:59 INFO - 'NO_EM_RESTART': '1', 05:53:59 INFO - 'PAGER': '/bin/cat', 05:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:59 INFO - 'PWD': '/builds/slave/test', 05:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:59 INFO - 'SHELL': '/bin/bash', 05:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:53:59 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:59 INFO - 'USER': 'cltbld', 05:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:59 INFO - 'XPC_FLAGS': '0x0', 05:53:59 INFO - 'XPC_SERVICE_NAME': '0', 05:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:59 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-QM6wWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:59 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)) 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:59 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-9sMPZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:59 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)) 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:59 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Z6SLW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:59 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)) 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:59 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-pRdtWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:59 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)) 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:59 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-0qoVas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:59 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)) 05:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-XePkpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Ao6Uyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-AnXVDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-XlPf_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-4CnZ5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-mWlzla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-3SKLu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-obbhJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-LS4HZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:00 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)) 05:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:00 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-3FcuKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:01 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Fl9WWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:01 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)) 05:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:01 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-GNj0rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:01 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)) 05:54:01 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)) 05:54:01 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)) 05:54:01 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)) 05:54:01 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)) 05:54:01 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)) 05:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:03 INFO - Downloading blessings-1.6.tar.gz 05:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:03 INFO - Installing collected packages: blessings 05:54:03 INFO - Running setup.py install for blessings 05:54:03 INFO - Successfully installed blessings 05:54:03 INFO - Cleaning up... 05:54:04 INFO - Return code: 0 05:54:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:04 INFO - Reading from file tmpfile_stdout 05:54:04 INFO - Current package versions: 05:54:04 INFO - blessings == 1.6 05:54:04 INFO - blobuploader == 1.2.4 05:54:04 INFO - docopt == 0.6.1 05:54:04 INFO - manifestparser == 1.1 05:54:04 INFO - mozInstall == 1.12 05:54:04 INFO - mozcrash == 0.17 05:54:04 INFO - mozdebug == 0.1 05:54:04 INFO - mozdevice == 0.48 05:54:04 INFO - mozfile == 1.2 05:54:04 INFO - mozhttpd == 0.7 05:54:04 INFO - mozinfo == 0.9 05:54:04 INFO - mozleak == 0.1 05:54:04 INFO - mozlog == 3.2 05:54:04 INFO - moznetwork == 0.27 05:54:04 INFO - mozprocess == 0.22 05:54:04 INFO - mozprofile == 0.28 05:54:04 INFO - mozrunner == 6.11 05:54:04 INFO - mozscreenshot == 0.1 05:54:04 INFO - mozsystemmonitor == 0.3 05:54:04 INFO - moztest == 0.7 05:54:04 INFO - mozversion == 1.4 05:54:04 INFO - psutil == 3.1.1 05:54:04 INFO - requests == 1.2.3 05:54:04 INFO - wsgiref == 0.1.2 05:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:04 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:54:04 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 05:54:04 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 05:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:04 INFO - 'HOME': '/Users/cltbld', 05:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:04 INFO - 'LOGNAME': 'cltbld', 05:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:04 INFO - 'MOZ_NO_REMOTE': '1', 05:54:04 INFO - 'NO_EM_RESTART': '1', 05:54:04 INFO - 'PAGER': '/bin/cat', 05:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:04 INFO - 'PWD': '/builds/slave/test', 05:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:04 INFO - 'SHELL': '/bin/bash', 05:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:54:04 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:04 INFO - 'USER': 'cltbld', 05:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:04 INFO - 'XPC_FLAGS': '0x0', 05:54:04 INFO - 'XPC_SERVICE_NAME': '0', 05:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 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)) 05:54:04 INFO - Cleaning up... 05:54:04 INFO - Return code: 0 05:54:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:04 INFO - 'HOME': '/Users/cltbld', 05:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:04 INFO - 'LOGNAME': 'cltbld', 05:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:04 INFO - 'MOZ_NO_REMOTE': '1', 05:54:04 INFO - 'NO_EM_RESTART': '1', 05:54:04 INFO - 'PAGER': '/bin/cat', 05:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:04 INFO - 'PWD': '/builds/slave/test', 05:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:04 INFO - 'SHELL': '/bin/bash', 05:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:54:04 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:04 INFO - 'USER': 'cltbld', 05:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:04 INFO - 'XPC_FLAGS': '0x0', 05:54:04 INFO - 'XPC_SERVICE_NAME': '0', 05:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:04 INFO - Downloading/unpacking jsonschema 05:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:07 INFO - Downloading/unpacking functools32 (from jsonschema) 05:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:07 INFO - warning: no files found matching '*.txt' 05:54:07 INFO - no previously-included directories found matching 'build' 05:54:07 INFO - no previously-included directories found matching 'dist' 05:54:07 INFO - no previously-included directories found matching '.git*' 05:54:07 INFO - Installing collected packages: jsonschema, functools32 05:54:07 INFO - Running setup.py install for functools32 05:54:07 INFO - warning: no files found matching '*.txt' 05:54:07 INFO - no previously-included directories found matching 'build' 05:54:07 INFO - no previously-included directories found matching 'dist' 05:54:07 INFO - no previously-included directories found matching '.git*' 05:54:07 INFO - Successfully installed jsonschema functools32 05:54:07 INFO - Cleaning up... 05:54:08 INFO - Return code: 0 05:54:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:54:08 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')]} 05:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:08 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')]} 05:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:08 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 0x10db58a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8f04736e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, '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.GJpga8GeZT/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.6aaWWzGEPD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 05:54:08 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 05:54:08 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 05:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:08 INFO - 'HOME': '/Users/cltbld', 05:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:08 INFO - 'LOGNAME': 'cltbld', 05:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:08 INFO - 'MOZ_NO_REMOTE': '1', 05:54:08 INFO - 'NO_EM_RESTART': '1', 05:54:08 INFO - 'PAGER': '/bin/cat', 05:54:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:08 INFO - 'PWD': '/builds/slave/test', 05:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:08 INFO - 'SHELL': '/bin/bash', 05:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:54:08 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:08 INFO - 'USER': 'cltbld', 05:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:08 INFO - 'XPC_FLAGS': '0x0', 05:54:08 INFO - 'XPC_SERVICE_NAME': '0', 05:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:08 INFO - Downloading/unpacking flake8 05:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:54:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:54:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 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 05:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:54:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:11 INFO - no previously-included directories found matching 'docs/_build' 05:54:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:54:12 INFO - Running setup.py install for pep8 05:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:12 INFO - no previously-included directories found matching 'docs/_build' 05:54:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:54:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:54:12 INFO - Cleaning up... 05:54:12 INFO - Return code: 0 05:54:12 INFO - Running post-action listener: _resource_record_post_action 05:54:12 INFO - Running post-action listener: _start_resource_monitoring 05:54:12 INFO - Starting resource monitoring. 05:54:12 INFO - ##### 05:54:12 INFO - ##### Finished create-virtualenv step (success) 05:54:12 INFO - ##### 05:54:12 INFO - ##### 05:54:12 INFO - ##### Running install step. 05:54:12 INFO - ##### 05:54:12 INFO - Running pre-action listener: _resource_record_pre_action 05:54:12 INFO - Running main action method: install 05:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:12 INFO - Reading from file tmpfile_stdout 05:54:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:12 INFO - Reading from file tmpfile_stdout 05:54:12 INFO - Output received: 05:54:12 INFO - Usage: mozinstall [options] installer 05:54:12 INFO - Options: 05:54:12 INFO - -h, --help show this help message and exit 05:54:12 INFO - -d DEST, --destination=DEST 05:54:12 INFO - Directory to install application into. [default: 05:54:12 INFO - "/builds/slave/test"] 05:54:12 INFO - --app=APP Application being installed. [default: firefox] 05:54:12 INFO - mkdir: /builds/slave/test/build/application 05:54:12 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'] 05:54:12 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 05:54:46 INFO - Reading from file tmpfile_stdout 05:54:46 INFO - Output received: 05:54:46 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:54:46 INFO - Running post-action listener: _resource_record_post_action 05:54:46 INFO - ##### 05:54:46 INFO - ##### Finished install step (success) 05:54:46 INFO - ##### 05:54:46 INFO - ##### 05:54:46 INFO - ##### Running run-tests step. 05:54:46 INFO - ##### 05:54:46 INFO - Running pre-action listener: _resource_record_pre_action 05:54:46 INFO - Running main action method: run_tests 05:54:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:54:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:54:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:54:46 INFO - 2016-06-02 05:54:46.306 screenresolution[1641:7765] starting screenresolution argv=screenresolution get 05:54:46 INFO - 2016-06-02 05:54:46.309 screenresolution[1641:7765] Display 0: 1600x1200x32@60 05:54:46 INFO - 2016-06-02 05:54:46.315 screenresolution[1642:7767] starting screenresolution argv=screenresolution list 05:54:46 INFO - Available Modes on Display 0 05:54:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:54:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:54:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:54:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:54:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:54:46 INFO - Intel Iris: 05:54:46 INFO - Chipset Model: Intel Iris 05:54:46 INFO - Type: GPU 05:54:46 INFO - Bus: Built-In 05:54:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:54:46 INFO - Vendor: Intel (0x8086) 05:54:46 INFO - Device ID: 0x0a2e 05:54:46 INFO - Revision ID: 0x0009 05:54:46 INFO - Displays: 05:54:46 INFO - Display: 05:54:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:54:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:54:46 INFO - Display Serial Number: 1600x1200 60 05:54:46 INFO - Main Display: Yes 05:54:46 INFO - Mirror: Off 05:54:46 INFO - Online: Yes 05:54:46 INFO - Rotation: Supported 05:54:46 INFO - Return code: 0 05:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:54:46 INFO - Python 2.7.3 05:54:46 INFO - Return code: 0 05:54:46 INFO - grabbing minidump binary from tooltool 05:54: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')]} 05:54:46 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 0x10d9fcd30>, '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 0x10d820db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d9f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:54:46 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 05:54:46 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 05:54:46 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 05:54:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:54:46 INFO - Return code: 0 05:54:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:54:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:54:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:54:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:54:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:54:47 INFO - Return code: 0 05:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 05:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --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 05:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJpga8GeZT/Render', 05:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:47 INFO - 'HOME': '/Users/cltbld', 05:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:47 INFO - 'LOGNAME': 'cltbld', 05:54:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:54:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:47 INFO - 'MOZ_NO_REMOTE': '1', 05:54:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:54:47 INFO - 'NO_EM_RESTART': '1', 05:54:47 INFO - 'PAGER': '/bin/cat', 05:54:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:47 INFO - 'PWD': '/builds/slave/test', 05:54:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:47 INFO - 'SHELL': '/bin/bash', 05:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6aaWWzGEPD/Listeners', 05:54:47 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 05:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:47 INFO - 'USER': 'cltbld', 05:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:47 INFO - 'XPC_FLAGS': '0x0', 05:54:47 INFO - 'XPC_SERVICE_NAME': '0', 05:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 05:54:47 INFO - mozversion application_buildid: 20160602030220 05:54:47 INFO - mozversion application_changeset: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 05:54:47 INFO - mozversion application_display_name: Nightly 05:54:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:54:47 INFO - mozversion application_name: Firefox 05:54:47 INFO - mozversion application_remotingname: firefox 05:54:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:54:47 INFO - mozversion application_vendor: Mozilla 05:54:47 INFO - mozversion application_version: 49.0a1 05:54:47 INFO - mozversion platform_buildid: 20160602030220 05:54:47 INFO - mozversion platform_changeset: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 05:54:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:54:47 INFO - mozversion platform_version: 49.0a1 05:54:47 INFO - using testdate: 1464872087 05:54:47 INFO - actual date: 1464872087 05:54:47 INFO - starting webserver on 'localhost:49270' 05:54:47 INFO - SUITE-START | Running 2 tests 05:54:47 INFO - TEST-START | dromaeo_css 05:54:47 INFO - operating with platform_type : mac_ 05:54:47 INFO - Initialising browser for dromaeo_css test... 05:54:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpxweKar/profile http://localhost:49270/getInfo.html) 05:54:50 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:54:50 INFO - PROCESS | 1660 | colorDepth:24 05:54:50 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:54:50 INFO - PROCESS | 1660 | __metrics 05:54:50 INFO - PROCESS | 1660 | 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" 05:54:50 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:54:51 INFO - TEST-INFO | 1660: exit 0 05:54:51 INFO - Browser initialized. 05:54:51 INFO - Running cycle 1/1 for dromaeo_css test... 05:54:51 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpxweKar/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:03 INFO - PROCESS | 1664 | RSS: Main: 242393088 05:55:03 INFO - PROCESS | 1664 | 05:55:54 INFO - PROCESS | 1664 | 1464872154551 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] 05:58:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:58:06 INFO - PROCESS | 1664 | RSS: Main: 339537920 05:58:06 INFO - PROCESS | 1664 | 06:01:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 06:01:10 INFO - PROCESS | 1664 | RSS: Main: 371011584 06:01:10 INFO - PROCESS | 1664 | 06:04:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 06:04:13 INFO - PROCESS | 1664 | RSS: Main: 372977664 06:04:13 INFO - PROCESS | 1664 | 06:07:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 06:07:15 INFO - PROCESS | 1664 | RSS: Main: 371769344 06:07:15 INFO - PROCESS | 1664 | 06:10:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 06:10:19 INFO - PROCESS | 1664 | RSS: Main: 334000128 06:10:19 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 06:13:23 INFO - PROCESS | 1664 | RSS: Main: 379342848 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | __start_tp_report 06:13:23 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 06:13:23 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 06:13:23 INFO - PROCESS | 1664 | |i|pagename|runs| 06:13:23 INFO - PROCESS | 1664 | |0;dojo.html;3390.8813191538293;3396.575428071491;3421.3670470962875;3433.502142189381;3472.4444088945775;5463.863403414916;5510.228568000478;5541.0580201365765;5605.271314729084;5630.802921897734;5542.75057622407;5649.039663257246;5683.204351390808;5698.458646428583;5742.2247996520455;5022.723750193738;5029.094762942669;5030.320906677606;5041.798328066891;5122.6158038147105;5180.45605211453;5188.7146206958605;5229.60777941654;5233.162693968966;5240.737963101848;5013.097642424362;5055.241713742931;5150.356205474316;5153.1754919212935;5164.431912489623;4194.958050419487;4199.727017743868;5112.948870511284;5120.948790512084;5121.334226550524;5000.74996250186;5077.060743762416;5080.669756465855;5095.719735414551;5096.159133742928;4491.663125265618;5487.945120548823;5501.944980550184;5508.14623733322;5525.364583072957;6087.624903920053;6102.982862712703;6103.450689437927;6111.419415155553;6119.357467465941;6004.489618382443;6031.215941927565;6102.511799056065;6105.45050945417;6130;5706.029974904276;5717.656940583578;5726.570507211975;5740.885182296331;5756.56825738063;6273.811785646438;6299.874002520016;6302.747890084345;6303.527235457359;6314.4317011468265;5814.9242390157115;5856.736446859902;5934.495567876693;5937.554683398763;5989.880202396015;6118;6120.44915957566;6132.5707200495535;6133.662648554283;6147.784827531015;5859.974504461702;5989.401059893976;6004.849878753066;6034.577579569475;6064;5907.734151963172;6022.096685497123;6088.665123418166;6094.512439004958;6147.754089836357;4812.494688057704;4861.222204447342;4867.1969125093965;4896.779644915988;4931.457539670633;4320.330348795942;4352.869413917524;4357.564243575617;4379.956200437955;4412.801423935931;5488.121900495901;5505.201745746925;5526.253955716007;5539.556835453154;5588.105903055492;6150.876982460326;6153.138560601519;6162.845928851814;6166.7224974876235;6183.165272688219;5544.584156188302;5564.387917329091;5566.276384070045;5567.2762540870235;5569.805056823073;4456.376107344974;4691.756684478616;5256.159014557652;5288.206768984606;5293.973530132324;6218.533609979269;6242.469390101892;6244.500439964701;6248.968755156195;6255.687215639291;5533.667979921218;5775.451332123442;5978.3722709116655;6096.573239873166;6166;6169.598976066542;6205.2553693555255;6211.534134939988;6264.21697287839;6286.811395658137;6000.87998240024;6033.75864965414;6045.879082418415;6048.029052887843;6071.937410952977;6009.639421634716;6074.3925607438905;6078.483328917092;6131.509479241561;6132.724027418688;5832.096025116114;5916.674582897982;5927.199828023334;5951.940480595139;5989.101634754647;5788.797392091256;5838.241028666246;5849.268841394825;5900.055991041413;5947.167396564571;5651.350094739158;5692.743826527899;5710.571707122065;5769.653820770767;5777.191193232867;5841.269841269841;5857.355690873916;5898.262717160355;5899.823005309848;5905.261842269716;5776.277965254343;5787.8553036174435;5794.942050579441;5795.8551036224435;5800 06:13:23 INFO - PROCESS | 1664 | |1;ext.html;509.3302307465683;514.7837908078607;519.1071357265502;533.6051322021704;543.2991441041057;26811.195664130082;42820.57179428205;44019;44024.11951760963;44050.559494405046;42195;45030;45076.971536280864;45558.31662525065;45629.543704562944;8537.060923298432;9492.193163581087;9733.756656083619;9740.561674724619;9741.31810773246;8630.266427353683;11630.883691163064;11890.524379024828;11957.342346170957;12240.571579994701;11895.7620847583;11975.46110425033;11976.323314735944;11989.220700654474;11989.820152697717;8654.394192406535;11702.063834893188;11780.646580602595;11907.64277071685;11941.343226122559;11633.941830290794;11692.356920369377;11716.062714982781;11734.295942243407;11742.471588778526;34131.63473461058;37530.62469375299;39571.80214098911;39689.80155099235;39966;3565.447355659851;3611.331903597843;3701.4262789267705;3702.1114932416026;3791.6587507124214;32204;32878.67121328804;32925.847595334286;33145.01694847451;33303;3108.782385233035;3152.590163278782;3188.8846865855676;3196.328770958102;3249.772515923886;363657;369942;395907;396779;396828.01585992175;60104.69947650234;63028.68485657634;63534.729305414556;63637;63794.68102659457;35505.28989420249;36361.818190908874;36389.81805090958;37525;37724.62275377266;16868.156592170344;17018.319267229173;17025.31898724037;17031.914840425718;17051.658966820592;11760.294382337088;11935.76128477443;12790;12887.871121288854;13051;20081.79918200799;20094.89952550228;20228.393148205283;20318.28885989013;20345.694814577793;6914.239433662293;8002;8042.879356809653;8045.637946292431;8073.31376832964;966.990330096704;1033.6692258477365;1042.7966546523355;1083.6207327435334;1095.3569645531702;28486.572701409495;29652.703472964993;30980.0705978821;31098.689013110034;31234;1100.4002818463957;1101.3502033800096;1123.1351859068634;1158.6350299655583;1188.5839956015325;13662.18026918388;15749.763753543477;16043.759343609856;16117.274722637509;16184.676306473804;676254;679231;687771.561142191;687787;688999;61585.69207154025;62113.68943155346;62293.37706622967;62312;62513;1265.949362025527;1310.3775706539318;1461.1306272767686;1481.5259117082646;1485.4578079001303;31448.056558303288;31643.683563164075;31884.043478695676;32169.517457238628;32628;51740;52249;52906;52978.73510632577;53018.46981530135;551470;553383;553652;553761;554467;23807.761922380556;26757.197284081507;27476.313092172855;27500.03749868745;28287;49153;49353.25970110523;50183;50884.74557627188;51104.23343649923;39923.60076399199;39967.60113396017;40732.31556469292;40851.182976340904;40997.795011024755;39828.20343593054;40467;40566;41532.37701434542;41803.58196417997;39226.2154756909;39497.80251098726;39797.60202397939;40005.79997099996;40158.59841401548;30777.692223077484;31361.05916822499;33478.83260583682;35610;36590.63409365872;25931.351716207246;25934.481310374063;25999.960001599724;26413;26620.600690989264 06:13:23 INFO - PROCESS | 1664 | |2;jquery.html;388.4930166133196;390.5567181249281;394.5718894998928;394.8953527315262;410.167360258675;13707.871906608558;19718.21127154914;20222.494437639067;20821.583568328642;20849.166033358666;16383.180840957933;17428.477145685618;19920.800791992075;20523.589528209428;20586.073626686797;5890.88218235635;6056.303525094627;6057;6097.115918191859;6100.389961003909;5997.88004239915;6034.396560343953;6036.2152920120325;6050.515958723292;6090.269167699882;5095.566876815456;5763.366029736726;6000.669963152025;6004.099385092228;6004.219451471325;5224.555912747421;5744.023516002289;5903.557233207505;5985.43138401849;6003.6397816131175;5185.870353241162;5229.477052294778;5238.05714971305;5239.083160446945;5251.763670634792;24524.877375613185;24697.38256543583;25524;25585.232443026554;25840.612390814153;2607.99592157019;2650.271175426754;2810.971890281112;2832.178668186244;3088.7992280501694;12783.872161278361;14571.979961402705;15174.620634484116;15370.462033828762;15815.920920395325;1821.8360347568782;1823.370937026737;1888.7544619199552;1891.290765962764;1901.0969789350086;109456.90543094547;111717;112605;113917.43041284823;115216.84783152062;9727.610895564098;10398.324108932895;10549.261551691461;10804.729881752863;11048.281861678965;12495.500539935267;12974;13891.513797017056;13910.026298159235;13961.86038139626;7653.57905315213;7986.0816006158875;8214.876776848352;8483.394557313117;8620.181082797124;7587.317141457295;8353.20644538768;8623.482591044558;8669.176428239307;8778.429402088845;6670.5664131832245;6760.18877734675;6770.898436523456;6784.389404953479;6794.694238759268;1851.5556266495944;1883.1996401529295;1883.839873610731;1887.5186827358937;1887.5375532994503;909.8953620333614;937.4562753602894;1006.3861044762689;1026.7946410717886;1091.2197778588347;20863.269785557433;21172.682409763867;21355.35933921985;21373.46566335854;21500.211332388368;841.6927821345165;894.7047474333455;1012.5443550402348;1022.9795404092025;1052.9315594486334;9412.682224488577;10120.240981926385;10594.629187978384;11031.448427578589;11143.387113708663;583549;585466;587412;611425;614587;129702.35148824195;130644.3467782655;136567;137740;137974.3101284487;1045.968620941373;1056.0706578211125;1076.2251179150999;1078.3745427651943;1084.1976937066672;15198.544043678707;18208.635827283644;18329.90835045816;18390.356337528123;18566.562432983857;51446.485535145664;53011;53338.73330633322;53500;53598.46401536091;80160;81268;82382;83608;84453;7717.729879454192;7997.560134192675;8136.105028446985;8188.5905704715715;8202.384821138558;7223.313785190398;8578.528180950108;8618.61216245283;8826.293896488138;8859.424137431046;6195.411435913581;7840.176781438095;7849;7915.050193976529;8134.511929284025;7530.698772049057;7706.574283757523;7931.405144614062;7955.045394552691;8099.352051835956;5112.309838171799;8027.83944321122;8118.553479558681;8224.259816616533;8504.829903402022;10559.947200264256;11979.940100299442;12260.816087758869;12353.011759059433;12491.562795302121;11729.35488548138;11894.702632434259;12001.819972700241;12016.879831201577;12071.637850864488 06:13:23 INFO - PROCESS | 1664 | |3;mootools.html;1411.0334420921663;1557.244736302893;1567.0910871694414;1811.510892059144;1959.0400703655214;7695.384369250461;8384.077751447343;8621.008584012841;8668.959724833028;8723.694670686527;8344.582770861463;8585.22732954034;8601.612927418266;8602.397832151752;8604.39769216155;2332.976670233297;2363.515479326734;2367.3844800351862;2369.7037870266217;2371.4071482129466;2319.9188028419007;2354.411397150712;2367.621180611103;2368.100121953655;2374.489484760776;2326.767323267668;2347.0259842165483;2356.140008896749;2357.1278626907983;2357.6227803551437;2306.2158865985643;2325.197806756666;2330.417395651087;2344.8241381896255;2347.5445223961083;2255.9323220303254;2264.354658922199;2264.4904896398343;2268.739095004079;2270.965935510969;8416.074231834493;8549.658013679446;8644.394892357537;8657;8677.913220867773;5126.436092029911;5334.866628334284;5335.146376579729;5344.251804747339;5345.224942383372;3521.3485505181625;3533.0460775590705;3641.3809652359164;3644.2893635741043;3646.161382881952;1056.2539783577315;1056.7410984308817;1059.6079450603327;1065.488565488562;1079.5735684404617;11250.324980501196;11519.020883224935;11524.711882202928;11528.077753779762;11534.59628912984;4611.930821037688;4644.8374306899095;4721.15019296523;4764.356811830427;4769.8569042928075;10032.197424206191;10036.347637403545;10054.648087317055;10062.345947513388;10070.848937265946;4848.272759086165;5267.157254839284;5318.627696061316;5323.973380133075;5328.307320048447;4385.342198670225;4560.270356742972;4561.954380456219;4714.38712967312;4866.026794641086;3244.5561725032135;3359.4960755886814;3583.211693427442;3865.072382628205;4071.470708807836;1137.7440075982838;1161.8083016302216;1292.4959265886312;1305.6344223617402;1326.966825829362;626.3298270850147;753.9132999704995;810.7648781853314;912.7535565397305;955.19763398744;4974.303597496353;5115.335006449138;5454.972725136349;5854.356020837705;5973.163757074013;498.6559274100859;656.4354654996699;1013.4578000769553;1047.6542740895486;1049.7585555322319;3243.351329734063;3960.2871483133313;4026.7382620129597;4050.6354428101613;4094.529129150186;9929.652462163935;10222.284440089272;10524.947505249567;10532.36805791655;10594.152467802558;10426.999999999849;10440.791184176274;10620.150387968944;10644.201684873666;10644.627438039786;7881.645325960346;8009.31920786728;8195.631196596167;8203.097659257477;8219.589020548949;5897.262842144732;6048.909266361096;6070.6357618543025;6091.604045737013;6151.96924015377;9832;9857.260705446977;9928.25538084661;9958.253131015057;9966.750831229278;9754.317197796086;9855.655052073429;9981.700548983252;10130.392176469435;10217.846732298873;7448.994385757854;7459;7569.394448444001;7585.658645360862;7588.544687318778;8083;8085.272325490734;8129.9187008129165;8191.918080819354;8192.713255036046;7350.411967042731;7377.299156580117;7392.039034925425;7510.098788145458;7529.88705169412;6117.265928088658;7150.320719531845;7256.854862902606;7372.225916278928;7412.925870741439;6903.240643529108;6961.721531138698;6987.371136597731;6989.5107342485535;6996.230414654486;4738.50245724194;4839;4839.177339852304;4964.900701986012;4977.104121258209;4815.783289752039;4904.460509343898;5000.2499625056535;5038.370203724535;5169.741512924264 06:13:23 INFO - PROCESS | 1664 | |4;prototype.html;306.0985398002881;310.95646609474665;315.05483549351953;318.78959886474934;321.20181349346853;4472.306792447168;4909.5581397674205;5255.76349064292;5295.338082739658;5313.787448502056;4389.692721509496;4666.673332866701;5207.947920520794;5222.608304377168;5243.4007627673545;3285.3265080658466;3687.1335236219466;3700.703943684499;3975;3991.361382178852;2560.7695307422327;3774.226283611853;3886.3587508061137;3981;4026.8389264429534;3001.039667306463;3649.835757390924;3919.412088186766;4013.257547353749;4029.1538776856896;3437.295354452344;3702.648248416396;3986.4618276532588;4022.7529465865528;4024;3311.9503207451908;3445.4831775233665;3459.221675123102;3459.567554055743;3463.341965026662;4405.634253381462;5027.396712394499;5032.924506132411;5078.26365177051;5120.15517439622;4069.04377471294;4520.886977825548;4578.862634120982;4632.86101416958;4652.464966528859;4929.176204804209;5263.894722105575;5547.365001624236;5596.552275817925;5616.691081990489;3565.732570057256;3646.9635303647156;3782.829772660237;3811;3823.2735780201674;4568.977155114203;5598.468145526169;5686.9715651421475;5823.825285241407;5854.2389489366105;3790.3177428062663;3831.524862927737;3837.1174629835295;3854.2291541691775;3855.5566109897723;3604.441311596707;3881.3401721706814;3904.7657140571655;3905;3912.2981503046267;4258.254805409041;4264.528737585528;4282.207791558571;4519.717542641011;4563.771811409416;4563.657725670588;4695.741734204583;5324.680519168863;5583.4137415571595;5663.518600918886;1300.387519475862;1325.4101924643635;1330.9334533273297;1341.53716967646;1343.3619030960215;769.3921801776646;800.9759707208793;815.8409110223449;820.2945466898582;821.0762891746784;3697.8890633281044;4151.252774500558;4151.896202594899;4338.761368124783;4354.127725078183;4230.450041494586;4512.638988880882;4782.832600858953;4802.687825291345;4840.878978025507;3933.347993842593;3953.003733114816;4292.828286868553;4335.550414016071;4364.54172311909;2601.375669839225;2679.8928042878065;2713.796465265113;2950.9376624415195;3086.429010632995;5283.282933046775;5615.691136987505;5984.700764961734;5993.190919225936;6030.758769649165;5373.005993891143;5907.202527658711;5932;5933.970330148321;5977.372375900555;5462.3445186577865;5723.942760572424;5788.710564471844;5963.5825492216;5982.192404025488;4391.978040109779;5241.449647786927;5290.550303224269;5547.528460080777;5766.884662306814;5305.6920239695855;5655.660660360391;5691.861627674399;5705.885882282248;5706.771729130955;4873.610111191026;5556.527695145958;5580.274564306615;5808.854778630568;5848.473637372486;3277.885274015398;3696.667299943018;3713.3872910970467;3716.4239542870896;3739.4016957286703;5008.924866127084;5753.884922301447;5758.62568933018;5784.681842498703;5787.710614469175;4928.975355123202;5121.505095374973;5186.507281808223;5499.972500137474;5581.799913018721;5123.745551957127;5548.9722551388595;5559.8610034747835;5564.4991950724625;5568.888622227614;4629.699069560468;5020.021095886267;5021.372328458942;5267.367915850123;5438.755256013568;4988.5260900214425;5507.366652834975;5524.972375138099;5529.364123125731;5536.252605898152;4824.49342818999;5004.47453017428;5478.91781623284;5490.505854473116;5527.50252477279 06:13:23 INFO - PROCESS | 1664 | |5;yui.html;705.8164877131945;780.6331024418521;794.8410317936414;827.7765003449073;828.329053466692;15541.456049038286;15609.141497217648;16056.438024669138;16063;16108.436204732836;14375.353109110089;14509.492167774104;14518.491852785155;14519.78220326696;14537.200453975054;693.9971740834496;720.0243669827387;722.1803253307481;723.6381809095452;898.9910100898989;710.133636962907;712.4727701500877;876.1151237250391;910.7632015675906;915.7985243246509;961.2502248246344;967.2890425537224;968.2979839616257;968.9612415503371;970.4177493503877;957.0859828863419;961.2838435365627;962.870012548304;963.720521048895;964.3731574476576;538.0610834094491;689.5173378634976;711.2638419236066;717.3292971072012;719.6905330707793;15012.549623511311;15064.472743454036;15065.171415572138;15066.698666026618;15074.54776356711;13830;14187.219702916336;14258.144511329354;14324.713505729931;14374.425022999067;12924.224546527144;13007.94336297329;13079.411426485736;13132.6716832079;13169.078164528486;4589.1968905441745;4666.929996050062;4694.342792009122;4832.4684284728655;4853.247746599282;87480.12519874783;97893;99151;100103;101932.49033754783;10187.439690817077;10251.179905607534;10379.948100259451;10424.322419042737;10877.728056798485;21484;21500.462488437915;21516.677249841265;21521.246756363453;21559.78440215578;8228.136045715113;8262.710805121791;8268.793280168044;8318.376121790769;8495;4333.978330108329;4415.324180620578;4469.821207151743;4479.260921947842;4506.954930450654;3523.2601153757996;3641.417373220272;3642.635736426389;3644.890653280406;3645.3620616392027;662.9767958121442;673.9831504212434;674.2583158525582;687.4637782529635;689.5862482510454;525.4666513488812;565.4712843491349;582.9970850145722;627.2535682537857;701.698269744015;9321.300902432346;9631.036896310314;9684.32209745311;9729.518972661926;9772.560234789451;686.4817063117315;689.5552368722147;693.1508901595505;709.7551344786041;710.9502334836512;4518.209313370147;4559.475660299042;4569.497355290915;4628.490866004737;4732.763361831895;80139;142979;145904.5409545912;148329;148743;116277;117906;118995.40502297433;119691.40154299173;120525;72127.6393618039;72747.63626181835;100821.4876776849;110422.44788776165;110889.89110108795;8849.601767920587;8852.955735221283;8932.252984455527;9059.956323208049;9221.354505184572;42474.575254248295;48511;60209.69895150672;60452.39547604468;62214.68892655508;58482;81254.18745812702;82789.17210827814;83071;87645.56177219328;10357.844632330673;13145;13286.136401133896;13297.335133243105;13706.451741930212;14674.853251467775;14805.925970370079;15082.472113475975;15143;15492.690146196788;11969.401529923643;13681.110727802661;13885.166889986633;13955.162690238618;14232.928835355757;13572.457101715821;13699.178049317074;13931.721365572834;14301.785473217702;14320.856791431952;13501.257430841399;13509.797353039909;13680.521181758591;13681.179129252278;13767.155372609386;11131.332120072822;11157.888421115686;11384.43077846121;11421;11714.004309633776;11241.71895702614;11314.717132071435;11447.656570302905;11487.770244594894;11590.94204528972 06:13:23 INFO - PROCESS | 1664 | __end_tp_report 06:13:23 INFO - PROCESS | 1664 | __start_cc_report 06:13:23 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1117 06:13:23 INFO - PROCESS | 1664 | __end_cc_report 06:13:23 INFO - PROCESS | 1664 | __startTimestamp1464873203473__endTimestamp 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | ------- Summary: start ------- 06:13:23 INFO - PROCESS | 1664 | Number of tests: 6 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5593.77 Median:5777.19 stddev:634.93 (11.0%) stddev-sans-first:612.91 06:13:23 INFO - PROCESS | 1664 | Values: 3390.9 3396.6 3421.4 3433.5 3472.4 5463.9 5510.2 5541.1 5605.3 5630.8 5542.8 5649.0 5683.2 5698.5 5742.2 5022.7 5029.1 5030.3 5041.8 5122.6 5180.5 5188.7 5229.6 5233.2 5240.7 5013.1 5055.2 5150.4 5153.2 5164.4 4195.0 4199.7 5112.9 5120.9 5121.3 5000.7 5077.1 5080.7 5095.7 5096.2 4491.7 5487.9 5501.9 5508.1 5525.4 6087.6 6103.0 6103.5 6111.4 6119.4 6004.5 6031.2 6102.5 6105.5 6130.0 5706.0 5717.7 5726.6 5740.9 5756.6 6273.8 6299.9 6302.7 6303.5 6314.4 5814.9 5856.7 5934.5 5937.6 5989.9 6118.0 6120.4 6132.6 6133.7 6147.8 5860.0 5989.4 6004.8 6034.6 6064.0 5907.7 6022.1 6088.7 6094.5 6147.8 4812.5 4861.2 4867.2 4896.8 4931.5 4320.3 4352.9 4357.6 4380.0 4412.8 5488.1 5505.2 5526.3 5539.6 5588.1 6150.9 6153.1 6162.8 6166.7 6183.2 5544.6 5564.4 5566.3 5567.3 5569.8 4456.4 4691.8 5256.2 5288.2 5294.0 6218.5 6242.5 6244.5 6249.0 6255.7 5533.7 5775.5 5978.4 6096.6 6166.0 6169.6 6205.3 6211.5 6264.2 6286.8 6000.9 6033.8 6045.9 6048.0 6071.9 6009.6 6074.4 6078.5 6131.5 6132.7 5832.1 5916.7 5927.2 5951.9 5989.1 5788.8 5838.2 5849.3 5900.1 5947.2 5651.4 5692.7 5710.6 5769.7 5777.2 5841.3 5857.4 5898.3 5899.8 5905.3 5776.3 5787.9 5794.9 5795.9 5800.0 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68108.95 Median:19986.58 stddev:148274.32 (741.9%) stddev-sans-first:148603.40 06:13:23 INFO - PROCESS | 1664 | Values: 509.3 514.8 519.1 533.6 543.3 26811.2 42820.6 44019.0 44024.1 44050.6 42195.0 45030.0 45077.0 45558.3 45629.5 8537.1 9492.2 9733.8 9740.6 9741.3 8630.3 11630.9 11890.5 11957.3 12240.6 11895.8 11975.5 11976.3 11989.2 11989.8 8654.4 11702.1 11780.6 11907.6 11941.3 11633.9 11692.4 11716.1 11734.3 11742.5 34131.6 37530.6 39571.8 39689.8 39966.0 3565.4 3611.3 3701.4 3702.1 3791.7 32204.0 32878.7 32925.8 33145.0 33303.0 3108.8 3152.6 3188.9 3196.3 3249.8 363657.0 369942.0 395907.0 396779.0 396828.0 60104.7 63028.7 63534.7 63637.0 63794.7 35505.3 36361.8 36389.8 37525.0 37724.6 16868.2 17018.3 17025.3 17031.9 17051.7 11760.3 11935.8 12790.0 12887.9 13051.0 20081.8 20094.9 20228.4 20318.3 20345.7 6914.2 8002.0 8042.9 8045.6 8073.3 967.0 1033.7 1042.8 1083.6 1095.4 28486.6 29652.7 30980.1 31098.7 31234.0 1100.4 1101.4 1123.1 1158.6 1188.6 13662.2 15749.8 16043.8 16117.3 16184.7 676254.0 679231.0 687771.6 687787.0 688999.0 61585.7 62113.7 62293.4 62312.0 62513.0 1265.9 1310.4 1461.1 1481.5 1485.5 31448.1 31643.7 31884.0 32169.5 32628.0 51740.0 52249.0 52906.0 52978.7 53018.5 551470.0 553383.0 553652.0 553761.0 554467.0 23807.8 26757.2 27476.3 27500.0 28287.0 49153.0 49353.3 50183.0 50884.7 51104.2 39923.6 39967.6 40732.3 40851.2 40997.8 39828.2 40467.0 40566.0 41532.4 41803.6 39226.2 39497.8 39797.6 40005.8 40158.6 30777.7 31361.1 33478.8 35610.0 36590.6 25931.4 25934.5 26000.0 26413.0 26620.6 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34886.30 Median:392.56 stddev:99759.54 (25412.3%) stddev-sans-first:100005.76 06:13:23 INFO - PROCESS | 1664 | Values: 388.5 390.6 394.6 394.9 410.2 13707.9 19718.2 20222.5 20821.6 20849.2 16383.2 17428.5 19920.8 20523.6 20586.1 5890.9 6056.3 6057.0 6097.1 6100.4 5997.9 6034.4 6036.2 6050.5 6090.3 5095.6 5763.4 6000.7 6004.1 6004.2 5224.6 5744.0 5903.6 5985.4 6003.6 5185.9 5229.5 5238.1 5239.1 5251.8 24524.9 24697.4 25524.0 25585.2 25840.6 2608.0 2650.3 2811.0 2832.2 3088.8 12783.9 14572.0 15174.6 15370.5 15815.9 1821.8 1823.4 1888.8 1891.3 1901.1 109456.9 111717.0 112605.0 113917.4 115216.8 9727.6 10398.3 10549.3 10804.7 11048.3 12495.5 12974.0 13891.5 13910.0 13961.9 7653.6 7986.1 8214.9 8483.4 8620.2 7587.3 8353.2 8623.5 8669.2 8778.4 6670.6 6760.2 6770.9 6784.4 6794.7 1851.6 1883.2 1883.8 1887.5 1887.5 909.9 937.5 1006.4 1026.8 1091.2 20863.3 21172.7 21355.4 21373.5 21500.2 841.7 894.7 1012.5 1023.0 1052.9 9412.7 10120.2 10594.6 11031.4 11143.4 583549.0 585466.0 587412.0 611425.0 614587.0 129702.4 130644.3 136567.0 137740.0 137974.3 1046.0 1056.1 1076.2 1078.4 1084.2 15198.5 18208.6 18329.9 18390.4 18566.6 51446.5 53011.0 53338.7 53500.0 53598.5 80160.0 81268.0 82382.0 83608.0 84453.0 7717.7 7997.6 8136.1 8188.6 8202.4 7223.3 8578.5 8618.6 8826.3 8859.4 6195.4 7840.2 7849.0 7915.1 8134.5 7530.7 7706.6 7931.4 7955.0 8099.4 5112.3 8027.8 8118.6 8224.3 8504.8 10559.9 11979.9 12260.8 12353.0 12491.6 11729.4 11894.7 12001.8 12016.9 12071.6 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5631.48 Median:4839.09 stddev:3160.88 (65.3%) stddev-sans-first:3153.84 06:13:23 INFO - PROCESS | 1664 | Values: 1411.0 1557.2 1567.1 1811.5 1959.0 7695.4 8384.1 8621.0 8669.0 8723.7 8344.6 8585.2 8601.6 8602.4 8604.4 2333.0 2363.5 2367.4 2369.7 2371.4 2319.9 2354.4 2367.6 2368.1 2374.5 2326.8 2347.0 2356.1 2357.1 2357.6 2306.2 2325.2 2330.4 2344.8 2347.5 2255.9 2264.4 2264.5 2268.7 2271.0 8416.1 8549.7 8644.4 8657.0 8677.9 5126.4 5334.9 5335.1 5344.3 5345.2 3521.3 3533.0 3641.4 3644.3 3646.2 1056.3 1056.7 1059.6 1065.5 1079.6 11250.3 11519.0 11524.7 11528.1 11534.6 4611.9 4644.8 4721.2 4764.4 4769.9 10032.2 10036.3 10054.6 10062.3 10070.8 4848.3 5267.2 5318.6 5324.0 5328.3 4385.3 4560.3 4562.0 4714.4 4866.0 3244.6 3359.5 3583.2 3865.1 4071.5 1137.7 1161.8 1292.5 1305.6 1327.0 626.3 753.9 810.8 912.8 955.2 4974.3 5115.3 5455.0 5854.4 5973.2 498.7 656.4 1013.5 1047.7 1049.8 3243.4 3960.3 4026.7 4050.6 4094.5 9929.7 10222.3 10524.9 10532.4 10594.2 10427.0 10440.8 10620.2 10644.2 10644.6 7881.6 8009.3 8195.6 8203.1 8219.6 5897.3 6048.9 6070.6 6091.6 6152.0 9832.0 9857.3 9928.3 9958.3 9966.8 9754.3 9855.7 9981.7 10130.4 10217.8 7449.0 7459.0 7569.4 7585.7 7588.5 8083.0 8085.3 8129.9 8191.9 8192.7 7350.4 7377.3 7392.0 7510.1 7529.9 6117.3 7150.3 7256.9 7372.2 7412.9 6903.2 6961.7 6987.4 6989.5 6996.2 4738.5 4839.0 4839.2 4964.9 4977.1 4815.8 4904.5 5000.2 5038.4 5169.7 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4370.86 Median:4739.29 stddev:1373.31 (29.0%) stddev-sans-first:1342.85 06:13:23 INFO - PROCESS | 1664 | Values: 306.1 311.0 315.1 318.8 321.2 4472.3 4909.6 5255.8 5295.3 5313.8 4389.7 4666.7 5207.9 5222.6 5243.4 3285.3 3687.1 3700.7 3975.0 3991.4 2560.8 3774.2 3886.4 3981.0 4026.8 3001.0 3649.8 3919.4 4013.3 4029.2 3437.3 3702.6 3986.5 4022.8 4024.0 3312.0 3445.5 3459.2 3459.6 3463.3 4405.6 5027.4 5032.9 5078.3 5120.2 4069.0 4520.9 4578.9 4632.9 4652.5 4929.2 5263.9 5547.4 5596.6 5616.7 3565.7 3647.0 3782.8 3811.0 3823.3 4569.0 5598.5 5687.0 5823.8 5854.2 3790.3 3831.5 3837.1 3854.2 3855.6 3604.4 3881.3 3904.8 3905.0 3912.3 4258.3 4264.5 4282.2 4519.7 4563.8 4563.7 4695.7 5324.7 5583.4 5663.5 1300.4 1325.4 1330.9 1341.5 1343.4 769.4 801.0 815.8 820.3 821.1 3697.9 4151.3 4151.9 4338.8 4354.1 4230.5 4512.6 4782.8 4802.7 4840.9 3933.3 3953.0 4292.8 4335.6 4364.5 2601.4 2679.9 2713.8 2950.9 3086.4 5283.3 5615.7 5984.7 5993.2 6030.8 5373.0 5907.2 5932.0 5934.0 5977.4 5462.3 5723.9 5788.7 5963.6 5982.2 4392.0 5241.4 5290.6 5547.5 5766.9 5305.7 5655.7 5691.9 5705.9 5706.8 4873.6 5556.5 5580.3 5808.9 5848.5 3277.9 3696.7 3713.4 3716.4 3739.4 5008.9 5753.9 5758.6 5784.7 5787.7 4929.0 5121.5 5186.5 5500.0 5581.8 5123.7 5549.0 5559.9 5564.5 5568.9 4629.7 5020.0 5021.4 5267.4 5438.8 4988.5 5507.4 5525.0 5529.4 5536.3 4824.5 5004.5 5478.9 5490.5 5527.5 06:13:23 INFO - PROCESS | 1664 | 06:13:23 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:22878.60 Median:4374.65 stddev:35355.38 (808.2%) stddev-sans-first:35415.36 06:13:23 INFO - PROCESS | 1664 | Values: 705.8 780.6 794.8 827.8 828.3 15541.5 15609.1 16056.4 16063.0 16108.4 14375.4 14509.5 14518.5 14519.8 14537.2 694.0 720.0 722.2 723.6 899.0 710.1 712.5 876.1 910.8 915.8 961.3 967.3 968.3 969.0 970.4 957.1 961.3 962.9 963.7 964.4 538.1 689.5 711.3 717.3 719.7 15012.5 15064.5 15065.2 15066.7 15074.5 13830.0 14187.2 14258.1 14324.7 14374.4 12924.2 13007.9 13079.4 13132.7 13169.1 4589.2 4666.9 4694.3 4832.5 4853.2 87480.1 97893.0 99151.0 100103.0 101932.5 10187.4 10251.2 10379.9 10424.3 10877.7 21484.0 21500.5 21516.7 21521.2 21559.8 8228.1 8262.7 8268.8 8318.4 8495.0 4334.0 4415.3 4469.8 4479.3 4507.0 3523.3 3641.4 3642.6 3644.9 3645.4 663.0 674.0 674.3 687.5 689.6 525.5 565.5 583.0 627.3 701.7 9321.3 9631.0 9684.3 9729.5 9772.6 686.5 689.6 693.2 709.8 711.0 4518.2 4559.5 4569.5 4628.5 4732.8 80139.0 142979.0 145904.5 148329.0 148743.0 116277.0 117906.0 118995.4 119691.4 120525.0 72127.6 72747.6 100821.5 110422.4 110889.9 8849.6 8853.0 8932.3 9060.0 9221.4 42474.6 48511.0 60209.7 60452.4 62214.7 58482.0 81254.2 82789.2 83071.0 87645.6 10357.8 13145.0 13286.1 13297.3 13706.5 14674.9 14805.9 15082.5 15143.0 15492.7 11969.4 13681.1 13885.2 13955.2 14232.9 13572.5 13699.2 13931.7 14301.8 14320.9 13501.3 13509.8 13680.5 13681.2 13767.2 11131.3 11157.9 11384.4 11421.0 11714.0 11241.7 11314.7 11447.7 11487.8 11590.9 06:13:23 INFO - PROCESS | 1664 | -------- Summary: end -------- 06:13:23 INFO - PROCESS | 1664 | 06:13:24 INFO - TEST-INFO | 1664: exit 0 06:13:24 INFO - TEST-OK | dromaeo_css | took 1116083ms 06:13:24 INFO - TEST-START | kraken 06:13:24 INFO - operating with platform_type : mac_ 06:13:24 INFO - Initialising browser for kraken test... 06:13:24 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmp96MbCh/profile http://localhost:49270/getInfo.html) 06:13:26 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 06:13:26 INFO - PROCESS | 1670 | colorDepth:24 06:13:26 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 06:13:26 INFO - PROCESS | 1670 | __metrics 06:13:26 INFO - PROCESS | 1670 | 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" 06:13:26 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:13:27 INFO - TEST-INFO | 1670: exit 0 06:13:27 INFO - Browser initialized. 06:13:27 INFO - Running cycle 1/1 for kraken test... 06:13:27 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmp96MbCh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:13:39 INFO - PROCESS | 1672 | RSS: Main: 242364416 06:13:39 INFO - PROCESS | 1672 | 06:13:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 06:13:42 INFO - PROCESS | 1672 | RSS: Main: 292089856 06:13:42 INFO - PROCESS | 1672 | 06:13:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 06:13:45 INFO - PROCESS | 1672 | RSS: Main: 302268416 06:13:45 INFO - PROCESS | 1672 | 06:13:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 06:13:49 INFO - PROCESS | 1672 | RSS: Main: 385060864 06:13:49 INFO - PROCESS | 1672 | 06:13:51 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 06:13:52 INFO - PROCESS | 1672 | RSS: Main: 288145408 06:13:52 INFO - PROCESS | 1672 | 06:13:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:13:55 INFO - PROCESS | 1672 | RSS: Main: 293310464 06:13:55 INFO - PROCESS | 1672 | 06:13:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 06:13:59 INFO - PROCESS | 1672 | RSS: Main: 331026432 06:13:59 INFO - PROCESS | 1672 | 06:14:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:02 INFO - PROCESS | 1672 | RSS: Main: 333033472 06:14:02 INFO - PROCESS | 1672 | 06:14:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 06:14:06 INFO - PROCESS | 1672 | RSS: Main: 331776000 06:14:06 INFO - PROCESS | 1672 | 06:14:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:08 INFO - PROCESS | 1672 | RSS: Main: 311721984 06:14:08 INFO - PROCESS | 1672 | 06:14:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:10 INFO - PROCESS | 1672 | RSS: Main: 319209472 06:14:10 INFO - PROCESS | 1672 | 06:14:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:13 INFO - PROCESS | 1672 | RSS: Main: 322826240 06:14:13 INFO - PROCESS | 1672 | 06:14:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:17 INFO - PROCESS | 1672 | RSS: Main: 326160384 06:14:17 INFO - PROCESS | 1672 | 06:14:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:14:20 INFO - PROCESS | 1672 | RSS: Main: 327073792 06:14:20 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 06:14:22 INFO - PROCESS | 1672 | RSS: Main: 370950144 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | __start_tp_report 06:14:22 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 06:14:22 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 06:14:22 INFO - PROCESS | 1672 | |i|pagename|runs| 06:14:22 INFO - PROCESS | 1672 | |0;ai-astar;113;106;112;112;111;111;111;110;110;113 06:14:22 INFO - PROCESS | 1672 | |1;audio-beat-detection;153;113;118;168;119;118;139;117;150;118 06:14:22 INFO - PROCESS | 1672 | |2;audio-dft;183;183;181;181;183;181;180;184;181;184 06:14:22 INFO - PROCESS | 1672 | |3;audio-fft;79;73;59;124;80;84;58;67;94;55 06:14:22 INFO - PROCESS | 1672 | |4;audio-oscillator;85;128;124;102;70;69;94;70;91;117 06:14:22 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;86;89;108;114;74;120;134;130;86;89 06:14:22 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;93;93;101;92;92;93;93;92;93 06:14:22 INFO - PROCESS | 1672 | |7;imaging-desaturate;83;86;87;86;84;83;86;82;94;138 06:14:22 INFO - PROCESS | 1672 | |8;json-parse-financial;57;54;54;55;57;54;54;56;54;54 06:14:22 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;70;71;78;75;72;71;72;71;71 06:14:22 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;87;87;87;86;83;97;105;107;146;87 06:14:22 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;137;149;147;143;128;142;125;161;145;136 06:14:22 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;189;162;163;173;166;162;164;165;160;164 06:14:22 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;66;69;67;66;64;66;65;67;68 06:14:22 INFO - PROCESS | 1672 | __end_tp_report 06:14:22 INFO - PROCESS | 1672 | __start_cc_report 06:14:22 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1982 06:14:22 INFO - PROCESS | 1672 | __end_cc_report 06:14:22 INFO - PROCESS | 1672 | __startTimestamp1464873262614__endTimestamp 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | ------- Summary: start ------- 06:14:22 INFO - PROCESS | 1672 | Number of tests: 14 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:2.02 (1.8%) stddev-sans-first:2.00 06:14:22 INFO - PROCESS | 1672 | Values: 113.0 106.0 112.0 112.0 111.0 111.0 111.0 110.0 110.0 113.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:129.00 stddev:19.57 (15.2%) stddev-sans-first:19.12 06:14:22 INFO - PROCESS | 1672 | Values: 153.0 113.0 118.0 168.0 119.0 118.0 139.0 117.0 150.0 118.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.10 Median:183.00 stddev:1.45 (0.8%) stddev-sans-first:1.50 06:14:22 INFO - PROCESS | 1672 | Values: 183.0 183.0 181.0 181.0 183.0 181.0 180.0 184.0 181.0 184.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:77.30 Median:76.00 stddev:20.67 (27.2%) stddev-sans-first:21.91 06:14:22 INFO - PROCESS | 1672 | Values: 79.0 73.0 59.0 124.0 80.0 84.0 58.0 67.0 94.0 55.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:70.00 stddev:22.38 (32.0%) stddev-sans-first:23.44 06:14:22 INFO - PROCESS | 1672 | Values: 85.0 128.0 124.0 102.0 70.0 69.0 94.0 70.0 91.0 117.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:80.00 stddev:20.91 (26.1%) stddev-sans-first:21.26 06:14:22 INFO - PROCESS | 1672 | Values: 86.0 89.0 108.0 114.0 74.0 120.0 134.0 130.0 86.0 89.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.00 stddev:2.68 (2.9%) stddev-sans-first:2.83 06:14:22 INFO - PROCESS | 1672 | Values: 93.0 93.0 93.0 101.0 92.0 92.0 93.0 93.0 92.0 93.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:86.00 stddev:16.89 (19.6%) stddev-sans-first:17.67 06:14:22 INFO - PROCESS | 1672 | Values: 83.0 86.0 87.0 86.0 84.0 83.0 86.0 82.0 94.0 138.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.50 stddev:1.29 (2.4%) stddev-sans-first:1.12 06:14:22 INFO - PROCESS | 1672 | Values: 57.0 54.0 54.0 55.0 57.0 54.0 54.0 56.0 54.0 54.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.34 (4.7%) stddev-sans-first:2.55 06:14:22 INFO - PROCESS | 1672 | Values: 65.0 70.0 71.0 78.0 75.0 72.0 71.0 72.0 71.0 71.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:87.00 stddev:19.10 (21.9%) stddev-sans-first:19.89 06:14:22 INFO - PROCESS | 1672 | Values: 87.0 87.0 87.0 86.0 83.0 97.0 105.0 107.0 146.0 87.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:144.00 stddev:10.47 (7.3%) stddev-sans-first:10.99 06:14:22 INFO - PROCESS | 1672 | Values: 137.0 149.0 147.0 143.0 128.0 142.0 125.0 161.0 145.0 136.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:164.50 stddev:8.55 (5.2%) stddev-sans-first:3.71 06:14:22 INFO - PROCESS | 1672 | Values: 189.0 162.0 163.0 173.0 166.0 162.0 164.0 165.0 160.0 164.0 06:14:22 INFO - PROCESS | 1672 | 06:14:22 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.43 (2.2%) stddev-sans-first:1.51 06:14:22 INFO - PROCESS | 1672 | Values: 66.0 66.0 69.0 67.0 66.0 64.0 66.0 65.0 67.0 68.0 06:14:22 INFO - PROCESS | 1672 | -------- Summary: end -------- 06:14:22 INFO - PROCESS | 1672 | 06:14:23 INFO - TEST-INFO | 1672: exit 0 06:14:23 INFO - TEST-OK | kraken | took 59348ms 06:14:23 INFO - SUITE-END | took 1175s 06:14:23 INFO - Completed test suite (00:19:35) 06:14:23 INFO - javascript benchmark 06:14:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.8813191538293, 3396.575428071491, 3421.3670470962875, 3433.502142189381, 3472.4444088945775, 5463.863403414916, 5510.228568000478, 5541.0580201365765, 5605.271314729084, 5630.802921897734, 5542.75057622407, 5649.039663257246, 5683.204351390808, 5698.458646428583, 5742.2247996520455, 5022.723750193738, 5029.094762942669, 5030.320906677606, 5041.798328066891, 5122.6158038147105, 5180.45605211453, 5188.7146206958605, 5229.60777941654, 5233.162693968966, 5240.737963101848, 5013.097642424362, 5055.241713742931, 5150.356205474316, 5153.1754919212935, 5164.431912489623, 4194.958050419487, 4199.727017743868, 5112.948870511284, 5120.948790512084, 5121.334226550524, 5000.74996250186, 5077.060743762416, 5080.669756465855, 5095.719735414551, 5096.159133742928, 4491.663125265618, 5487.945120548823, 5501.944980550184, 5508.14623733322, 5525.364583072957, 6087.624903920053, 6102.982862712703, 6103.450689437927, 6111.419415155553, 6119.357467465941, 6004.489618382443, 6031.215941927565, 6102.511799056065, 6105.45050945417, 6130.0, 5706.029974904276, 5717.656940583578, 5726.570507211975, 5740.885182296331, 5756.56825738063, 6273.811785646438, 6299.874002520016, 6302.747890084345, 6303.527235457359, 6314.4317011468265, 5814.9242390157115, 5856.736446859902, 5934.495567876693, 5937.554683398763, 5989.880202396015, 6118.0, 6120.44915957566, 6132.5707200495535, 6133.662648554283, 6147.784827531015, 5859.974504461702, 5989.401059893976, 6004.849878753066, 6034.577579569475, 6064.0, 5907.734151963172, 6022.096685497123, 6088.665123418166, 6094.512439004958, 6147.754089836357, 4812.494688057704, 4861.222204447342, 4867.1969125093965, 4896.779644915988, 4931.457539670633, 4320.330348795942, 4352.869413917524, 4357.564243575617, 4379.956200437955, 4412.801423935931, 5488.121900495901, 5505.201745746925, 5526.253955716007, 5539.556835453154, 5588.105903055492, 6150.876982460326, 6153.138560601519, 6162.845928851814, 6166.7224974876235, 6183.165272688219, 5544.584156188302, 5564.387917329091, 5566.276384070045, 5567.2762540870235, 5569.805056823073, 4456.376107344974, 4691.756684478616, 5256.159014557652, 5288.206768984606, 5293.973530132324, 6218.533609979269, 6242.469390101892, 6244.500439964701, 6248.968755156195, 6255.687215639291, 5533.667979921218, 5775.451332123442, 5978.3722709116655, 6096.573239873166, 6166.0, 6169.598976066542, 6205.2553693555255, 6211.534134939988, 6264.21697287839, 6286.811395658137, 6000.87998240024, 6033.75864965414, 6045.879082418415, 6048.029052887843, 6071.937410952977, 6009.639421634716, 6074.3925607438905, 6078.483328917092, 6131.509479241561, 6132.724027418688, 5832.096025116114, 5916.674582897982, 5927.199828023334, 5951.940480595139, 5989.101634754647, 5788.797392091256, 5838.241028666246, 5849.268841394825, 5900.055991041413, 5947.167396564571, 5651.350094739158, 5692.743826527899, 5710.571707122065, 5769.653820770767, 5777.191193232867, 5841.269841269841, 5857.355690873916, 5898.262717160355, 5899.823005309848, 5905.261842269716, 5776.277965254343, 5787.8553036174435, 5794.942050579441, 5795.8551036224435, 5800.0], "value": 5554.29194651634, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.3302307465683, 514.7837908078607, 519.1071357265502, 533.6051322021704, 543.2991441041057, 26811.195664130082, 42820.57179428205, 44019.0, 44024.11951760963, 44050.559494405046, 42195.0, 45030.0, 45076.971536280864, 45558.31662525065, 45629.543704562944, 8537.060923298432, 9492.193163581087, 9733.756656083619, 9740.561674724619, 9741.31810773246, 8630.266427353683, 11630.883691163064, 11890.524379024828, 11957.342346170957, 12240.571579994701, 11895.7620847583, 11975.46110425033, 11976.323314735944, 11989.220700654474, 11989.820152697717, 8654.394192406535, 11702.063834893188, 11780.646580602595, 11907.64277071685, 11941.343226122559, 11633.941830290794, 11692.356920369377, 11716.062714982781, 11734.295942243407, 11742.471588778526, 34131.63473461058, 37530.62469375299, 39571.80214098911, 39689.80155099235, 39966.0, 3565.447355659851, 3611.331903597843, 3701.4262789267705, 3702.1114932416026, 3791.6587507124214, 32204.0, 32878.67121328804, 32925.847595334286, 33145.01694847451, 33303.0, 3108.782385233035, 3152.590163278782, 3188.8846865855676, 3196.328770958102, 3249.772515923886, 363657.0, 369942.0, 395907.0, 396779.0, 396828.01585992175, 60104.69947650234, 63028.68485657634, 63534.729305414556, 63637.0, 63794.68102659457, 35505.28989420249, 36361.818190908874, 36389.81805090958, 37525.0, 37724.62275377266, 16868.156592170344, 17018.319267229173, 17025.31898724037, 17031.914840425718, 17051.658966820592, 11760.294382337088, 11935.76128477443, 12790.0, 12887.871121288854, 13051.0, 20081.79918200799, 20094.89952550228, 20228.393148205283, 20318.28885989013, 20345.694814577793, 6914.239433662293, 8002.0, 8042.879356809653, 8045.637946292431, 8073.31376832964, 966.990330096704, 1033.6692258477365, 1042.7966546523355, 1083.6207327435334, 1095.3569645531702, 28486.572701409495, 29652.703472964993, 30980.0705978821, 31098.689013110034, 31234.0, 1100.4002818463957, 1101.3502033800096, 1123.1351859068634, 1158.6350299655583, 1188.5839956015325, 13662.18026918388, 15749.763753543477, 16043.759343609856, 16117.274722637509, 16184.676306473804, 676254.0, 679231.0, 687771.561142191, 687787.0, 688999.0, 61585.69207154025, 62113.68943155346, 62293.37706622967, 62312.0, 62513.0, 1265.949362025527, 1310.3775706539318, 1461.1306272767686, 1481.5259117082646, 1485.4578079001303, 31448.056558303288, 31643.683563164075, 31884.043478695676, 32169.517457238628, 32628.0, 51740.0, 52249.0, 52906.0, 52978.73510632577, 53018.46981530135, 551470.0, 553383.0, 553652.0, 553761.0, 554467.0, 23807.761922380556, 26757.197284081507, 27476.313092172855, 27500.03749868745, 28287.0, 49153.0, 49353.25970110523, 50183.0, 50884.74557627188, 51104.23343649923, 39923.60076399199, 39967.60113396017, 40732.31556469292, 40851.182976340904, 40997.795011024755, 39828.20343593054, 40467.0, 40566.0, 41532.37701434542, 41803.58196417997, 39226.2154756909, 39497.80251098726, 39797.60202397939, 40005.79997099996, 40158.59841401548, 30777.692223077484, 31361.05916822499, 33478.83260583682, 35610.0, 36590.63409365872, 25931.351716207246, 25934.481310374063, 25999.960001599724, 26413.0, 26620.600690989264], "value": 20494.653128409045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.4930166133196, 390.5567181249281, 394.5718894998928, 394.8953527315262, 410.167360258675, 13707.871906608558, 19718.21127154914, 20222.494437639067, 20821.583568328642, 20849.166033358666, 16383.180840957933, 17428.477145685618, 19920.800791992075, 20523.589528209428, 20586.073626686797, 5890.88218235635, 6056.303525094627, 6057.0, 6097.115918191859, 6100.389961003909, 5997.88004239915, 6034.396560343953, 6036.2152920120325, 6050.515958723292, 6090.269167699882, 5095.566876815456, 5763.366029736726, 6000.669963152025, 6004.099385092228, 6004.219451471325, 5224.555912747421, 5744.023516002289, 5903.557233207505, 5985.43138401849, 6003.6397816131175, 5185.870353241162, 5229.477052294778, 5238.05714971305, 5239.083160446945, 5251.763670634792, 24524.877375613185, 24697.38256543583, 25524.0, 25585.232443026554, 25840.612390814153, 2607.99592157019, 2650.271175426754, 2810.971890281112, 2832.178668186244, 3088.7992280501694, 12783.872161278361, 14571.979961402705, 15174.620634484116, 15370.462033828762, 15815.920920395325, 1821.8360347568782, 1823.370937026737, 1888.7544619199552, 1891.290765962764, 1901.0969789350086, 109456.90543094547, 111717.0, 112605.0, 113917.43041284823, 115216.84783152062, 9727.610895564098, 10398.324108932895, 10549.261551691461, 10804.729881752863, 11048.281861678965, 12495.500539935267, 12974.0, 13891.513797017056, 13910.026298159235, 13961.86038139626, 7653.57905315213, 7986.0816006158875, 8214.876776848352, 8483.394557313117, 8620.181082797124, 7587.317141457295, 8353.20644538768, 8623.482591044558, 8669.176428239307, 8778.429402088845, 6670.5664131832245, 6760.18877734675, 6770.898436523456, 6784.389404953479, 6794.694238759268, 1851.5556266495944, 1883.1996401529295, 1883.839873610731, 1887.5186827358937, 1887.5375532994503, 909.8953620333614, 937.4562753602894, 1006.3861044762689, 1026.7946410717886, 1091.2197778588347, 20863.269785557433, 21172.682409763867, 21355.35933921985, 21373.46566335854, 21500.211332388368, 841.6927821345165, 894.7047474333455, 1012.5443550402348, 1022.9795404092025, 1052.9315594486334, 9412.682224488577, 10120.240981926385, 10594.629187978384, 11031.448427578589, 11143.387113708663, 583549.0, 585466.0, 587412.0, 611425.0, 614587.0, 129702.35148824195, 130644.3467782655, 136567.0, 137740.0, 137974.3101284487, 1045.968620941373, 1056.0706578211125, 1076.2251179150999, 1078.3745427651943, 1084.1976937066672, 15198.544043678707, 18208.635827283644, 18329.90835045816, 18390.356337528123, 18566.562432983857, 51446.485535145664, 53011.0, 53338.73330633322, 53500.0, 53598.46401536091, 80160.0, 81268.0, 82382.0, 83608.0, 84453.0, 7717.729879454192, 7997.560134192675, 8136.105028446985, 8188.5905704715715, 8202.384821138558, 7223.313785190398, 8578.528180950108, 8618.61216245283, 8826.293896488138, 8859.424137431046, 6195.411435913581, 7840.176781438095, 7849.0, 7915.050193976529, 8134.511929284025, 7530.698772049057, 7706.574283757523, 7931.405144614062, 7955.045394552691, 8099.352051835956, 5112.309838171799, 8027.83944321122, 8118.553479558681, 8224.259816616533, 8504.829903402022, 10559.947200264256, 11979.940100299442, 12260.816087758869, 12353.011759059433, 12491.562795302121, 11729.35488548138, 11894.702632434259, 12001.819972700241, 12016.879831201577, 12071.637850864488], "value": 9491.242410152367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.0334420921663, 1557.244736302893, 1567.0910871694414, 1811.510892059144, 1959.0400703655214, 7695.384369250461, 8384.077751447343, 8621.008584012841, 8668.959724833028, 8723.694670686527, 8344.582770861463, 8585.22732954034, 8601.612927418266, 8602.397832151752, 8604.39769216155, 2332.976670233297, 2363.515479326734, 2367.3844800351862, 2369.7037870266217, 2371.4071482129466, 2319.9188028419007, 2354.411397150712, 2367.621180611103, 2368.100121953655, 2374.489484760776, 2326.767323267668, 2347.0259842165483, 2356.140008896749, 2357.1278626907983, 2357.6227803551437, 2306.2158865985643, 2325.197806756666, 2330.417395651087, 2344.8241381896255, 2347.5445223961083, 2255.9323220303254, 2264.354658922199, 2264.4904896398343, 2268.739095004079, 2270.965935510969, 8416.074231834493, 8549.658013679446, 8644.394892357537, 8657.0, 8677.913220867773, 5126.436092029911, 5334.866628334284, 5335.146376579729, 5344.251804747339, 5345.224942383372, 3521.3485505181625, 3533.0460775590705, 3641.3809652359164, 3644.2893635741043, 3646.161382881952, 1056.2539783577315, 1056.7410984308817, 1059.6079450603327, 1065.488565488562, 1079.5735684404617, 11250.324980501196, 11519.020883224935, 11524.711882202928, 11528.077753779762, 11534.59628912984, 4611.930821037688, 4644.8374306899095, 4721.15019296523, 4764.356811830427, 4769.8569042928075, 10032.197424206191, 10036.347637403545, 10054.648087317055, 10062.345947513388, 10070.848937265946, 4848.272759086165, 5267.157254839284, 5318.627696061316, 5323.973380133075, 5328.307320048447, 4385.342198670225, 4560.270356742972, 4561.954380456219, 4714.38712967312, 4866.026794641086, 3244.5561725032135, 3359.4960755886814, 3583.211693427442, 3865.072382628205, 4071.470708807836, 1137.7440075982838, 1161.8083016302216, 1292.4959265886312, 1305.6344223617402, 1326.966825829362, 626.3298270850147, 753.9132999704995, 810.7648781853314, 912.7535565397305, 955.19763398744, 4974.303597496353, 5115.335006449138, 5454.972725136349, 5854.356020837705, 5973.163757074013, 498.6559274100859, 656.4354654996699, 1013.4578000769553, 1047.6542740895486, 1049.7585555322319, 3243.351329734063, 3960.2871483133313, 4026.7382620129597, 4050.6354428101613, 4094.529129150186, 9929.652462163935, 10222.284440089272, 10524.947505249567, 10532.36805791655, 10594.152467802558, 10426.999999999849, 10440.791184176274, 10620.150387968944, 10644.201684873666, 10644.627438039786, 7881.645325960346, 8009.31920786728, 8195.631196596167, 8203.097659257477, 8219.589020548949, 5897.262842144732, 6048.909266361096, 6070.6357618543025, 6091.604045737013, 6151.96924015377, 9832.0, 9857.260705446977, 9928.25538084661, 9958.253131015057, 9966.750831229278, 9754.317197796086, 9855.655052073429, 9981.700548983252, 10130.392176469435, 10217.846732298873, 7448.994385757854, 7459.0, 7569.394448444001, 7585.658645360862, 7588.544687318778, 8083.0, 8085.272325490734, 8129.9187008129165, 8191.918080819354, 8192.713255036046, 7350.411967042731, 7377.299156580117, 7392.039034925425, 7510.098788145458, 7529.88705169412, 6117.265928088658, 7150.320719531845, 7256.854862902606, 7372.225916278928, 7412.925870741439, 6903.240643529108, 6961.721531138698, 6987.371136597731, 6989.5107342485535, 6996.230414654486, 4738.50245724194, 4839.0, 4839.177339852304, 4964.900701986012, 4977.104121258209, 4815.783289752039, 4904.460509343898, 5000.2499625056535, 5038.370203724535, 5169.741512924264], "value": 4511.790716846477, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.0985398002881, 310.95646609474665, 315.05483549351953, 318.78959886474934, 321.20181349346853, 4472.306792447168, 4909.5581397674205, 5255.76349064292, 5295.338082739658, 5313.787448502056, 4389.692721509496, 4666.673332866701, 5207.947920520794, 5222.608304377168, 5243.4007627673545, 3285.3265080658466, 3687.1335236219466, 3700.703943684499, 3975.0, 3991.361382178852, 2560.7695307422327, 3774.226283611853, 3886.3587508061137, 3981.0, 4026.8389264429534, 3001.039667306463, 3649.835757390924, 3919.412088186766, 4013.257547353749, 4029.1538776856896, 3437.295354452344, 3702.648248416396, 3986.4618276532588, 4022.7529465865528, 4024.0, 3311.9503207451908, 3445.4831775233665, 3459.221675123102, 3459.567554055743, 3463.341965026662, 4405.634253381462, 5027.396712394499, 5032.924506132411, 5078.26365177051, 5120.15517439622, 4069.04377471294, 4520.886977825548, 4578.862634120982, 4632.86101416958, 4652.464966528859, 4929.176204804209, 5263.894722105575, 5547.365001624236, 5596.552275817925, 5616.691081990489, 3565.732570057256, 3646.9635303647156, 3782.829772660237, 3811.0, 3823.2735780201674, 4568.977155114203, 5598.468145526169, 5686.9715651421475, 5823.825285241407, 5854.2389489366105, 3790.3177428062663, 3831.524862927737, 3837.1174629835295, 3854.2291541691775, 3855.5566109897723, 3604.441311596707, 3881.3401721706814, 3904.7657140571655, 3905.0, 3912.2981503046267, 4258.254805409041, 4264.528737585528, 4282.207791558571, 4519.717542641011, 4563.771811409416, 4563.657725670588, 4695.741734204583, 5324.680519168863, 5583.4137415571595, 5663.518600918886, 1300.387519475862, 1325.4101924643635, 1330.9334533273297, 1341.53716967646, 1343.3619030960215, 769.3921801776646, 800.9759707208793, 815.8409110223449, 820.2945466898582, 821.0762891746784, 3697.8890633281044, 4151.252774500558, 4151.896202594899, 4338.761368124783, 4354.127725078183, 4230.450041494586, 4512.638988880882, 4782.832600858953, 4802.687825291345, 4840.878978025507, 3933.347993842593, 3953.003733114816, 4292.828286868553, 4335.550414016071, 4364.54172311909, 2601.375669839225, 2679.8928042878065, 2713.796465265113, 2950.9376624415195, 3086.429010632995, 5283.282933046775, 5615.691136987505, 5984.700764961734, 5993.190919225936, 6030.758769649165, 5373.005993891143, 5907.202527658711, 5932.0, 5933.970330148321, 5977.372375900555, 5462.3445186577865, 5723.942760572424, 5788.710564471844, 5963.5825492216, 5982.192404025488, 4391.978040109779, 5241.449647786927, 5290.550303224269, 5547.528460080777, 5766.884662306814, 5305.6920239695855, 5655.660660360391, 5691.861627674399, 5705.885882282248, 5706.771729130955, 4873.610111191026, 5556.527695145958, 5580.274564306615, 5808.854778630568, 5848.473637372486, 3277.885274015398, 3696.667299943018, 3713.3872910970467, 3716.4239542870896, 3739.4016957286703, 5008.924866127084, 5753.884922301447, 5758.62568933018, 5784.681842498703, 5787.710614469175, 4928.975355123202, 5121.505095374973, 5186.507281808223, 5499.972500137474, 5581.799913018721, 5123.745551957127, 5548.9722551388595, 5559.8610034747835, 5564.4991950724625, 5568.888622227614, 4629.699069560468, 5020.021095886267, 5021.372328458942, 5267.367915850123, 5438.755256013568, 4988.5260900214425, 5507.366652834975, 5524.972375138099, 5529.364123125731, 5536.252605898152, 4824.49342818999, 5004.47453017428, 5478.91781623284, 5490.505854473116, 5527.50252477279], "value": 3941.4173006194314, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8164877131945, 780.6331024418521, 794.8410317936414, 827.7765003449073, 828.329053466692, 15541.456049038286, 15609.141497217648, 16056.438024669138, 16063.0, 16108.436204732836, 14375.353109110089, 14509.492167774104, 14518.491852785155, 14519.78220326696, 14537.200453975054, 693.9971740834496, 720.0243669827387, 722.1803253307481, 723.6381809095452, 898.9910100898989, 710.133636962907, 712.4727701500877, 876.1151237250391, 910.7632015675906, 915.7985243246509, 961.2502248246344, 967.2890425537224, 968.2979839616257, 968.9612415503371, 970.4177493503877, 957.0859828863419, 961.2838435365627, 962.870012548304, 963.720521048895, 964.3731574476576, 538.0610834094491, 689.5173378634976, 711.2638419236066, 717.3292971072012, 719.6905330707793, 15012.549623511311, 15064.472743454036, 15065.171415572138, 15066.698666026618, 15074.54776356711, 13830.0, 14187.219702916336, 14258.144511329354, 14324.713505729931, 14374.425022999067, 12924.224546527144, 13007.94336297329, 13079.411426485736, 13132.6716832079, 13169.078164528486, 4589.1968905441745, 4666.929996050062, 4694.342792009122, 4832.4684284728655, 4853.247746599282, 87480.12519874783, 97893.0, 99151.0, 100103.0, 101932.49033754783, 10187.439690817077, 10251.179905607534, 10379.948100259451, 10424.322419042737, 10877.728056798485, 21484.0, 21500.462488437915, 21516.677249841265, 21521.246756363453, 21559.78440215578, 8228.136045715113, 8262.710805121791, 8268.793280168044, 8318.376121790769, 8495.0, 4333.978330108329, 4415.324180620578, 4469.821207151743, 4479.260921947842, 4506.954930450654, 3523.2601153757996, 3641.417373220272, 3642.635736426389, 3644.890653280406, 3645.3620616392027, 662.9767958121442, 673.9831504212434, 674.2583158525582, 687.4637782529635, 689.5862482510454, 525.4666513488812, 565.4712843491349, 582.9970850145722, 627.2535682537857, 701.698269744015, 9321.300902432346, 9631.036896310314, 9684.32209745311, 9729.518972661926, 9772.560234789451, 686.4817063117315, 689.5552368722147, 693.1508901595505, 709.7551344786041, 710.9502334836512, 4518.209313370147, 4559.475660299042, 4569.497355290915, 4628.490866004737, 4732.763361831895, 80139.0, 142979.0, 145904.5409545912, 148329.0, 148743.0, 116277.0, 117906.0, 118995.40502297433, 119691.40154299173, 120525.0, 72127.6393618039, 72747.63626181835, 100821.4876776849, 110422.44788776165, 110889.89110108795, 8849.601767920587, 8852.955735221283, 8932.252984455527, 9059.956323208049, 9221.354505184572, 42474.575254248295, 48511.0, 60209.69895150672, 60452.39547604468, 62214.68892655508, 58482.0, 81254.18745812702, 82789.17210827814, 83071.0, 87645.56177219328, 10357.844632330673, 13145.0, 13286.136401133896, 13297.335133243105, 13706.451741930212, 14674.853251467775, 14805.925970370079, 15082.472113475975, 15143.0, 15492.690146196788, 11969.401529923643, 13681.110727802661, 13885.166889986633, 13955.162690238618, 14232.928835355757, 13572.457101715821, 13699.178049317074, 13931.721365572834, 14301.785473217702, 14320.856791431952, 13501.257430841399, 13509.797353039909, 13680.521181758591, 13681.179129252278, 13767.155372609386, 11131.332120072822, 11157.888421115686, 11384.43077846121, 11421.0, 11714.004309633776, 11241.71895702614, 11314.717132071435, 11447.656570302905, 11487.770244594894, 11590.94204528972], "value": 7793.566123557556, "unit": "score"}], "name": "dromaeo_css", "value": 7287.243380833076, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 112.0, 111.0, 111.0, 111.0, 110.0, 110.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 113.0, 118.0, 168.0, 119.0, 118.0, 139.0, 117.0, 150.0, 118.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 181.0, 181.0, 183.0, 181.0, 180.0, 184.0, 181.0, 184.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 59.0, 124.0, 80.0, 84.0, 58.0, 67.0, 94.0, 55.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 128.0, 124.0, 102.0, 70.0, 69.0, 94.0, 70.0, 91.0, 117.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 108.0, 114.0, 74.0, 120.0, 134.0, 130.0, 86.0, 89.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 101.0, 92.0, 92.0, 93.0, 93.0, 92.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 87.0, 86.0, 84.0, 83.0, 86.0, 82.0, 94.0, 138.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 55.0, 57.0, 54.0, 54.0, 56.0, 54.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 71.0, 78.0, 75.0, 72.0, 71.0, 72.0, 71.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 86.0, 83.0, 97.0, 105.0, 107.0, 146.0, 87.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 149.0, 147.0, 143.0, 128.0, 142.0, 125.0, 161.0, 145.0, 136.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 162.0, 163.0, 173.0, 166.0, 162.0, 164.0, 165.0, 160.0, 164.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 67.0, 66.0, 64.0, 66.0, 65.0, 67.0, 68.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1482.2, "alertThreshold": 2.0}]} 06:14:23 INFO - Return code: 0 06:14:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:14:23 ERROR - # TBPL SUCCESS # 06:14:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:14:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:14:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:14:23 INFO - 2016-06-02 06:14:23.907 screenresolution[1676:9959] starting screenresolution argv=screenresolution get 06:14:23 INFO - 2016-06-02 06:14:23.909 screenresolution[1676:9959] Display 0: 1600x1200x32@60 06:14:23 INFO - 2016-06-02 06:14:23.915 screenresolution[1677:9962] starting screenresolution argv=screenresolution list 06:14:23 INFO - Available Modes on Display 0 06:14:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:14:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:14:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:14:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:14:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:14:24 INFO - Intel Iris: 06:14:24 INFO - Chipset Model: Intel Iris 06:14:24 INFO - Type: GPU 06:14:24 INFO - Bus: Built-In 06:14:24 INFO - VRAM (Dynamic, Max): 1536 MB 06:14:24 INFO - Vendor: Intel (0x8086) 06:14:24 INFO - Device ID: 0x0a2e 06:14:24 INFO - Revision ID: 0x0009 06:14:24 INFO - Displays: 06:14:24 INFO - Display: 06:14:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:14:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:14:24 INFO - Display Serial Number: 1600x1200 60 06:14:24 INFO - Main Display: Yes 06:14:24 INFO - Mirror: Off 06:14:24 INFO - Online: Yes 06:14:24 INFO - Rotation: Supported 06:14:24 INFO - Return code: 0 06:14:24 INFO - Running post-action listener: _resource_record_post_action 06:14:24 INFO - ##### 06:14:24 INFO - ##### Finished run-tests step (success) 06:14:24 INFO - ##### 06:14:24 INFO - Running post-run listener: _resource_record_post_run 06:14:24 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31587840; Write bytes: 697866240; Read time: 197; Write time: 1964 06:14:24 INFO - TinderboxPrint: CPU usage
53.6% 06:14:24 INFO - TinderboxPrint: I/O read bytes / time
31,587,840 / 197 06:14:24 INFO - TinderboxPrint: I/O write bytes / time
697,866,240 / 1,964 06:14:24 INFO - TinderboxPrint: CPU idle
2,247.6 (46.4%) 06:14:24 INFO - TinderboxPrint: CPU system
961.5 (19.8%) 06:14:24 INFO - TinderboxPrint: CPU user
1,636.5 (33.8%) 06:14:24 INFO - TinderboxPrint: Swap in / out
427,343,872 / 0 06:14:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378915328; Write bytes: 372241920; Read time: 28168; Write time: 818 06:14:24 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30629376; Write bytes: 295143936; Read time: 185; Write time: 1072 06:14:24 INFO - Running post-run listener: _upload_blobber_files 06:14:24 INFO - Blob upload gear active. 06:14:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:24 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 06:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:14:25 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:25 INFO - (blobuploader) - INFO - Done attempting. 06:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:26 INFO - (blobuploader) - INFO - Done attempting. 06:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:14:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:29 INFO - (blobuploader) - INFO - Done attempting. 06:14:29 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:29 INFO - Return code: 0 06:14:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef43075f282ba97ce495c76a3aa723e64e50d6e817d06e657e3220a3b6dc1a928aeaded4da84150a03ed18878701ac13e4df82afece928b8c212c094a91e114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863b89a0ebd0a2178ff43dc3451a8ccf3eb8dca458e3932d582e19e0d698d70a68aad80abeee1e3336ee630db04b8e0458522ee2fde82868ba2b8876c3892cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8328cd080b0f37360ba7eada158fe4f8dab82fca78151bcd9638dbaffad7f2d499d920e0693c41cdd63385f6920fd6cd99ebf35334b3949df1ed5e2142068a95"} 06:14:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:29 INFO - Contents: 06:14:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef43075f282ba97ce495c76a3aa723e64e50d6e817d06e657e3220a3b6dc1a928aeaded4da84150a03ed18878701ac13e4df82afece928b8c212c094a91e114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863b89a0ebd0a2178ff43dc3451a8ccf3eb8dca458e3932d582e19e0d698d70a68aad80abeee1e3336ee630db04b8e0458522ee2fde82868ba2b8876c3892cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8328cd080b0f37360ba7eada158fe4f8dab82fca78151bcd9638dbaffad7f2d499d920e0693c41cdd63385f6920fd6cd99ebf35334b3949df1ed5e2142068a95"} 06:14:29 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:29 INFO - Copying logs to upload dir... 06:14:29 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.527373 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-06-02 06:14:29.131692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:14:29.135826) ========= 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.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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/Firefox/sha512/0ef43075f282ba97ce495c76a3aa723e64e50d6e817d06e657e3220a3b6dc1a928aeaded4da84150a03ed18878701ac13e4df82afece928b8c212c094a91e114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863b89a0ebd0a2178ff43dc3451a8ccf3eb8dca458e3932d582e19e0d698d70a68aad80abeee1e3336ee630db04b8e0458522ee2fde82868ba2b8876c3892cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8328cd080b0f37360ba7eada158fe4f8dab82fca78151bcd9638dbaffad7f2d499d920e0693c41cdd63385f6920fd6cd99ebf35334b3949df1ed5e2142068a95"} build_url:https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011067 build_url: 'https://queue.taskcluster.net/v1/task/HNwPjWBETnWF8PAaWndqhQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ef43075f282ba97ce495c76a3aa723e64e50d6e817d06e657e3220a3b6dc1a928aeaded4da84150a03ed18878701ac13e4df82afece928b8c212c094a91e114", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863b89a0ebd0a2178ff43dc3451a8ccf3eb8dca458e3932d582e19e0d698d70a68aad80abeee1e3336ee630db04b8e0458522ee2fde82868ba2b8876c3892cfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8328cd080b0f37360ba7eada158fe4f8dab82fca78151bcd9638dbaffad7f2d499d920e0693c41cdd63385f6920fd6cd99ebf35334b3949df1ed5e2142068a95"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:14:29.166392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:14:29.166733) ========= 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.GJpga8GeZT/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.6aaWWzGEPD/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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.005214 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:14:29.215326) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:14:29.215663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:14:29.216016) ========= ========= Total master_lag: 0.17 =========