builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1464933680.05 results: success (0) buildid: 20160602205209 builduid: ba23e03f45f7424dac4f9df69ecbd33b revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.052614) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.053064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.053380) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.092959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.093268) ========= 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.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:01:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.2M=0.001s 2016-06-02 23:01:20 (17.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128113 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.242019) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.242339) ========= 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.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.026204 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.290797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.291189) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a39da695528a04705d25d0159d6250917c95eee0 --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 a39da695528a04705d25d0159d6250917c95eee0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:01:20,377 truncating revision to first 12 chars 2016-06-02 23:01:20,377 Setting DEBUG logging. 2016-06-02 23:01:20,377 attempt 1/10 2016-06-02 23:01:20,377 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a39da695528a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 23:01:20,721 unpacking tar archive at: mozilla-central-a39da695528a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.625734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.936572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.936904) ========= script_repo_revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.937317) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.937633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 23:01:20.953708) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-06-02 23:01:20.954033) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:01:21 INFO - MultiFileLogger online at 20160602 23:01:21 in /builds/slave/test 23:01:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 23:01:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:01:21 INFO - {'append_to_log': False, 23:01:21 INFO - 'base_work_dir': '/builds/slave/test', 23:01:21 INFO - 'blob_upload_branch': 'Firefox', 23:01:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:01:21 INFO - 'branch': 'Firefox', 23:01:21 INFO - 'buildbot_json_path': 'buildprops.json', 23:01:21 INFO - 'config_files': ('talos/mac_config.py',), 23:01:21 INFO - 'default_actions': ('clobber', 23:01:21 INFO - 'read-buildbot-config', 23:01:21 INFO - 'download-and-extract', 23:01:21 INFO - 'populate-webroot', 23:01:21 INFO - 'create-virtualenv', 23:01:21 INFO - 'install', 23:01:21 INFO - 'run-tests'), 23:01:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:01:21 INFO - 'download_minidump_stackwalk': True, 23:01:21 INFO - 'download_symbols': 'ondemand', 23:01:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:01:21 INFO - 'tooltool.py': '/tools/tooltool.py', 23:01:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:01:21 INFO - '/tools/misc-python/virtualenv.py')}, 23:01:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:01:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:01:21 INFO - 'installer_path': 'installer.exe', 23:01:21 INFO - 'log_level': 'info', 23:01:21 INFO - 'log_name': 'talos', 23:01:21 INFO - 'log_to_console': True, 23:01:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:01:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:01:21 INFO - 'opt_config_files': (), 23:01:21 INFO - 'pip_index': False, 23:01:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:01:21 INFO - 'cmd': ('bash', 23:01:21 INFO - '-c', 23:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:01:21 INFO - 'enabled': True, 23:01:21 INFO - 'halt_on_failure': False, 23:01:21 INFO - 'name': 'check_screen_resolution'},), 23:01:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:01:21 INFO - 'cmd': ('bash', 23:01:21 INFO - '-c', 23:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:01:21 INFO - 'enabled': True, 23:01:21 INFO - 'halt_on_failure': False, 23:01:21 INFO - 'name': 'check_screen_resolution'},), 23:01:21 INFO - 'run_cmd_checks_enabled': True, 23:01:21 INFO - 'sps_profile': False, 23:01:21 INFO - 'sps_profile_interval': 0, 23:01:21 INFO - 'suite': 'dromaeojs-e10s', 23:01:21 INFO - 'system_bits': '32', 23:01:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:01:21 INFO - 'title': 't-yosemite-r7-0314', 23:01:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:01:21 INFO - 'use_talos_json': True, 23:01:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:01:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:01:21 INFO - 'work_dir': 'build'} 23:01:21 INFO - ##### 23:01:21 INFO - ##### Running clobber step. 23:01:21 INFO - ##### 23:01:21 INFO - Running pre-action listener: _resource_record_pre_action 23:01:21 INFO - Running main action method: clobber 23:01:21 INFO - rmtree: /builds/slave/test/build 23:01:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:01:22 INFO - Running post-action listener: _resource_record_post_action 23:01:22 INFO - ##### 23:01:22 INFO - ##### Finished clobber step (success) 23:01:22 INFO - ##### 23:01:22 INFO - ##### 23:01:22 INFO - ##### Running read-buildbot-config step. 23:01:22 INFO - ##### 23:01:22 INFO - Running pre-action listener: _resource_record_pre_action 23:01:22 INFO - Running main action method: read_buildbot_config 23:01:22 INFO - Using buildbot properties: 23:01:22 INFO - { 23:01:22 INFO - "project": "", 23:01:22 INFO - "product": "firefox", 23:01:22 INFO - "script_repo_revision": "production", 23:01:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:01:22 INFO - "repository": "", 23:01:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:01:22 INFO - "buildid": "20160602205209", 23:01:22 INFO - "pgo_build": "False", 23:01:22 INFO - "basedir": "/builds/slave/test", 23:01:22 INFO - "buildnumber": 18, 23:01:22 INFO - "slavename": "t-yosemite-r7-0314", 23:01:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 23:01:22 INFO - "platform": "yosemite_r7", 23:01:22 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:01:22 INFO - "branch": "mozilla-central", 23:01:22 INFO - "repo_path": "mozilla-central", 23:01:22 INFO - "revision": "a39da695528a04705d25d0159d6250917c95eee0", 23:01:22 INFO - "stage_platform": "macosx64", 23:01:22 INFO - "builduid": "ba23e03f45f7424dac4f9df69ecbd33b", 23:01:22 INFO - "slavebuilddir": "test" 23:01:22 INFO - } 23:01:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 23:01:22 INFO - Running post-action listener: _resource_record_post_action 23:01:22 INFO - ##### 23:01:22 INFO - ##### Finished read-buildbot-config step (success) 23:01:22 INFO - ##### 23:01:22 INFO - ##### 23:01:22 INFO - ##### Running download-and-extract step. 23:01:22 INFO - ##### 23:01:22 INFO - Running pre-action listener: _resource_record_pre_action 23:01:22 INFO - Running main action method: download_and_extract 23:01:22 INFO - mkdir: /builds/slave/test/build/tests 23:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:22 INFO - https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:01:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:01:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:01:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:24 INFO - Downloaded 1199 bytes. 23:01:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 23:01:24 INFO - Using the following test package requirements: 23:01:24 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:01:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 23:01:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 23:01:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'jsshell-mac.zip'], 23:01:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 23:01:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:01:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 23:01:24 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 23:01:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 23:01:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:01:24 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 23:01:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:24 INFO - https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:01:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:01:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:01:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:39 INFO - Downloaded 18697148 bytes. 23:01:39 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'] 23:01:39 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 23:01:42 INFO - Return code: 0 23:01:42 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 23:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:42 INFO - https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:43 INFO - Downloaded 18697148 bytes. 23:01:43 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'] 23:01:43 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 23:01:46 INFO - Return code: 0 23:01: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')]} 23:01:46 INFO - https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:01:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:01:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:01:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:47 INFO - Downloaded 10834055 bytes. 23:01:47 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'] 23:01:47 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 23:01:48 INFO - Return code: 0 23:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:48 INFO - https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 23:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKDvLZTrT-yZteCozg5pcA/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 23:01:54 INFO - Downloaded 111094380 bytes. 23:01:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:01:54 INFO - mkdir: /builds/slave/test/properties 23:01:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:01:54 INFO - Writing to file /builds/slave/test/properties/build_url 23:01:54 INFO - Contents: 23:01:54 INFO - build_url:https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:01:55 INFO - Running post-action listener: _resource_record_post_action 23:01:55 INFO - Running post-action listener: set_extra_try_arguments 23:01:55 INFO - ##### 23:01:55 INFO - ##### Finished download-and-extract step (success) 23:01:55 INFO - ##### 23:01:55 INFO - ##### 23:01:55 INFO - ##### Running populate-webroot step. 23:01:55 INFO - ##### 23:01:55 INFO - Running pre-action listener: _resource_record_pre_action 23:01:55 INFO - Running main action method: populate_webroot 23:01:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:01:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:01:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:01:55 INFO - '--tppagecycles', 23:01:55 INFO - '7'], 23:01:55 INFO - 'tests': ['tsvgm']}}, 23:01:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:01:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:01:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:01:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:01:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:01:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['damp', 'tps']}, 23:01:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'tests': ['damp', 'tps']}, 23:01:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['dromaeo_dom']}, 23:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:01:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['a11yr', 23:01:55 INFO - 'ts_paint', 23:01:55 INFO - 'tpaint', 23:01:55 INFO - 'sessionrestore', 23:01:55 INFO - 'sessionrestore_no_auto_restore', 23:01:55 INFO - 'tabpaint']}, 23:01:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['tsvgx', 23:01:55 INFO - 'tsvgr_opacity', 23:01:55 INFO - 'tart', 23:01:55 INFO - 'tscrollx', 23:01:55 INFO - 'cart']}, 23:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:01:55 INFO - 'tsvgr_opacity', 23:01:55 INFO - 'tart', 23:01:55 INFO - 'tscrollx', 23:01:55 INFO - 'cart']}, 23:01:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'talos_options': ['--disable-e10s'], 23:01:55 INFO - 'tests': ['tp5o']}, 23:01:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'tests': ['tp5o']}, 23:01:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'talos_options': ['--disable-e10s', 23:01:55 INFO - '--xperf_path', 23:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:01:55 INFO - 'tests': ['tp5n']}, 23:01:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:01:55 INFO - 'talos_options': ['--xperf_path', 23:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:01:55 INFO - 'tests': ['tp5n']}}, 23:01:55 INFO - 'talos.zip': {'path': '', 23:01:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:01:55 INFO - Running post-action listener: _resource_record_post_action 23:01:55 INFO - ##### 23:01:55 INFO - ##### Finished populate-webroot step (success) 23:01:55 INFO - ##### 23:01:55 INFO - ##### 23:01:55 INFO - ##### Running create-virtualenv step. 23:01:55 INFO - ##### 23:01:55 INFO - Running pre-action listener: _resource_record_pre_action 23:01:55 INFO - Running main action method: create_virtualenv 23:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 23:01:55 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 23:01:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:01:55 INFO - Using real prefix '/tools/python27' 23:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:01:56 INFO - Installing distribute.............................................................................................................................................................................................done. 23:01:58 INFO - Installing pip.................done. 23:01:58 INFO - Return code: 0 23:01:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:01:58 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')]} 23:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:58 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')]} 23:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:58 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:01:58 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 23:01:58 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 23:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:01:58 INFO - 'HOME': '/Users/cltbld', 23:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:01:58 INFO - 'LOGNAME': 'cltbld', 23:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:58 INFO - 'MOZ_NO_REMOTE': '1', 23:01:58 INFO - 'NO_EM_RESTART': '1', 23:01:58 INFO - 'PAGER': '/bin/cat', 23:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:58 INFO - 'PWD': '/builds/slave/test', 23:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:01:58 INFO - 'SHELL': '/bin/bash', 23:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:01:58 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:01:58 INFO - 'USER': 'cltbld', 23:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:58 INFO - 'XPC_FLAGS': '0x0', 23:01:58 INFO - 'XPC_SERVICE_NAME': '0', 23:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:59 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 23:01:59 INFO - Cleaning up... 23:01:59 INFO - Return code: 0 23:01:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:01: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')]} 23:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01: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')]} 23:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:01:59 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:01:59 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 23:01:59 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 23:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:01:59 INFO - 'HOME': '/Users/cltbld', 23:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:01:59 INFO - 'LOGNAME': 'cltbld', 23:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:01:59 INFO - 'MOZ_NO_REMOTE': '1', 23:01:59 INFO - 'NO_EM_RESTART': '1', 23:01:59 INFO - 'PAGER': '/bin/cat', 23:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:01:59 INFO - 'PWD': '/builds/slave/test', 23:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:01:59 INFO - 'SHELL': '/bin/bash', 23:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:01:59 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:01:59 INFO - 'USER': 'cltbld', 23:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:01:59 INFO - 'XPC_FLAGS': '0x0', 23:01:59 INFO - 'XPC_SERVICE_NAME': '0', 23:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:01:59 INFO - Downloading/unpacking psutil>=3.1.1 23:01:59 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 23:01:59 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 23:01:59 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 23:01:59 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 23:01:59 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 23:01:59 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 23:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:02:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:02 INFO - Installing collected packages: psutil 23:02:02 INFO - Running setup.py install for psutil 23:02:02 INFO - building 'psutil._psutil_osx' extension 23:02:02 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 23:02:03 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 23:02:03 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 23:02:03 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 23:02:03 INFO - building 'psutil._psutil_posix' extension 23:02:03 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 23:02:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:02:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:02:03 INFO - ^ 23:02:03 INFO - 1 warning generated. 23:02:03 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 23:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:03 INFO - Successfully installed psutil 23:02:03 INFO - Cleaning up... 23:02:03 INFO - Return code: 0 23:02:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:02:03 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')]} 23:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:03 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')]} 23:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:03 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:03 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 23:02:03 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 23:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:03 INFO - 'HOME': '/Users/cltbld', 23:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:03 INFO - 'LOGNAME': 'cltbld', 23:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:03 INFO - 'MOZ_NO_REMOTE': '1', 23:02:03 INFO - 'NO_EM_RESTART': '1', 23:02:03 INFO - 'PAGER': '/bin/cat', 23:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:03 INFO - 'PWD': '/builds/slave/test', 23:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:03 INFO - 'SHELL': '/bin/bash', 23:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:03 INFO - 'USER': 'cltbld', 23:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:03 INFO - 'XPC_FLAGS': '0x0', 23:02:03 INFO - 'XPC_SERVICE_NAME': '0', 23:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:02:03 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 23:02:03 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 23:02:03 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 23:02:03 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 23:02:03 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 23:02:03 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 23:02:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:02:06 INFO - Installing collected packages: mozsystemmonitor 23:02:06 INFO - Running setup.py install for mozsystemmonitor 23:02:06 INFO - Successfully installed mozsystemmonitor 23:02:06 INFO - Cleaning up... 23:02:06 INFO - Return code: 0 23:02:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:06 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:06 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 23:02:06 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 23:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:06 INFO - 'HOME': '/Users/cltbld', 23:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:06 INFO - 'LOGNAME': 'cltbld', 23:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:06 INFO - 'MOZ_NO_REMOTE': '1', 23:02:06 INFO - 'NO_EM_RESTART': '1', 23:02:06 INFO - 'PAGER': '/bin/cat', 23:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:06 INFO - 'PWD': '/builds/slave/test', 23:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:06 INFO - 'SHELL': '/bin/bash', 23:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:06 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:06 INFO - 'USER': 'cltbld', 23:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:06 INFO - 'XPC_FLAGS': '0x0', 23:02:06 INFO - 'XPC_SERVICE_NAME': '0', 23:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:06 INFO - Downloading/unpacking blobuploader==1.2.4 23:02:06 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 23:02:06 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 23:02:06 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 23:02:06 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 23:02:06 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 23:02:06 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 23:02:09 INFO - Downloading blobuploader-1.2.4.tar.gz 23:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:02:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:02:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:09 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 23:02:10 INFO - Downloading docopt-0.6.1.tar.gz 23:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:02:10 INFO - Installing collected packages: blobuploader, requests, docopt 23:02:10 INFO - Running setup.py install for blobuploader 23:02:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:02:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:02:10 INFO - Running setup.py install for requests 23:02:11 INFO - Running setup.py install for docopt 23:02:11 INFO - Successfully installed blobuploader requests docopt 23:02:11 INFO - Cleaning up... 23:02:11 INFO - Return code: 0 23:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:11 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:11 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 23:02:11 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 23:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:11 INFO - 'HOME': '/Users/cltbld', 23:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:11 INFO - 'LOGNAME': 'cltbld', 23:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:11 INFO - 'MOZ_NO_REMOTE': '1', 23:02:11 INFO - 'NO_EM_RESTART': '1', 23:02:11 INFO - 'PAGER': '/bin/cat', 23:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:11 INFO - 'PWD': '/builds/slave/test', 23:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:11 INFO - 'SHELL': '/bin/bash', 23:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:11 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:11 INFO - 'USER': 'cltbld', 23:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:11 INFO - 'XPC_FLAGS': '0x0', 23:02:11 INFO - 'XPC_SERVICE_NAME': '0', 23:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:02:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-jn1TX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:02:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-_pY4Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:02:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-B88tKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:02:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-rb8dQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-61xS5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0NONKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-iQZ3o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-o_1i_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Z1pqze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qmcugY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-tkIAbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-f3ogNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-dd5Aw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:02:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2552fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-iDSf8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:02:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-h5RiVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:02:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-3D6CrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:02:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:02:13 INFO - Running setup.py install for manifestparser 23:02:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:02:13 INFO - Running setup.py install for mozcrash 23:02:13 INFO - Running setup.py install for mozdebug 23:02:13 INFO - Running setup.py install for mozdevice 23:02:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:02:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Running setup.py install for mozfile 23:02:14 INFO - Running setup.py install for mozhttpd 23:02:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Running setup.py install for mozinfo 23:02:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Running setup.py install for mozInstall 23:02:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Running setup.py install for mozleak 23:02:14 INFO - Running setup.py install for mozlog 23:02:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:02:14 INFO - Running setup.py install for moznetwork 23:02:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Running setup.py install for mozprocess 23:02:15 INFO - Running setup.py install for mozprofile 23:02:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Running setup.py install for mozrunner 23:02:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Running setup.py install for mozscreenshot 23:02:15 INFO - Running setup.py install for moztest 23:02:15 INFO - Running setup.py install for mozversion 23:02:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:02:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:02:15 INFO - Cleaning up... 23:02:15 INFO - Return code: 0 23:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:15 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')]} 23:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:15 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')]} 23:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:15 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:15 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 23:02:15 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 23:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:15 INFO - 'HOME': '/Users/cltbld', 23:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:15 INFO - 'LOGNAME': 'cltbld', 23:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:15 INFO - 'MOZ_NO_REMOTE': '1', 23:02:15 INFO - 'NO_EM_RESTART': '1', 23:02:15 INFO - 'PAGER': '/bin/cat', 23:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:15 INFO - 'PWD': '/builds/slave/test', 23:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:15 INFO - 'SHELL': '/bin/bash', 23:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:15 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:15 INFO - 'USER': 'cltbld', 23:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:15 INFO - 'XPC_FLAGS': '0x0', 23:02:15 INFO - 'XPC_SERVICE_NAME': '0', 23:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-7y_iqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-NiyVqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Bw1LJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-NswyvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-axl7kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-DJyZG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-4xk_Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:02:16 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)) 23:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:02:16 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-dMjU8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-H7mxN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MHw3Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MgUvSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Vh5DAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mpn8Nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-yP7SDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-EY2xel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MxeluQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:02:17 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)) 23:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:02:17 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qrnQtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:02:18 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)) 23:02:18 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)) 23:02:18 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)) 23:02:18 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)) 23:02:18 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)) 23:02:18 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)) 23:02:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:02:18 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 23:02:18 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 23:02:18 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 23:02:18 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 23:02:18 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 23:02:18 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 23:02:20 INFO - Downloading blessings-1.6.tar.gz 23:02:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:02:20 INFO - Installing collected packages: blessings 23:02:20 INFO - Running setup.py install for blessings 23:02:20 INFO - Successfully installed blessings 23:02:20 INFO - Cleaning up... 23:02:20 INFO - Return code: 0 23:02:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:02:21 INFO - Reading from file tmpfile_stdout 23:02:21 INFO - Current package versions: 23:02:21 INFO - blessings == 1.6 23:02:21 INFO - blobuploader == 1.2.4 23:02:21 INFO - docopt == 0.6.1 23:02:21 INFO - manifestparser == 1.1 23:02:21 INFO - mozInstall == 1.12 23:02:21 INFO - mozcrash == 0.17 23:02:21 INFO - mozdebug == 0.1 23:02:21 INFO - mozdevice == 0.48 23:02:21 INFO - mozfile == 1.2 23:02:21 INFO - mozhttpd == 0.7 23:02:21 INFO - mozinfo == 0.9 23:02:21 INFO - mozleak == 0.1 23:02:21 INFO - mozlog == 3.2 23:02:21 INFO - moznetwork == 0.27 23:02:21 INFO - mozprocess == 0.22 23:02:21 INFO - mozprofile == 0.28 23:02:21 INFO - mozrunner == 6.11 23:02:21 INFO - mozscreenshot == 0.1 23:02:21 INFO - mozsystemmonitor == 0.3 23:02:21 INFO - moztest == 0.7 23:02:21 INFO - mozversion == 1.4 23:02:21 INFO - psutil == 3.1.1 23:02:21 INFO - requests == 1.2.3 23:02:21 INFO - wsgiref == 0.1.2 23:02:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:21 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')]} 23:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:21 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')]} 23:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:21 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:21 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 23:02:21 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 23:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:21 INFO - 'HOME': '/Users/cltbld', 23:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:21 INFO - 'LOGNAME': 'cltbld', 23:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:21 INFO - 'MOZ_NO_REMOTE': '1', 23:02:21 INFO - 'NO_EM_RESTART': '1', 23:02:21 INFO - 'PAGER': '/bin/cat', 23:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:21 INFO - 'PWD': '/builds/slave/test', 23:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:21 INFO - 'SHELL': '/bin/bash', 23:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:21 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:21 INFO - 'USER': 'cltbld', 23:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:21 INFO - 'XPC_FLAGS': '0x0', 23:02:21 INFO - 'XPC_SERVICE_NAME': '0', 23:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 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)) 23:02:21 INFO - Cleaning up... 23:02:21 INFO - Return code: 0 23:02:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:02:21 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')]} 23:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:21 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')]} 23:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:21 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 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:21 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 23:02:21 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 23:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:21 INFO - 'HOME': '/Users/cltbld', 23:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:21 INFO - 'LOGNAME': 'cltbld', 23:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:21 INFO - 'MOZ_NO_REMOTE': '1', 23:02:21 INFO - 'NO_EM_RESTART': '1', 23:02:21 INFO - 'PAGER': '/bin/cat', 23:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:21 INFO - 'PWD': '/builds/slave/test', 23:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:21 INFO - 'SHELL': '/bin/bash', 23:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:21 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:21 INFO - 'USER': 'cltbld', 23:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:21 INFO - 'XPC_FLAGS': '0x0', 23:02:21 INFO - 'XPC_SERVICE_NAME': '0', 23:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:21 INFO - Downloading/unpacking jsonschema 23:02:21 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 23:02:21 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 23:02:21 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 23:02:21 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 23:02:21 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 23:02:21 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 23:02:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:02:24 INFO - Downloading/unpacking functools32 (from jsonschema) 23:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:24 INFO - Downloading functools32-3.2.3-2.tar.gz 23:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:02:24 INFO - warning: no files found matching '*.txt' 23:02:24 INFO - no previously-included directories found matching 'build' 23:02:24 INFO - no previously-included directories found matching 'dist' 23:02:24 INFO - no previously-included directories found matching '.git*' 23:02:24 INFO - Installing collected packages: jsonschema, functools32 23:02:25 INFO - Running setup.py install for functools32 23:02:25 INFO - warning: no files found matching '*.txt' 23:02:25 INFO - no previously-included directories found matching 'build' 23:02:25 INFO - no previously-included directories found matching 'dist' 23:02:25 INFO - no previously-included directories found matching '.git*' 23:02:25 INFO - Successfully installed jsonschema functools32 23:02:25 INFO - Cleaning up... 23:02:25 INFO - Return code: 0 23:02:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aaafa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf395abb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, '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.k7jOcBOTKK/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.DHUKexNunN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 23:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:02:25 INFO - 'HOME': '/Users/cltbld', 23:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:02:25 INFO - 'LOGNAME': 'cltbld', 23:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:25 INFO - 'MOZ_NO_REMOTE': '1', 23:02:25 INFO - 'NO_EM_RESTART': '1', 23:02:25 INFO - 'PAGER': '/bin/cat', 23:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:25 INFO - 'PWD': '/builds/slave/test', 23:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:02:25 INFO - 'SHELL': '/bin/bash', 23:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:02:25 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:02:25 INFO - 'USER': 'cltbld', 23:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:25 INFO - 'XPC_FLAGS': '0x0', 23:02:25 INFO - 'XPC_SERVICE_NAME': '0', 23:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:25 INFO - Downloading/unpacking flake8 23:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:02:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:02:27 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 23:02:27 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 23:02:27 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 23:02:27 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 23:02:27 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 23:02:27 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 23:02:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:02:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:02:29 INFO - no previously-included directories found matching 'docs/_build' 23:02:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:02:29 INFO - Running setup.py install for pep8 23:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:02:29 INFO - no previously-included directories found matching 'docs/_build' 23:02:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:02:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:02:29 INFO - Cleaning up... 23:02:29 INFO - Return code: 0 23:02:29 INFO - Running post-action listener: _resource_record_post_action 23:02:29 INFO - Running post-action listener: _start_resource_monitoring 23:02:29 INFO - Starting resource monitoring. 23:02:29 INFO - ##### 23:02:29 INFO - ##### Finished create-virtualenv step (success) 23:02:29 INFO - ##### 23:02:29 INFO - ##### 23:02:29 INFO - ##### Running install step. 23:02:29 INFO - ##### 23:02:29 INFO - Running pre-action listener: _resource_record_pre_action 23:02:29 INFO - Running main action method: install 23:02:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:02:29 INFO - Reading from file tmpfile_stdout 23:02:29 INFO - Detecting whether we're running mozinstall >=1.0... 23:02:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:02:29 INFO - Reading from file tmpfile_stdout 23:02:29 INFO - Output received: 23:02:29 INFO - Usage: mozinstall [options] installer 23:02:29 INFO - Options: 23:02:29 INFO - -h, --help show this help message and exit 23:02:29 INFO - -d DEST, --destination=DEST 23:02:29 INFO - Directory to install application into. [default: 23:02:29 INFO - "/builds/slave/test"] 23:02:29 INFO - --app=APP Application being installed. [default: firefox] 23:02:29 INFO - mkdir: /builds/slave/test/build/application 23:02:29 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'] 23:02:29 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 23:03:03 INFO - Reading from file tmpfile_stdout 23:03:03 INFO - Output received: 23:03:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:03:03 INFO - Running post-action listener: _resource_record_post_action 23:03:03 INFO - ##### 23:03:03 INFO - ##### Finished install step (success) 23:03:03 INFO - ##### 23:03:03 INFO - ##### 23:03:03 INFO - ##### Running run-tests step. 23:03:03 INFO - ##### 23:03:03 INFO - Running pre-action listener: _resource_record_pre_action 23:03:03 INFO - Running main action method: run_tests 23:03:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:03:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:03:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:03:03 INFO - 2016-06-02 23:03:03.775 screenresolution[1837:9305] starting screenresolution argv=screenresolution get 23:03:03 INFO - 2016-06-02 23:03:03.778 screenresolution[1837:9305] Display 0: 1600x1200x32@60 23:03:03 INFO - 2016-06-02 23:03:03.784 screenresolution[1838:9307] starting screenresolution argv=screenresolution list 23:03:03 INFO - Available Modes on Display 0 23:03:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:03:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:03:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:03:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:03:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:03:04 INFO - Intel Iris: 23:03:04 INFO - Chipset Model: Intel Iris 23:03:04 INFO - Type: GPU 23:03:04 INFO - Bus: Built-In 23:03:04 INFO - VRAM (Dynamic, Max): 1536 MB 23:03:04 INFO - Vendor: Intel (0x8086) 23:03:04 INFO - Device ID: 0x0a2e 23:03:04 INFO - Revision ID: 0x0009 23:03:04 INFO - Displays: 23:03:04 INFO - Display: 23:03:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:03:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:03:04 INFO - Display Serial Number: 1600x1200 60 23:03:04 INFO - Main Display: Yes 23:03:04 INFO - Mirror: Off 23:03:04 INFO - Online: Yes 23:03:04 INFO - Rotation: Supported 23:03:04 INFO - Return code: 0 23:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:03:04 INFO - Python 2.7.3 23:03:04 INFO - Return code: 0 23:03:04 INFO - grabbing minidump binary from tooltool 23:03: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')]} 23:03:04 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 0x10a953d30>, '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 0x10a777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a94ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:03:04 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 23:03:04 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 23:03:04 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 23:03:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:03:04 INFO - Return code: 0 23:03:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:03:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:03:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:03:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:03:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:03:05 INFO - Return code: 0 23:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k7jOcBOTKK/Render', 23:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:03:05 INFO - 'HOME': '/Users/cltbld', 23:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:03:05 INFO - 'LOGNAME': 'cltbld', 23:03:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:03:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:05 INFO - 'MOZ_NO_REMOTE': '1', 23:03:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:03:05 INFO - 'NO_EM_RESTART': '1', 23:03:05 INFO - 'PAGER': '/bin/cat', 23:03:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:05 INFO - 'PWD': '/builds/slave/test', 23:03:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:03:05 INFO - 'SHELL': '/bin/bash', 23:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHUKexNunN/Listeners', 23:03:05 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 23:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:03:05 INFO - 'USER': 'cltbld', 23:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:05 INFO - 'XPC_FLAGS': '0x0', 23:03:05 INFO - 'XPC_SERVICE_NAME': '0', 23:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:03:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:03:05 INFO - mozversion application_buildid: 20160602205209 23:03:05 INFO - mozversion application_changeset: a39da695528a04705d25d0159d6250917c95eee0 23:03:05 INFO - mozversion application_display_name: Nightly 23:03:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:03:05 INFO - mozversion application_name: Firefox 23:03:05 INFO - mozversion application_remotingname: firefox 23:03:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:03:05 INFO - mozversion application_vendor: Mozilla 23:03:05 INFO - mozversion application_version: 49.0a1 23:03:05 INFO - mozversion platform_buildid: 20160602205209 23:03:05 INFO - mozversion platform_changeset: a39da695528a04705d25d0159d6250917c95eee0 23:03:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:03:05 INFO - mozversion platform_version: 49.0a1 23:03:05 INFO - using testdate: 1464933785 23:03:05 INFO - actual date: 1464933785 23:03:05 INFO - starting webserver on 'localhost:49270' 23:03:05 INFO - SUITE-START | Running 2 tests 23:03:05 INFO - TEST-START | dromaeo_css 23:03:05 INFO - operating with platform_type : mac_ 23:03:05 INFO - Initialising browser for dromaeo_css test... 23:03:05 INFO - TEST-INFO | started process 1856 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpyaPQa0/profile http://localhost:49270/getInfo.html) 23:03:08 INFO - PROCESS | 1856 | __metrics Screen width/height:1600/1200 23:03:08 INFO - PROCESS | 1856 | colorDepth:24 23:03:08 INFO - PROCESS | 1856 | Browser inner width/height: 1024/689 23:03:08 INFO - PROCESS | 1856 | __metrics 23:03:08 ERROR - PROCESS | 1856 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 23:03:09 INFO - TEST-INFO | 1856: exit 0 23:03:09 INFO - Browser initialized. 23:03:09 INFO - Running cycle 1/1 for dromaeo_css test... 23:03:09 INFO - TEST-INFO | started process 1860 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpyaPQa0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:21 INFO - PROCESS | 1860 | RSS: Main: 253411328 23:03:21 INFO - PROCESS | 1860 | 23:04:11 INFO - PROCESS | 1860 | 1464933851344 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] 23:06:21 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 23:06:22 INFO - PROCESS | 1860 | RSS: Main: 265654272 23:06:22 INFO - PROCESS | 1860 | 23:09:25 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:09:25 INFO - PROCESS | 1860 | RSS: Main: 277020672 23:09:25 INFO - PROCESS | 1860 | 23:12:27 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:12:28 INFO - PROCESS | 1860 | RSS: Main: 279142400 23:12:28 INFO - PROCESS | 1860 | 23:15:30 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:15:30 INFO - PROCESS | 1860 | RSS: Main: 280350720 23:15:30 INFO - PROCESS | 1860 | 23:18:33 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:18:34 INFO - PROCESS | 1860 | RSS: Main: 284176384 23:18:34 INFO - PROCESS | 1860 | 23:21:36 INFO - PROCESS | 1860 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:21:37 INFO - PROCESS | 1860 | RSS: Main: 284471296 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | __start_tp_report 23:21:37 INFO - PROCESS | 1860 | _x_x_mozilla_page_load 23:21:37 INFO - PROCESS | 1860 | _x_x_mozilla_page_load_details 23:21:37 INFO - PROCESS | 1860 | |i|pagename|runs| 23:21:37 INFO - PROCESS | 1860 | |0;dojo.html;2994.891890000699;3355.1947532592185;3423.2468856851483;3436.6047904490974;3456;5600.131979543172;5659.292588426451;5750.137479378095;5751.482366587006;5754.769809207637;5468.699221542814;5528.419515950827;5574.60977731559;5644.181593668915;5671.319441667006;5128.3589551305995;5146;5218.478152184789;5236.476352364771;5266.289050978126;202.2788281084107;5116.360454943132;5183;5247;5248.501392367719;4663.9766801166115;4764.857054288377;5167.48325167484;5185.974070129644;5232;5233.555147812441;5239.5546378557865;5248.5538729208065;5267.9736601316745;5269.104252277122;5015.22264049073;5104.2088476286235;5160.354955630546;5173.198154286091;5276.287701160332;4691.953080469186;5515.917261241085;5543.77824887004;5575.69333686647;5588.497035266805;5373.435789242112;6124.326324104345;6219.2847822500535;6231;6272.184615999937;6117.614269788186;6154.476869466101;6158.107074474221;6209.316975132731;6223.906641400383;5922.3781502942;5943.55423343251;5943.821685349447;5948.583599148018;5979.1629171915965;6114.785982490592;6158.261008678987;6298.874022519615;6329.177206963157;6333.081703153017;6066.332703402622;6078.878422431527;6091.0558863376245;6093.725782339805;6095.6952152392205;6130.877382452326;6140.631562106288;6157.876842463125;6165;6167.414095660905;6098.644029113846;6130.846728831815;6145.5698101133385;6160.537959653044;6220.595661282002;6169.321374648785;6200.3799620038535;6211.192544969125;6225.844353891099;6234.470070044097;4882.804687812448;4943.530364615356;4950.4059512858685;4950.554450099508;4969.850904472872;4353.717008394444;4412.823487060482;4453.287474004143;4488.865334039984;4507.8196872124745;5621.156826476062;5734.455226753452;5799.39106393831;5859.853503662357;5879.500242479352;6185.165002724664;6188.381161883775;6197.566170368031;6203.596766210182;6206;5631.324241091095;5794.739236734357;5818.970905145447;5825;5874.706264686749;5408.810691625774;5416.647917885325;5420.783168673288;5423.810166644149;5427.213054107132;6375.87248255023;6381.23425188971;6392.648404337713;6396.10454536356;6449.903251451232;5719.399463056319;6106.582013105306;6114.052321890114;6142.877142457215;6159.4456498915315;6327.4305312520255;6328.778492752732;6339.651319177305;6344.587601805868;6351.237851457855;5992.580519363777;6067.271927368744;6112.633242005494;6131;6194.0708893666315;6147.877042459215;6166.445019948226;6174.876502470015;6202.782902598387;6203.596766210182;5377.139657654757;5690.687012214368;6123.7550497981365;6125.479334256638;6141.539384546088;4877.292792545132;6021.307549631849;6131.049687298476;6133.601315914451;6162.22972128484;5494.09347457681;5972.283326000907;6003.519718422603;6024.307204671519;6044.848878778066;5641.464060914206;6069.757209711562;6076.179715738495;6109.7861574844665;6112.2970858351855;5799.739011744566;5891.38140495259;5928.644281343133;5947.821565353046;6034.366391529002 23:21:37 INFO - PROCESS | 1860 | |1;ext.html;523.7695414017832;525.3957948359389;552.417199854154;564.8277275430992;579.2730123694762;29165.854170729144;41470;41483.17033659325;41524;41527.37708934368;42923.570764292344;43364.13271734564;43427.3485897711;43453.7827310863;43464;9419.48192849393;9537.33238673293;9640.084192001748;9648.469334186617;9650.951745241264;9551.90448095517;9634.75913102171;9638;9640.084192001748;9666.709998700015;9540.284478664093;9637.903620963805;9647.083527064953;9647.421154730739;9681.661141860037;9498.335116541879;9505.762355941088;9522.761930951712;9624.181944534723;9624.566894489764;9491.240700743923;9538.761530961712;9589.904100958971;9630.133288004043;9638.132568068908;40207.7989610053;40583;40610.796946015376;40632;40645;3061.8315992620396;3154.5741324921073;3437.089171369589;3596.9280614387826;3992.461017762594;30868;31431;31972.520412193837;32297.1925701857;32576.185595360068;2915.4023425197784;3255.332656805373;3275.279438523521;3279.7048265655967;3310.966890331114;487833;493001;498449;498603;498873.50563247316;65118.674406627666;65584;65729.34270657232;65783.67108164428;65877.68244635081;35009.82495087508;35729;36160.457593136125;36443.27113457716;36747.632523674954;16790;16811.411600594165;16948.237329319963;16969.23638436273;16984.49046528606;12374.319412432402;12740.681482962815;12758.61724148277;12830.422630981628;12840.229586224856;16909.408170713967;20253.886036267853;20330.49173770638;20350.982450877396;20498.795012049988;6805.421539169226;8140.3487720982175;8365.657615867445;8366.330693544502;8435.915640843636;809.4697972827807;885.7121435533477;955.8900726416551;983.4984158079288;1087.1900434176432;27151.456970860472;30576.235594110327;30900.845495772377;30904.845475772927;31658.208544786103;1014.1227837920294;1040.7242080848582;1070.2080460459204;1087.836824476319;1112.9165312601588;13243.66890827737;13333.466661333632;16105.436309729103;16761.41335053267;17173.7423938641;638733;694318;699438;700190;709920;61303.08045379323;61627.69186154041;61836.38163618306;61971;62222.688886556185;1190.1493178108126;1353.350391811916;1377.6418131285939;1384.6884450998446;1402.3268830960992;27499;31742.206444839063;32196.678033220305;32428.675713242566;32563.511547326336;55029.89940201093;55716.44283557113;55721.88556228934;55872;55988.720056399456;517578;522558;524113.3794331004;527073;528929;26633.067842625416;27580.862095689394;27643;27699.58450623202;27824.721752782214;49107.75446122889;49226.01547969092;49391.753041234566;49444;50614;39757.403638946016;40548.39177412282;40786.19912323086;40811.18377632371;40829.79585102056;39066.80466597649;39839.80080099581;40176.7991160054;40558.78323650295;40561.59438405578;33104.33791324112;38064.04839878914;38770.224595508495;39008.2198356037;39441.013974649715;33279.168020799676;35222;35744.821275894494;36369.81815090908;36396.636033639326;23891.283261502183;25627.743722562536;26135.869320653273;26336.34159146037;26417.33956651099 23:21:37 INFO - PROCESS | 1860 | |2;jquery.html;338.22208919485183;384.2027792330914;388.71623714688275;399.1896450206083;407.1145259061542;14943.252837358143;17587.912060439696;19326.903365483155;19454.41636750898;20520.794792052093;19488.610227795434;19768.81387116774;20582;20582.794172058275;20597.794022059814;5431.755570999315;5596.496315331619;5874.765009399619;5881.088431293157;5904.881902361972;5687.488126068653;5770.307563092414;5952.464278214959;6052.606580572271;6053.485453736438;5966.164736936832;6042.848928776772;6079.331273559905;6084;6099.969500152515;4962.454130045692;5564.360098588653;6018.097285407202;6031.457168854802;6043.697815109271;5630.577706672016;5797.188393624896;5826.766929322865;5827.7668893244645;5847.532197424196;13223.801642975362;13354.33228338589;16930.23813928364;24842.627360589606;25365.746342536524;2005.6991451282424;2050.938471845847;2161.7081693971268;2214.833887458431;2406.3863714752624;9696.321257511978;9773.706788796348;9830.459324737136;10053.949730251376;10272.178225741924;1812.7190285505635;1847.4734700610395;1847.8706490545667;1849.9815001849943;1858.4424672598302;110316.44841775739;120979.99999999913;121794;122434;123996.38001810023;9314;11513.597024104116;11577.421128943519;11626.24112063975;11781.941090294493;13207.471701131846;13948.511802086878;14716.926415368069;14863.33115009827;14880.255987200597;8574.571271436404;8648.740537783877;8668.523231222218;8705.6517739291;8774.692885748967;7045.674927273987;7119.644017799089;7995.480293780885;8620.396572240008;8641.783955401039;3774.6791522720328;3821.426785982125;4342.435483387139;4528.615067719281;4656.95343046572;1955.2570023391304;2004.4888553418784;2011.1151093518804;2014.6675798493377;2052.7434070741156;761.2577736706777;808.3492788305354;886.9512176830336;940.4357385568741;946.3612061858219;17699;20134.395968120978;20976.47558811042;21225.787742122382;21225.78774212269;731.7365748330596;782.1318336646318;965.9903400966041;992.8858181309242;1022.5091955861228;10624;11022.44887755609;11829.171957963032;11862.466189021343;12062.215955963009;459746;462358;469068;585564;597859;121400;135895;140224;140524;141831;990.4354517924858;1005.788784355279;1028.5011769291887;1030.05749738988;1037.8287582548794;16557.92373495719;17248.310067597657;18113.547161321072;18349.449516514527;18781.873087614786;50337.99324013426;53442.198367023746;54329.456705432436;54542;54810.72594637001;54383;55394.44605553893;65600.04799784116;78938.81591776013;79849.20150798418;6938.791836244921;7984.081830589556;7994.080680721791;8047.87928181066;8325.167483251626;12077.214981026205;12453.439595218419;12494.813407653442;12636.368181591068;12762.553310634083;10454;10518.368897866152;10905.618303359344;10984.890151098387;11026.0076593107;9753.951230243803;10270.589176432859;10419.531121099417;10619.044286014296;10854.023137917626;10107.64623238183;10984.176186785864;11013.2841365311;11069.83395249088;11196.216264861707;7915.406344524299;7918.604069796372;8059.677612895419;8096.757097287091;8131.552764598004;8043.6782528698195;10460.424676642857;10847.294925829796;10926.72683182895;10946.39794811293 23:21:37 INFO - PROCESS | 1860 | |3;mootools.html;1588.579026557962;1622.8620567251783;1702.6850032743944;1849.324996376323;1968.1241847377905;8064.959675201616;8442.91557084429;8603.78490537736;9110.726678199648;9147.039560846108;7682.116556595993;8508.872366914502;8562.743117706463;8700.129987001297;8822.823543529126;2284.314705588325;2333.1484008337006;2335.065973610552;2335.2643917166124;2346.2257455039794;2318.3508617587017;2326.5463234669246;2356.0575769692173;2364.4916342986253;2372.7627237276306;2294.070901284974;2295.7589453107435;2323.7908588227056;2332.6034574122355;2354.09367393553;2316.6177580699164;2328.6157783965627;2333.3116730564525;2341.2976107167783;2348.213348528245;2303.594807167626;2321.767823217682;2333.4749681321737;2345.0971376020184;2345.4839935214227;7226.421886249087;8769.693060742908;8849.911500884973;8864.227154569113;8873.201411872962;5056.191009438509;5287.524122828964;5355.892882142374;5364.275822763916;5432.728363581845;3508;3556.9644303556893;3634.291313193928;3723.925521489582;3729.086373838427;991.5290237137374;1030.958761649533;1056.5932116135266;1101.686019484443;1105.7788442311569;11408.315501069963;11521.827172592419;11623.535058597647;11641.534338626445;11658.825117623242;4349.238883195429;4462.44219472566;4739.549742774431;4855.490173531728;4860.416749990024;10159.746006349753;10168.644097456554;10187.9812018799;10190.286679932482;10239.897601023895;5352.518273355339;5363.115086010765;5412.134058550613;5429.592780541475;5452.863678408072;4415.558444155623;4748.002919386968;4816.807327706852;4829.082474329936;4934;3984.30274701926;4050.8379664813665;4056.675465962716;4113.239050775616;4125.29869922114;1085.365061439067;1302.5571305756089;1322.3123975532862;1322.9007824413015;1325.7083441642824;829.9377546684022;911.48956584313;928.7631653928205;930.437085563238;940.8682784410188;6010.909836352458;6047.0929360596265;6125.724342404514;6231.345708700612;6304.869505596843;928.313048344234;953.77109493721;962.7785609309898;987.7333120057544;1030.5778026323626;3979.4030895365927;4065.757659835948;4085.2850751118435;4107.260693075275;4138.668906487474;10368.585256589651;10628.627998020032;10735.78528429427;10759.56961721538;10828.837567436494;10491.737706557396;10539.314944528582;10646.414447205323;10660.77401098868;10706.892931070746;8217.79455513605;8231.917680823235;8233;8301.211384918422;8360.163983601591;5439.918401223906;5940.287165540163;5958.7608327523;6067.575269731253;6120.663363514871;9707.70876873695;9930.801383972424;10085.89914100879;10105.343152695052;10114.59541618327;10248.38509689421;10255.692329230134;10257.487125643836;10265.948670256601;10274.434906079938;7037.894431583428;7394.371478424394;7645.541267523967;7646.235376462309;7770.222977702185;8503;8561.272291855263;8569.185927336894;8576;8630.611622476878;7443.36731377839;7581.279778421042;7629;7653.502522336031;7668.846623067396;7411.592362420119;7419.1467981180695;7507.324340809353;7565.848683026419;7577.659005344663;6433.581817181868;7016.052832867702;7106.395956343768;7131.322524360177;7134.072570565793;4931.087748766489;5021.949780502295;5088.745562721775;5107.489251074863;5108.616853736059;5010.073136469765;5090.847274581768;5129;5168.431472538093;5256.001359741636 23:21:37 INFO - PROCESS | 1860 | |4;prototype.html;306.1000658065288;311.0094349496852;315.31419163319777;317.4793338924165;321.48562300319475;4474.709143905644;5260.736963151841;5386.676799392034;5396.298481197448;5411.161270003156;4515.796789144488;4700.435947686274;4748.860273534353;5291.391489978653;5311.49540793625;3622.9131260621707;3803.657670809627;3840.5967373425806;3983.9800800995954;4055.513338399396;3972.543157536877;4021.5375231848275;4022.275990321741;4047.8583249586272;4073.348264277716;3395.7622966392364;4065.26825171469;4073.9185216295655;4076.9388459173138;4119.599336225682;3472.0451875734125;3898.8440462381473;4036.4348991141264;4059.5940405959464;4098.979505102485;2799.468101060792;3382.30662714143;3521.9786261983995;3531.0819187011307;3536.169000284931;4021.8135649983324;4785.138675038492;4842.958763865786;5005.774740136702;5061.924071138936;4163.687723420756;4207.873763787091;4567.269236922077;4655.86032419028;4692.507286734912;5121.513456221653;5574.470425309589;5600.711836277638;5601.579881508903;5639.2668953036255;3149.7952633078776;3450.447928331455;3891.8248678809523;3922.8626998054933;3923.7449565778134;5516.393196748395;5856.414358564152;5939.851503712399;5954.672493012882;5961.926853166428;3623.202895363016;3908.7459315144993;3936.1340505088833;3951.78265195417;3957;3831.7126215533945;4021.416894550274;4022.235775202702;4029.315016447211;4032.697547683935;4349.913001740011;4630.791614377361;4631.143238500888;4673.3690951721765;4686.367340409001;4597.770111494478;5623.381428042912;5628.971855140698;5664.178694089416;5670.432956704297;1360.5306169371552;1376.1903097426853;1389.4442223110634;1390.881775049132;1391.5616580777023;785.8310463250428;804.4167978215747;846.8391005708895;859.2653281444275;864.573453801233;4965.155923493015;5052.9242061369105;5141.871453213625;5196.636235463481;5227.686338819683;5773.509251713652;5792.391798861143;5808.738606762706;5825.76696932118;5866.6480011199465;4801.903961920742;4912.410510738663;5186.536395212244;5216.634835561475;5307;2730.9044183453484;2837.964143087758;2932.3841993181245;2974.5538169274782;3060.8010479318773;5848.181254624356;6089.48239399656;6119.694015299217;6142.416470435302;6164;5284.313039304866;5724.996251312091;5750.166225897222;5766.394528574439;5994.250718660168;5056.317397151411;5754.863599338287;5942.573782292218;6106.3588323227195;6124.265088189446;4735.621150308036;5298.860744939702;5517.69652669107;5797.2173356788135;5849.1226316052935;5398.571906839107;5729.312482502127;5791.148885990769;5836.358000619844;5841.115658087324;5626.11525139083;6070.362611925684;6110.877782444238;6114.266288045463;6162.506999440124;2809.311718628949;3211.2935154266024;3227.515342942273;3268.460703983816;3313.1717070732316;4510.271285872132;4666.5533684557895;4778.76106194682;4794.113089078532;5002.224655178453;4373.890652733579;4508.661850361171;4621.735349678566;4916.0905232532095;4926.334944782552;4420.226460369358;4587.8623641290815;4815.494373090775;5170.71561064145;5763.221965034834;5220.530152286313;5289.7355132244;5360.758765855624;5586.02244607184;5586.357568879631;4823;5508.862278443071;5693.259876216231;5711.600187986801;5722.799702010409;4772.904541909212;5522.5581953442825;5525.254090697705;5560.944390556043;5597.384287728428 23:21:37 INFO - PROCESS | 1860 | |5;yui.html;696.9303069693029;726.8291951391423;732.6117157906308;790.5691398187987;826.884236206931;14593.27033648316;14847;15412.537623871287;15430;15868.682626347467;14600.780988285183;14640.34118464669;14652.047616904894;14819.92590037051;14858.182799946026;711.9288071192891;896.7399454158286;898.9325800564951;901.400568621869;903.9819203615924;909.2044461096541;916.4684482999844;917.655879045358;920.3879420185568;923.5520772425368;935.6210734652477;967.110258562121;970.4662435660393;971.5627967414656;972.358243559251;920.894097178823;933.7572231219864;956.7368973532265;962.8026254617787;964.5563041001147;684.7979845945458;704.8801703710382;713.20477667401;716.1656669979468;718.1633397092381;15138.470153544573;15254.618634534114;15519.45681901128;15788.289526971314;15810.604734881605;14143.222122783243;14258.358373873201;14293.428262869473;14307.284635768274;14537.345819438046;11890.524379024828;12222.144449888512;12282.38588070593;12383.257004579664;12446.3154526501;3860.652541271299;3870.8645197418236;4059.391091336294;4092.9386059209137;4098.426220329156;111078.3338249935;111590;112446.19314971974;112818.43590782076;112831;11093.667189984313;11454.828177577343;11526.250793698382;11540.884591153981;11727.120465965087;23875.044998200225;24220.636690449657;24230.636540451906;24459.021639134236;24470.51058978846;7145.4998150128995;8165.346772258205;8306.875396869053;8413.537255451009;8490.193431623986;4601.539846015372;4666.2767271073035;4671.906561868744;4701.858944231679;4734.218853889139;3575.481555174485;3610.350136975317;3667.431548109994;3678.2275722098125;3738.1028553147594;576.7923547522888;655.6918248423234;683.8222062263848;694.4964900447136;694.6110178300169;574.7729646789495;637.0317117980643;644.6615526848442;680.8059702984625;684.4797953555334;9872.210223182128;9977.301588888708;10021.498925053718;10056.69829905104;10071.496425178711;605.3039005144119;666.3669513961755;691.5574032619128;695.0963747128712;696.9825754356182;4525.275955847049;4725.669203155815;4763.952360476351;4766.785494652749;4819.373162589011;96060.51969740246;149525;157220;159111;159874.20062899843;114000;133005;135425.64574354328;136380;138740;101238.4938075305;102491.48754256181;102572;103859.96140038503;104746.47626761817;9173.082691730773;9177.357584968988;9190.494522801042;9210.078992100736;9283.675071372469;54088.91822163614;60051;61023.694881525305;62856.37143628688;64847.163755262256;85870.57064714887;86389.56805215933;91034.54482727544;92199.53900230456;92877.60683589887;10939.562417503528;11457.484413201593;13421.26183059902;13590.59228223155;13753.656158596115;14369.784453233;14620.195889226196;15113.47102851395;15350.46273380471;15459.149746764037;12928.612141635766;13508.527201547899;13551.661208469866;13656.385462654005;13804.447822087004;13184.736305274033;13613.319334033456;13615.79576306376;13656;13713.17720936747;13295.80056299137;13439.395227214995;13500.662483437993;13983.930080349533;14064.437422502986;10639.787204255717;11021.614243501439;11181.105511558893;11365.147613928822;11386.658400248005;9560.804899387576;11204.159688023268;11352.318860868374;11404.94297528507;11434.771304573696 23:21:37 INFO - PROCESS | 1860 | __end_tp_report 23:21:37 INFO - PROCESS | 1860 | __start_cc_report 23:21:37 INFO - PROCESS | 1860 | _x_x_mozilla_cycle_collect,420 23:21:37 INFO - PROCESS | 1860 | __end_cc_report 23:21:37 INFO - PROCESS | 1860 | __startTimestamp1464934897190__endTimestamp 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | ------- Summary: start ------- 23:21:37 INFO - PROCESS | 1860 | Number of tests: 6 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#0] dojo.html Cycles:165 Average:5667.32 Median:5943.82 stddev:774.46 (13.0%) stddev-sans-first:747.93 23:21:37 INFO - PROCESS | 1860 | Values: 2994.9 3355.2 3423.2 3436.6 3456.0 5600.1 5659.3 5750.1 5751.5 5754.8 5468.7 5528.4 5574.6 5644.2 5671.3 5128.4 5146.0 5218.5 5236.5 5266.3 202.3 5116.4 5183.0 5247.0 5248.5 4664.0 4764.9 5167.5 5186.0 5232.0 5233.6 5239.6 5248.6 5268.0 5269.1 5015.2 5104.2 5160.4 5173.2 5276.3 4692.0 5515.9 5543.8 5575.7 5588.5 5373.4 6124.3 6219.3 6231.0 6272.2 6117.6 6154.5 6158.1 6209.3 6223.9 5922.4 5943.6 5943.8 5948.6 5979.2 6114.8 6158.3 6298.9 6329.2 6333.1 6066.3 6078.9 6091.1 6093.7 6095.7 6130.9 6140.6 6157.9 6165.0 6167.4 6098.6 6130.8 6145.6 6160.5 6220.6 6169.3 6200.4 6211.2 6225.8 6234.5 4882.8 4943.5 4950.4 4950.6 4969.9 4353.7 4412.8 4453.3 4488.9 4507.8 5621.2 5734.5 5799.4 5859.9 5879.5 6185.2 6188.4 6197.6 6203.6 6206.0 5631.3 5794.7 5819.0 5825.0 5874.7 5408.8 5416.6 5420.8 5423.8 5427.2 6375.9 6381.2 6392.6 6396.1 6449.9 5719.4 6106.6 6114.1 6142.9 6159.4 6327.4 6328.8 6339.7 6344.6 6351.2 5992.6 6067.3 6112.6 6131.0 6194.1 6147.9 6166.4 6174.9 6202.8 6203.6 5377.1 5690.7 6123.8 6125.5 6141.5 4877.3 6021.3 6131.0 6133.6 6162.2 5494.1 5972.3 6003.5 6024.3 6044.8 5641.5 6069.8 6076.2 6109.8 6112.3 5799.7 5891.4 5928.6 5947.8 6034.4 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#1] ext.html Cycles:180 Average:70299.95 Median:40596.90 stddev:153974.56 (379.3%) stddev-sans-first:154317.38 23:21:37 INFO - PROCESS | 1860 | Values: 523.8 525.4 552.4 564.8 579.3 29165.9 41470.0 41483.2 41524.0 41527.4 42923.6 43364.1 43427.3 43453.8 43464.0 9419.5 9537.3 9640.1 9648.5 9651.0 9551.9 9634.8 9638.0 9640.1 9666.7 9540.3 9637.9 9647.1 9647.4 9681.7 9498.3 9505.8 9522.8 9624.2 9624.6 9491.2 9538.8 9589.9 9630.1 9638.1 40207.8 40583.0 40610.8 40632.0 40645.0 3061.8 3154.6 3437.1 3596.9 3992.5 30868.0 31431.0 31972.5 32297.2 32576.2 2915.4 3255.3 3275.3 3279.7 3311.0 487833.0 493001.0 498449.0 498603.0 498873.5 65118.7 65584.0 65729.3 65783.7 65877.7 35009.8 35729.0 36160.5 36443.3 36747.6 16790.0 16811.4 16948.2 16969.2 16984.5 12374.3 12740.7 12758.6 12830.4 12840.2 16909.4 20253.9 20330.5 20351.0 20498.8 6805.4 8140.3 8365.7 8366.3 8435.9 809.5 885.7 955.9 983.5 1087.2 27151.5 30576.2 30900.8 30904.8 31658.2 1014.1 1040.7 1070.2 1087.8 1112.9 13243.7 13333.5 16105.4 16761.4 17173.7 638733.0 694318.0 699438.0 700190.0 709920.0 61303.1 61627.7 61836.4 61971.0 62222.7 1190.1 1353.4 1377.6 1384.7 1402.3 27499.0 31742.2 32196.7 32428.7 32563.5 55029.9 55716.4 55721.9 55872.0 55988.7 517578.0 522558.0 524113.4 527073.0 528929.0 26633.1 27580.9 27643.0 27699.6 27824.7 49107.8 49226.0 49391.8 49444.0 50614.0 39757.4 40548.4 40786.2 40811.2 40829.8 39066.8 39839.8 40176.8 40558.8 40561.6 33104.3 38064.0 38770.2 39008.2 39441.0 33279.2 35222.0 35744.8 36369.8 36396.6 23891.3 25627.7 26135.9 26336.3 26417.3 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#2] jquery.html Cycles:180 Average:32311.58 Median:2310.61 stddev:87708.98 (3795.9%) stddev-sans-first:87922.17 23:21:37 INFO - PROCESS | 1860 | Values: 338.2 384.2 388.7 399.2 407.1 14943.3 17587.9 19326.9 19454.4 20520.8 19488.6 19768.8 20582.0 20582.8 20597.8 5431.8 5596.5 5874.8 5881.1 5904.9 5687.5 5770.3 5952.5 6052.6 6053.5 5966.2 6042.8 6079.3 6084.0 6100.0 4962.5 5564.4 6018.1 6031.5 6043.7 5630.6 5797.2 5826.8 5827.8 5847.5 13223.8 13354.3 16930.2 24842.6 25365.7 2005.7 2050.9 2161.7 2214.8 2406.4 9696.3 9773.7 9830.5 10053.9 10272.2 1812.7 1847.5 1847.9 1850.0 1858.4 110316.4 120980.0 121794.0 122434.0 123996.4 9314.0 11513.6 11577.4 11626.2 11781.9 13207.5 13948.5 14716.9 14863.3 14880.3 8574.6 8648.7 8668.5 8705.7 8774.7 7045.7 7119.6 7995.5 8620.4 8641.8 3774.7 3821.4 4342.4 4528.6 4657.0 1955.3 2004.5 2011.1 2014.7 2052.7 761.3 808.3 887.0 940.4 946.4 17699.0 20134.4 20976.5 21225.8 21225.8 731.7 782.1 966.0 992.9 1022.5 10624.0 11022.4 11829.2 11862.5 12062.2 459746.0 462358.0 469068.0 585564.0 597859.0 121400.0 135895.0 140224.0 140524.0 141831.0 990.4 1005.8 1028.5 1030.1 1037.8 16557.9 17248.3 18113.5 18349.4 18781.9 50338.0 53442.2 54329.5 54542.0 54810.7 54383.0 55394.4 65600.0 78938.8 79849.2 6938.8 7984.1 7994.1 8047.9 8325.2 12077.2 12453.4 12494.8 12636.4 12762.6 10454.0 10518.4 10905.6 10984.9 11026.0 9754.0 10270.6 10419.5 10619.0 10854.0 10107.6 10984.2 11013.3 11069.8 11196.2 7915.4 7918.6 8059.7 8096.8 8131.6 8043.7 10460.4 10847.3 10926.7 10946.4 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#3] mootools.html Cycles:180 Average:5751.15 Median:4857.95 stddev:3198.13 (65.8%) stddev-sans-first:3191.80 23:21:37 INFO - PROCESS | 1860 | Values: 1588.6 1622.9 1702.7 1849.3 1968.1 8065.0 8442.9 8603.8 9110.7 9147.0 7682.1 8508.9 8562.7 8700.1 8822.8 2284.3 2333.1 2335.1 2335.3 2346.2 2318.4 2326.5 2356.1 2364.5 2372.8 2294.1 2295.8 2323.8 2332.6 2354.1 2316.6 2328.6 2333.3 2341.3 2348.2 2303.6 2321.8 2333.5 2345.1 2345.5 7226.4 8769.7 8849.9 8864.2 8873.2 5056.2 5287.5 5355.9 5364.3 5432.7 3508.0 3557.0 3634.3 3723.9 3729.1 991.5 1031.0 1056.6 1101.7 1105.8 11408.3 11521.8 11623.5 11641.5 11658.8 4349.2 4462.4 4739.5 4855.5 4860.4 10159.7 10168.6 10188.0 10190.3 10239.9 5352.5 5363.1 5412.1 5429.6 5452.9 4415.6 4748.0 4816.8 4829.1 4934.0 3984.3 4050.8 4056.7 4113.2 4125.3 1085.4 1302.6 1322.3 1322.9 1325.7 829.9 911.5 928.8 930.4 940.9 6010.9 6047.1 6125.7 6231.3 6304.9 928.3 953.8 962.8 987.7 1030.6 3979.4 4065.8 4085.3 4107.3 4138.7 10368.6 10628.6 10735.8 10759.6 10828.8 10491.7 10539.3 10646.4 10660.8 10706.9 8217.8 8231.9 8233.0 8301.2 8360.2 5439.9 5940.3 5958.8 6067.6 6120.7 9707.7 9930.8 10085.9 10105.3 10114.6 10248.4 10255.7 10257.5 10265.9 10274.4 7037.9 7394.4 7645.5 7646.2 7770.2 8503.0 8561.3 8569.2 8576.0 8630.6 7443.4 7581.3 7629.0 7653.5 7668.8 7411.6 7419.1 7507.3 7565.8 7577.7 6433.6 7016.1 7106.4 7131.3 7134.1 4931.1 5021.9 5088.7 5107.5 5108.6 5010.1 5090.8 5129.0 5168.4 5256.0 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#4] prototype.html Cycles:180 Average:4482.00 Median:4832.98 stddev:1409.42 (29.2%) stddev-sans-first:1378.09 23:21:37 INFO - PROCESS | 1860 | Values: 306.1 311.0 315.3 317.5 321.5 4474.7 5260.7 5386.7 5396.3 5411.2 4515.8 4700.4 4748.9 5291.4 5311.5 3622.9 3803.7 3840.6 3984.0 4055.5 3972.5 4021.5 4022.3 4047.9 4073.3 3395.8 4065.3 4073.9 4076.9 4119.6 3472.0 3898.8 4036.4 4059.6 4099.0 2799.5 3382.3 3522.0 3531.1 3536.2 4021.8 4785.1 4843.0 5005.8 5061.9 4163.7 4207.9 4567.3 4655.9 4692.5 5121.5 5574.5 5600.7 5601.6 5639.3 3149.8 3450.4 3891.8 3922.9 3923.7 5516.4 5856.4 5939.9 5954.7 5961.9 3623.2 3908.7 3936.1 3951.8 3957.0 3831.7 4021.4 4022.2 4029.3 4032.7 4349.9 4630.8 4631.1 4673.4 4686.4 4597.8 5623.4 5629.0 5664.2 5670.4 1360.5 1376.2 1389.4 1390.9 1391.6 785.8 804.4 846.8 859.3 864.6 4965.2 5052.9 5141.9 5196.6 5227.7 5773.5 5792.4 5808.7 5825.8 5866.6 4801.9 4912.4 5186.5 5216.6 5307.0 2730.9 2838.0 2932.4 2974.6 3060.8 5848.2 6089.5 6119.7 6142.4 6164.0 5284.3 5725.0 5750.2 5766.4 5994.3 5056.3 5754.9 5942.6 6106.4 6124.3 4735.6 5298.9 5517.7 5797.2 5849.1 5398.6 5729.3 5791.1 5836.4 5841.1 5626.1 6070.4 6110.9 6114.3 6162.5 2809.3 3211.3 3227.5 3268.5 3313.2 4510.3 4666.6 4778.8 4794.1 5002.2 4373.9 4508.7 4621.7 4916.1 4926.3 4420.2 4587.9 4815.5 5170.7 5763.2 5220.5 5289.7 5360.8 5586.0 5586.4 4823.0 5508.9 5693.3 5711.6 5722.8 4772.9 5522.6 5525.3 5560.9 5597.4 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - PROCESS | 1860 | [#5] yui.html Cycles:180 Average:24746.82 Median:14023.00 stddev:39181.20 (279.4%) stddev-sans-first:39249.50 23:21:37 INFO - PROCESS | 1860 | Values: 696.9 726.8 732.6 790.6 826.9 14593.3 14847.0 15412.5 15430.0 15868.7 14600.8 14640.3 14652.0 14819.9 14858.2 711.9 896.7 898.9 901.4 904.0 909.2 916.5 917.7 920.4 923.6 935.6 967.1 970.5 971.6 972.4 920.9 933.8 956.7 962.8 964.6 684.8 704.9 713.2 716.2 718.2 15138.5 15254.6 15519.5 15788.3 15810.6 14143.2 14258.4 14293.4 14307.3 14537.3 11890.5 12222.1 12282.4 12383.3 12446.3 3860.7 3870.9 4059.4 4092.9 4098.4 111078.3 111590.0 112446.2 112818.4 112831.0 11093.7 11454.8 11526.3 11540.9 11727.1 23875.0 24220.6 24230.6 24459.0 24470.5 7145.5 8165.3 8306.9 8413.5 8490.2 4601.5 4666.3 4671.9 4701.9 4734.2 3575.5 3610.4 3667.4 3678.2 3738.1 576.8 655.7 683.8 694.5 694.6 574.8 637.0 644.7 680.8 684.5 9872.2 9977.3 10021.5 10056.7 10071.5 605.3 666.4 691.6 695.1 697.0 4525.3 4725.7 4764.0 4766.8 4819.4 96060.5 149525.0 157220.0 159111.0 159874.2 114000.0 133005.0 135425.6 136380.0 138740.0 101238.5 102491.5 102572.0 103860.0 104746.5 9173.1 9177.4 9190.5 9210.1 9283.7 54088.9 60051.0 61023.7 62856.4 64847.2 85870.6 86389.6 91034.5 92199.5 92877.6 10939.6 11457.5 13421.3 13590.6 13753.7 14369.8 14620.2 15113.5 15350.5 15459.1 12928.6 13508.5 13551.7 13656.4 13804.4 13184.7 13613.3 13615.8 13656.0 13713.2 13295.8 13439.4 13500.7 13983.9 14064.4 10639.8 11021.6 11181.1 11365.1 11386.7 9560.8 11204.2 11352.3 11404.9 11434.8 23:21:37 INFO - PROCESS | 1860 | -------- Summary: end -------- 23:21:37 INFO - PROCESS | 1860 | 23:21:37 INFO - TEST-INFO | 1860: exit 0 23:21:37 INFO - TEST-OK | dromaeo_css | took 1112286ms 23:21:37 INFO - TEST-START | kraken 23:21:37 INFO - operating with platform_type : mac_ 23:21:37 INFO - Initialising browser for kraken test... 23:21:37 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpYge_uW/profile http://localhost:49270/getInfo.html) 23:21:40 INFO - PROCESS | 1978 | __metrics Screen width/height:1600/1200 23:21:40 INFO - PROCESS | 1978 | colorDepth:24 23:21:40 INFO - PROCESS | 1978 | Browser inner width/height: 1024/689 23:21:40 INFO - PROCESS | 1978 | __metrics 23:21:41 ERROR - PROCESS | 1978 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 23:21:41 INFO - TEST-INFO | 1978: exit 0 23:21:41 INFO - Browser initialized. 23:21:41 INFO - Running cycle 1/1 for kraken test... 23:21:41 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpYge_uW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:21:53 INFO - PROCESS | 1980 | RSS: Main: 253136896 23:21:53 INFO - PROCESS | 1980 | 23:21:56 INFO - PROCESS | 1980 | 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) 23:21:57 INFO - PROCESS | 1980 | RSS: Main: 265859072 23:21:57 INFO - PROCESS | 1980 | 23:21:59 INFO - PROCESS | 1980 | 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) 23:22:00 INFO - PROCESS | 1980 | RSS: Main: 269893632 23:22:00 INFO - PROCESS | 1980 | 23:22:03 INFO - PROCESS | 1980 | 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) 23:22:04 INFO - PROCESS | 1980 | RSS: Main: 270331904 23:22:04 INFO - PROCESS | 1980 | 23:22:06 INFO - PROCESS | 1980 | 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) 23:22:07 INFO - PROCESS | 1980 | RSS: Main: 271163392 23:22:07 INFO - PROCESS | 1980 | 23:22:09 INFO - PROCESS | 1980 | 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) 23:22:09 INFO - PROCESS | 1980 | RSS: Main: 271806464 23:22:09 INFO - PROCESS | 1980 | 23:22:12 INFO - PROCESS | 1980 | 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) 23:22:13 INFO - PROCESS | 1980 | RSS: Main: 272740352 23:22:13 INFO - PROCESS | 1980 | 23:22:16 INFO - PROCESS | 1980 | 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) 23:22:16 INFO - PROCESS | 1980 | RSS: Main: 272781312 23:22:16 INFO - PROCESS | 1980 | 23:22:19 INFO - PROCESS | 1980 | 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) 23:22:20 INFO - PROCESS | 1980 | RSS: Main: 272773120 23:22:20 INFO - PROCESS | 1980 | 23:22:22 INFO - PROCESS | 1980 | 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) 23:22:22 INFO - PROCESS | 1980 | RSS: Main: 272896000 23:22:22 INFO - PROCESS | 1980 | 23:22:24 INFO - PROCESS | 1980 | 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) 23:22:25 INFO - PROCESS | 1980 | RSS: Main: 272277504 23:22:25 INFO - PROCESS | 1980 | 23:22:27 INFO - PROCESS | 1980 | 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) 23:22:28 INFO - PROCESS | 1980 | RSS: Main: 272265216 23:22:28 INFO - PROCESS | 1980 | 23:22:31 INFO - PROCESS | 1980 | 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) 23:22:31 INFO - PROCESS | 1980 | RSS: Main: 272371712 23:22:31 INFO - PROCESS | 1980 | 23:22:34 INFO - PROCESS | 1980 | 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) 23:22:35 INFO - PROCESS | 1980 | RSS: Main: 272334848 23:22:35 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | 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) 23:22:37 INFO - PROCESS | 1980 | RSS: Main: 272990208 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | __start_tp_report 23:22:37 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 23:22:37 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 23:22:37 INFO - PROCESS | 1980 | |i|pagename|runs| 23:22:37 INFO - PROCESS | 1980 | |0;ai-astar;117;111;118;116;115;115;116;114;117;117 23:22:37 INFO - PROCESS | 1980 | |1;audio-beat-detection;147;121;149;159;121;141;137;121;163;129 23:22:37 INFO - PROCESS | 1980 | |2;audio-dft;268;227;198;202;215;218;193;195;199;193 23:22:37 INFO - PROCESS | 1980 | |3;audio-fft;73;82;74;141;60;58;59;62;135;58 23:22:37 INFO - PROCESS | 1980 | |4;audio-oscillator;70;71;127;83;99;113;140;85;73;109 23:22:37 INFO - PROCESS | 1980 | |5;imaging-gaussian-blur;90;86;91;91;133;85;96;85;89;85 23:22:37 INFO - PROCESS | 1980 | |6;imaging-darkroom;94;97;97;96;96;121;96;96;99;151 23:22:37 INFO - PROCESS | 1980 | |7;imaging-desaturate;91;95;98;95;87;147;123;91;124;94 23:22:37 INFO - PROCESS | 1980 | |8;json-parse-financial;55;58;56;57;61;80;86;85;84;73 23:22:37 INFO - PROCESS | 1980 | |9;json-stringify-tinderbox;66;72;73;73;79;80;83;87;94;102 23:22:37 INFO - PROCESS | 1980 | |10;stanford-crypto-aes;88;87;87;89;87;90;86;81;108;89 23:22:37 INFO - PROCESS | 1980 | |11;stanford-crypto-ccm;136;164;150;141;146;151;139;143;142;128 23:22:37 INFO - PROCESS | 1980 | |12;stanford-crypto-pbkdf2;159;170;217;216;175;165;159;173;146;185 23:22:37 INFO - PROCESS | 1980 | |13;stanford-crypto-sha256-iterative;67;73;92;73;69;66;68;68;82;75 23:22:37 INFO - PROCESS | 1980 | __end_tp_report 23:22:37 INFO - PROCESS | 1980 | __start_cc_report 23:22:37 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,1117 23:22:37 INFO - PROCESS | 1980 | __end_cc_report 23:22:37 INFO - PROCESS | 1980 | __startTimestamp1464934957536__endTimestamp 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | ------- Summary: start ------- 23:22:37 INFO - PROCESS | 1980 | Number of tests: 14 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.50 stddev:2.01 (1.7%) stddev-sans-first:2.07 23:22:37 INFO - PROCESS | 1980 | Values: 117.0 111.0 118.0 116.0 115.0 115.0 116.0 114.0 117.0 117.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:144.00 stddev:15.70 (10.9%) stddev-sans-first:16.37 23:22:37 INFO - PROCESS | 1980 | Values: 147.0 121.0 149.0 159.0 121.0 141.0 137.0 121.0 163.0 129.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#2] audio-dft Cycles:10 Average:210.80 Median:208.50 stddev:23.26 (11.2%) stddev-sans-first:12.41 23:22:37 INFO - PROCESS | 1980 | Values: 268.0 227.0 198.0 202.0 215.0 218.0 193.0 195.0 199.0 193.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#3] audio-fft Cycles:10 Average:80.20 Median:61.00 stddev:31.57 (51.7%) stddev-sans-first:33.37 23:22:37 INFO - PROCESS | 1980 | Values: 73.0 82.0 74.0 141.0 60.0 58.0 59.0 62.0 135.0 58.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:72.00 stddev:24.66 (34.3%) stddev-sans-first:24.15 23:22:37 INFO - PROCESS | 1980 | Values: 70.0 71.0 127.0 83.0 99.0 113.0 140.0 85.0 73.0 109.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:89.50 stddev:14.46 (16.2%) stddev-sans-first:15.30 23:22:37 INFO - PROCESS | 1980 | Values: 90.0 86.0 91.0 91.0 133.0 85.0 96.0 85.0 89.0 85.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:96.00 stddev:18.18 (18.9%) stddev-sans-first:18.90 23:22:37 INFO - PROCESS | 1980 | Values: 94.0 97.0 97.0 96.0 96.0 121.0 96.0 96.0 99.0 151.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:92.50 stddev:19.81 (21.4%) stddev-sans-first:20.40 23:22:37 INFO - PROCESS | 1980 | Values: 91.0 95.0 98.0 95.0 87.0 147.0 123.0 91.0 124.0 94.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#8] json-parse-financial Cycles:10 Average:69.50 Median:76.50 stddev:13.33 (17.4%) stddev-sans-first:13.06 23:22:37 INFO - PROCESS | 1980 | Values: 55.0 58.0 56.0 57.0 61.0 80.0 86.0 85.0 84.0 73.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#9] json-stringify-tinderbox Cycles:10 Average:80.90 Median:79.50 stddev:11.00 (13.8%) stddev-sans-first:10.26 23:22:37 INFO - PROCESS | 1980 | Values: 66.0 72.0 73.0 73.0 79.0 80.0 83.0 87.0 94.0 102.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:87.50 stddev:7.05 (8.1%) stddev-sans-first:7.47 23:22:37 INFO - PROCESS | 1980 | Values: 88.0 87.0 87.0 89.0 87.0 90.0 86.0 81.0 108.0 89.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:144.50 stddev:9.71 (6.7%) stddev-sans-first:9.85 23:22:37 INFO - PROCESS | 1980 | Values: 136.0 164.0 150.0 141.0 146.0 151.0 139.0 143.0 142.0 128.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:174.00 stddev:23.58 (13.6%) stddev-sans-first:24.15 23:22:37 INFO - PROCESS | 1980 | Values: 159.0 170.0 217.0 216.0 175.0 165.0 159.0 173.0 146.0 185.0 23:22:37 INFO - PROCESS | 1980 | 23:22:37 INFO - PROCESS | 1980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.00 stddev:8.14 (11.1%) stddev-sans-first:8.31 23:22:37 INFO - PROCESS | 1980 | Values: 67.0 73.0 92.0 73.0 69.0 66.0 68.0 68.0 82.0 75.0 23:22:37 INFO - PROCESS | 1980 | -------- Summary: end -------- 23:22:37 INFO - PROCESS | 1980 | 23:22:38 INFO - TEST-INFO | 1980: exit 0 23:22:38 INFO - TEST-OK | kraken | took 60556ms 23:22:38 INFO - SUITE-END | took 1172s 23:22:38 INFO - Completed test suite (00:19:33) 23:22:38 INFO - javascript benchmark 23:22:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7339.411976451643, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2994.891890000699, 3355.1947532592185, 3423.2468856851483, 3436.6047904490974, 3456.0, 5600.131979543172, 5659.292588426451, 5750.137479378095, 5751.482366587006, 5754.769809207637, 5468.699221542814, 5528.419515950827, 5574.60977731559, 5644.181593668915, 5671.319441667006, 5128.3589551305995, 5146.0, 5218.478152184789, 5236.476352364771, 5266.289050978126, 202.2788281084107, 5116.360454943132, 5183.0, 5247.0, 5248.501392367719, 4663.9766801166115, 4764.857054288377, 5167.48325167484, 5185.974070129644, 5232.0, 5233.555147812441, 5239.5546378557865, 5248.5538729208065, 5267.9736601316745, 5269.104252277122, 5015.22264049073, 5104.2088476286235, 5160.354955630546, 5173.198154286091, 5276.287701160332, 4691.953080469186, 5515.917261241085, 5543.77824887004, 5575.69333686647, 5588.497035266805, 5373.435789242112, 6124.326324104345, 6219.2847822500535, 6231.0, 6272.184615999937, 6117.614269788186, 6154.476869466101, 6158.107074474221, 6209.316975132731, 6223.906641400383, 5922.3781502942, 5943.55423343251, 5943.821685349447, 5948.583599148018, 5979.1629171915965, 6114.785982490592, 6158.261008678987, 6298.874022519615, 6329.177206963157, 6333.081703153017, 6066.332703402622, 6078.878422431527, 6091.0558863376245, 6093.725782339805, 6095.6952152392205, 6130.877382452326, 6140.631562106288, 6157.876842463125, 6165.0, 6167.414095660905, 6098.644029113846, 6130.846728831815, 6145.5698101133385, 6160.537959653044, 6220.595661282002, 6169.321374648785, 6200.3799620038535, 6211.192544969125, 6225.844353891099, 6234.470070044097, 4882.804687812448, 4943.530364615356, 4950.4059512858685, 4950.554450099508, 4969.850904472872, 4353.717008394444, 4412.823487060482, 4453.287474004143, 4488.865334039984, 4507.8196872124745, 5621.156826476062, 5734.455226753452, 5799.39106393831, 5859.853503662357, 5879.500242479352, 6185.165002724664, 6188.381161883775, 6197.566170368031, 6203.596766210182, 6206.0, 5631.324241091095, 5794.739236734357, 5818.970905145447, 5825.0, 5874.706264686749, 5408.810691625774, 5416.647917885325, 5420.783168673288, 5423.810166644149, 5427.213054107132, 6375.87248255023, 6381.23425188971, 6392.648404337713, 6396.10454536356, 6449.903251451232, 5719.399463056319, 6106.582013105306, 6114.052321890114, 6142.877142457215, 6159.4456498915315, 6327.4305312520255, 6328.778492752732, 6339.651319177305, 6344.587601805868, 6351.237851457855, 5992.580519363777, 6067.271927368744, 6112.633242005494, 6131.0, 6194.0708893666315, 6147.877042459215, 6166.445019948226, 6174.876502470015, 6202.782902598387, 6203.596766210182, 5377.139657654757, 5690.687012214368, 6123.7550497981365, 6125.479334256638, 6141.539384546088, 4877.292792545132, 6021.307549631849, 6131.049687298476, 6133.601315914451, 6162.22972128484, 5494.09347457681, 5972.283326000907, 6003.519718422603, 6024.307204671519, 6044.848878778066, 5641.464060914206, 6069.757209711562, 6076.179715738495, 6109.7861574844665, 6112.2970858351855, 5799.739011744566, 5891.38140495259, 5928.644281343133, 5947.821565353046, 6034.366391529002], "value": 5620.300099862427, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.7695414017832, 525.3957948359389, 552.417199854154, 564.8277275430992, 579.2730123694762, 29165.854170729144, 41470.0, 41483.17033659325, 41524.0, 41527.37708934368, 42923.570764292344, 43364.13271734564, 43427.3485897711, 43453.7827310863, 43464.0, 9419.48192849393, 9537.33238673293, 9640.084192001748, 9648.469334186617, 9650.951745241264, 9551.90448095517, 9634.75913102171, 9638.0, 9640.084192001748, 9666.709998700015, 9540.284478664093, 9637.903620963805, 9647.083527064953, 9647.421154730739, 9681.661141860037, 9498.335116541879, 9505.762355941088, 9522.761930951712, 9624.181944534723, 9624.566894489764, 9491.240700743923, 9538.761530961712, 9589.904100958971, 9630.133288004043, 9638.132568068908, 40207.7989610053, 40583.0, 40610.796946015376, 40632.0, 40645.0, 3061.8315992620396, 3154.5741324921073, 3437.089171369589, 3596.9280614387826, 3992.461017762594, 30868.0, 31431.0, 31972.520412193837, 32297.1925701857, 32576.185595360068, 2915.4023425197784, 3255.332656805373, 3275.279438523521, 3279.7048265655967, 3310.966890331114, 487833.0, 493001.0, 498449.0, 498603.0, 498873.50563247316, 65118.674406627666, 65584.0, 65729.34270657232, 65783.67108164428, 65877.68244635081, 35009.82495087508, 35729.0, 36160.457593136125, 36443.27113457716, 36747.632523674954, 16790.0, 16811.411600594165, 16948.237329319963, 16969.23638436273, 16984.49046528606, 12374.319412432402, 12740.681482962815, 12758.61724148277, 12830.422630981628, 12840.229586224856, 16909.408170713967, 20253.886036267853, 20330.49173770638, 20350.982450877396, 20498.795012049988, 6805.421539169226, 8140.3487720982175, 8365.657615867445, 8366.330693544502, 8435.915640843636, 809.4697972827807, 885.7121435533477, 955.8900726416551, 983.4984158079288, 1087.1900434176432, 27151.456970860472, 30576.235594110327, 30900.845495772377, 30904.845475772927, 31658.208544786103, 1014.1227837920294, 1040.7242080848582, 1070.2080460459204, 1087.836824476319, 1112.9165312601588, 13243.66890827737, 13333.466661333632, 16105.436309729103, 16761.41335053267, 17173.7423938641, 638733.0, 694318.0, 699438.0, 700190.0, 709920.0, 61303.08045379323, 61627.69186154041, 61836.38163618306, 61971.0, 62222.688886556185, 1190.1493178108126, 1353.350391811916, 1377.6418131285939, 1384.6884450998446, 1402.3268830960992, 27499.0, 31742.206444839063, 32196.678033220305, 32428.675713242566, 32563.511547326336, 55029.89940201093, 55716.44283557113, 55721.88556228934, 55872.0, 55988.720056399456, 517578.0, 522558.0, 524113.3794331004, 527073.0, 528929.0, 26633.067842625416, 27580.862095689394, 27643.0, 27699.58450623202, 27824.721752782214, 49107.75446122889, 49226.01547969092, 49391.753041234566, 49444.0, 50614.0, 39757.403638946016, 40548.39177412282, 40786.19912323086, 40811.18377632371, 40829.79585102056, 39066.80466597649, 39839.80080099581, 40176.7991160054, 40558.78323650295, 40561.59438405578, 33104.33791324112, 38064.04839878914, 38770.224595508495, 39008.2198356037, 39441.013974649715, 33279.168020799676, 35222.0, 35744.821275894494, 36369.81815090908, 36396.636033639326, 23891.283261502183, 25627.743722562536, 26135.869320653273, 26336.34159146037, 26417.33956651099], "value": 20077.329439094916, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.22208919485183, 384.2027792330914, 388.71623714688275, 399.1896450206083, 407.1145259061542, 14943.252837358143, 17587.912060439696, 19326.903365483155, 19454.41636750898, 20520.794792052093, 19488.610227795434, 19768.81387116774, 20582.0, 20582.794172058275, 20597.794022059814, 5431.755570999315, 5596.496315331619, 5874.765009399619, 5881.088431293157, 5904.881902361972, 5687.488126068653, 5770.307563092414, 5952.464278214959, 6052.606580572271, 6053.485453736438, 5966.164736936832, 6042.848928776772, 6079.331273559905, 6084.0, 6099.969500152515, 4962.454130045692, 5564.360098588653, 6018.097285407202, 6031.457168854802, 6043.697815109271, 5630.577706672016, 5797.188393624896, 5826.766929322865, 5827.7668893244645, 5847.532197424196, 13223.801642975362, 13354.33228338589, 16930.23813928364, 24842.627360589606, 25365.746342536524, 2005.6991451282424, 2050.938471845847, 2161.7081693971268, 2214.833887458431, 2406.3863714752624, 9696.321257511978, 9773.706788796348, 9830.459324737136, 10053.949730251376, 10272.178225741924, 1812.7190285505635, 1847.4734700610395, 1847.8706490545667, 1849.9815001849943, 1858.4424672598302, 110316.44841775739, 120979.99999999913, 121794.0, 122434.0, 123996.38001810023, 9314.0, 11513.597024104116, 11577.421128943519, 11626.24112063975, 11781.941090294493, 13207.471701131846, 13948.511802086878, 14716.926415368069, 14863.33115009827, 14880.255987200597, 8574.571271436404, 8648.740537783877, 8668.523231222218, 8705.6517739291, 8774.692885748967, 7045.674927273987, 7119.644017799089, 7995.480293780885, 8620.396572240008, 8641.783955401039, 3774.6791522720328, 3821.426785982125, 4342.435483387139, 4528.615067719281, 4656.95343046572, 1955.2570023391304, 2004.4888553418784, 2011.1151093518804, 2014.6675798493377, 2052.7434070741156, 761.2577736706777, 808.3492788305354, 886.9512176830336, 940.4357385568741, 946.3612061858219, 17699.0, 20134.395968120978, 20976.47558811042, 21225.787742122382, 21225.78774212269, 731.7365748330596, 782.1318336646318, 965.9903400966041, 992.8858181309242, 1022.5091955861228, 10624.0, 11022.44887755609, 11829.171957963032, 11862.466189021343, 12062.215955963009, 459746.0, 462358.0, 469068.0, 585564.0, 597859.0, 121400.0, 135895.0, 140224.0, 140524.0, 141831.0, 990.4354517924858, 1005.788784355279, 1028.5011769291887, 1030.05749738988, 1037.8287582548794, 16557.92373495719, 17248.310067597657, 18113.547161321072, 18349.449516514527, 18781.873087614786, 50337.99324013426, 53442.198367023746, 54329.456705432436, 54542.0, 54810.72594637001, 54383.0, 55394.44605553893, 65600.04799784116, 78938.81591776013, 79849.20150798418, 6938.791836244921, 7984.081830589556, 7994.080680721791, 8047.87928181066, 8325.167483251626, 12077.214981026205, 12453.439595218419, 12494.813407653442, 12636.368181591068, 12762.553310634083, 10454.0, 10518.368897866152, 10905.618303359344, 10984.890151098387, 11026.0076593107, 9753.951230243803, 10270.589176432859, 10419.531121099417, 10619.044286014296, 10854.023137917626, 10107.64623238183, 10984.176186785864, 11013.2841365311, 11069.83395249088, 11196.216264861707, 7915.406344524299, 7918.604069796372, 8059.677612895419, 8096.757097287091, 8131.552764598004, 8043.6782528698195, 10460.424676642857, 10847.294925829796, 10926.72683182895, 10946.39794811293], "value": 9269.097026625981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1588.579026557962, 1622.8620567251783, 1702.6850032743944, 1849.324996376323, 1968.1241847377905, 8064.959675201616, 8442.91557084429, 8603.78490537736, 9110.726678199648, 9147.039560846108, 7682.116556595993, 8508.872366914502, 8562.743117706463, 8700.129987001297, 8822.823543529126, 2284.314705588325, 2333.1484008337006, 2335.065973610552, 2335.2643917166124, 2346.2257455039794, 2318.3508617587017, 2326.5463234669246, 2356.0575769692173, 2364.4916342986253, 2372.7627237276306, 2294.070901284974, 2295.7589453107435, 2323.7908588227056, 2332.6034574122355, 2354.09367393553, 2316.6177580699164, 2328.6157783965627, 2333.3116730564525, 2341.2976107167783, 2348.213348528245, 2303.594807167626, 2321.767823217682, 2333.4749681321737, 2345.0971376020184, 2345.4839935214227, 7226.421886249087, 8769.693060742908, 8849.911500884973, 8864.227154569113, 8873.201411872962, 5056.191009438509, 5287.524122828964, 5355.892882142374, 5364.275822763916, 5432.728363581845, 3508.0, 3556.9644303556893, 3634.291313193928, 3723.925521489582, 3729.086373838427, 991.5290237137374, 1030.958761649533, 1056.5932116135266, 1101.686019484443, 1105.7788442311569, 11408.315501069963, 11521.827172592419, 11623.535058597647, 11641.534338626445, 11658.825117623242, 4349.238883195429, 4462.44219472566, 4739.549742774431, 4855.490173531728, 4860.416749990024, 10159.746006349753, 10168.644097456554, 10187.9812018799, 10190.286679932482, 10239.897601023895, 5352.518273355339, 5363.115086010765, 5412.134058550613, 5429.592780541475, 5452.863678408072, 4415.558444155623, 4748.002919386968, 4816.807327706852, 4829.082474329936, 4934.0, 3984.30274701926, 4050.8379664813665, 4056.675465962716, 4113.239050775616, 4125.29869922114, 1085.365061439067, 1302.5571305756089, 1322.3123975532862, 1322.9007824413015, 1325.7083441642824, 829.9377546684022, 911.48956584313, 928.7631653928205, 930.437085563238, 940.8682784410188, 6010.909836352458, 6047.0929360596265, 6125.724342404514, 6231.345708700612, 6304.869505596843, 928.313048344234, 953.77109493721, 962.7785609309898, 987.7333120057544, 1030.5778026323626, 3979.4030895365927, 4065.757659835948, 4085.2850751118435, 4107.260693075275, 4138.668906487474, 10368.585256589651, 10628.627998020032, 10735.78528429427, 10759.56961721538, 10828.837567436494, 10491.737706557396, 10539.314944528582, 10646.414447205323, 10660.77401098868, 10706.892931070746, 8217.79455513605, 8231.917680823235, 8233.0, 8301.211384918422, 8360.163983601591, 5439.918401223906, 5940.287165540163, 5958.7608327523, 6067.575269731253, 6120.663363514871, 9707.70876873695, 9930.801383972424, 10085.89914100879, 10105.343152695052, 10114.59541618327, 10248.38509689421, 10255.692329230134, 10257.487125643836, 10265.948670256601, 10274.434906079938, 7037.894431583428, 7394.371478424394, 7645.541267523967, 7646.235376462309, 7770.222977702185, 8503.0, 8561.272291855263, 8569.185927336894, 8576.0, 8630.611622476878, 7443.36731377839, 7581.279778421042, 7629.0, 7653.502522336031, 7668.846623067396, 7411.592362420119, 7419.1467981180695, 7507.324340809353, 7565.848683026419, 7577.659005344663, 6433.581817181868, 7016.052832867702, 7106.395956343768, 7131.322524360177, 7134.072570565793, 4931.087748766489, 5021.949780502295, 5088.745562721775, 5107.489251074863, 5108.616853736059, 5010.073136469765, 5090.847274581768, 5129.0, 5168.431472538093, 5256.001359741636], "value": 4631.242744486424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.1000658065288, 311.0094349496852, 315.31419163319777, 317.4793338924165, 321.48562300319475, 4474.709143905644, 5260.736963151841, 5386.676799392034, 5396.298481197448, 5411.161270003156, 4515.796789144488, 4700.435947686274, 4748.860273534353, 5291.391489978653, 5311.49540793625, 3622.9131260621707, 3803.657670809627, 3840.5967373425806, 3983.9800800995954, 4055.513338399396, 3972.543157536877, 4021.5375231848275, 4022.275990321741, 4047.8583249586272, 4073.348264277716, 3395.7622966392364, 4065.26825171469, 4073.9185216295655, 4076.9388459173138, 4119.599336225682, 3472.0451875734125, 3898.8440462381473, 4036.4348991141264, 4059.5940405959464, 4098.979505102485, 2799.468101060792, 3382.30662714143, 3521.9786261983995, 3531.0819187011307, 3536.169000284931, 4021.8135649983324, 4785.138675038492, 4842.958763865786, 5005.774740136702, 5061.924071138936, 4163.687723420756, 4207.873763787091, 4567.269236922077, 4655.86032419028, 4692.507286734912, 5121.513456221653, 5574.470425309589, 5600.711836277638, 5601.579881508903, 5639.2668953036255, 3149.7952633078776, 3450.447928331455, 3891.8248678809523, 3922.8626998054933, 3923.7449565778134, 5516.393196748395, 5856.414358564152, 5939.851503712399, 5954.672493012882, 5961.926853166428, 3623.202895363016, 3908.7459315144993, 3936.1340505088833, 3951.78265195417, 3957.0, 3831.7126215533945, 4021.416894550274, 4022.235775202702, 4029.315016447211, 4032.697547683935, 4349.913001740011, 4630.791614377361, 4631.143238500888, 4673.3690951721765, 4686.367340409001, 4597.770111494478, 5623.381428042912, 5628.971855140698, 5664.178694089416, 5670.432956704297, 1360.5306169371552, 1376.1903097426853, 1389.4442223110634, 1390.881775049132, 1391.5616580777023, 785.8310463250428, 804.4167978215747, 846.8391005708895, 859.2653281444275, 864.573453801233, 4965.155923493015, 5052.9242061369105, 5141.871453213625, 5196.636235463481, 5227.686338819683, 5773.509251713652, 5792.391798861143, 5808.738606762706, 5825.76696932118, 5866.6480011199465, 4801.903961920742, 4912.410510738663, 5186.536395212244, 5216.634835561475, 5307.0, 2730.9044183453484, 2837.964143087758, 2932.3841993181245, 2974.5538169274782, 3060.8010479318773, 5848.181254624356, 6089.48239399656, 6119.694015299217, 6142.416470435302, 6164.0, 5284.313039304866, 5724.996251312091, 5750.166225897222, 5766.394528574439, 5994.250718660168, 5056.317397151411, 5754.863599338287, 5942.573782292218, 6106.3588323227195, 6124.265088189446, 4735.621150308036, 5298.860744939702, 5517.69652669107, 5797.2173356788135, 5849.1226316052935, 5398.571906839107, 5729.312482502127, 5791.148885990769, 5836.358000619844, 5841.115658087324, 5626.11525139083, 6070.362611925684, 6110.877782444238, 6114.266288045463, 6162.506999440124, 2809.311718628949, 3211.2935154266024, 3227.515342942273, 3268.460703983816, 3313.1717070732316, 4510.271285872132, 4666.5533684557895, 4778.76106194682, 4794.113089078532, 5002.224655178453, 4373.890652733579, 4508.661850361171, 4621.735349678566, 4916.0905232532095, 4926.334944782552, 4420.226460369358, 4587.8623641290815, 4815.494373090775, 5170.71561064145, 5763.221965034834, 5220.530152286313, 5289.7355132244, 5360.758765855624, 5586.02244607184, 5586.357568879631, 4823.0, 5508.862278443071, 5693.259876216231, 5711.600187986801, 5722.799702010409, 4772.904541909212, 5522.5581953442825, 5525.254090697705, 5560.944390556043, 5597.384287728428], "value": 4037.493416159617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.9303069693029, 726.8291951391423, 732.6117157906308, 790.5691398187987, 826.884236206931, 14593.27033648316, 14847.0, 15412.537623871287, 15430.0, 15868.682626347467, 14600.780988285183, 14640.34118464669, 14652.047616904894, 14819.92590037051, 14858.182799946026, 711.9288071192891, 896.7399454158286, 898.9325800564951, 901.400568621869, 903.9819203615924, 909.2044461096541, 916.4684482999844, 917.655879045358, 920.3879420185568, 923.5520772425368, 935.6210734652477, 967.110258562121, 970.4662435660393, 971.5627967414656, 972.358243559251, 920.894097178823, 933.7572231219864, 956.7368973532265, 962.8026254617787, 964.5563041001147, 684.7979845945458, 704.8801703710382, 713.20477667401, 716.1656669979468, 718.1633397092381, 15138.470153544573, 15254.618634534114, 15519.45681901128, 15788.289526971314, 15810.604734881605, 14143.222122783243, 14258.358373873201, 14293.428262869473, 14307.284635768274, 14537.345819438046, 11890.524379024828, 12222.144449888512, 12282.38588070593, 12383.257004579664, 12446.3154526501, 3860.652541271299, 3870.8645197418236, 4059.391091336294, 4092.9386059209137, 4098.426220329156, 111078.3338249935, 111590.0, 112446.19314971974, 112818.43590782076, 112831.0, 11093.667189984313, 11454.828177577343, 11526.250793698382, 11540.884591153981, 11727.120465965087, 23875.044998200225, 24220.636690449657, 24230.636540451906, 24459.021639134236, 24470.51058978846, 7145.4998150128995, 8165.346772258205, 8306.875396869053, 8413.537255451009, 8490.193431623986, 4601.539846015372, 4666.2767271073035, 4671.906561868744, 4701.858944231679, 4734.218853889139, 3575.481555174485, 3610.350136975317, 3667.431548109994, 3678.2275722098125, 3738.1028553147594, 576.7923547522888, 655.6918248423234, 683.8222062263848, 694.4964900447136, 694.6110178300169, 574.7729646789495, 637.0317117980643, 644.6615526848442, 680.8059702984625, 684.4797953555334, 9872.210223182128, 9977.301588888708, 10021.498925053718, 10056.69829905104, 10071.496425178711, 605.3039005144119, 666.3669513961755, 691.5574032619128, 695.0963747128712, 696.9825754356182, 4525.275955847049, 4725.669203155815, 4763.952360476351, 4766.785494652749, 4819.373162589011, 96060.51969740246, 149525.0, 157220.0, 159111.0, 159874.20062899843, 114000.0, 133005.0, 135425.64574354328, 136380.0, 138740.0, 101238.4938075305, 102491.48754256181, 102572.0, 103859.96140038503, 104746.47626761817, 9173.082691730773, 9177.357584968988, 9190.494522801042, 9210.078992100736, 9283.675071372469, 54088.91822163614, 60051.0, 61023.694881525305, 62856.37143628688, 64847.163755262256, 85870.57064714887, 86389.56805215933, 91034.54482727544, 92199.53900230456, 92877.60683589887, 10939.562417503528, 11457.484413201593, 13421.26183059902, 13590.59228223155, 13753.656158596115, 14369.784453233, 14620.195889226196, 15113.47102851395, 15350.46273380471, 15459.149746764037, 12928.612141635766, 13508.527201547899, 13551.661208469866, 13656.385462654005, 13804.447822087004, 13184.736305274033, 13613.319334033456, 13615.79576306376, 13656.0, 13713.17720936747, 13295.80056299137, 13439.395227214995, 13500.662483437993, 13983.930080349533, 14064.437422502986, 10639.787204255717, 11021.614243501439, 11181.105511558893, 11365.147613928822, 11386.658400248005, 9560.804899387576, 11204.159688023268, 11352.318860868374, 11404.94297528507, 11434.771304573696], "value": 7991.143904816565, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 118.0, 116.0, 115.0, 115.0, 116.0, 114.0, 117.0, 117.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 149.0, 159.0, 121.0, 141.0, 137.0, 121.0, 163.0, 129.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [268.0, 227.0, 198.0, 202.0, 215.0, 218.0, 193.0, 195.0, 199.0, 193.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 74.0, 141.0, 60.0, 58.0, 59.0, 62.0, 135.0, 58.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 127.0, 83.0, 99.0, 113.0, 140.0, 85.0, 73.0, 109.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 91.0, 91.0, 133.0, 85.0, 96.0, 85.0, 89.0, 85.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 97.0, 96.0, 96.0, 121.0, 96.0, 96.0, 99.0, 151.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 98.0, 95.0, 87.0, 147.0, 123.0, 91.0, 124.0, 94.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 56.0, 57.0, 61.0, 80.0, 86.0, 85.0, 84.0, 73.0], "value": 69.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 73.0, 73.0, 79.0, 80.0, 83.0, 87.0, 94.0, 102.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 89.0, 87.0, 90.0, 86.0, 81.0, 108.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 164.0, 150.0, 141.0, 146.0, 151.0, 139.0, 143.0, 142.0, 128.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 170.0, 217.0, 216.0, 175.0, 165.0, 159.0, 173.0, 146.0, 185.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 92.0, 73.0, 69.0, 66.0, 68.0, 68.0, 82.0, 75.0], "value": 73.3, "unit": "score"}]}]} 23:22:38 INFO - Return code: 0 23:22:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:22:38 ERROR - # TBPL SUCCESS # 23:22:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:22:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:22:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:22:38 INFO - 2016-06-02 23:22:38.849 screenresolution[1991:12102] starting screenresolution argv=screenresolution get 23:22:38 INFO - 2016-06-02 23:22:38.852 screenresolution[1991:12102] Display 0: 1600x1200x32@60 23:22:38 INFO - 2016-06-02 23:22:38.857 screenresolution[1992:12105] starting screenresolution argv=screenresolution list 23:22:38 INFO - Available Modes on Display 0 23:22:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:22:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:22:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:22:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:22:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:22:39 INFO - Intel Iris: 23:22:39 INFO - Chipset Model: Intel Iris 23:22:39 INFO - Type: GPU 23:22:39 INFO - Bus: Built-In 23:22:39 INFO - VRAM (Dynamic, Max): 1536 MB 23:22:39 INFO - Vendor: Intel (0x8086) 23:22:39 INFO - Device ID: 0x0a2e 23:22:39 INFO - Revision ID: 0x0009 23:22:39 INFO - Displays: 23:22:39 INFO - Display: 23:22:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:22:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:22:39 INFO - Display Serial Number: 1600x1200 60 23:22:39 INFO - Main Display: Yes 23:22:39 INFO - Mirror: Off 23:22:39 INFO - Online: Yes 23:22:39 INFO - Rotation: Supported 23:22:39 INFO - Return code: 0 23:22:39 INFO - Running post-action listener: _resource_record_post_action 23:22:39 INFO - ##### 23:22:39 INFO - ##### Finished run-tests step (success) 23:22:39 INFO - ##### 23:22:39 INFO - Running post-run listener: _resource_record_post_run 23:22:39 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31489536; Write bytes: 704625664; Read time: 192; Write time: 2007 23:22:39 INFO - TinderboxPrint: CPU usage
54.7% 23:22:39 INFO - TinderboxPrint: I/O read bytes / time
31,489,536 / 192 23:22:39 INFO - TinderboxPrint: I/O write bytes / time
704,625,664 / 2,007 23:22:39 INFO - TinderboxPrint: CPU idle
2,192.4 (45.3%) 23:22:39 INFO - TinderboxPrint: CPU system
976.4 (20.2%) 23:22:39 INFO - TinderboxPrint: CPU user
1,668.7 (34.5%) 23:22:39 INFO - TinderboxPrint: Swap in / out
426,942,464 / 0 23:22:39 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399847936; Write bytes: 394904064; Read time: 29780; Write time: 794 23:22:39 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29707776; Write bytes: 300542464; Read time: 177; Write time: 1185 23:22:39 INFO - Running post-run listener: _upload_blobber_files 23:22:39 INFO - Blob upload gear active. 23:22:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:22:39 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 23:22:39 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'] 23:22:39 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 23:22:39 INFO - (blobuploader) - INFO - Open directory for files ... 23:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:41 INFO - (blobuploader) - INFO - Done attempting. 23:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:42 INFO - (blobuploader) - INFO - Done attempting. 23:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:44 INFO - (blobuploader) - INFO - Done attempting. 23:22:44 INFO - (blobuploader) - INFO - Iteration through files over. 23:22:44 INFO - Return code: 0 23:22:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:22:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:22:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee853e86b575867b9d6bd7fe64a95ced651ba5fbd610a055d30b4d3470821d6ba67a48b22c11d6c520d806047e52a7cc2b13a80b63d583113909ddcb43f3f48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be521cbbcb9d9120f8802418bedaac27dd92dd34b3aa83ab292268dab24089e5ffd5b02fae1127e311f6700840b7b3ca4540966dd723b425dff5053a9c943214", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b345e719f90610c6f38da4ae37147ddce88d47db22cecacc9afb52d86bbbd619e820b5a9d21521ac474591f9f27922bbec7826abb5f48c1b9b0607ad333cd9f7"} 23:22:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:22:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:22:44 INFO - Contents: 23:22:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee853e86b575867b9d6bd7fe64a95ced651ba5fbd610a055d30b4d3470821d6ba67a48b22c11d6c520d806047e52a7cc2b13a80b63d583113909ddcb43f3f48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be521cbbcb9d9120f8802418bedaac27dd92dd34b3aa83ab292268dab24089e5ffd5b02fae1127e311f6700840b7b3ca4540966dd723b425dff5053a9c943214", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b345e719f90610c6f38da4ae37147ddce88d47db22cecacc9afb52d86bbbd619e820b5a9d21521ac474591f9f27922bbec7826abb5f48c1b9b0607ad333cd9f7"} 23:22:44 INFO - Running post-run listener: copy_logs_to_upload_dir 23:22:44 INFO - Copying logs to upload dir... 23:22:44 INFO - mkdir: /builds/slave/test/build/upload/logs 23:22:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.496772 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-06-02 23:22:44.491886) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 23:22:44.494967) ========= 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.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee853e86b575867b9d6bd7fe64a95ced651ba5fbd610a055d30b4d3470821d6ba67a48b22c11d6c520d806047e52a7cc2b13a80b63d583113909ddcb43f3f48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be521cbbcb9d9120f8802418bedaac27dd92dd34b3aa83ab292268dab24089e5ffd5b02fae1127e311f6700840b7b3ca4540966dd723b425dff5053a9c943214", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b345e719f90610c6f38da4ae37147ddce88d47db22cecacc9afb52d86bbbd619e820b5a9d21521ac474591f9f27922bbec7826abb5f48c1b9b0607ad333cd9f7"} build_url:https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010803 build_url: 'https://queue.taskcluster.net/v1/task/QKDvLZTrT-yZteCozg5pcA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee853e86b575867b9d6bd7fe64a95ced651ba5fbd610a055d30b4d3470821d6ba67a48b22c11d6c520d806047e52a7cc2b13a80b63d583113909ddcb43f3f48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be521cbbcb9d9120f8802418bedaac27dd92dd34b3aa83ab292268dab24089e5ffd5b02fae1127e311f6700840b7b3ca4540966dd723b425dff5053a9c943214", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b345e719f90610c6f38da4ae37147ddce88d47db22cecacc9afb52d86bbbd619e820b5a9d21521ac474591f9f27922bbec7826abb5f48c1b9b0607ad333cd9f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 23:22:44.525405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:22:44.525742) ========= 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.k7jOcBOTKK/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.DHUKexNunN/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005092 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:22:44.577121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 23:22:44.577861) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 23:22:44.578712) ========= ========= Total master_lag: 0.17 =========