builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0217 starttime: 1464903214.59 results: success (0) buildid: 20160602133837 builduid: ea5a11430493471aa784c33209d6f9e1 revision: f76a0fdadbf053c3373961f9afbc966c011a50fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.593003) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.593454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.593745) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.615593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.615920) ========= 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.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:33:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-06-02 14:33:34 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133691 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.769707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.770036) ========= 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.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.025992 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:34.818386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 14:33:34.818749) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f76a0fdadbf053c3373961f9afbc966c011a50fe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f76a0fdadbf053c3373961f9afbc966c011a50fe --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:33:34,901 truncating revision to first 12 chars 2016-06-02 14:33:34,902 Setting DEBUG logging. 2016-06-02 14:33:34,902 attempt 1/10 2016-06-02 14:33:34,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f76a0fdadbf0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 14:33:36,395 unpacking tar archive at: fx-team-f76a0fdadbf0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.775669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 14:33:36.615109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:36.615430) ========= script_repo_revision: f76a0fdadbf053c3373961f9afbc966c011a50fe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:36.615823) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:36.616092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 14:33:36.633369) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-02 14:33:36.633757) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:33:36 INFO - MultiFileLogger online at 20160602 14:33:36 in /builds/slave/test 14:33:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:33:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:33:36 INFO - {'append_to_log': False, 14:33:36 INFO - 'base_work_dir': '/builds/slave/test', 14:33:36 INFO - 'blob_upload_branch': 'Fx-Team', 14:33:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:33:36 INFO - 'branch': 'Fx-Team', 14:33:36 INFO - 'buildbot_json_path': 'buildprops.json', 14:33:36 INFO - 'config_files': ('talos/mac_config.py',), 14:33:36 INFO - 'default_actions': ('clobber', 14:33:36 INFO - 'read-buildbot-config', 14:33:36 INFO - 'download-and-extract', 14:33:36 INFO - 'populate-webroot', 14:33:36 INFO - 'create-virtualenv', 14:33:36 INFO - 'install', 14:33:36 INFO - 'run-tests'), 14:33:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:33:36 INFO - 'download_minidump_stackwalk': True, 14:33:36 INFO - 'download_symbols': 'ondemand', 14:33:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:33:36 INFO - 'tooltool.py': '/tools/tooltool.py', 14:33:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:33:36 INFO - '/tools/misc-python/virtualenv.py')}, 14:33:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:33:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:33:36 INFO - 'installer_path': 'installer.exe', 14:33:36 INFO - 'log_level': 'info', 14:33:36 INFO - 'log_name': 'talos', 14:33:36 INFO - 'log_to_console': True, 14:33:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:33:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:33:36 INFO - 'opt_config_files': (), 14:33:36 INFO - 'pip_index': False, 14:33:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:36 INFO - 'cmd': ('bash', 14:33:36 INFO - '-c', 14:33:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:36 INFO - 'enabled': True, 14:33:36 INFO - 'halt_on_failure': False, 14:33:36 INFO - 'name': 'check_screen_resolution'},), 14:33:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:36 INFO - 'cmd': ('bash', 14:33:36 INFO - '-c', 14:33:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:36 INFO - 'enabled': True, 14:33:36 INFO - 'halt_on_failure': False, 14:33:36 INFO - 'name': 'check_screen_resolution'},), 14:33:36 INFO - 'run_cmd_checks_enabled': True, 14:33:36 INFO - 'sps_profile': False, 14:33:36 INFO - 'sps_profile_interval': 0, 14:33:36 INFO - 'suite': 'dromaeojs', 14:33:36 INFO - 'system_bits': '32', 14:33:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:33:36 INFO - 'title': 't-yosemite-r7-0217', 14:33:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:33:36 INFO - 'use_talos_json': True, 14:33:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:33:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:33:36 INFO - 'work_dir': 'build'} 14:33:36 INFO - ##### 14:33:36 INFO - ##### Running clobber step. 14:33:36 INFO - ##### 14:33:36 INFO - Running pre-action listener: _resource_record_pre_action 14:33:36 INFO - Running main action method: clobber 14:33:36 INFO - rmtree: /builds/slave/test/build 14:33:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:33:38 INFO - Running post-action listener: _resource_record_post_action 14:33:38 INFO - ##### 14:33:38 INFO - ##### Finished clobber step (success) 14:33:38 INFO - ##### 14:33:38 INFO - ##### 14:33:38 INFO - ##### Running read-buildbot-config step. 14:33:38 INFO - ##### 14:33:38 INFO - Running pre-action listener: _resource_record_pre_action 14:33:38 INFO - Running main action method: read_buildbot_config 14:33:38 INFO - Using buildbot properties: 14:33:38 INFO - { 14:33:38 INFO - "project": "", 14:33:38 INFO - "product": "firefox", 14:33:38 INFO - "script_repo_revision": "production", 14:33:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:33:38 INFO - "repository": "", 14:33:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:33:38 INFO - "buildid": "20160602133837", 14:33:38 INFO - "pgo_build": "False", 14:33:38 INFO - "basedir": "/builds/slave/test", 14:33:38 INFO - "buildnumber": 47, 14:33:38 INFO - "slavename": "t-yosemite-r7-0217", 14:33:38 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:33:38 INFO - "platform": "yosemite_r7", 14:33:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:33:38 INFO - "branch": "fx-team", 14:33:38 INFO - "repo_path": "integration/fx-team", 14:33:38 INFO - "revision": "f76a0fdadbf053c3373961f9afbc966c011a50fe", 14:33:38 INFO - "stage_platform": "macosx64", 14:33:38 INFO - "builduid": "ea5a11430493471aa784c33209d6f9e1", 14:33:38 INFO - "slavebuilddir": "test" 14:33:38 INFO - } 14:33:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:33:38 INFO - Running post-action listener: _resource_record_post_action 14:33:38 INFO - ##### 14:33:38 INFO - ##### Finished read-buildbot-config step (success) 14:33:38 INFO - ##### 14:33:38 INFO - ##### 14:33:38 INFO - ##### Running download-and-extract step. 14:33:38 INFO - ##### 14:33:38 INFO - Running pre-action listener: _resource_record_pre_action 14:33:38 INFO - Running main action method: download_and_extract 14:33:38 INFO - mkdir: /builds/slave/test/build/tests 14:33:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:38 INFO - https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:33:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:33:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:33:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:40 INFO - Downloaded 1199 bytes. 14:33:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:33:40 INFO - Using the following test package requirements: 14:33:40 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:33:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:33:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:33:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'jsshell-mac.zip'], 14:33:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:33:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:33:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:33:40 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:33:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:33:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:33:40 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:33:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:40 INFO - https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:33:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:33:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:33:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:42 INFO - Downloaded 18713587 bytes. 14:33:42 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'] 14:33:42 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 14:33:46 INFO - Return code: 0 14:33:46 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 14:33: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')]} 14:33:46 INFO - https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:33:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:33:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:33:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:48 INFO - Downloaded 18713587 bytes. 14:33:48 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'] 14:33:48 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 14:33:51 INFO - Return code: 0 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:51 INFO - https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:33:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:33:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:33:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:52 INFO - Downloaded 10834055 bytes. 14:33:52 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'] 14:33:52 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 14:33:53 INFO - Return code: 0 14:33:53 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')]} 14:33:53 INFO - https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:33:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:33:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:33:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/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 14:33:56 INFO - Downloaded 111075045 bytes. 14:33:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:33:56 INFO - mkdir: /builds/slave/test/properties 14:33:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:56 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:56 INFO - Contents: 14:33:56 INFO - build_url:https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:33:57 INFO - Running post-action listener: _resource_record_post_action 14:33:57 INFO - Running post-action listener: set_extra_try_arguments 14:33:57 INFO - ##### 14:33:57 INFO - ##### Finished download-and-extract step (success) 14:33:57 INFO - ##### 14:33:57 INFO - ##### 14:33:57 INFO - ##### Running populate-webroot step. 14:33:57 INFO - ##### 14:33:57 INFO - Running pre-action listener: _resource_record_pre_action 14:33:57 INFO - Running main action method: populate_webroot 14:33:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:57 INFO - '--tppagecycles', 14:33:57 INFO - '7'], 14:33:57 INFO - 'tests': ['tsvgm']}}, 14:33:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['damp', 'tps']}, 14:33:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'tests': ['damp', 'tps']}, 14:33:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['dromaeo_dom']}, 14:33:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['a11yr', 14:33:57 INFO - 'ts_paint', 14:33:57 INFO - 'tpaint', 14:33:57 INFO - 'sessionrestore', 14:33:57 INFO - 'sessionrestore_no_auto_restore', 14:33:57 INFO - 'tabpaint']}, 14:33:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['tsvgx', 14:33:57 INFO - 'tsvgr_opacity', 14:33:57 INFO - 'tart', 14:33:57 INFO - 'tscrollx', 14:33:57 INFO - 'cart']}, 14:33:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:57 INFO - 'tsvgr_opacity', 14:33:57 INFO - 'tart', 14:33:57 INFO - 'tscrollx', 14:33:57 INFO - 'cart']}, 14:33:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'talos_options': ['--disable-e10s'], 14:33:57 INFO - 'tests': ['tp5o']}, 14:33:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'tests': ['tp5o']}, 14:33:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'talos_options': ['--disable-e10s', 14:33:57 INFO - '--xperf_path', 14:33:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:57 INFO - 'tests': ['tp5n']}, 14:33:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:57 INFO - 'talos_options': ['--xperf_path', 14:33:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:57 INFO - 'tests': ['tp5n']}}, 14:33:57 INFO - 'talos.zip': {'path': '', 14:33:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:57 INFO - Running post-action listener: _resource_record_post_action 14:33:57 INFO - ##### 14:33:57 INFO - ##### Finished populate-webroot step (success) 14:33:57 INFO - ##### 14:33:57 INFO - ##### 14:33:57 INFO - ##### Running create-virtualenv step. 14:33:57 INFO - ##### 14:33:57 INFO - Running pre-action listener: _resource_record_pre_action 14:33:57 INFO - Running main action method: create_virtualenv 14:33:57 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:57 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 14:33:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:57 INFO - Using real prefix '/tools/python27' 14:33:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:58 INFO - Installing distribute.............................................................................................................................................................................................done. 14:34:01 INFO - Installing pip.................done. 14:34:01 INFO - Return code: 0 14:34:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:01 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 14:34:01 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 14:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:01 INFO - 'HOME': '/Users/cltbld', 14:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:01 INFO - 'LOGNAME': 'cltbld', 14:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:01 INFO - 'MOZ_NO_REMOTE': '1', 14:34:01 INFO - 'NO_EM_RESTART': '1', 14:34:01 INFO - 'PAGER': '/bin/cat', 14:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:01 INFO - 'PWD': '/builds/slave/test', 14:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:01 INFO - 'SHELL': '/bin/bash', 14:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:01 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:01 INFO - 'USER': 'cltbld', 14:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:01 INFO - 'XPC_FLAGS': '0x0', 14:34:01 INFO - 'XPC_SERVICE_NAME': '0', 14:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 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 14:34:01 INFO - Cleaning up... 14:34:01 INFO - Return code: 0 14:34:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:01 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 14:34:01 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 14:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:01 INFO - 'HOME': '/Users/cltbld', 14:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:01 INFO - 'LOGNAME': 'cltbld', 14:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:01 INFO - 'MOZ_NO_REMOTE': '1', 14:34:01 INFO - 'NO_EM_RESTART': '1', 14:34:01 INFO - 'PAGER': '/bin/cat', 14:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:01 INFO - 'PWD': '/builds/slave/test', 14:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:01 INFO - 'SHELL': '/bin/bash', 14:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:01 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:01 INFO - 'USER': 'cltbld', 14:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:01 INFO - 'XPC_FLAGS': '0x0', 14:34:01 INFO - 'XPC_SERVICE_NAME': '0', 14:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 INFO - Downloading/unpacking psutil>=3.1.1 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:34:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:34:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:34:04 INFO - Installing collected packages: psutil 14:34:04 INFO - Running setup.py install for psutil 14:34:04 INFO - building 'psutil._psutil_osx' extension 14:34:04 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 14:34:05 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 14:34:05 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 14:34:05 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 14:34:05 INFO - building 'psutil._psutil_posix' extension 14:34:05 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 14:34:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:34:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:34:05 INFO - ^ 14:34:05 INFO - 1 warning generated. 14:34:05 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 14:34:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:34:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:34:05 INFO - Successfully installed psutil 14:34:05 INFO - Cleaning up... 14:34:05 INFO - Return code: 0 14:34:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:34:05 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')]} 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 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')]} 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:05 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 14:34:05 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 14:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:05 INFO - 'HOME': '/Users/cltbld', 14:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:05 INFO - 'LOGNAME': 'cltbld', 14:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:05 INFO - 'MOZ_NO_REMOTE': '1', 14:34:05 INFO - 'NO_EM_RESTART': '1', 14:34:05 INFO - 'PAGER': '/bin/cat', 14:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:05 INFO - 'PWD': '/builds/slave/test', 14:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:05 INFO - 'SHELL': '/bin/bash', 14:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:05 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:05 INFO - 'USER': 'cltbld', 14:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:05 INFO - 'XPC_FLAGS': '0x0', 14:34:05 INFO - 'XPC_SERVICE_NAME': '0', 14:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:34:05 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 14:34:05 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 14:34:05 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 14:34:05 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 14:34:05 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 14:34:05 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 14:34:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:34:08 INFO - Installing collected packages: mozsystemmonitor 14:34:08 INFO - Running setup.py install for mozsystemmonitor 14:34:08 INFO - Successfully installed mozsystemmonitor 14:34:08 INFO - Cleaning up... 14:34:08 INFO - Return code: 0 14:34:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:34:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:08 INFO - 'HOME': '/Users/cltbld', 14:34:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:08 INFO - 'LOGNAME': 'cltbld', 14:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:08 INFO - 'MOZ_NO_REMOTE': '1', 14:34:08 INFO - 'NO_EM_RESTART': '1', 14:34:08 INFO - 'PAGER': '/bin/cat', 14:34:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:08 INFO - 'PWD': '/builds/slave/test', 14:34:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:08 INFO - 'SHELL': '/bin/bash', 14:34:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:08 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:08 INFO - 'USER': 'cltbld', 14:34:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:08 INFO - 'XPC_FLAGS': '0x0', 14:34:08 INFO - 'XPC_SERVICE_NAME': '0', 14:34:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:08 INFO - Downloading/unpacking blobuploader==1.2.4 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:11 INFO - Downloading blobuploader-1.2.4.tar.gz 14:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:34:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:34:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:34:12 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 14:34:12 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 14:34:12 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 14:34:12 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 14:34:12 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 14:34:12 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 14:34:12 INFO - Downloading docopt-0.6.1.tar.gz 14:34:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:34:12 INFO - Installing collected packages: blobuploader, requests, docopt 14:34:12 INFO - Running setup.py install for blobuploader 14:34:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:34:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:34:12 INFO - Running setup.py install for requests 14:34:13 INFO - Running setup.py install for docopt 14:34:13 INFO - Successfully installed blobuploader requests docopt 14:34:13 INFO - Cleaning up... 14:34:13 INFO - Return code: 0 14:34:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:13 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')]} 14:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:13 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')]} 14:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:13 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:13 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 14:34:13 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 14:34:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:13 INFO - 'HOME': '/Users/cltbld', 14:34:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:13 INFO - 'LOGNAME': 'cltbld', 14:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:13 INFO - 'MOZ_NO_REMOTE': '1', 14:34:13 INFO - 'NO_EM_RESTART': '1', 14:34:13 INFO - 'PAGER': '/bin/cat', 14:34:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:13 INFO - 'PWD': '/builds/slave/test', 14:34:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:13 INFO - 'SHELL': '/bin/bash', 14:34:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:13 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:13 INFO - 'USER': 'cltbld', 14:34:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:13 INFO - 'XPC_FLAGS': '0x0', 14:34:13 INFO - 'XPC_SERVICE_NAME': '0', 14:34:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:13 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-HpfVmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:13 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-bBLlDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:13 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-uFXgj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Ni6JkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-oidc9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-sgvPat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-hH7LGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-aNKxSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-Yg4koA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-F0vJZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-1FumPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-lIhDdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:14 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-QSKJJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:15 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-V1qhvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:15 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-NmpeM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:15 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-wH4fAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:15 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-cSBvv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:34:15 INFO - Running setup.py install for manifestparser 14:34:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:34:15 INFO - Running setup.py install for mozcrash 14:34:15 INFO - Running setup.py install for mozdebug 14:34:15 INFO - Running setup.py install for mozdevice 14:34:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Running setup.py install for mozfile 14:34:16 INFO - Running setup.py install for mozhttpd 14:34:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Running setup.py install for mozinfo 14:34:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Running setup.py install for mozInstall 14:34:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Running setup.py install for mozleak 14:34:16 INFO - Running setup.py install for mozlog 14:34:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:34:16 INFO - Running setup.py install for moznetwork 14:34:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:34:17 INFO - Running setup.py install for mozprocess 14:34:17 INFO - Running setup.py install for mozprofile 14:34:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:34:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:34:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:34:17 INFO - Running setup.py install for mozrunner 14:34:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:34:17 INFO - Running setup.py install for mozscreenshot 14:34:17 INFO - Running setup.py install for moztest 14:34:17 INFO - Running setup.py install for mozversion 14:34:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:18 INFO - Cleaning up... 14:34:18 INFO - Return code: 0 14:34:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:18 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:18 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 14:34:18 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 14:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:18 INFO - 'HOME': '/Users/cltbld', 14:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:18 INFO - 'LOGNAME': 'cltbld', 14:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:18 INFO - 'MOZ_NO_REMOTE': '1', 14:34:18 INFO - 'NO_EM_RESTART': '1', 14:34:18 INFO - 'PAGER': '/bin/cat', 14:34:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:18 INFO - 'PWD': '/builds/slave/test', 14:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:18 INFO - 'SHELL': '/bin/bash', 14:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:18 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:18 INFO - 'USER': 'cltbld', 14:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:18 INFO - 'XPC_FLAGS': '0x0', 14:34:18 INFO - 'XPC_SERVICE_NAME': '0', 14:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-CbdDF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-VSoqGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-zXCW4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-0o5PyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-VBlLjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-5vIbB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:18 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)) 14:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:18 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-zgYaDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-oiDj5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-EC3G6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-VOnClU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-LDZMxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-tN3wwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-HWS93a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-iyxtOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-E0DyE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:19 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)) 14:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:19 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-cxBe__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:20 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)) 14:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:20 INFO - Running setup.py (path:/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/pip-UQZlNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:20 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 14:34:20 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 14:34:20 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 14:34:20 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 14:34:20 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 14:34:20 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 14:34:22 INFO - Downloading blessings-1.6.tar.gz 14:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:22 INFO - Installing collected packages: blessings 14:34:22 INFO - Running setup.py install for blessings 14:34:22 INFO - Successfully installed blessings 14:34:22 INFO - Cleaning up... 14:34:22 INFO - Return code: 0 14:34:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:23 INFO - Reading from file tmpfile_stdout 14:34:23 INFO - Current package versions: 14:34:23 INFO - blessings == 1.6 14:34:23 INFO - blobuploader == 1.2.4 14:34:23 INFO - docopt == 0.6.1 14:34:23 INFO - manifestparser == 1.1 14:34:23 INFO - mozInstall == 1.12 14:34:23 INFO - mozcrash == 0.17 14:34:23 INFO - mozdebug == 0.1 14:34:23 INFO - mozdevice == 0.48 14:34:23 INFO - mozfile == 1.2 14:34:23 INFO - mozhttpd == 0.7 14:34:23 INFO - mozinfo == 0.9 14:34:23 INFO - mozleak == 0.1 14:34:23 INFO - mozlog == 3.2 14:34:23 INFO - moznetwork == 0.27 14:34:23 INFO - mozprocess == 0.22 14:34:23 INFO - mozprofile == 0.28 14:34:23 INFO - mozrunner == 6.11 14:34:23 INFO - mozscreenshot == 0.1 14:34:23 INFO - mozsystemmonitor == 0.3 14:34:23 INFO - moztest == 0.7 14:34:23 INFO - mozversion == 1.4 14:34:23 INFO - psutil == 3.1.1 14:34:23 INFO - requests == 1.2.3 14:34:23 INFO - wsgiref == 0.1.2 14:34:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:23 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 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:23 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 14:34:23 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 14:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:23 INFO - 'HOME': '/Users/cltbld', 14:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:23 INFO - 'LOGNAME': 'cltbld', 14:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:23 INFO - 'MOZ_NO_REMOTE': '1', 14:34:23 INFO - 'NO_EM_RESTART': '1', 14:34:23 INFO - 'PAGER': '/bin/cat', 14:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:23 INFO - 'PWD': '/builds/slave/test', 14:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:23 INFO - 'SHELL': '/bin/bash', 14:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:23 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:23 INFO - 'USER': 'cltbld', 14:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:23 INFO - 'XPC_FLAGS': '0x0', 14:34:23 INFO - 'XPC_SERVICE_NAME': '0', 14:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 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)) 14:34:23 INFO - Cleaning up... 14:34:23 INFO - Return code: 0 14:34:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:34:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:23 INFO - 'HOME': '/Users/cltbld', 14:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:23 INFO - 'LOGNAME': 'cltbld', 14:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:23 INFO - 'MOZ_NO_REMOTE': '1', 14:34:23 INFO - 'NO_EM_RESTART': '1', 14:34:23 INFO - 'PAGER': '/bin/cat', 14:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:23 INFO - 'PWD': '/builds/slave/test', 14:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:23 INFO - 'SHELL': '/bin/bash', 14:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:23 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:23 INFO - 'USER': 'cltbld', 14:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:23 INFO - 'XPC_FLAGS': '0x0', 14:34:23 INFO - 'XPC_SERVICE_NAME': '0', 14:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:23 INFO - Downloading/unpacking jsonschema 14:34:23 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 14:34:23 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 14:34:23 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 14:34:23 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 14:34:23 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 14:34:23 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 14:34:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:34:26 INFO - Downloading/unpacking functools32 (from jsonschema) 14:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:26 INFO - Downloading functools32-3.2.3-2.tar.gz 14:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:34:26 INFO - warning: no files found matching '*.txt' 14:34:26 INFO - no previously-included directories found matching 'build' 14:34:26 INFO - no previously-included directories found matching 'dist' 14:34:26 INFO - no previously-included directories found matching '.git*' 14:34:26 INFO - Installing collected packages: jsonschema, functools32 14:34:26 INFO - Running setup.py install for functools32 14:34:26 INFO - warning: no files found matching '*.txt' 14:34:26 INFO - no previously-included directories found matching 'build' 14:34:26 INFO - no previously-included directories found matching 'dist' 14:34:26 INFO - no previously-included directories found matching '.git*' 14:34:26 INFO - Successfully installed jsonschema functools32 14:34:26 INFO - Cleaning up... 14:34:26 INFO - Return code: 0 14:34:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10adbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f968b597340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, '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.KAswU9sXIO/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.EYIbIVicFn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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 14:34:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:34:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:34:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:26 INFO - 'HOME': '/Users/cltbld', 14:34:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:26 INFO - 'LOGNAME': 'cltbld', 14:34:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:26 INFO - 'MOZ_NO_REMOTE': '1', 14:34:26 INFO - 'NO_EM_RESTART': '1', 14:34:26 INFO - 'PAGER': '/bin/cat', 14:34:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:26 INFO - 'PWD': '/builds/slave/test', 14:34:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:26 INFO - 'SHELL': '/bin/bash', 14:34:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:34:26 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:34:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:26 INFO - 'USER': 'cltbld', 14:34:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:26 INFO - 'XPC_FLAGS': '0x0', 14:34:26 INFO - 'XPC_SERVICE_NAME': '0', 14:34:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:27 INFO - Downloading/unpacking flake8 14:34: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 14:34: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 14:34: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 14:34: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 14:34: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 14:34: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 14:34:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:34:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:34:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:29 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 14:34:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:34:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:34:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:34:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:34:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:34:30 INFO - no previously-included directories found matching 'docs/_build' 14:34:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:34:30 INFO - Running setup.py install for pep8 14:34:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:34:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:34:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:34:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:34:31 INFO - no previously-included directories found matching 'docs/_build' 14:34:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:34:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:34:31 INFO - Cleaning up... 14:34:31 INFO - Return code: 0 14:34:31 INFO - Running post-action listener: _resource_record_post_action 14:34:31 INFO - Running post-action listener: _start_resource_monitoring 14:34:31 INFO - Starting resource monitoring. 14:34:31 INFO - ##### 14:34:31 INFO - ##### Finished create-virtualenv step (success) 14:34:31 INFO - ##### 14:34:31 INFO - ##### 14:34:31 INFO - ##### Running install step. 14:34:31 INFO - ##### 14:34:31 INFO - Running pre-action listener: _resource_record_pre_action 14:34:31 INFO - Running main action method: install 14:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:31 INFO - Reading from file tmpfile_stdout 14:34:31 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:31 INFO - Reading from file tmpfile_stdout 14:34:31 INFO - Output received: 14:34:31 INFO - Usage: mozinstall [options] installer 14:34:31 INFO - Options: 14:34:31 INFO - -h, --help show this help message and exit 14:34:31 INFO - -d DEST, --destination=DEST 14:34:31 INFO - Directory to install application into. [default: 14:34:31 INFO - "/builds/slave/test"] 14:34:31 INFO - --app=APP Application being installed. [default: firefox] 14:34:31 INFO - mkdir: /builds/slave/test/build/application 14:34:31 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'] 14:34:31 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 14:35:06 INFO - Reading from file tmpfile_stdout 14:35:06 INFO - Output received: 14:35:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:35:06 INFO - Running post-action listener: _resource_record_post_action 14:35:06 INFO - ##### 14:35:06 INFO - ##### Finished install step (success) 14:35:06 INFO - ##### 14:35:06 INFO - ##### 14:35:06 INFO - ##### Running run-tests step. 14:35:06 INFO - ##### 14:35:06 INFO - Running pre-action listener: _resource_record_pre_action 14:35:06 INFO - Running main action method: run_tests 14:35:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:35:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:35:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:35:06 INFO - 2016-06-02 14:35:06.045 screenresolution[1644:7551] starting screenresolution argv=screenresolution get 14:35:06 INFO - 2016-06-02 14:35:06.048 screenresolution[1644:7551] Display 0: 1600x1200x32@60 14:35:06 INFO - 2016-06-02 14:35:06.053 screenresolution[1645:7553] starting screenresolution argv=screenresolution list 14:35:06 INFO - Available Modes on Display 0 14:35:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:35:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:35:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:35:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:35:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:35:06 INFO - Intel Iris: 14:35:06 INFO - Chipset Model: Intel Iris 14:35:06 INFO - Type: GPU 14:35:06 INFO - Bus: Built-In 14:35:06 INFO - VRAM (Dynamic, Max): 1536 MB 14:35:06 INFO - Vendor: Intel (0x8086) 14:35:06 INFO - Device ID: 0x0a2e 14:35:06 INFO - Revision ID: 0x0009 14:35:06 INFO - Displays: 14:35:06 INFO - Display: 14:35:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:35:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:35:06 INFO - Display Serial Number: 1600x1200 60 14:35:06 INFO - Main Display: Yes 14:35:06 INFO - Mirror: Off 14:35:06 INFO - Online: Yes 14:35:06 INFO - Rotation: Supported 14:35:06 INFO - Return code: 0 14:35:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:35:06 INFO - Python 2.7.3 14:35:06 INFO - Return code: 0 14:35:06 INFO - grabbing minidump binary from tooltool 14:35: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')]} 14:35:06 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 0x10ac62d30>, '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 0x10aa86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac59b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:35:06 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 14:35:06 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 14:35:06 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 14:35:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:35:06 INFO - Return code: 0 14:35:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:35:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:35:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:35:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:35:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:35:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:35:07 INFO - Return code: 0 14:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0217 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAswU9sXIO/Render', 14:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:35:07 INFO - 'HOME': '/Users/cltbld', 14:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:35:07 INFO - 'LOGNAME': 'cltbld', 14:35:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:35:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:07 INFO - 'MOZ_NO_REMOTE': '1', 14:35:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:35:07 INFO - 'NO_EM_RESTART': '1', 14:35:07 INFO - 'PAGER': '/bin/cat', 14:35:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:07 INFO - 'PWD': '/builds/slave/test', 14:35:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:35:07 INFO - 'SHELL': '/bin/bash', 14:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYIbIVicFn/Listeners', 14:35:07 INFO - 'TMPDIR': '/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/', 14:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:35:07 INFO - 'USER': 'cltbld', 14:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:07 INFO - 'XPC_FLAGS': '0x0', 14:35:07 INFO - 'XPC_SERVICE_NAME': '0', 14:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:35:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0217', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:35:07 INFO - mozversion application_buildid: 20160602133837 14:35:07 INFO - mozversion application_changeset: f76a0fdadbf053c3373961f9afbc966c011a50fe 14:35:07 INFO - mozversion application_display_name: Nightly 14:35:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:35:07 INFO - mozversion application_name: Firefox 14:35:07 INFO - mozversion application_remotingname: firefox 14:35:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:35:07 INFO - mozversion application_vendor: Mozilla 14:35:07 INFO - mozversion application_version: 49.0a1 14:35:07 INFO - mozversion platform_buildid: 20160602133837 14:35:07 INFO - mozversion platform_changeset: f76a0fdadbf053c3373961f9afbc966c011a50fe 14:35:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:35:07 INFO - mozversion platform_version: 49.0a1 14:35:07 INFO - using testdate: 1464903307 14:35:07 INFO - actual date: 1464903307 14:35:07 INFO - starting webserver on 'localhost:49289' 14:35:07 INFO - SUITE-START | Running 2 tests 14:35:07 INFO - TEST-START | dromaeo_css 14:35:07 INFO - operating with platform_type : mac_ 14:35:07 INFO - Initialising browser for dromaeo_css test... 14:35:07 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpfCVYPt/profile http://localhost:49289/getInfo.html) 14:35:10 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 14:35:10 INFO - PROCESS | 1663 | colorDepth:24 14:35:10 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 14:35:10 INFO - PROCESS | 1663 | __metrics 14:35:10 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:35:10 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:35:10 INFO - TEST-INFO | 1663: exit 0 14:35:10 INFO - Browser initialized. 14:35:10 INFO - Running cycle 1/1 for dromaeo_css test... 14:35:10 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpfCVYPt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:35:22 INFO - PROCESS | 1665 | RSS: Main: 242024448 14:35:22 INFO - PROCESS | 1665 | 14:36:14 INFO - PROCESS | 1665 | 1464903374203 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] 14:38:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49289/tests/dromaeo/cssquery-ext.html) 14:38:26 INFO - PROCESS | 1665 | RSS: Main: 446648320 14:38:26 INFO - PROCESS | 1665 | 14:41:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-ext.html (next: http://localhost:49289/tests/dromaeo/cssquery-jquery.html) 14:41:29 INFO - PROCESS | 1665 | RSS: Main: 376131584 14:41:29 INFO - PROCESS | 1665 | 14:44:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49289/tests/dromaeo/cssquery-mootools.html) 14:44:32 INFO - PROCESS | 1665 | RSS: Main: 368685056 14:44:32 INFO - PROCESS | 1665 | 14:47:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49289/tests/dromaeo/cssquery-prototype.html) 14:47:35 INFO - PROCESS | 1665 | RSS: Main: 372887552 14:47:35 INFO - PROCESS | 1665 | 14:50:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49289/tests/dromaeo/cssquery-yui.html) 14:50:39 INFO - PROCESS | 1665 | RSS: Main: 343298048 14:50:39 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49289/tests/dromaeo/cssquery-yui.html (next: http://localhost:49289/tests/dromaeo/cssquery-dojo.html) 14:53:42 INFO - PROCESS | 1665 | RSS: Main: 381759488 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | __start_tp_report 14:53:42 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 14:53:42 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 14:53:42 INFO - PROCESS | 1665 | |i|pagename|runs| 14:53:42 INFO - PROCESS | 1665 | |0;dojo.html;3418.606860211076;3440.4323286657705;3442.1738782692146;3456.429689101298;3466.826658667066;5413.458654134584;5516.089845175551;5524.723763811813;5647.802326918559;5669.63147395419;5570.805021824237;5623.550115990721;5640.097584386509;5666.433356664342;5692.146178073291;5048.974755126219;5055.77249023793;5135.075686376451;5135.922961155586;5232.3197984262;5236.8690782730355;5238.580913526927;5259.447757985395;5284.022455845662;5288.709120998344;4900.142475066849;4950.18321976877;5018.874528136789;5060.721660308682;5167.4574169712205;4640.535946405333;4911.042346742387;5117.82087626935;5129.358830146231;5190.273361729361;4231.365295205713;5075.8984820303385;5118.744062796845;5121.564667003309;5173.793048278064;5436.401995780461;5490.368607610136;5572.331320241557;5628.240187574666;5675.262215911946;6140.324564297924;6148.907766383508;6171;6180.351063138351;6181.598196117283;6135.601185922946;6209.223847019122;6213.5339849511465;6222.284437289724;6228.781992630282;5563.693996830173;5638.633488823254;5641.605087643826;5721.742521586539;5755.30936287644;6310.842228944313;6316.778912738124;6322.241331040304;6334.683265836689;6339.144215530937;6058.878822423527;6061.572500672761;6072.057200250621;6089.130025094462;6092.958858782357;6067.696615169224;6123.050927106306;6142.109394137825;6170.876582468325;6227.719752611143;6142.324344322121;6156.168917196211;6186.659733714689;6195.19462469885;6213.937860621426;6177.938220617826;6186.319504854462;6230.875382492325;6246;6261.342559031236;4818.566329030404;4874.7562621868765;4879.658423910363;4936.703797772145;4966.553010229097;4413.691041627056;4474.552544745499;4494.977525112354;4500.549945005539;4502.527234640315;5564.304461942257;5616.747246373923;5626.915596266059;5664.122061080539;5685.943140568541;6215.595986260879;6273.271573679725;6283.905741413882;6288.937110628926;6309.810705678837;5502.669839809624;5525.143602741581;5663.093904975184;5684.261046064068;5693.259876216148;5372.865678358073;5373.596980226499;5376.946230537723;5378.058839703114;5379.1393377059485;6120.629988696826;6292.842678933063;6295.024271237966;6306.1171435998995;6328.01915703076;5173.896522069463;6160.661163636043;6206.813795586139;6226.470749986121;6233.719482623384;6193.7793689863865;6291.685415729286;6296.5592408530965;6314.936850631435;6316.147320111726;5991.610545314541;6062.151298818258;6080.969595151996;6115.449609535163;6115.480184184216;6221.06683997404;6259.718312675849;6265.686715664107;6271.843203919939;6275.780347687809;4839;6009.8197054086695;6042.033274676207;6072.240969878765;6103.694815259308;5833.854153646193;5884.440978107073;5890.587658863838;5977.760889564369;5982.790602329072;5827.3007239131575;5844.678542680023;5855.707214639337;5857.970710146422;5868.911966320594;5982.461578457785;5996.010658241516;5997.880042399041;5999.340072591924;6004.00933845928;5831.825045248649;5861.531077513874;5862.032764593965;5877.265341832271;5891.3224979127945 14:53:42 INFO - PROCESS | 1665 | |1;ext.html;494.94308154562214;500.4170141784821;507.9644424890258;509.9235114732792;521.5436493068565;28122;42120.78939605301;42184.78907605458;42608.57391426085;45048;45149.54850451495;45504.089918201615;45534.77232613841;45662.543374566245;45928.770356148256;8109.513429194252;9670.984546622609;9675.951620241889;9678.758031049245;9685.661001864935;9497.382670126453;9672.322937394385;9673.322867399285;9675.709728708114;9704.932457429677;9620.086091821266;9656.903430965705;9663.371880827759;9672.854907176397;9681.806363872718;9593.568289426958;9653;9653.469059201741;9659.661911833122;9662.90337096627;9381.061893810633;9448.149666529976;9551.90448095517;9553.856692149624;9581.568829402624;40940.79529602363;41042.38436423456;41208.79395603033;41216;41306.38040429396;3489.075395020296;3535.381308271042;3542.1498840278405;3543.061088811467;3593.6047034826147;31456.213594660087;31868;32074.518882216784;32112.678873211204;32456.864009759545;3212;3260.0871755908383;3267.673232676737;3267.869285228588;3374.8818791342433;382141;382228;382495.1750482487;382498;382685;64709.35290647127;65158.674206629614;65183.67408162929;65236;65241.67379163074;58369;58671.70664146652;59832.40167598355;68062.95811125674;69334;17198.91400542989;17278.827211727974;17450.56373590645;17489.95060296386;17514.21186046631;13082.934585327013;13273.933630331787;13287.202767833767;13300.667483312802;13300.935925125967;16388.93471924321;16753.413630522875;16755.832441675426;17280;19092.2363105477;5612.550995920316;5737.942620573825;5822.359540450547;6350.69810688817;6618.86762264752;822.366777581259;1058.5342449322427;1063.1335461598771;1122.449999500239;1188.6552899659002;25285.747142528708;30281.848590756905;30536.389272214874;31138.68861311403;31208.687913121033;1043.684956954228;1100.0154861399003;1103.8896110388896;1149.1955631058127;1185.3421351150193;15052.892595036703;15850.96968697054;15891.205439727968;15914.68170636604;15918.76121858173;651466;659631;666823;667184;667454;62786.686066569375;63090.68454657697;63097.053544196875;63106.36893631005;63165.68417157885;1379.4964837834118;1407.310417895224;1414.844367119616;1416.6033510617044;1430.5708287513705;30255.941042063423;30315;30346.544801828433;30746.692533074383;31157;54970.72514637401;55075;55442.168367473714;55514.16728748991;55561.444385557246;495491;498365;498949;499976;501958;43510.78244608884;44204;44853;44975.10049799051;44990;49316.75341623269;49584;50182;50870.49129508805;51338.74330628323;39457.40813887736;40220.39669404903;40679.18641627092;40815;41325.586744133376;33994;38625;40447.59552404438;40491.79754101211;41440.585594143675;38941.61058389493;39432.6056739429;39777;39928.00179995524;39946.800265998485;32396.83801580977;34402.65597343994;34766.560255387274;35330;35660.46509302415;23028.654570181126;25616.231513054638;25617.743822561537;25867.22398328053;26087 14:53:42 INFO - PROCESS | 1665 | |2;jquery.html;360.713232979781;383.6968794652225;387.6007712056582;389.4080996884737;407.85724996251315;14882.107073575593;15083.698326033473;20276.29032983846;20350.6947395789;20373.796262037376;20392.694109588367;20960.37118886432;21061.473463163427;21420.9289535523;21514.569708605817;5819.2725909261235;5982.910256346158;6071.969640151793;6079.604825686339;6624.56940298879;6593.076969224312;6594.109795177662;6598.208215014205;6612.966935165317;6633.402993730563;5601.187827764992;6443.967780161092;6484;6586.176727909012;6610.471162307028;6453.774117905898;6488.929326661095;6533.2160140782935;6566.343365663443;6574.506911981596;4720.716756994591;5444.292242008513;5621.325440947072;5640.097584386478;5650.237217975562;25829.837657305467;26091;26251.868740656366;26555.46889062208;26800.59799103015;2259.4916143867663;2273.2952784636814;2351.9529609407714;2512.962305565418;2792.7765778737653;14897.627559311104;15579.610509737347;15836.76244856328;16069.357225710957;16410.425635102834;1842.68674325365;1923.394130848778;1925.0471016846611;1966.2626515056854;2013.6508539278718;53267.20099198515;80582.59708701419;102096;103193;114675;9092.954535227414;10959.01368876805;10993.34039957589;11058.723531911606;11152.832707509393;13628.795568066487;13706.24615646149;13718.794218086736;13745.450181992808;13750.518731844337;8595.828083438297;8765.605547750367;8773.780655483664;8854;8900.821983560421;8767.298616110696;8775.868361974575;8802.735917922344;8910.643574257087;8936.910630893608;6589.406953374123;6696.46428285736;6729.966350168315;6736.6968486417245;6741.52809303344;1404.7682132448235;1569.669519862885;1790.3286267649632;1825.7352683860765;1862.1434140295346;921.9216366608913;944.6363150187227;1024.4058446101244;1025.7845852370936;1079.6545105566142;17613.647727045387;21158.365249042552;21384.71691698472;21531.89234053851;22273.331800046024;979.911807937289;1003.859459675646;1007.5516395204211;1011.8229309870891;1047.6123834181249;9790.510474476248;11557.190996630155;11772.764544709058;11932.642020739391;11938.88061119395;548253;565581;566432;586002;612194;114924.38067144998;139680;144139.27930360282;147666;153288.2335588315;991.1278075293695;1011.4032720694826;1030.2118879057527;1038.1331588124;1038.8285932821152;19010.42968710941;19420.61158776845;19437.8056219436;19497.70753438671;19544.413667589994;43279.78360108179;52347;53141.468585313654;54983;56191.43808561862;70787.29212708013;78394.6080269595;79531;82256.58871705603;83654;11664.241824281395;12123.030157587196;12139.210951288138;12252.87747122553;12302.446389912297;13179.67050823737;13219.933900330438;13281.203127812361;13485.528006519724;13519.324033798468;11282.43587820622;11459.828102578635;11612.94193529027;11634.301941883514;11714.824277636013;10818.188635852186;11387.259828111146;11401.771964560496;11666.591669291534;11746.236494627821;7671.884921726067;7807.063152421519;8333.37499687538;10587.735306617396;11923.463444144862;9742.464164470739;12251;12338.02591585342;12499.125061245626;12584.748305033665;11809.940950295537;11871.406429678655;11933.76132477363;12068.637940861789;12082.637520874388 14:53:42 INFO - PROCESS | 1665 | |3;mootools.html;1308.7709650811105;1534.9923250383747;1547.4119834462902;1724.9568760780978;1872.4476279497546;7675.04061992251;8349.20682535159;8579.142085791425;8588.613512391941;8742.694005709802;8403.579821008956;8603.225709686127;8616.43993140447;8623.525706086162;8630.266427353683;2099.1078791513623;2180.967285490719;2314.571804216217;2354.234873666061;2387.785099341059;2227;2259.9096036158617;2261.1407665087245;2263.5586060718165;2264.184893438361;2333.3466629343725;2356.893939772706;2379.7501262367464;2382.3210385040265;2385.5109702510945;2047.0992763184151;2273.602119629075;2322.2684854270933;2346.483773569812;2347.1315613223046;2350.1304517328526;2371.513839662882;2373.964390534143;2375.6674065630827;2376.1089591403224;7227.530210536351;8594.957025214897;8602.956985215096;8605.225529702295;8612.655493780305;5112.156494178456;5277.287566178555;5280.049591073605;5281.445448227958;5283.920741188886;3248.7076163145193;3317.9004629861142;3424.9143771405666;3436.553248077759;3535.805530695811;1040.5369610523321;1056.614335767447;1059.978800423995;1083.7236504691252;1083.8211695070304;11364.94317528407;11375.943120284428;11378.37418941958;11408.771824563462;11409.42952852354;4600.4479462464715;4602.654800889946;4711.246200607886;4737.099951009297;4752.596029337476;9698.224142068617;9698.321117521706;9706.077922597484;9711.36876103051;9728.464934428674;5257.658252213594;5259.289995850588;5264.289320941624;5266.552343050807;5269.973650131725;4380.912381752347;4533.727976321431;4673.345731597579;4683.6721429500285;4828.61371090319;3346.698797108223;3878.941815872764;3963.7621742695533;4015.678745700337;4041.5150181977765;1263.91784534005;1286.9537435477635;1291.1930043722673;1297.7534268488957;1298.1821452485062;905.171767832426;909.3861643390685;919.9954000229956;933.9091120443537;958.2525630008604;5751.539876809845;5760.884782304331;5876.118582212617;5878.088896221093;5920.378360272195;426.78447384071467;446.4196544492147;448.2044371241073;458.0518327063014;458.55978260869273;3936.291467535871;3962.9590760758233;3976.681865450757;3982.980085099556;4038.7172897896867;10046.29675922693;10277.845832312521;10473.895261047444;10477.057064864199;10498.580056797642;10385.532651030722;10484.842727359095;10489;10503.369797812156;10506.579736810441;7632.923670763222;7946.165652606509;8057.435979481497;8086.393520485987;8087.63605637736;5695.145728140812;5826;5849.912251316148;5915.6450612961635;6014.127951447103;9443.150116489549;9766.414015159113;9808.65669701557;9933;9951.054649808257;8396.496210227406;9746;10006.599736010477;10060.547275372479;10136;6921.169459664872;7138.678759455733;7148.678309475982;7149.428045756431;7472.140703818913;7838.216178382117;7926.643301051351;7985.2014798519685;8229.094799572162;8234.547099909563;7277.16312624055;7375.2256013119995;7388.187299396955;7496.962515187389;7548.584827834522;7225.783226503213;7289.307515785992;7307.744228951961;7386.446016548673;7412.221716719882;6934.098567186234;6935;7019.982102595197;7023.578585284899;7036.894446583407;4641.257398816173;4799.688020278671;4846.312287095002;5044.445111037856;5079.187330027178;4239.342901850218;4953.801847926043;4998.075356059141;5014.222795466709;5091.0836049511445 14:53:42 INFO - PROCESS | 1665 | |4;prototype.html;304.5279816284758;313.1810316023598;314.5753233135268;320.6168628488954;321.437484402296;5288.826703977612;5307.52232299093;5311.60162987776;5320.228566857803;5384.6499977501535;5152.716600586966;5240.266362709214;5242.6330156888935;5280.676502338968;5289.841304760864;3790.185110201306;3846.922661762645;3921.8431262749455;4060.9593904061026;4077.612626800449;3928.685705143596;3999.5000624921886;4000.979505349794;4019.638232559069;4023;3957.881263562098;3964.6233607807358;3964.9976761735284;3976.667816281549;4003.8198281077275;3584.1006499337586;3774.301754175486;3953.960460395388;3962.9405558916637;4003.5596084430836;3432.9313413731584;3445.5176275321473;3467.895963121085;3475.7219422446396;3495.405781017233;4305.31115021595;4595.310703394484;4960.851174464772;4993.625478089121;5030.711026482978;3636.1636823530735;3884.028992751812;4425.004374015853;4528.916698318777;4563.885902852422;4883.536064073943;4896.36347274852;5351.812686555991;5498.890022199574;5534.363548191929;3781.357169281229;3781.773093614392;3794.9810250948844;3795.8671446499516;3797.9240415191543;5458.4541545845495;5547.639403438763;5802.62282951611;5851.210086638292;5881.147233651097;2990.790644654897;3310.0566338593867;3789.412641040643;3836.67388272;3853.672437842815;3590.425531914881;3773.735798507476;3882.4758657580865;3885.9404515606775;3897.902552436155;3697.8335974881193;3995.6403923647013;4555.886102847456;4559.772011399416;4615.14619795339;5033.899322013539;5312;5577.549837042398;5602.411746766531;5682.659040457585;1287.3048553780854;1311.2984553263957;1317.6442360562787;1329.7805862032656;1340.8592097829587;688.3048121397425;735.1398863329916;761.3794757272805;816.322452364536;820.6512232301249;4737.360456338351;4898.795542006041;4918.360613120271;4938.506149385033;4943.233798761198;4968.726720030433;5491.693488734741;5674.319081710221;5696.974544581932;5715.39949323057;4221.514525829569;4491.775411229491;4814.975925120352;5107.489251074863;5164.9741751291;2566.9090636479423;2837.645294338208;2982.925426864346;3004.444177827109;3011.472992226352;5573.080441727069;5758.884822303614;5960.284765828128;5969.98510253258;6003.669798161143;4925.24186455586;5747.913781293284;5861.863902811975;5871.911921321183;5877.735501902424;5470.425605311385;5885.440883116097;5892.292924849045;5917.644941303622;5970.462658360769;4814.350062741625;5241.9737901310245;5497.780088796563;5631.549476041825;5703.034422100567;4934.506549345037;5489.835304940858;5513.944860551504;5563.468621777162;5606.523445507014;5388.919166212431;5820.476157145878;5863.384344643751;5889.499392551681;5919.822405327847;3172.0491897483344;3212.9678703212667;3294.7528935329465;3298.274379636476;3300.0364782954102;4449.888752781077;4523.570260825217;4571.702839315434;4837.782874388492;4860.635452341159;4630.3517507548295;4650.720956742606;4661.883452913704;4697.037107392908;4722.763861806827;4251.426057482324;4306.784660766886;4385.662372976273;4676.7661616919695;4693.906121877611;3987;4199.601037901394;4335.891602709958;4431.8448854289945;4611.953880461152;5186.351706036746;5205.167173252262;5323.334583177103;5365.712229064919;5520.199571062254;5361.839144825662;5390.407055224001;5392.838214853561;5400.918986215132;5414.539764120094 14:53:42 INFO - PROCESS | 1665 | |5;yui.html;682.6416131530949;683.4976292425067;717.2253965717026;772.1660606544932;828.1139181076251;15291.541253762378;15384.153871537075;15672.902896797243;15677.29452174652;15701.607459813511;14126.929365353184;14254.429822807075;14256.429742810302;14306.570802875904;14324.06893551921;707.3209718670083;729.1214087025148;869.1786261982445;875.3041332140963;892.4734406700046;890.7639475538988;909.6725178935578;910.8041771019263;912.2610685344858;912.5847739278612;943.0663642993421;964.5466630683602;966.6761634852332;967.013646080997;968.3366893677818;745.9216782237895;916.9816603667889;945.1588086602928;956.5982287439257;959.2086528613921;696.2689176364847;696.3767428151826;697.8639165362706;698.7868700046483;701.0150738212819;14677.706445871023;14737.189454579993;14740.336684849208;14758.188299643516;14787.112773233534;13423.597292081253;13661.385237664328;13758.904598190875;13792.310384480836;13878.375473103633;12704.80942785859;12705.745885082246;12740.80888786669;12925.54760583375;12958.48166073356;4550.476695180064;4718.985418135083;4720.905581888343;4725.10223057618;4726.550977657117;98483.97715554803;101418.49290753572;102244;103571;103897.48051259771;10789.514471848786;10922.836157457645;10959.56161753521;10961.287516311415;11104.777904441866;21081.578368432853;21104.577908442054;21209.15163393447;21227.46931326698;21480.892595537236;7877.212278772192;7885.723999659985;7915.762527124196;8003.279704826477;8190.344772418192;4406.845760398371;4465.553444655573;4480.798364073559;4530.977345113253;4680.4149481314835;3270.8364581771293;3431.227973705946;3432.7940323580165;3434.0213039283685;3437.157896315369;532.4675324675325;672.1128110893671;676.0737789228788;690.5960013392222;693.3447891742255;615.258613370887;623.6943897490197;682.668905580793;689.7068745783123;696.3663066609361;8429.620667069874;9709.98840746292;10005.43502650194;10128.746781330527;10199.91961534455;576.3314555116045;593.7090825495476;627.1940556385023;647.2039391548421;704.7709494414336;4532.388127602731;4662.510436404196;4690.390249267642;4691.577758001797;4769.57525645688;42431.7878410606;95010;108791.2802179936;113102.86897131088;150220;96538.51730741301;113952;118750.40624796994;123537;130812;88041.11958880458;100365.49817251012;101316;108399;111506.44246778714;9467.053294670754;9753.853692194765;9895.65365212214;9918.504074796376;9923.156531694887;47987;56398.43601564096;57789;59629.70185149046;60165.69917150386;57275;57941.11326026112;85086.57456712677;85437;89131;11164.38595877202;12608.49566017349;12743.491898587527;13152.342382880626;13311.933440332736;14425.78361324602;14461.13233206011;14516.637084072983;15195.164265965477;15466.845331546541;12705.36473176356;12731.87268127307;12767.87232127704;12809.679758006125;13156.671083222996;11212;13675.974301927195;13819.654508636962;13820.308984550933;14013.299335033003;13321.53374631922;13354.799678004643;13616.86383136156;13736.793948090986;14016.929915350358;10328.535215915152;10874.728131796452;11188.440577970907;11246.550137994389;11543.711407214887;10463.372197668165;11114.666560003212;11217.158713096713;11421.200515963803;11527.8270825936 14:53:42 INFO - PROCESS | 1665 | __end_tp_report 14:53:42 INFO - PROCESS | 1665 | __start_cc_report 14:53:42 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2247 14:53:42 INFO - PROCESS | 1665 | __end_cc_report 14:53:42 INFO - PROCESS | 1665 | __startTimestamp1464904422925__endTimestamp 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | ------- Summary: start ------- 14:53:42 INFO - PROCESS | 1665 | Number of tests: 6 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5669.98 Median:5857.97 stddev:641.36 (10.9%) stddev-sans-first:618.54 14:53:42 INFO - PROCESS | 1665 | Values: 3418.6 3440.4 3442.2 3456.4 3466.8 5413.5 5516.1 5524.7 5647.8 5669.6 5570.8 5623.6 5640.1 5666.4 5692.1 5049.0 5055.8 5135.1 5135.9 5232.3 5236.9 5238.6 5259.4 5284.0 5288.7 4900.1 4950.2 5018.9 5060.7 5167.5 4640.5 4911.0 5117.8 5129.4 5190.3 4231.4 5075.9 5118.7 5121.6 5173.8 5436.4 5490.4 5572.3 5628.2 5675.3 6140.3 6148.9 6171.0 6180.4 6181.6 6135.6 6209.2 6213.5 6222.3 6228.8 5563.7 5638.6 5641.6 5721.7 5755.3 6310.8 6316.8 6322.2 6334.7 6339.1 6058.9 6061.6 6072.1 6089.1 6093.0 6067.7 6123.1 6142.1 6170.9 6227.7 6142.3 6156.2 6186.7 6195.2 6213.9 6177.9 6186.3 6230.9 6246.0 6261.3 4818.6 4874.8 4879.7 4936.7 4966.6 4413.7 4474.6 4495.0 4500.5 4502.5 5564.3 5616.7 5626.9 5664.1 5685.9 6215.6 6273.3 6283.9 6288.9 6309.8 5502.7 5525.1 5663.1 5684.3 5693.3 5372.9 5373.6 5376.9 5378.1 5379.1 6120.6 6292.8 6295.0 6306.1 6328.0 5173.9 6160.7 6206.8 6226.5 6233.7 6193.8 6291.7 6296.6 6314.9 6316.1 5991.6 6062.2 6081.0 6115.4 6115.5 6221.1 6259.7 6265.7 6271.8 6275.8 4839.0 6009.8 6042.0 6072.2 6103.7 5833.9 5884.4 5890.6 5977.8 5982.8 5827.3 5844.7 5855.7 5858.0 5868.9 5982.5 5996.0 5997.9 5999.3 6004.0 5831.8 5861.5 5862.0 5877.3 5891.3 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:66976.10 Median:41212.40 stddev:140706.73 (341.4%) stddev-sans-first:141012.91 14:53:42 INFO - PROCESS | 1665 | Values: 494.9 500.4 508.0 509.9 521.5 28122.0 42120.8 42184.8 42608.6 45048.0 45149.5 45504.1 45534.8 45662.5 45928.8 8109.5 9671.0 9676.0 9678.8 9685.7 9497.4 9672.3 9673.3 9675.7 9704.9 9620.1 9656.9 9663.4 9672.9 9681.8 9593.6 9653.0 9653.5 9659.7 9662.9 9381.1 9448.1 9551.9 9553.9 9581.6 40940.8 41042.4 41208.8 41216.0 41306.4 3489.1 3535.4 3542.1 3543.1 3593.6 31456.2 31868.0 32074.5 32112.7 32456.9 3212.0 3260.1 3267.7 3267.9 3374.9 382141.0 382228.0 382495.2 382498.0 382685.0 64709.4 65158.7 65183.7 65236.0 65241.7 58369.0 58671.7 59832.4 68063.0 69334.0 17198.9 17278.8 17450.6 17490.0 17514.2 13082.9 13273.9 13287.2 13300.7 13300.9 16388.9 16753.4 16755.8 17280.0 19092.2 5612.6 5737.9 5822.4 6350.7 6618.9 822.4 1058.5 1063.1 1122.4 1188.7 25285.7 30281.8 30536.4 31138.7 31208.7 1043.7 1100.0 1103.9 1149.2 1185.3 15052.9 15851.0 15891.2 15914.7 15918.8 651466.0 659631.0 666823.0 667184.0 667454.0 62786.7 63090.7 63097.1 63106.4 63165.7 1379.5 1407.3 1414.8 1416.6 1430.6 30255.9 30315.0 30346.5 30746.7 31157.0 54970.7 55075.0 55442.2 55514.2 55561.4 495491.0 498365.0 498949.0 499976.0 501958.0 43510.8 44204.0 44853.0 44975.1 44990.0 49316.8 49584.0 50182.0 50870.5 51338.7 39457.4 40220.4 40679.2 40815.0 41325.6 33994.0 38625.0 40447.6 40491.8 41440.6 38941.6 39432.6 39777.0 39928.0 39946.8 32396.8 34402.7 34766.6 35330.0 35660.5 23028.7 25616.2 25617.7 25867.2 26087.0 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34468.08 Median:21010.92 stddev:96141.74 (457.6%) stddev-sans-first:96377.34 14:53:42 INFO - PROCESS | 1665 | Values: 360.7 383.7 387.6 389.4 407.9 14882.1 15083.7 20276.3 20350.7 20373.8 20392.7 20960.4 21061.5 21420.9 21514.6 5819.3 5982.9 6072.0 6079.6 6624.6 6593.1 6594.1 6598.2 6613.0 6633.4 5601.2 6444.0 6484.0 6586.2 6610.5 6453.8 6488.9 6533.2 6566.3 6574.5 4720.7 5444.3 5621.3 5640.1 5650.2 25829.8 26091.0 26251.9 26555.5 26800.6 2259.5 2273.3 2352.0 2513.0 2792.8 14897.6 15579.6 15836.8 16069.4 16410.4 1842.7 1923.4 1925.0 1966.3 2013.7 53267.2 80582.6 102096.0 103193.0 114675.0 9093.0 10959.0 10993.3 11058.7 11152.8 13628.8 13706.2 13718.8 13745.5 13750.5 8595.8 8765.6 8773.8 8854.0 8900.8 8767.3 8775.9 8802.7 8910.6 8936.9 6589.4 6696.5 6730.0 6736.7 6741.5 1404.8 1569.7 1790.3 1825.7 1862.1 921.9 944.6 1024.4 1025.8 1079.7 17613.6 21158.4 21384.7 21531.9 22273.3 979.9 1003.9 1007.6 1011.8 1047.6 9790.5 11557.2 11772.8 11932.6 11938.9 548253.0 565581.0 566432.0 586002.0 612194.0 114924.4 139680.0 144139.3 147666.0 153288.2 991.1 1011.4 1030.2 1038.1 1038.8 19010.4 19420.6 19437.8 19497.7 19544.4 43279.8 52347.0 53141.5 54983.0 56191.4 70787.3 78394.6 79531.0 82256.6 83654.0 11664.2 12123.0 12139.2 12252.9 12302.4 13179.7 13219.9 13281.2 13485.5 13519.3 11282.4 11459.8 11612.9 11634.3 11714.8 10818.2 11387.3 11401.8 11666.6 11746.2 7671.9 7807.1 8333.4 10587.7 11923.5 9742.5 12251.0 12338.0 12499.1 12584.7 11809.9 11871.4 11933.8 12068.6 12082.6 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5580.27 Median:4837.46 stddev:3126.95 (64.6%) stddev-sans-first:3119.24 14:53:42 INFO - PROCESS | 1665 | Values: 1308.8 1535.0 1547.4 1725.0 1872.4 7675.0 8349.2 8579.1 8588.6 8742.7 8403.6 8603.2 8616.4 8623.5 8630.3 2099.1 2181.0 2314.6 2354.2 2387.8 2227.0 2259.9 2261.1 2263.6 2264.2 2333.3 2356.9 2379.8 2382.3 2385.5 2047.1 2273.6 2322.3 2346.5 2347.1 2350.1 2371.5 2374.0 2375.7 2376.1 7227.5 8595.0 8603.0 8605.2 8612.7 5112.2 5277.3 5280.0 5281.4 5283.9 3248.7 3317.9 3424.9 3436.6 3535.8 1040.5 1056.6 1060.0 1083.7 1083.8 11364.9 11375.9 11378.4 11408.8 11409.4 4600.4 4602.7 4711.2 4737.1 4752.6 9698.2 9698.3 9706.1 9711.4 9728.5 5257.7 5259.3 5264.3 5266.6 5270.0 4380.9 4533.7 4673.3 4683.7 4828.6 3346.7 3878.9 3963.8 4015.7 4041.5 1263.9 1287.0 1291.2 1297.8 1298.2 905.2 909.4 920.0 933.9 958.3 5751.5 5760.9 5876.1 5878.1 5920.4 426.8 446.4 448.2 458.1 458.6 3936.3 3963.0 3976.7 3983.0 4038.7 10046.3 10277.8 10473.9 10477.1 10498.6 10385.5 10484.8 10489.0 10503.4 10506.6 7632.9 7946.2 8057.4 8086.4 8087.6 5695.1 5826.0 5849.9 5915.6 6014.1 9443.2 9766.4 9808.7 9933.0 9951.1 8396.5 9746.0 10006.6 10060.5 10136.0 6921.2 7138.7 7148.7 7149.4 7472.1 7838.2 7926.6 7985.2 8229.1 8234.5 7277.2 7375.2 7388.2 7497.0 7548.6 7225.8 7289.3 7307.7 7386.4 7412.2 6934.1 6935.0 7020.0 7023.6 7036.9 4641.3 4799.7 4846.3 5044.4 5079.2 4239.3 4953.8 4998.1 5014.2 5091.1 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4361.34 Median:4709.90 stddev:1358.40 (28.8%) stddev-sans-first:1327.65 14:53:42 INFO - PROCESS | 1665 | Values: 304.5 313.2 314.6 320.6 321.4 5288.8 5307.5 5311.6 5320.2 5384.6 5152.7 5240.3 5242.6 5280.7 5289.8 3790.2 3846.9 3921.8 4061.0 4077.6 3928.7 3999.5 4001.0 4019.6 4023.0 3957.9 3964.6 3965.0 3976.7 4003.8 3584.1 3774.3 3954.0 3962.9 4003.6 3432.9 3445.5 3467.9 3475.7 3495.4 4305.3 4595.3 4960.9 4993.6 5030.7 3636.2 3884.0 4425.0 4528.9 4563.9 4883.5 4896.4 5351.8 5498.9 5534.4 3781.4 3781.8 3795.0 3795.9 3797.9 5458.5 5547.6 5802.6 5851.2 5881.1 2990.8 3310.1 3789.4 3836.7 3853.7 3590.4 3773.7 3882.5 3885.9 3897.9 3697.8 3995.6 4555.9 4559.8 4615.1 5033.9 5312.0 5577.5 5602.4 5682.7 1287.3 1311.3 1317.6 1329.8 1340.9 688.3 735.1 761.4 816.3 820.7 4737.4 4898.8 4918.4 4938.5 4943.2 4968.7 5491.7 5674.3 5697.0 5715.4 4221.5 4491.8 4815.0 5107.5 5165.0 2566.9 2837.6 2982.9 3004.4 3011.5 5573.1 5758.9 5960.3 5970.0 6003.7 4925.2 5747.9 5861.9 5871.9 5877.7 5470.4 5885.4 5892.3 5917.6 5970.5 4814.4 5242.0 5497.8 5631.5 5703.0 4934.5 5489.8 5513.9 5563.5 5606.5 5388.9 5820.5 5863.4 5889.5 5919.8 3172.0 3213.0 3294.8 3298.3 3300.0 4449.9 4523.6 4571.7 4837.8 4860.6 4630.4 4650.7 4661.9 4697.0 4722.8 4251.4 4306.8 4385.7 4676.8 4693.9 3987.0 4199.6 4335.9 4431.8 4612.0 5186.4 5205.2 5323.3 5365.7 5520.2 5361.8 5390.4 5392.8 5400.9 5414.5 14:53:42 INFO - PROCESS | 1665 | 14:53:42 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:22169.73 Median:3433.41 stddev:33680.17 (981.0%) stddev-sans-first:33736.01 14:53:42 INFO - PROCESS | 1665 | Values: 682.6 683.5 717.2 772.2 828.1 15291.5 15384.2 15672.9 15677.3 15701.6 14126.9 14254.4 14256.4 14306.6 14324.1 707.3 729.1 869.2 875.3 892.5 890.8 909.7 910.8 912.3 912.6 943.1 964.5 966.7 967.0 968.3 745.9 917.0 945.2 956.6 959.2 696.3 696.4 697.9 698.8 701.0 14677.7 14737.2 14740.3 14758.2 14787.1 13423.6 13661.4 13758.9 13792.3 13878.4 12704.8 12705.7 12740.8 12925.5 12958.5 4550.5 4719.0 4720.9 4725.1 4726.6 98484.0 101418.5 102244.0 103571.0 103897.5 10789.5 10922.8 10959.6 10961.3 11104.8 21081.6 21104.6 21209.2 21227.5 21480.9 7877.2 7885.7 7915.8 8003.3 8190.3 4406.8 4465.6 4480.8 4531.0 4680.4 3270.8 3431.2 3432.8 3434.0 3437.2 532.5 672.1 676.1 690.6 693.3 615.3 623.7 682.7 689.7 696.4 8429.6 9710.0 10005.4 10128.7 10199.9 576.3 593.7 627.2 647.2 704.8 4532.4 4662.5 4690.4 4691.6 4769.6 42431.8 95010.0 108791.3 113102.9 150220.0 96538.5 113952.0 118750.4 123537.0 130812.0 88041.1 100365.5 101316.0 108399.0 111506.4 9467.1 9753.9 9895.7 9918.5 9923.2 47987.0 56398.4 57789.0 59629.7 60165.7 57275.0 57941.1 85086.6 85437.0 89131.0 11164.4 12608.5 12743.5 13152.3 13311.9 14425.8 14461.1 14516.6 15195.2 15466.8 12705.4 12731.9 12767.9 12809.7 13156.7 11212.0 13676.0 13819.7 13820.3 14013.3 13321.5 13354.8 13616.9 13736.8 14016.9 10328.5 10874.7 11188.4 11246.6 11543.7 10463.4 11114.7 11217.2 11421.2 11527.8 14:53:42 INFO - PROCESS | 1665 | -------- Summary: end -------- 14:53:42 INFO - PROCESS | 1665 | 14:53:43 INFO - TEST-INFO | 1665: exit 0 14:53:43 INFO - TEST-OK | dromaeo_css | took 1115772ms 14:53:43 INFO - TEST-START | kraken 14:53:43 INFO - operating with platform_type : mac_ 14:53:43 INFO - Initialising browser for kraken test... 14:53:43 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpPO6fCw/profile http://localhost:49289/getInfo.html) 14:53:45 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 14:53:45 INFO - PROCESS | 1672 | colorDepth:24 14:53:45 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 14:53:45 INFO - PROCESS | 1672 | __metrics 14:53:45 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:53:45 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:53:46 INFO - TEST-INFO | 1672: exit 0 14:53:46 INFO - Browser initialized. 14:53:46 INFO - Running cycle 1/1 for kraken test... 14:53:46 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/tmpPO6fCw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:58 INFO - PROCESS | 1673 | RSS: Main: 242221056 14:53:58 INFO - PROCESS | 1673 | 14:54:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-beat-detection) 14:54:02 INFO - PROCESS | 1673 | RSS: Main: 292454400 14:54:02 INFO - PROCESS | 1673 | 14:54:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-dft) 14:54:05 INFO - PROCESS | 1673 | RSS: Main: 302428160 14:54:05 INFO - PROCESS | 1673 | 14:54:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-fft) 14:54:10 INFO - PROCESS | 1673 | RSS: Main: 389636096 14:54:10 INFO - PROCESS | 1673 | 14:54:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49289/tests/kraken/driver.html?testName=audio-oscillator) 14:54:12 INFO - PROCESS | 1673 | RSS: Main: 291110912 14:54:12 INFO - PROCESS | 1673 | 14:54:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:54:15 INFO - PROCESS | 1673 | RSS: Main: 294588416 14:54:15 INFO - PROCESS | 1673 | 14:54:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-darkroom) 14:54:18 INFO - PROCESS | 1673 | RSS: Main: 331214848 14:54:18 INFO - PROCESS | 1673 | 14:54:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49289/tests/kraken/driver.html?testName=imaging-desaturate) 14:54:22 INFO - PROCESS | 1673 | RSS: Main: 333201408 14:54:22 INFO - PROCESS | 1673 | 14:54:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49289/tests/kraken/driver.html?testName=json-parse-financial) 14:54:25 INFO - PROCESS | 1673 | RSS: Main: 330579968 14:54:25 INFO - PROCESS | 1673 | 14:54:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:54:27 INFO - PROCESS | 1673 | RSS: Main: 310120448 14:54:27 INFO - PROCESS | 1673 | 14:54:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:54:30 INFO - PROCESS | 1673 | RSS: Main: 316661760 14:54:30 INFO - PROCESS | 1673 | 14:54:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:54:33 INFO - PROCESS | 1673 | RSS: Main: 320802816 14:54:33 INFO - PROCESS | 1673 | 14:54:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:36 INFO - PROCESS | 1673 | RSS: Main: 323280896 14:54:36 INFO - PROCESS | 1673 | 14:54:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:40 INFO - PROCESS | 1673 | RSS: Main: 323932160 14:54:40 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49289/tests/kraken/driver.html?testName=ai-astar) 14:54:42 INFO - PROCESS | 1673 | RSS: Main: 367923200 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | __start_tp_report 14:54:42 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 14:54:42 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 14:54:42 INFO - PROCESS | 1673 | |i|pagename|runs| 14:54:42 INFO - PROCESS | 1673 | |0;ai-astar;110;111;164;151;106;165;164;154;111;110 14:54:42 INFO - PROCESS | 1673 | |1;audio-beat-detection;156;144;135;199;142;118;138;118;150;122 14:54:42 INFO - PROCESS | 1673 | |2;audio-dft;183;183;187;183;204;258;182;181;180;182 14:54:42 INFO - PROCESS | 1673 | |3;audio-fft;79;73;57;105;57;61;79;90;82;74 14:54:42 INFO - PROCESS | 1673 | |4;audio-oscillator;78;88;84;74;102;99;127;72;68;102 14:54:42 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;84;87;84;88;83;87;84;87;84 14:54:42 INFO - PROCESS | 1673 | |6;imaging-darkroom;94;102;93;93;93;92;103;151;93;93 14:54:42 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;86;83;85;82;84;85;84;86;86 14:54:42 INFO - PROCESS | 1673 | |8;json-parse-financial;56;57;54;54;55;55;55;54;55;56 14:54:42 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;67;66;83;80;73;72;74;72;72;73 14:54:42 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;88;92;108;89;85;84;86;87;121;87 14:54:42 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;138;140;137;138;141;138;140;143;163;139 14:54:42 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;175;194;165;224;163;163;165;160;161 14:54:42 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;65;66;69;63;84;66;67;66;66;65 14:54:42 INFO - PROCESS | 1673 | __end_tp_report 14:54:42 INFO - PROCESS | 1673 | __start_cc_report 14:54:42 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1953 14:54:42 INFO - PROCESS | 1673 | __end_cc_report 14:54:42 INFO - PROCESS | 1673 | __startTimestamp1464904482419__endTimestamp 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | ------- Summary: start ------- 14:54:42 INFO - PROCESS | 1673 | Number of tests: 14 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:134.60 Median:152.50 stddev:26.75 (17.5%) stddev-sans-first:26.85 14:54:42 INFO - PROCESS | 1673 | Values: 110.0 111.0 164.0 151.0 106.0 165.0 164.0 154.0 111.0 110.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:143.00 stddev:23.87 (16.7%) stddev-sans-first:24.79 14:54:42 INFO - PROCESS | 1673 | Values: 156.0 144.0 135.0 199.0 142.0 118.0 138.0 118.0 150.0 122.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:192.30 Median:183.00 stddev:24.11 (13.2%) stddev-sans-first:25.34 14:54:42 INFO - PROCESS | 1673 | Values: 183.0 183.0 187.0 183.0 204.0 258.0 182.0 181.0 180.0 182.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:75.70 Median:76.50 stddev:15.09 (19.7%) stddev-sans-first:15.96 14:54:42 INFO - PROCESS | 1673 | Values: 79.0 73.0 57.0 105.0 57.0 61.0 79.0 90.0 82.0 74.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:76.00 stddev:18.20 (24.0%) stddev-sans-first:18.83 14:54:42 INFO - PROCESS | 1673 | Values: 78.0 88.0 84.0 74.0 102.0 99.0 127.0 72.0 68.0 102.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:1.84 (2.1%) stddev-sans-first:1.87 14:54:42 INFO - PROCESS | 1673 | Values: 87.0 84.0 87.0 84.0 88.0 83.0 87.0 84.0 87.0 84.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:93.00 stddev:18.12 (19.5%) stddev-sans-first:19.05 14:54:42 INFO - PROCESS | 1673 | Values: 94.0 102.0 93.0 93.0 93.0 92.0 103.0 151.0 93.0 93.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 14:54:42 INFO - PROCESS | 1673 | Values: 85.0 86.0 83.0 85.0 82.0 84.0 85.0 84.0 86.0 86.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 14:54:42 INFO - PROCESS | 1673 | Values: 56.0 57.0 54.0 54.0 55.0 55.0 55.0 54.0 55.0 56.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:73.00 stddev:5.14 (7.0%) stddev-sans-first:4.94 14:54:42 INFO - PROCESS | 1673 | Values: 67.0 66.0 83.0 80.0 73.0 72.0 74.0 72.0 72.0 73.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:87.00 stddev:12.09 (13.9%) stddev-sans-first:12.71 14:54:42 INFO - PROCESS | 1673 | Values: 88.0 92.0 108.0 89.0 85.0 84.0 86.0 87.0 121.0 87.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:140.00 stddev:7.69 (5.5%) stddev-sans-first:8.04 14:54:42 INFO - PROCESS | 1673 | Values: 138.0 140.0 137.0 138.0 141.0 138.0 140.0 143.0 163.0 139.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:166.00 stddev:20.33 (12.2%) stddev-sans-first:21.41 14:54:42 INFO - PROCESS | 1673 | Values: 167.0 175.0 194.0 165.0 224.0 163.0 163.0 165.0 160.0 161.0 14:54:42 INFO - PROCESS | 1673 | 14:54:42 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:66.00 stddev:5.93 (9.0%) stddev-sans-first:6.20 14:54:42 INFO - PROCESS | 1673 | Values: 65.0 66.0 69.0 63.0 84.0 66.0 67.0 66.0 66.0 65.0 14:54:42 INFO - PROCESS | 1673 | -------- Summary: end -------- 14:54:42 INFO - PROCESS | 1673 | 14:54:43 INFO - TEST-INFO | 1673: exit 0 14:54:43 INFO - TEST-OK | kraken | took 59724ms 14:54:43 INFO - SUITE-END | took 1175s 14:54:43 INFO - Completed test suite (00:19:35) 14:54:43 INFO - javascript benchmark 14:54:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.606860211076, 3440.4323286657705, 3442.1738782692146, 3456.429689101298, 3466.826658667066, 5413.458654134584, 5516.089845175551, 5524.723763811813, 5647.802326918559, 5669.63147395419, 5570.805021824237, 5623.550115990721, 5640.097584386509, 5666.433356664342, 5692.146178073291, 5048.974755126219, 5055.77249023793, 5135.075686376451, 5135.922961155586, 5232.3197984262, 5236.8690782730355, 5238.580913526927, 5259.447757985395, 5284.022455845662, 5288.709120998344, 4900.142475066849, 4950.18321976877, 5018.874528136789, 5060.721660308682, 5167.4574169712205, 4640.535946405333, 4911.042346742387, 5117.82087626935, 5129.358830146231, 5190.273361729361, 4231.365295205713, 5075.8984820303385, 5118.744062796845, 5121.564667003309, 5173.793048278064, 5436.401995780461, 5490.368607610136, 5572.331320241557, 5628.240187574666, 5675.262215911946, 6140.324564297924, 6148.907766383508, 6171.0, 6180.351063138351, 6181.598196117283, 6135.601185922946, 6209.223847019122, 6213.5339849511465, 6222.284437289724, 6228.781992630282, 5563.693996830173, 5638.633488823254, 5641.605087643826, 5721.742521586539, 5755.30936287644, 6310.842228944313, 6316.778912738124, 6322.241331040304, 6334.683265836689, 6339.144215530937, 6058.878822423527, 6061.572500672761, 6072.057200250621, 6089.130025094462, 6092.958858782357, 6067.696615169224, 6123.050927106306, 6142.109394137825, 6170.876582468325, 6227.719752611143, 6142.324344322121, 6156.168917196211, 6186.659733714689, 6195.19462469885, 6213.937860621426, 6177.938220617826, 6186.319504854462, 6230.875382492325, 6246.0, 6261.342559031236, 4818.566329030404, 4874.7562621868765, 4879.658423910363, 4936.703797772145, 4966.553010229097, 4413.691041627056, 4474.552544745499, 4494.977525112354, 4500.549945005539, 4502.527234640315, 5564.304461942257, 5616.747246373923, 5626.915596266059, 5664.122061080539, 5685.943140568541, 6215.595986260879, 6273.271573679725, 6283.905741413882, 6288.937110628926, 6309.810705678837, 5502.669839809624, 5525.143602741581, 5663.093904975184, 5684.261046064068, 5693.259876216148, 5372.865678358073, 5373.596980226499, 5376.946230537723, 5378.058839703114, 5379.1393377059485, 6120.629988696826, 6292.842678933063, 6295.024271237966, 6306.1171435998995, 6328.01915703076, 5173.896522069463, 6160.661163636043, 6206.813795586139, 6226.470749986121, 6233.719482623384, 6193.7793689863865, 6291.685415729286, 6296.5592408530965, 6314.936850631435, 6316.147320111726, 5991.610545314541, 6062.151298818258, 6080.969595151996, 6115.449609535163, 6115.480184184216, 6221.06683997404, 6259.718312675849, 6265.686715664107, 6271.843203919939, 6275.780347687809, 4839.0, 6009.8197054086695, 6042.033274676207, 6072.240969878765, 6103.694815259308, 5833.854153646193, 5884.440978107073, 5890.587658863838, 5977.760889564369, 5982.790602329072, 5827.3007239131575, 5844.678542680023, 5855.707214639337, 5857.970710146422, 5868.911966320594, 5982.461578457785, 5996.010658241516, 5997.880042399041, 5999.340072591924, 6004.00933845928, 5831.825045248649, 5861.531077513874, 5862.032764593965, 5877.265341832271, 5891.3224979127945], "value": 5630.237517631514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.94308154562214, 500.4170141784821, 507.9644424890258, 509.9235114732792, 521.5436493068565, 28122.0, 42120.78939605301, 42184.78907605458, 42608.57391426085, 45048.0, 45149.54850451495, 45504.089918201615, 45534.77232613841, 45662.543374566245, 45928.770356148256, 8109.513429194252, 9670.984546622609, 9675.951620241889, 9678.758031049245, 9685.661001864935, 9497.382670126453, 9672.322937394385, 9673.322867399285, 9675.709728708114, 9704.932457429677, 9620.086091821266, 9656.903430965705, 9663.371880827759, 9672.854907176397, 9681.806363872718, 9593.568289426958, 9653.0, 9653.469059201741, 9659.661911833122, 9662.90337096627, 9381.061893810633, 9448.149666529976, 9551.90448095517, 9553.856692149624, 9581.568829402624, 40940.79529602363, 41042.38436423456, 41208.79395603033, 41216.0, 41306.38040429396, 3489.075395020296, 3535.381308271042, 3542.1498840278405, 3543.061088811467, 3593.6047034826147, 31456.213594660087, 31868.0, 32074.518882216784, 32112.678873211204, 32456.864009759545, 3212.0, 3260.0871755908383, 3267.673232676737, 3267.869285228588, 3374.8818791342433, 382141.0, 382228.0, 382495.1750482487, 382498.0, 382685.0, 64709.35290647127, 65158.674206629614, 65183.67408162929, 65236.0, 65241.67379163074, 58369.0, 58671.70664146652, 59832.40167598355, 68062.95811125674, 69334.0, 17198.91400542989, 17278.827211727974, 17450.56373590645, 17489.95060296386, 17514.21186046631, 13082.934585327013, 13273.933630331787, 13287.202767833767, 13300.667483312802, 13300.935925125967, 16388.93471924321, 16753.413630522875, 16755.832441675426, 17280.0, 19092.2363105477, 5612.550995920316, 5737.942620573825, 5822.359540450547, 6350.69810688817, 6618.86762264752, 822.366777581259, 1058.5342449322427, 1063.1335461598771, 1122.449999500239, 1188.6552899659002, 25285.747142528708, 30281.848590756905, 30536.389272214874, 31138.68861311403, 31208.687913121033, 1043.684956954228, 1100.0154861399003, 1103.8896110388896, 1149.1955631058127, 1185.3421351150193, 15052.892595036703, 15850.96968697054, 15891.205439727968, 15914.68170636604, 15918.76121858173, 651466.0, 659631.0, 666823.0, 667184.0, 667454.0, 62786.686066569375, 63090.68454657697, 63097.053544196875, 63106.36893631005, 63165.68417157885, 1379.4964837834118, 1407.310417895224, 1414.844367119616, 1416.6033510617044, 1430.5708287513705, 30255.941042063423, 30315.0, 30346.544801828433, 30746.692533074383, 31157.0, 54970.72514637401, 55075.0, 55442.168367473714, 55514.16728748991, 55561.444385557246, 495491.0, 498365.0, 498949.0, 499976.0, 501958.0, 43510.78244608884, 44204.0, 44853.0, 44975.10049799051, 44990.0, 49316.75341623269, 49584.0, 50182.0, 50870.49129508805, 51338.74330628323, 39457.40813887736, 40220.39669404903, 40679.18641627092, 40815.0, 41325.586744133376, 33994.0, 38625.0, 40447.59552404438, 40491.79754101211, 41440.585594143675, 38941.61058389493, 39432.6056739429, 39777.0, 39928.00179995524, 39946.800265998485, 32396.83801580977, 34402.65597343994, 34766.560255387274, 35330.0, 35660.46509302415, 23028.654570181126, 25616.231513054638, 25617.743822561537, 25867.22398328053, 26087.0], "value": 20403.383731043676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.713232979781, 383.6968794652225, 387.6007712056582, 389.4080996884737, 407.85724996251315, 14882.107073575593, 15083.698326033473, 20276.29032983846, 20350.6947395789, 20373.796262037376, 20392.694109588367, 20960.37118886432, 21061.473463163427, 21420.9289535523, 21514.569708605817, 5819.2725909261235, 5982.910256346158, 6071.969640151793, 6079.604825686339, 6624.56940298879, 6593.076969224312, 6594.109795177662, 6598.208215014205, 6612.966935165317, 6633.402993730563, 5601.187827764992, 6443.967780161092, 6484.0, 6586.176727909012, 6610.471162307028, 6453.774117905898, 6488.929326661095, 6533.2160140782935, 6566.343365663443, 6574.506911981596, 4720.716756994591, 5444.292242008513, 5621.325440947072, 5640.097584386478, 5650.237217975562, 25829.837657305467, 26091.0, 26251.868740656366, 26555.46889062208, 26800.59799103015, 2259.4916143867663, 2273.2952784636814, 2351.9529609407714, 2512.962305565418, 2792.7765778737653, 14897.627559311104, 15579.610509737347, 15836.76244856328, 16069.357225710957, 16410.425635102834, 1842.68674325365, 1923.394130848778, 1925.0471016846611, 1966.2626515056854, 2013.6508539278718, 53267.20099198515, 80582.59708701419, 102096.0, 103193.0, 114675.0, 9092.954535227414, 10959.01368876805, 10993.34039957589, 11058.723531911606, 11152.832707509393, 13628.795568066487, 13706.24615646149, 13718.794218086736, 13745.450181992808, 13750.518731844337, 8595.828083438297, 8765.605547750367, 8773.780655483664, 8854.0, 8900.821983560421, 8767.298616110696, 8775.868361974575, 8802.735917922344, 8910.643574257087, 8936.910630893608, 6589.406953374123, 6696.46428285736, 6729.966350168315, 6736.6968486417245, 6741.52809303344, 1404.7682132448235, 1569.669519862885, 1790.3286267649632, 1825.7352683860765, 1862.1434140295346, 921.9216366608913, 944.6363150187227, 1024.4058446101244, 1025.7845852370936, 1079.6545105566142, 17613.647727045387, 21158.365249042552, 21384.71691698472, 21531.89234053851, 22273.331800046024, 979.911807937289, 1003.859459675646, 1007.5516395204211, 1011.8229309870891, 1047.6123834181249, 9790.510474476248, 11557.190996630155, 11772.764544709058, 11932.642020739391, 11938.88061119395, 548253.0, 565581.0, 566432.0, 586002.0, 612194.0, 114924.38067144998, 139680.0, 144139.27930360282, 147666.0, 153288.2335588315, 991.1278075293695, 1011.4032720694826, 1030.2118879057527, 1038.1331588124, 1038.8285932821152, 19010.42968710941, 19420.61158776845, 19437.8056219436, 19497.70753438671, 19544.413667589994, 43279.78360108179, 52347.0, 53141.468585313654, 54983.0, 56191.43808561862, 70787.29212708013, 78394.6080269595, 79531.0, 82256.58871705603, 83654.0, 11664.241824281395, 12123.030157587196, 12139.210951288138, 12252.87747122553, 12302.446389912297, 13179.67050823737, 13219.933900330438, 13281.203127812361, 13485.528006519724, 13519.324033798468, 11282.43587820622, 11459.828102578635, 11612.94193529027, 11634.301941883514, 11714.824277636013, 10818.188635852186, 11387.259828111146, 11401.771964560496, 11666.591669291534, 11746.236494627821, 7671.884921726067, 7807.063152421519, 8333.37499687538, 10587.735306617396, 11923.463444144862, 9742.464164470739, 12251.0, 12338.02591585342, 12499.125061245626, 12584.748305033665, 11809.940950295537, 11871.406429678655, 11933.76132477363, 12068.637940861789, 12082.637520874388], "value": 10082.176600943489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.7709650811105, 1534.9923250383747, 1547.4119834462902, 1724.9568760780978, 1872.4476279497546, 7675.04061992251, 8349.20682535159, 8579.142085791425, 8588.613512391941, 8742.694005709802, 8403.579821008956, 8603.225709686127, 8616.43993140447, 8623.525706086162, 8630.266427353683, 2099.1078791513623, 2180.967285490719, 2314.571804216217, 2354.234873666061, 2387.785099341059, 2227.0, 2259.9096036158617, 2261.1407665087245, 2263.5586060718165, 2264.184893438361, 2333.3466629343725, 2356.893939772706, 2379.7501262367464, 2382.3210385040265, 2385.5109702510945, 2047.0992763184151, 2273.602119629075, 2322.2684854270933, 2346.483773569812, 2347.1315613223046, 2350.1304517328526, 2371.513839662882, 2373.964390534143, 2375.6674065630827, 2376.1089591403224, 7227.530210536351, 8594.957025214897, 8602.956985215096, 8605.225529702295, 8612.655493780305, 5112.156494178456, 5277.287566178555, 5280.049591073605, 5281.445448227958, 5283.920741188886, 3248.7076163145193, 3317.9004629861142, 3424.9143771405666, 3436.553248077759, 3535.805530695811, 1040.5369610523321, 1056.614335767447, 1059.978800423995, 1083.7236504691252, 1083.8211695070304, 11364.94317528407, 11375.943120284428, 11378.37418941958, 11408.771824563462, 11409.42952852354, 4600.4479462464715, 4602.654800889946, 4711.246200607886, 4737.099951009297, 4752.596029337476, 9698.224142068617, 9698.321117521706, 9706.077922597484, 9711.36876103051, 9728.464934428674, 5257.658252213594, 5259.289995850588, 5264.289320941624, 5266.552343050807, 5269.973650131725, 4380.912381752347, 4533.727976321431, 4673.345731597579, 4683.6721429500285, 4828.61371090319, 3346.698797108223, 3878.941815872764, 3963.7621742695533, 4015.678745700337, 4041.5150181977765, 1263.91784534005, 1286.9537435477635, 1291.1930043722673, 1297.7534268488957, 1298.1821452485062, 905.171767832426, 909.3861643390685, 919.9954000229956, 933.9091120443537, 958.2525630008604, 5751.539876809845, 5760.884782304331, 5876.118582212617, 5878.088896221093, 5920.378360272195, 426.78447384071467, 446.4196544492147, 448.2044371241073, 458.0518327063014, 458.55978260869273, 3936.291467535871, 3962.9590760758233, 3976.681865450757, 3982.980085099556, 4038.7172897896867, 10046.29675922693, 10277.845832312521, 10473.895261047444, 10477.057064864199, 10498.580056797642, 10385.532651030722, 10484.842727359095, 10489.0, 10503.369797812156, 10506.579736810441, 7632.923670763222, 7946.165652606509, 8057.435979481497, 8086.393520485987, 8087.63605637736, 5695.145728140812, 5826.0, 5849.912251316148, 5915.6450612961635, 6014.127951447103, 9443.150116489549, 9766.414015159113, 9808.65669701557, 9933.0, 9951.054649808257, 8396.496210227406, 9746.0, 10006.599736010477, 10060.547275372479, 10136.0, 6921.169459664872, 7138.678759455733, 7148.678309475982, 7149.428045756431, 7472.140703818913, 7838.216178382117, 7926.643301051351, 7985.2014798519685, 8229.094799572162, 8234.547099909563, 7277.16312624055, 7375.2256013119995, 7388.187299396955, 7496.962515187389, 7548.584827834522, 7225.783226503213, 7289.307515785992, 7307.744228951961, 7386.446016548673, 7412.221716719882, 6934.098567186234, 6935.0, 7019.982102595197, 7023.578585284899, 7036.894446583407, 4641.257398816173, 4799.688020278671, 4846.312287095002, 5044.445111037856, 5079.187330027178, 4239.342901850218, 4953.801847926043, 4998.075356059141, 5014.222795466709, 5091.0836049511445], "value": 4423.3506296902115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.5279816284758, 313.1810316023598, 314.5753233135268, 320.6168628488954, 321.437484402296, 5288.826703977612, 5307.52232299093, 5311.60162987776, 5320.228566857803, 5384.6499977501535, 5152.716600586966, 5240.266362709214, 5242.6330156888935, 5280.676502338968, 5289.841304760864, 3790.185110201306, 3846.922661762645, 3921.8431262749455, 4060.9593904061026, 4077.612626800449, 3928.685705143596, 3999.5000624921886, 4000.979505349794, 4019.638232559069, 4023.0, 3957.881263562098, 3964.6233607807358, 3964.9976761735284, 3976.667816281549, 4003.8198281077275, 3584.1006499337586, 3774.301754175486, 3953.960460395388, 3962.9405558916637, 4003.5596084430836, 3432.9313413731584, 3445.5176275321473, 3467.895963121085, 3475.7219422446396, 3495.405781017233, 4305.31115021595, 4595.310703394484, 4960.851174464772, 4993.625478089121, 5030.711026482978, 3636.1636823530735, 3884.028992751812, 4425.004374015853, 4528.916698318777, 4563.885902852422, 4883.536064073943, 4896.36347274852, 5351.812686555991, 5498.890022199574, 5534.363548191929, 3781.357169281229, 3781.773093614392, 3794.9810250948844, 3795.8671446499516, 3797.9240415191543, 5458.4541545845495, 5547.639403438763, 5802.62282951611, 5851.210086638292, 5881.147233651097, 2990.790644654897, 3310.0566338593867, 3789.412641040643, 3836.67388272, 3853.672437842815, 3590.425531914881, 3773.735798507476, 3882.4758657580865, 3885.9404515606775, 3897.902552436155, 3697.8335974881193, 3995.6403923647013, 4555.886102847456, 4559.772011399416, 4615.14619795339, 5033.899322013539, 5312.0, 5577.549837042398, 5602.411746766531, 5682.659040457585, 1287.3048553780854, 1311.2984553263957, 1317.6442360562787, 1329.7805862032656, 1340.8592097829587, 688.3048121397425, 735.1398863329916, 761.3794757272805, 816.322452364536, 820.6512232301249, 4737.360456338351, 4898.795542006041, 4918.360613120271, 4938.506149385033, 4943.233798761198, 4968.726720030433, 5491.693488734741, 5674.319081710221, 5696.974544581932, 5715.39949323057, 4221.514525829569, 4491.775411229491, 4814.975925120352, 5107.489251074863, 5164.9741751291, 2566.9090636479423, 2837.645294338208, 2982.925426864346, 3004.444177827109, 3011.472992226352, 5573.080441727069, 5758.884822303614, 5960.284765828128, 5969.98510253258, 6003.669798161143, 4925.24186455586, 5747.913781293284, 5861.863902811975, 5871.911921321183, 5877.735501902424, 5470.425605311385, 5885.440883116097, 5892.292924849045, 5917.644941303622, 5970.462658360769, 4814.350062741625, 5241.9737901310245, 5497.780088796563, 5631.549476041825, 5703.034422100567, 4934.506549345037, 5489.835304940858, 5513.944860551504, 5563.468621777162, 5606.523445507014, 5388.919166212431, 5820.476157145878, 5863.384344643751, 5889.499392551681, 5919.822405327847, 3172.0491897483344, 3212.9678703212667, 3294.7528935329465, 3298.274379636476, 3300.0364782954102, 4449.888752781077, 4523.570260825217, 4571.702839315434, 4837.782874388492, 4860.635452341159, 4630.3517507548295, 4650.720956742606, 4661.883452913704, 4697.037107392908, 4722.763861806827, 4251.426057482324, 4306.784660766886, 4385.662372976273, 4676.7661616919695, 4693.906121877611, 3987.0, 4199.601037901394, 4335.891602709958, 4431.8448854289945, 4611.953880461152, 5186.351706036746, 5205.167173252262, 5323.334583177103, 5365.712229064919, 5520.199571062254, 5361.839144825662, 5390.407055224001, 5392.838214853561, 5400.918986215132, 5414.539764120094], "value": 3929.6795155111395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.6416131530949, 683.4976292425067, 717.2253965717026, 772.1660606544932, 828.1139181076251, 15291.541253762378, 15384.153871537075, 15672.902896797243, 15677.29452174652, 15701.607459813511, 14126.929365353184, 14254.429822807075, 14256.429742810302, 14306.570802875904, 14324.06893551921, 707.3209718670083, 729.1214087025148, 869.1786261982445, 875.3041332140963, 892.4734406700046, 890.7639475538988, 909.6725178935578, 910.8041771019263, 912.2610685344858, 912.5847739278612, 943.0663642993421, 964.5466630683602, 966.6761634852332, 967.013646080997, 968.3366893677818, 745.9216782237895, 916.9816603667889, 945.1588086602928, 956.5982287439257, 959.2086528613921, 696.2689176364847, 696.3767428151826, 697.8639165362706, 698.7868700046483, 701.0150738212819, 14677.706445871023, 14737.189454579993, 14740.336684849208, 14758.188299643516, 14787.112773233534, 13423.597292081253, 13661.385237664328, 13758.904598190875, 13792.310384480836, 13878.375473103633, 12704.80942785859, 12705.745885082246, 12740.80888786669, 12925.54760583375, 12958.48166073356, 4550.476695180064, 4718.985418135083, 4720.905581888343, 4725.10223057618, 4726.550977657117, 98483.97715554803, 101418.49290753572, 102244.0, 103571.0, 103897.48051259771, 10789.514471848786, 10922.836157457645, 10959.56161753521, 10961.287516311415, 11104.777904441866, 21081.578368432853, 21104.577908442054, 21209.15163393447, 21227.46931326698, 21480.892595537236, 7877.212278772192, 7885.723999659985, 7915.762527124196, 8003.279704826477, 8190.344772418192, 4406.845760398371, 4465.553444655573, 4480.798364073559, 4530.977345113253, 4680.4149481314835, 3270.8364581771293, 3431.227973705946, 3432.7940323580165, 3434.0213039283685, 3437.157896315369, 532.4675324675325, 672.1128110893671, 676.0737789228788, 690.5960013392222, 693.3447891742255, 615.258613370887, 623.6943897490197, 682.668905580793, 689.7068745783123, 696.3663066609361, 8429.620667069874, 9709.98840746292, 10005.43502650194, 10128.746781330527, 10199.91961534455, 576.3314555116045, 593.7090825495476, 627.1940556385023, 647.2039391548421, 704.7709494414336, 4532.388127602731, 4662.510436404196, 4690.390249267642, 4691.577758001797, 4769.57525645688, 42431.7878410606, 95010.0, 108791.2802179936, 113102.86897131088, 150220.0, 96538.51730741301, 113952.0, 118750.40624796994, 123537.0, 130812.0, 88041.11958880458, 100365.49817251012, 101316.0, 108399.0, 111506.44246778714, 9467.053294670754, 9753.853692194765, 9895.65365212214, 9918.504074796376, 9923.156531694887, 47987.0, 56398.43601564096, 57789.0, 59629.70185149046, 60165.69917150386, 57275.0, 57941.11326026112, 85086.57456712677, 85437.0, 89131.0, 11164.38595877202, 12608.49566017349, 12743.491898587527, 13152.342382880626, 13311.933440332736, 14425.78361324602, 14461.13233206011, 14516.637084072983, 15195.164265965477, 15466.845331546541, 12705.36473176356, 12731.87268127307, 12767.87232127704, 12809.679758006125, 13156.671083222996, 11212.0, 13675.974301927195, 13819.654508636962, 13820.308984550933, 14013.299335033003, 13321.53374631922, 13354.799678004643, 13616.86383136156, 13736.793948090986, 14016.929915350358, 10328.535215915152, 10874.728131796452, 11188.440577970907, 11246.550137994389, 11543.711407214887, 10463.372197668165, 11114.666560003212, 11217.158713096713, 11421.200515963803, 11527.8270825936], "value": 7709.807106147508, "unit": "score"}], "name": "dromaeo_css", "value": 7331.019628415993, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 164.0, 151.0, 106.0, 165.0, 164.0, 154.0, 111.0, 110.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 144.0, 135.0, 199.0, 142.0, 118.0, 138.0, 118.0, 150.0, 122.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 187.0, 183.0, 204.0, 258.0, 182.0, 181.0, 180.0, 182.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 105.0, 57.0, 61.0, 79.0, 90.0, 82.0, 74.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 88.0, 84.0, 74.0, 102.0, 99.0, 127.0, 72.0, 68.0, 102.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 88.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 93.0, 93.0, 93.0, 92.0, 103.0, 151.0, 93.0, 93.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 85.0, 82.0, 84.0, 85.0, 84.0, 86.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 83.0, 80.0, 73.0, 72.0, 74.0, 72.0, 72.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 108.0, 89.0, 85.0, 84.0, 86.0, 87.0, 121.0, 87.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 137.0, 138.0, 141.0, 138.0, 140.0, 143.0, 163.0, 139.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 175.0, 194.0, 165.0, 224.0, 163.0, 163.0, 165.0, 160.0, 161.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 69.0, 63.0, 84.0, 66.0, 67.0, 66.0, 66.0, 65.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1509.1000000000001, "alertThreshold": 2.0}]} 14:54:43 INFO - Return code: 0 14:54:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:54:43 ERROR - # TBPL SUCCESS # 14:54:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:43 INFO - 2016-06-02 14:54:43.700 screenresolution[1677:9713] starting screenresolution argv=screenresolution get 14:54:43 INFO - 2016-06-02 14:54:43.702 screenresolution[1677:9713] Display 0: 1600x1200x32@60 14:54:43 INFO - 2016-06-02 14:54:43.708 screenresolution[1678:9716] starting screenresolution argv=screenresolution list 14:54:43 INFO - Available Modes on Display 0 14:54:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:43 INFO - Intel Iris: 14:54:43 INFO - Chipset Model: Intel Iris 14:54:43 INFO - Type: GPU 14:54:43 INFO - Bus: Built-In 14:54:43 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:43 INFO - Vendor: Intel (0x8086) 14:54:43 INFO - Device ID: 0x0a2e 14:54:43 INFO - Revision ID: 0x0009 14:54:43 INFO - Displays: 14:54:43 INFO - Display: 14:54:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:43 INFO - Display Serial Number: 1600x1200 60 14:54:43 INFO - Main Display: Yes 14:54:43 INFO - Mirror: Off 14:54:43 INFO - Online: Yes 14:54:43 INFO - Rotation: Supported 14:54:43 INFO - Return code: 0 14:54:43 INFO - Running post-action listener: _resource_record_post_action 14:54:43 INFO - ##### 14:54:43 INFO - ##### Finished run-tests step (success) 14:54:43 INFO - ##### 14:54:43 INFO - Running post-run listener: _resource_record_post_run 14:54:44 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36447744; Write bytes: 697943040; Read time: 242; Write time: 1982 14:54:44 INFO - TinderboxPrint: CPU usage
53.7% 14:54:44 INFO - TinderboxPrint: I/O read bytes / time
36,447,744 / 242 14:54:44 INFO - TinderboxPrint: I/O write bytes / time
697,943,040 / 1,982 14:54:44 INFO - TinderboxPrint: CPU idle
2,243.8 (46.3%) 14:54:44 INFO - TinderboxPrint: CPU system
965.5 (19.9%) 14:54:44 INFO - TinderboxPrint: CPU user
1,640.4 (33.8%) 14:54:44 INFO - TinderboxPrint: Swap in / out
436,015,104 / 0 14:54:44 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387987456; Write bytes: 376514048; Read time: 29385; Write time: 808 14:54:44 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30256640; Write bytes: 296259072; Read time: 184; Write time: 1128 14:54:44 INFO - Running post-run listener: _upload_blobber_files 14:54:44 INFO - Blob upload gear active. 14:54:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:44 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 14:54:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:54:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:54:44 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:54:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:54:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:46 INFO - (blobuploader) - INFO - Done attempting. 14:54:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:54:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:54:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:47 INFO - (blobuploader) - INFO - Done attempting. 14:54:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:54:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:49 INFO - (blobuploader) - INFO - Done attempting. 14:54:49 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:49 INFO - Return code: 0 14:54:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba4d15c621761cf03247d255eefbefbc6ac0dedb48fa2f3d7629db31638695c0b905d42d59ae939fe5f5a619c065e650a8aac432fea2b290b51f7c9b3a0b9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a6dc1cb445e57ccd10ea640a2a18ea93ae147d239b1acee30816b96af9a94b6d358ff668be16f5f617e1818d9aa5b77119670ba3fb27602fba1b0211eb7f410", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11036dfa6d355583a580a1c5666f509b683df4270aae69ce061fa7861c2d60dbed0735dc77a1b6a93a5bc718d88e06c38e6bc5ba9d2d30a6140a3f73ec6e3d85"} 14:54:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:49 INFO - Contents: 14:54:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba4d15c621761cf03247d255eefbefbc6ac0dedb48fa2f3d7629db31638695c0b905d42d59ae939fe5f5a619c065e650a8aac432fea2b290b51f7c9b3a0b9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a6dc1cb445e57ccd10ea640a2a18ea93ae147d239b1acee30816b96af9a94b6d358ff668be16f5f617e1818d9aa5b77119670ba3fb27602fba1b0211eb7f410", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11036dfa6d355583a580a1c5666f509b683df4270aae69ce061fa7861c2d60dbed0735dc77a1b6a93a5bc718d88e06c38e6bc5ba9d2d30a6140a3f73ec6e3d85"} 14:54:49 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:49 INFO - Copying logs to upload dir... 14:54:49 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.262864 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-02 14:54:50.339968) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 14:54:50.345030) ========= 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.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba4d15c621761cf03247d255eefbefbc6ac0dedb48fa2f3d7629db31638695c0b905d42d59ae939fe5f5a619c065e650a8aac432fea2b290b51f7c9b3a0b9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a6dc1cb445e57ccd10ea640a2a18ea93ae147d239b1acee30816b96af9a94b6d358ff668be16f5f617e1818d9aa5b77119670ba3fb27602fba1b0211eb7f410", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11036dfa6d355583a580a1c5666f509b683df4270aae69ce061fa7861c2d60dbed0735dc77a1b6a93a5bc718d88e06c38e6bc5ba9d2d30a6140a3f73ec6e3d85"} build_url:https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011005 build_url: 'https://queue.taskcluster.net/v1/task/fmo_J1iVQVqi2bEE2k8TgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba4d15c621761cf03247d255eefbefbc6ac0dedb48fa2f3d7629db31638695c0b905d42d59ae939fe5f5a619c065e650a8aac432fea2b290b51f7c9b3a0b9c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a6dc1cb445e57ccd10ea640a2a18ea93ae147d239b1acee30816b96af9a94b6d358ff668be16f5f617e1818d9aa5b77119670ba3fb27602fba1b0211eb7f410", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11036dfa6d355583a580a1c5666f509b683df4270aae69ce061fa7861c2d60dbed0735dc77a1b6a93a5bc718d88e06c38e6bc5ba9d2d30a6140a3f73ec6e3d85"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 14:54:50.383444) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:54:50.383838) ========= 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.KAswU9sXIO/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.EYIbIVicFn/Listeners TMPDIR=/var/folders/m1/k6zf3gmj55ngzdrqbcq3628800000w/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.005236 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 14:54:50.432893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 14:54:50.433227) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 14:54:50.436584) ========= ========= Total master_lag: 0.58 =========