builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1464730273.49 results: success (0) buildid: 20160531133141 builduid: 642f1e55206446edb857d9fdd05bb192 revision: aee75e50fbc15694bbe48724eb28dea184ef985e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.493055) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.493486) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.493756) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.548430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.548709) ========= 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.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 14:31:13-- 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% 10.2M=0.001s 2016-05-31 14:31:13 (10.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142222 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.711454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.711787) ========= 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.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.020318 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:13.784399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-31 14:31:13.784737) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aee75e50fbc15694bbe48724eb28dea184ef985e --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 aee75e50fbc15694bbe48724eb28dea184ef985e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 14:31:13,859 truncating revision to first 12 chars 2016-05-31 14:31:13,860 Setting DEBUG logging. 2016-05-31 14:31:13,860 attempt 1/10 2016-05-31 14:31:13,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aee75e50fbc1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 14:31:16,073 unpacking tar archive at: fx-team-aee75e50fbc1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.477650 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-31 14:31:16.284246) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:16.284581) ========= script_repo_revision: aee75e50fbc15694bbe48724eb28dea184ef985e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:16.285001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:16.285297) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:31:16.307942) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-31 14:31:16.308562) ========= /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.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:16 INFO - MultiFileLogger online at 20160531 14:31:16 in /builds/slave/test 14:31:16 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:31:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:31:16 INFO - {'append_to_log': False, 14:31:16 INFO - 'base_work_dir': '/builds/slave/test', 14:31:16 INFO - 'blob_upload_branch': 'Fx-Team', 14:31:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:31:16 INFO - 'branch': 'Fx-Team', 14:31:16 INFO - 'buildbot_json_path': 'buildprops.json', 14:31:16 INFO - 'config_files': ('talos/mac_config.py',), 14:31:16 INFO - 'default_actions': ('clobber', 14:31:16 INFO - 'read-buildbot-config', 14:31:16 INFO - 'download-and-extract', 14:31:16 INFO - 'populate-webroot', 14:31:16 INFO - 'create-virtualenv', 14:31:16 INFO - 'install', 14:31:16 INFO - 'run-tests'), 14:31:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:31:16 INFO - 'download_minidump_stackwalk': True, 14:31:16 INFO - 'download_symbols': 'ondemand', 14:31:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:31:16 INFO - 'tooltool.py': '/tools/tooltool.py', 14:31:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:31:16 INFO - '/tools/misc-python/virtualenv.py')}, 14:31:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:31:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:31:16 INFO - 'installer_path': 'installer.exe', 14:31:16 INFO - 'log_level': 'info', 14:31:16 INFO - 'log_name': 'talos', 14:31:16 INFO - 'log_to_console': True, 14:31:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:31:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:31:16 INFO - 'opt_config_files': (), 14:31:16 INFO - 'pip_index': False, 14:31:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:31:16 INFO - 'cmd': ('bash', 14:31:16 INFO - '-c', 14:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:31:16 INFO - 'enabled': True, 14:31:16 INFO - 'halt_on_failure': False, 14:31:16 INFO - 'name': 'check_screen_resolution'},), 14:31:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:31:16 INFO - 'cmd': ('bash', 14:31:16 INFO - '-c', 14:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:31:16 INFO - 'enabled': True, 14:31:16 INFO - 'halt_on_failure': False, 14:31:16 INFO - 'name': 'check_screen_resolution'},), 14:31:16 INFO - 'run_cmd_checks_enabled': True, 14:31:16 INFO - 'sps_profile': False, 14:31:16 INFO - 'sps_profile_interval': 0, 14:31:16 INFO - 'suite': 'dromaeojs', 14:31:16 INFO - 'system_bits': '32', 14:31:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:31:16 INFO - 'title': 't-yosemite-r7-0159', 14:31:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:31:16 INFO - 'use_talos_json': True, 14:31:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:31:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:31:16 INFO - 'work_dir': 'build'} 14:31:16 INFO - ##### 14:31:16 INFO - ##### Running clobber step. 14:31:16 INFO - ##### 14:31:16 INFO - Running pre-action listener: _resource_record_pre_action 14:31:16 INFO - Running main action method: clobber 14:31:16 INFO - rmtree: /builds/slave/test/build 14:31:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:31:17 INFO - Running post-action listener: _resource_record_post_action 14:31:17 INFO - ##### 14:31:17 INFO - ##### Finished clobber step (success) 14:31:17 INFO - ##### 14:31:17 INFO - ##### 14:31:17 INFO - ##### Running read-buildbot-config step. 14:31:17 INFO - ##### 14:31:17 INFO - Running pre-action listener: _resource_record_pre_action 14:31:17 INFO - Running main action method: read_buildbot_config 14:31:17 INFO - Using buildbot properties: 14:31:17 INFO - { 14:31:17 INFO - "project": "", 14:31:17 INFO - "product": "firefox", 14:31:17 INFO - "script_repo_revision": "production", 14:31:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:31:17 INFO - "repository": "", 14:31:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:31:17 INFO - "buildid": "20160531133141", 14:31:17 INFO - "pgo_build": "False", 14:31:17 INFO - "basedir": "/builds/slave/test", 14:31:17 INFO - "buildnumber": 45, 14:31:17 INFO - "slavename": "t-yosemite-r7-0159", 14:31:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:31:17 INFO - "platform": "yosemite_r7", 14:31:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:31:17 INFO - "branch": "fx-team", 14:31:17 INFO - "repo_path": "integration/fx-team", 14:31:17 INFO - "revision": "aee75e50fbc15694bbe48724eb28dea184ef985e", 14:31:17 INFO - "stage_platform": "macosx64", 14:31:17 INFO - "builduid": "642f1e55206446edb857d9fdd05bb192", 14:31:17 INFO - "slavebuilddir": "test" 14:31:17 INFO - } 14:31:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:31:17 INFO - Running post-action listener: _resource_record_post_action 14:31:17 INFO - ##### 14:31:17 INFO - ##### Finished read-buildbot-config step (success) 14:31:17 INFO - ##### 14:31:17 INFO - ##### 14:31:17 INFO - ##### Running download-and-extract step. 14:31:17 INFO - ##### 14:31:17 INFO - Running pre-action listener: _resource_record_pre_action 14:31:17 INFO - Running main action method: download_and_extract 14:31:17 INFO - mkdir: /builds/slave/test/build/tests 14:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:17 INFO - https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:20 INFO - Downloaded 1199 bytes. 14:31:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:31:20 INFO - Using the following test package requirements: 14:31:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:31:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:31:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:31:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'jsshell-mac.zip'], 14:31:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:31:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:31:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:31:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:31:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:31:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:31:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:31:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:31:20 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:31:20 INFO - https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:31:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:31:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:31:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:21 INFO - Downloaded 18687108 bytes. 14:31:21 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:31:21 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:31:24 INFO - Return code: 0 14:31:24 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:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:24 INFO - https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:31:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:31:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:31:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:28 INFO - Downloaded 18687108 bytes. 14:31:28 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:31:28 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:31:31 INFO - Return code: 0 14:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:31 INFO - https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:31:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:31:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:33 INFO - Downloaded 10834055 bytes. 14:31:33 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:31:33 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:31:33 INFO - Return code: 0 14:31:33 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:31:33 INFO - https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:31:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:31:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:31:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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:31:37 INFO - Downloaded 110987366 bytes. 14:31:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:31:37 INFO - mkdir: /builds/slave/test/properties 14:31:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:31:37 INFO - Writing to file /builds/slave/test/properties/build_url 14:31:37 INFO - Contents: 14:31:37 INFO - build_url:https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:31:39 INFO - Running post-action listener: _resource_record_post_action 14:31:39 INFO - Running post-action listener: set_extra_try_arguments 14:31:39 INFO - ##### 14:31:39 INFO - ##### Finished download-and-extract step (success) 14:31:39 INFO - ##### 14:31:39 INFO - ##### 14:31:39 INFO - ##### Running populate-webroot step. 14:31:39 INFO - ##### 14:31:39 INFO - Running pre-action listener: _resource_record_pre_action 14:31:39 INFO - Running main action method: populate_webroot 14:31:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:31:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:31:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:31:39 INFO - '--tppagecycles', 14:31:39 INFO - '7'], 14:31:39 INFO - 'tests': ['tsvgm']}}, 14:31:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:31:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:31:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:31:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:31:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:31:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:31:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['damp', 'tps']}, 14:31:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'tests': ['damp', 'tps']}, 14:31:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['dromaeo_dom']}, 14:31:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:31:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'other-e10s': {'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['a11yr', 14:31:39 INFO - 'ts_paint', 14:31:39 INFO - 'tpaint', 14:31:39 INFO - 'sessionrestore', 14:31:39 INFO - 'sessionrestore_no_auto_restore', 14:31:39 INFO - 'tabpaint']}, 14:31:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['tsvgx', 14:31:39 INFO - 'tsvgr_opacity', 14:31:39 INFO - 'tart', 14:31:39 INFO - 'tscrollx', 14:31:39 INFO - 'cart']}, 14:31:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:31:39 INFO - 'tsvgr_opacity', 14:31:39 INFO - 'tart', 14:31:39 INFO - 'tscrollx', 14:31:39 INFO - 'cart']}, 14:31:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'talos_options': ['--disable-e10s'], 14:31:39 INFO - 'tests': ['tp5o']}, 14:31:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'tests': ['tp5o']}, 14:31:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'talos_options': ['--disable-e10s', 14:31:39 INFO - '--xperf_path', 14:31:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:31:39 INFO - 'tests': ['tp5n']}, 14:31:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:31:39 INFO - 'talos_options': ['--xperf_path', 14:31:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:31:39 INFO - 'tests': ['tp5n']}}, 14:31:39 INFO - 'talos.zip': {'path': '', 14:31:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:31:39 INFO - Running post-action listener: _resource_record_post_action 14:31:39 INFO - ##### 14:31:39 INFO - ##### Finished populate-webroot step (success) 14:31:39 INFO - ##### 14:31:39 INFO - ##### 14:31:39 INFO - ##### Running create-virtualenv step. 14:31:39 INFO - ##### 14:31:39 INFO - Running pre-action listener: _resource_record_pre_action 14:31:39 INFO - Running main action method: create_virtualenv 14:31:39 INFO - Creating virtualenv /builds/slave/test/build/venv 14:31:39 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:31:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:31:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:31:39 INFO - Using real prefix '/tools/python27' 14:31:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:31:40 INFO - Installing distribute.............................................................................................................................................................................................done. 14:31:42 INFO - Installing pip.................done. 14:31:42 INFO - Return code: 0 14:31:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:42 INFO - 'HOME': '/Users/cltbld', 14:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:42 INFO - 'LOGNAME': 'cltbld', 14:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:42 INFO - 'MOZ_NO_REMOTE': '1', 14:31:42 INFO - 'NO_EM_RESTART': '1', 14:31:42 INFO - 'PAGER': '/bin/cat', 14:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:42 INFO - 'PWD': '/builds/slave/test', 14:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:42 INFO - 'SHELL': '/bin/bash', 14:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:31:42 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:42 INFO - 'USER': 'cltbld', 14:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:42 INFO - 'XPC_FLAGS': '0x0', 14:31:42 INFO - 'XPC_SERVICE_NAME': '0', 14:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:43 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:31:43 INFO - Cleaning up... 14:31:43 INFO - Return code: 0 14:31:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:43 INFO - 'HOME': '/Users/cltbld', 14:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:43 INFO - 'LOGNAME': 'cltbld', 14:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:43 INFO - 'MOZ_NO_REMOTE': '1', 14:31:43 INFO - 'NO_EM_RESTART': '1', 14:31:43 INFO - 'PAGER': '/bin/cat', 14:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:43 INFO - 'PWD': '/builds/slave/test', 14:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:43 INFO - 'SHELL': '/bin/bash', 14:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:31:43 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:43 INFO - 'USER': 'cltbld', 14:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:43 INFO - 'XPC_FLAGS': '0x0', 14:31:43 INFO - 'XPC_SERVICE_NAME': '0', 14:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:43 INFO - Downloading/unpacking psutil>=3.1.1 14:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:31:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:31:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:31:46 INFO - Installing collected packages: psutil 14:31:46 INFO - Running setup.py install for psutil 14:31:46 INFO - building 'psutil._psutil_osx' extension 14:31:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:31:47 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:31:47 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:31:47 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:31:47 INFO - building 'psutil._psutil_posix' extension 14:31:47 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:31:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:31:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:31:47 INFO - ^ 14:31:47 INFO - 1 warning generated. 14:31:47 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:31:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:31:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:31:47 INFO - Successfully installed psutil 14:31:47 INFO - Cleaning up... 14:31:47 INFO - Return code: 0 14:31:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:31:47 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:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:47 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:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:47 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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:47 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:31:47 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:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:47 INFO - 'HOME': '/Users/cltbld', 14:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:47 INFO - 'LOGNAME': 'cltbld', 14:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:47 INFO - 'MOZ_NO_REMOTE': '1', 14:31:47 INFO - 'NO_EM_RESTART': '1', 14:31:47 INFO - 'PAGER': '/bin/cat', 14:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:47 INFO - 'PWD': '/builds/slave/test', 14:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:47 INFO - 'SHELL': '/bin/bash', 14:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:31:47 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:47 INFO - 'USER': 'cltbld', 14:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:47 INFO - 'XPC_FLAGS': '0x0', 14:31:47 INFO - 'XPC_SERVICE_NAME': '0', 14:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:31:50 INFO - Installing collected packages: mozsystemmonitor 14:31:50 INFO - Running setup.py install for mozsystemmonitor 14:31:50 INFO - Successfully installed mozsystemmonitor 14:31:50 INFO - Cleaning up... 14:31:50 INFO - Return code: 0 14:31:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:31:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:50 INFO - 'HOME': '/Users/cltbld', 14:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:50 INFO - 'LOGNAME': 'cltbld', 14:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:50 INFO - 'MOZ_NO_REMOTE': '1', 14:31:50 INFO - 'NO_EM_RESTART': '1', 14:31:50 INFO - 'PAGER': '/bin/cat', 14:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:50 INFO - 'PWD': '/builds/slave/test', 14:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:50 INFO - 'SHELL': '/bin/bash', 14:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:31:50 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:50 INFO - 'USER': 'cltbld', 14:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:50 INFO - 'XPC_FLAGS': '0x0', 14:31:50 INFO - 'XPC_SERVICE_NAME': '0', 14:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:50 INFO - Downloading/unpacking blobuploader==1.2.4 14:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:31:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:31:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:31:54 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:31:54 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:31:54 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:31:54 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:31:54 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:31:54 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:31:54 INFO - Downloading docopt-0.6.1.tar.gz 14:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:31:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:31:54 INFO - Running setup.py install for blobuploader 14:31:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:31:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:31:54 INFO - Running setup.py install for requests 14:31:55 INFO - Running setup.py install for docopt 14:31:55 INFO - Successfully installed blobuploader requests docopt 14:31:55 INFO - Cleaning up... 14:31:55 INFO - Return code: 0 14:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31:55 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:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:55 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:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:55 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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:31:55 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:31:55 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:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:55 INFO - 'HOME': '/Users/cltbld', 14:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:55 INFO - 'LOGNAME': 'cltbld', 14:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:55 INFO - 'MOZ_NO_REMOTE': '1', 14:31:55 INFO - 'NO_EM_RESTART': '1', 14:31:55 INFO - 'PAGER': '/bin/cat', 14:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:55 INFO - 'PWD': '/builds/slave/test', 14:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:55 INFO - 'SHELL': '/bin/bash', 14:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:31:55 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:55 INFO - 'USER': 'cltbld', 14:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:55 INFO - 'XPC_FLAGS': '0x0', 14:31:55 INFO - 'XPC_SERVICE_NAME': '0', 14:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:31:55 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-xdAMQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:31:55 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-DqqtvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:31:55 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7QruJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-DW9ElR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3XsJuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lCsr7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8o15P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-rQhNcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-u8hh1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oeWXb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-KaasTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Bz6qLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:31:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9X547d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:31:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-voM7T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9rVjtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:31:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-v7fEwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:31:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-AIpTf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:31:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:31:57 INFO - Running setup.py install for manifestparser 14:31:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:31:57 INFO - Running setup.py install for mozcrash 14:31:57 INFO - Running setup.py install for mozdebug 14:31:57 INFO - Running setup.py install for mozdevice 14:31:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Running setup.py install for mozfile 14:31:58 INFO - Running setup.py install for mozhttpd 14:31:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Running setup.py install for mozinfo 14:31:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Running setup.py install for mozInstall 14:31:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Running setup.py install for mozleak 14:31:58 INFO - Running setup.py install for mozlog 14:31:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:31:58 INFO - Running setup.py install for moznetwork 14:31:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:31:59 INFO - Running setup.py install for mozprocess 14:31:59 INFO - Running setup.py install for mozprofile 14:31:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:31:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:31:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:31:59 INFO - Running setup.py install for mozrunner 14:31:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:31:59 INFO - Running setup.py install for mozscreenshot 14:31:59 INFO - Running setup.py install for moztest 14:31:59 INFO - Running setup.py install for mozversion 14:32:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:32:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:32:00 INFO - Cleaning up... 14:32:00 INFO - Return code: 0 14:32:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:00 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:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:00 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:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:00 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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:32:00 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:32:00 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:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:00 INFO - 'HOME': '/Users/cltbld', 14:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:00 INFO - 'LOGNAME': 'cltbld', 14:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:00 INFO - 'MOZ_NO_REMOTE': '1', 14:32:00 INFO - 'NO_EM_RESTART': '1', 14:32:00 INFO - 'PAGER': '/bin/cat', 14:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:00 INFO - 'PWD': '/builds/slave/test', 14:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:00 INFO - 'SHELL': '/bin/bash', 14:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:32:00 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:00 INFO - 'USER': 'cltbld', 14:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:00 INFO - 'XPC_FLAGS': '0x0', 14:32:00 INFO - 'XPC_SERVICE_NAME': '0', 14:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-l2F_yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-OpWRc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-o0DU7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2CCJnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-r6PUIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lq5s6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:32:00 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-g_IUks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lSbQfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oXQm9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gkUWMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fVwcec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-C5w9qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-61BNGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lRD9jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:32:01 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:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-xQftpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Et2O6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:32:01 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-I1hcbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:05 INFO - Downloading blessings-1.6.tar.gz 14:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:32:05 INFO - Installing collected packages: blessings 14:32:05 INFO - Running setup.py install for blessings 14:32:05 INFO - Successfully installed blessings 14:32:05 INFO - Cleaning up... 14:32:05 INFO - Return code: 0 14:32:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:32:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:32:06 INFO - Reading from file tmpfile_stdout 14:32:06 INFO - Current package versions: 14:32:06 INFO - blessings == 1.6 14:32:06 INFO - blobuploader == 1.2.4 14:32:06 INFO - docopt == 0.6.1 14:32:06 INFO - manifestparser == 1.1 14:32:06 INFO - mozInstall == 1.12 14:32:06 INFO - mozcrash == 0.17 14:32:06 INFO - mozdebug == 0.1 14:32:06 INFO - mozdevice == 0.48 14:32:06 INFO - mozfile == 1.2 14:32:06 INFO - mozhttpd == 0.7 14:32:06 INFO - mozinfo == 0.9 14:32:06 INFO - mozleak == 0.1 14:32:06 INFO - mozlog == 3.2 14:32:06 INFO - moznetwork == 0.27 14:32:06 INFO - mozprocess == 0.22 14:32:06 INFO - mozprofile == 0.28 14:32:06 INFO - mozrunner == 6.11 14:32:06 INFO - mozscreenshot == 0.1 14:32:06 INFO - mozsystemmonitor == 0.3 14:32:06 INFO - moztest == 0.7 14:32:06 INFO - mozversion == 1.4 14:32:06 INFO - psutil == 3.1.1 14:32:06 INFO - requests == 1.2.3 14:32:06 INFO - wsgiref == 0.1.2 14:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32: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:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32: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:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:06 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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:32:06 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:32:06 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:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:06 INFO - 'HOME': '/Users/cltbld', 14:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:06 INFO - 'LOGNAME': 'cltbld', 14:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:06 INFO - 'MOZ_NO_REMOTE': '1', 14:32:06 INFO - 'NO_EM_RESTART': '1', 14:32:06 INFO - 'PAGER': '/bin/cat', 14:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:06 INFO - 'PWD': '/builds/slave/test', 14:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:06 INFO - 'SHELL': '/bin/bash', 14:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:32:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:06 INFO - 'USER': 'cltbld', 14:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:06 INFO - 'XPC_FLAGS': '0x0', 14:32:06 INFO - 'XPC_SERVICE_NAME': '0', 14:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 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:32:06 INFO - Cleaning up... 14:32:06 INFO - Return code: 0 14:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:32: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:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32: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:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:06 INFO - 'HOME': '/Users/cltbld', 14:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:06 INFO - 'LOGNAME': 'cltbld', 14:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:06 INFO - 'MOZ_NO_REMOTE': '1', 14:32:06 INFO - 'NO_EM_RESTART': '1', 14:32:06 INFO - 'PAGER': '/bin/cat', 14:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:06 INFO - 'PWD': '/builds/slave/test', 14:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:06 INFO - 'SHELL': '/bin/bash', 14:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:32:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:06 INFO - 'USER': 'cltbld', 14:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:06 INFO - 'XPC_FLAGS': '0x0', 14:32:06 INFO - 'XPC_SERVICE_NAME': '0', 14:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:06 INFO - Downloading/unpacking jsonschema 14:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:32:09 INFO - Downloading/unpacking functools32 (from jsonschema) 14:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:09 INFO - Downloading functools32-3.2.3-2.tar.gz 14:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:32:09 INFO - warning: no files found matching '*.txt' 14:32:09 INFO - no previously-included directories found matching 'build' 14:32:09 INFO - no previously-included directories found matching 'dist' 14:32:09 INFO - no previously-included directories found matching '.git*' 14:32:09 INFO - Installing collected packages: jsonschema, functools32 14:32:09 INFO - Running setup.py install for functools32 14:32:09 INFO - warning: no files found matching '*.txt' 14:32:09 INFO - no previously-included directories found matching 'build' 14:32:09 INFO - no previously-included directories found matching 'dist' 14:32:09 INFO - no previously-included directories found matching '.git*' 14:32:09 INFO - Successfully installed jsonschema functools32 14:32:09 INFO - Cleaning up... 14:32:09 INFO - Return code: 0 14:32:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:32:09 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:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:09 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:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:09 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 0x10231da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102323570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe89586270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, '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.9f7BxOF9Hn/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.geMf89JION/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:32:09 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:32:09 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:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:09 INFO - 'HOME': '/Users/cltbld', 14:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:09 INFO - 'LOGNAME': 'cltbld', 14:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:09 INFO - 'MOZ_NO_REMOTE': '1', 14:32:09 INFO - 'NO_EM_RESTART': '1', 14:32:09 INFO - 'PAGER': '/bin/cat', 14:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:09 INFO - 'PWD': '/builds/slave/test', 14:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:09 INFO - 'SHELL': '/bin/bash', 14:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:32:09 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:09 INFO - 'USER': 'cltbld', 14:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:09 INFO - 'XPC_FLAGS': '0x0', 14:32:09 INFO - 'XPC_SERVICE_NAME': '0', 14:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:10 INFO - Downloading/unpacking flake8 14:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:32:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:32: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:32: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:32: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:32: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:32: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:32: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:32:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:32:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:32: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:32: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:32: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:32: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:32: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:32: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:32:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:32:13 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:32:13 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:32:13 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:32:13 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:32:13 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:32:13 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:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:32:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:32:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:32:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:32:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:32:13 INFO - no previously-included directories found matching 'docs/_build' 14:32:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:32:13 INFO - Running setup.py install for pep8 14:32:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:32:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:32:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:32:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:32:14 INFO - no previously-included directories found matching 'docs/_build' 14:32:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:32:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:32:14 INFO - Cleaning up... 14:32:14 INFO - Return code: 0 14:32:14 INFO - Running post-action listener: _resource_record_post_action 14:32:14 INFO - Running post-action listener: _start_resource_monitoring 14:32:14 INFO - Starting resource monitoring. 14:32:14 INFO - ##### 14:32:14 INFO - ##### Finished create-virtualenv step (success) 14:32:14 INFO - ##### 14:32:14 INFO - ##### 14:32:14 INFO - ##### Running install step. 14:32:14 INFO - ##### 14:32:14 INFO - Running pre-action listener: _resource_record_pre_action 14:32:14 INFO - Running main action method: install 14:32:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:32:14 INFO - Reading from file tmpfile_stdout 14:32:14 INFO - Detecting whether we're running mozinstall >=1.0... 14:32:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:32:14 INFO - Reading from file tmpfile_stdout 14:32:14 INFO - Output received: 14:32:14 INFO - Usage: mozinstall [options] installer 14:32:14 INFO - Options: 14:32:14 INFO - -h, --help show this help message and exit 14:32:14 INFO - -d DEST, --destination=DEST 14:32:14 INFO - Directory to install application into. [default: 14:32:14 INFO - "/builds/slave/test"] 14:32:14 INFO - --app=APP Application being installed. [default: firefox] 14:32:14 INFO - mkdir: /builds/slave/test/build/application 14:32:14 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:32:14 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:32:49 INFO - Reading from file tmpfile_stdout 14:32:49 INFO - Output received: 14:32:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:32:49 INFO - Running post-action listener: _resource_record_post_action 14:32:49 INFO - ##### 14:32:49 INFO - ##### Finished install step (success) 14:32:49 INFO - ##### 14:32:49 INFO - ##### 14:32:49 INFO - ##### Running run-tests step. 14:32:49 INFO - ##### 14:32:49 INFO - Running pre-action listener: _resource_record_pre_action 14:32:49 INFO - Running main action method: run_tests 14:32:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:32:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:32:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:32:49 INFO - 2016-05-31 14:32:49.304 screenresolution[1928:16485] starting screenresolution argv=screenresolution get 14:32:49 INFO - 2016-05-31 14:32:49.307 screenresolution[1928:16485] Display 0: 1600x1200x32@60 14:32:49 INFO - 2016-05-31 14:32:49.312 screenresolution[1929:16487] starting screenresolution argv=screenresolution list 14:32:49 INFO - Available Modes on Display 0 14:32:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:32:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:32:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:32:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:32:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:32:49 INFO - Intel Iris: 14:32:49 INFO - Chipset Model: Intel Iris 14:32:49 INFO - Type: GPU 14:32:49 INFO - Bus: Built-In 14:32:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:32:49 INFO - Vendor: Intel (0x8086) 14:32:49 INFO - Device ID: 0x0a2e 14:32:49 INFO - Revision ID: 0x0009 14:32:49 INFO - Displays: 14:32:49 INFO - Display: 14:32:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:32:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:32:49 INFO - Display Serial Number: 1600x1200 60 14:32:49 INFO - Main Display: Yes 14:32:49 INFO - Mirror: Off 14:32:49 INFO - Online: Yes 14:32:49 INFO - Rotation: Supported 14:32:49 INFO - Return code: 0 14:32:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:32:49 INFO - Python 2.7.3 14:32:49 INFO - Return code: 0 14:32:49 INFO - grabbing minidump binary from tooltool 14:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:49 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 0x1021c1d30>, '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 0x101fe5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:32:49 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:32:49 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:32:49 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:32:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:32:49 INFO - Return code: 0 14:32:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:32:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:32:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:32:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:32:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:32:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:32:50 INFO - Return code: 0 14:32:50 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/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:32:50 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/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9f7BxOF9Hn/Render', 14:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:32:50 INFO - 'HOME': '/Users/cltbld', 14:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:32:50 INFO - 'LOGNAME': 'cltbld', 14:32:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:32:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:50 INFO - 'MOZ_NO_REMOTE': '1', 14:32:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:32:50 INFO - 'NO_EM_RESTART': '1', 14:32:50 INFO - 'PAGER': '/bin/cat', 14:32:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:50 INFO - 'PWD': '/builds/slave/test', 14:32:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:32:50 INFO - 'SHELL': '/bin/bash', 14:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.geMf89JION/Listeners', 14:32:50 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 14:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:32:50 INFO - 'USER': 'cltbld', 14:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:50 INFO - 'XPC_FLAGS': '0x0', 14:32:50 INFO - 'XPC_SERVICE_NAME': '0', 14:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:32:50 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/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:32:50 INFO - mozversion application_buildid: 20160531133141 14:32:50 INFO - mozversion application_changeset: aee75e50fbc15694bbe48724eb28dea184ef985e 14:32:50 INFO - mozversion application_display_name: Nightly 14:32:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:32:50 INFO - mozversion application_name: Firefox 14:32:50 INFO - mozversion application_remotingname: firefox 14:32:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:32:50 INFO - mozversion application_vendor: Mozilla 14:32:50 INFO - mozversion application_version: 49.0a1 14:32:50 INFO - mozversion platform_buildid: 20160531133141 14:32:50 INFO - mozversion platform_changeset: aee75e50fbc15694bbe48724eb28dea184ef985e 14:32:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:32:50 INFO - mozversion platform_version: 49.0a1 14:32:50 INFO - using testdate: 1464730370 14:32:50 INFO - actual date: 1464730370 14:32:50 INFO - starting webserver on 'localhost:52099' 14:32:50 INFO - SUITE-START | Running 2 tests 14:32:50 INFO - TEST-START | dromaeo_css 14:32:50 INFO - operating with platform_type : mac_ 14:32:50 INFO - Initialising browser for dromaeo_css test... 14:32:50 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpb7zMML/profile http://localhost:52099/getInfo.html) 14:32:53 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 14:32:53 INFO - PROCESS | 1947 | colorDepth:24 14:32:53 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 14:32:53 INFO - PROCESS | 1947 | __metrics 14:32:53 INFO - PROCESS | 1947 | 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:32:53 ERROR - PROCESS | 1947 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:32:53 INFO - TEST-INFO | 1947: exit 0 14:32:53 INFO - Browser initialized. 14:32:53 INFO - Running cycle 1/1 for dromaeo_css test... 14:32:53 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpb7zMML/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:33:06 INFO - PROCESS | 1949 | RSS: Main: 241717248 14:33:06 INFO - PROCESS | 1949 | 14:33:57 INFO - PROCESS | 1949 | 1464730437004 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:36:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52099/tests/dromaeo/cssquery-ext.html) 14:36:10 INFO - PROCESS | 1949 | RSS: Main: 376147968 14:36:10 INFO - PROCESS | 1949 | 14:39:13 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-ext.html (next: http://localhost:52099/tests/dromaeo/cssquery-jquery.html) 14:39:14 INFO - PROCESS | 1949 | RSS: Main: 381214720 14:39:14 INFO - PROCESS | 1949 | 14:42:16 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52099/tests/dromaeo/cssquery-mootools.html) 14:42:17 INFO - PROCESS | 1949 | RSS: Main: 375660544 14:42:17 INFO - PROCESS | 1949 | 14:45:19 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52099/tests/dromaeo/cssquery-prototype.html) 14:45:19 INFO - PROCESS | 1949 | RSS: Main: 372064256 14:45:19 INFO - PROCESS | 1949 | 14:48:23 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52099/tests/dromaeo/cssquery-yui.html) 14:48:23 INFO - PROCESS | 1949 | RSS: Main: 340979712 14:48:23 INFO - PROCESS | 1949 | 14:51:26 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52099/tests/dromaeo/cssquery-yui.html (next: http://localhost:52099/tests/dromaeo/cssquery-dojo.html) 14:51:27 INFO - PROCESS | 1949 | RSS: Main: 379076608 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | __start_tp_report 14:51:27 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 14:51:27 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 14:51:27 INFO - PROCESS | 1949 | |i|pagename|runs| 14:51:27 INFO - PROCESS | 1949 | |0;dojo.html;3426.79439233646;3455.4471284594456;3460.8615655373787;3499.9825000875026;3509.7543171977954;5581.609287349887;5661.179129026299;5723.713814309289;5754.769809207627;5772.567057470691;5696.658200507973;5760.36635970043;5768.336641286253;5774.047282198443;5779.2775903012125;5143.562797162245;5188.974055129719;5196.350456192976;5213.087709650815;5230.293910322115;5241.318628578299;5278.868028299285;5282.313299271109;5284.44513326101;5285.709285989269;5156.046131465672;5165.147750621143;5185.87035324118;5191.688498690071;5273.050850846846;5261.39493958194;5279.70961597112;5284.339457567804;5285.814996475105;5343.652662576959;5001.224810154432;5084.79660813567;5087.440381558025;5190.480951904817;5190.844274671766;5489.149181876815;5551.916721249185;5600.4959553640365;5604.075327570987;5630.577706672016;6107.877842443127;6140.416660417253;6147.385261473817;6151.753929842801;6158.6612736299485;6046.72789724459;6106.278657334058;6152.077188421727;6183;6196.628202307876;5921.555883308769;5926.348101708809;5926.61477004002;5929.584929054968;5933.050711886121;6275.811725648238;6310.842228944313;6314.273858506239;6315.810525684236;6321.336259692666;6028.517718582503;6066.500092264219;6107.572469927062;6113.083037544315;6182.505399568114;6192.659403732838;6193.504519638418;6196.132541444202;6200.410960958701;6206.007038873759;6141.662208578571;6185.072239164071;6205.906911396332;6209;6230.688465576703;6138.51101655979;6144.8771024579255;6188.0717892315615;6194.318624951252;6215.254169499689;4911.582515486224;4932.235503496964;4942.678725882806;4943.431505376928;4979.850604481871;4397.560243975577;4415.933760993587;4418.867433976986;4429.092036132585;4456.955430445719;5213.504717051874;5596.776128954796;5745.712714364348;5786.3056433228285;5811.128330750421;6066.787662431793;6191.690415479208;6203.2556093269095;6206.937930620727;6219.346968568326;5731.484166425042;5754.712264386764;5756.510696590753;5768.769249229984;5772;4455.376247325377;5267.72649487863;5319.494648008433;5400.108982017923;5418.431064738145;5327.706976116351;6242.906356404657;6296;6388.520860935356;6401.679916004088;6193.752249910133;6197.566170368031;6202.100695399052;6225.439710426083;6231.158793562811;6210.7515699371515;6376.426121649074;6397.424231819158;6403.167588213502;6408.1349017881985;6101.883383140012;6161.075838624242;6169.537284703575;6219.253689557282;6257.061440783918;6141.385861414002;6188.845278868064;6198.783042593487;6199.752009919733;6204.906926396198;5969.3732158122775;6022.337542870368;6126.23422072241;6159.938400615936;6181.165542651865;5028.069807085701;6049.274087109575;6119.173911521888;6128.601640893327;6132.661461321697;5977.850553736192;6003.339632640319;6024.5481588881885;6047.153398524122;6062.605930614496;5992.910106348496;6041.154238406714;6124.096685771244;6128.1726966858905;6136.20229370179;5903.498202652822;5955.464008239279;5992.161097446274;6017.759289628366;6030.849228769141 14:51:27 INFO - PROCESS | 1949 | |1;ext.html;495.70010143862964;499.70517394737095;507.72814100677806;512;520.489919878519;30193.79224831007;31207.843960780192;31985.520217196703;32083.679163208362;32152.517712234276;42492.36261456073;43734;43822;46143.30785038227;46380.30429543551;8737.650493980233;9529.809403811902;9565.091316324939;9732.902670973306;9781.608735650565;8644.567771611426;8718.86921696175;8936.687215947442;9227.708120863084;9667.468289244089;9017.819643607125;11615.186936914419;11887.702807429798;11900.52397904087;12196.207246528991;11833.940830295836;11930;11940.70148246292;11947.641570752892;11955.701107472296;8581.356398270154;11420.31478111316;11771.764564708657;11911.344876031815;11922.821157682642;38844.805775971225;41061.794691026655;41092.58907410917;41236.38145427791;41236.38145427821;3495.8426870790877;3535.8939231823088;3600.7839529628304;3606.170580766412;3863.6715879150306;30838;32298;32607.184820379443;32733;32781.836090819634;3219.806811591289;3246.3152883299244;3273.4762438010043;3274.8526316315824;3291.374638818617;385103.07448462857;386992;387000;388954;388970;59362.40637593569;59821.70089149527;59885;60031.39968600258;60067.69966150229;64444.67777661081;64624.67687661532;64627;64635.67682161559;64704;16411.097389643684;16884.409045683344;16907.83092169087;16913.91543042277;16983.575410614834;13091.476340946256;13147.342632868318;13155.473781048651;13167.078304518593;13172.868271317357;14419.279036048156;14721.411143554138;16253.187340633158;16405.261763220682;16607.584810379838;6041.516678665784;6899.827504312432;7053.259407762213;7075.433965282765;7089.901065334881;897.3852910756023;1034.4672493665769;1037.725002874239;1170.9824352634716;1182.1429463638792;29895.10314690563;30056.699433005826;30173.849130754206;30862.691373086433;30901;1083.544911137324;1108.3682301088463;1124.9268797528134;1168.456667649547;1262.753763016203;14182.361793719101;14200.431982720782;15512.612184695472;15654.125215049697;15890.443834465737;628474;630990;636645;636876;640197;57983.71008145017;59664.40335596675;59774.70112649496;59846.70076649676;59859.10211346833;1295.5530342031984;1333.0735139078442;1413.6889884225454;1427.7358688642635;1447.9420823167166;29357.55963660504;31073;31270.843645781624;31564.842175788974;31697.5245371315;49263.507364927325;50032;50040.49959500504;50071.74964125156;50143;472741;477386;481392;482623;482998;39459.40810887782;40388.79805600953;40604.79697601493;40862.38706419346;40876.59123408728;47891.76054119707;47980.520194799;48034.27948580704;48574.514254857;49159;39355.01612459597;39689.007774804704;39693.404598930465;40115.99710007157;40812.79593602013;38524.42213366858;38728.61271387363;39070.02324941899;39128.608713912494;39517.41930322755;37969.86090417292;38128.61871381251;38623.42064869085;38627.034324140994;38676.41985370278;33782.83108584442;33915.15212119618;34732.9580112597;34836.82581587076;34860;21655.566888661822;23637.52724945526;24897.875510622333;25510.362240944123;25975.740242598087 14:51:27 INFO - PROCESS | 1949 | |2;jquery.html;383.5186840515153;384.69801206053233;386.33935969492165;390.8253743374291;411.12225398773626;19833.900830495844;20066.197352105897;20975;21047.842368669717;21163.47091322718;20530.794692053074;20700.861452620098;21691.215057901536;22061.889690551565;22076.88961555194;5847.356790753003;6135.171751813493;6142.8771424571705;6144.815655530341;6147.47746441553;5620.185073164389;5998.070299103646;6038.456538911497;6140.600860944024;6142.907856382158;5751.223584816059;6024.608400453956;6025.15647809307;6029.186059881904;6039.275286965571;6100.0849872519475;6112.6638034908065;6126.571140020201;6128.601640893372;6133.2026836511195;5247.950409917993;5547.611667183299;5865.56008299375;5905.202797622309;5918.437748413937;13322.467101315935;13476.797848032287;13558.525451609146;13629.863701362958;22158.446038848997;1874.9343772967882;2072.5336799220204;2094.86383385081;2155.0302363936294;2431.4529230923076;9208.585613647336;9215.677451289173;10162.898371016268;10183.388996660224;10339.069483746423;1606.4377467886147;1621.9351225950948;1714.2800023989955;1812.8821626594229;1836.2104295153079;116669.41665291705;117002;117715;119389;122778.38610806978;9578.281628877861;11451.942740286246;11571.190016698918;11577;11585;14849.554513364616;14959.102453852804;14980.925095374454;14991.625209369635;15052.849471505364;7679.6160191990175;8033.678652853937;8712.564371781385;8713.302935765123;8849.955750221208;8676;8683.872451249485;8684.522351270614;8842.425242359226;8846.336524760669;4431.645468362523;4505.549445055533;4510;4564.475085365226;4605.286180642006;1686.6626674665115;1715.6568686262797;1840.1351364858763;1857.4984754116583;1864.8601354898437;927.2813569483704;942.189716843514;945.2201933404968;977.4233202410612;991.8363470027509;20428.387148385573;21378.679319810217;21642.89178554097;21713.67429488559;21731.891340543196;850.59596691571;990.9058639429243;995.9452230127413;997.6209040564539;1050.3172937590627;11078.501467433985;11914.225575337749;11998.940005299917;12175.93912030452;12258.509659613694;540408;546016;548930;553981;554568;110329.93185374881;134097.3295133518;134945;135547.32226338805;138888;1044.4255659387459;1053.3627155570769;1054.651964851597;1083.9729006774894;1086.625114335553;16233.026018438932;18246.813957092745;19004.859708417538;19118.808811911702;19277.614447711247;45634;46495.30257046215;47627.5237247637;48073.5192648083;48351;69280.65359673371;69343.3065669357;69554.65222673854;69746.65126674334;70144.29855701582;5782.884342313046;7834.903113563992;8233.259006689426;8257.091719910684;8465.534395608302;8066.274035336613;12359.196652217577;13019;13383.598492045254;13482;11343;11596;11635.185537012328;11734.119941004288;11747.001504872182;10201.693949181536;10262;11431.59989400367;11493.82759258595;11647;11553.768924621292;11587.768244635228;11764.235324703866;11846.052315814542;12028;8079.070906845788;8096.433249672466;8355.164483551596;8360.832783344178;8529.3602979778;7353.8161545961775;10970.341779493256;11405.771884562428;11418.942905285421;11512.712182195512 14:51:27 INFO - PROCESS | 1949 | |3;mootools.html;1343.5633419138783;1533.394309247847;1568.4902406717815;1802.287826564763;1961.1272983522329;7947.562884041383;8645.481271123728;8863.423877447962;8878.334124940639;9024.368294219406;8548.658053677846;8827.29381649468;8832.381733278673;8832.91167088329;8835.983861855888;2354.411397150708;2385.272491889973;2385.725641551218;2386.1052105460453;2391.5575618510543;1896.924123035077;2343.2033108743026;2369.2655276864225;2395.508920671267;2443.499082688045;2347.647852822073;2368.0764501844296;2377.084822343402;2385.355953892448;2387.594109001474;2321.1991862807304;2348.9647655285185;2359.398353419884;2361.208994986673;2374.9406264843346;2146.5921474919874;2225.143319821864;2341.683872677184;2349.2247558305717;2356.352003199134;7934.563599002053;8521.318294536484;8745.693900713508;8815.118488151198;8821.25019373354;5300.575953923677;5479.506844383985;5482.369527504348;5482.945170548323;5485.396606373296;3604.675579197885;3695.9445608315896;3712.3688972874675;3769.717271204643;3774.433834924756;1060.734816295926;1077.3320541264388;1097.7639807441358;1104.7624760676492;1108.9944550277196;10491.527881245285;10508.632197873038;10515.73710657232;10538.578456861716;10559.313644613076;4308.5045219799495;4308.569143085666;4440.489343725449;4449.599536041707;4454.710443821141;8328.375371847136;8370.3303735701;8376.162383761575;8379.078301386842;8407.327413806881;4983.900321993612;4996.800127994912;5008.699478031257;5026.396832380138;5036.7733451994745;4417.425734654475;4558.270676691714;4582.518835522222;4655.6973796703105;4916.164252077156;3963.454252841394;4066.8169932353117;4123.092919557693;4157.147784704189;4157.937630935538;1294.8510921244178;1312.4356526693614;1315.8420989481324;1323.636000099961;1330.5808670268834;572.9272517879817;685.8114018644912;787.775483987061;978.9291470184512;993.9254555908336;5666.688332141689;5926.170336152942;5974.581779275409;6054.666993315322;6056.364081771439;476.7949781593879;481.2708746249435;489.11470238867764;495.2447517535715;567.3928896081153;3849.9615003849603;4059.411385349108;4059.7361171523758;4087.816048277835;4090.9795451022555;10358.378497290187;10503;10759.56961721538;10785.78428431427;10956.233063685466;10736.355818650905;10863.83704244437;10866.130709543218;10880.836787448194;10901.890981090246;7969.3226075784205;8211.8357632847;8311.6675332986;8337.166283371615;8360.414770966096;5861.677607731445;5908.940910590839;5958.493528050164;5958.851028724316;6031.185789918481;9909.207263418764;9943.701688949342;10113.797724045331;10119.949400252952;10146.847797283195;8667.566621668766;9924.156446701822;10172.694819155437;10243.590256389662;10599.417032063002;7575.166731659623;7589.772306830804;7671.3862890967475;7679.654415551426;7691.807704807425;7941.801454963672;8322.126176751353;8346.791330216793;8352.958235208785;8433.241008309282;7480.4015678746655;7557.206493318123;7590.316871481593;7608.771736847903;7680.884786728092;7435.4795164337975;7444.702211911679;7448.068991376078;7575.886361704689;7577.242275772379;6910.585364878123;7102.18324892644;7116.608586527584;7141.178764442156;7181.245969173162;5080.390353157644;5089.083964886356;5146.614003949644;5146.8970620588125;5203.375594928632;5057.8735531612;5067.46791586878;5169.53474187325;5265.4997775211295;5269.394019687632 14:51:27 INFO - PROCESS | 1949 | |4;prototype.html;300.7023047183288;308.42940559964063;317.31618362428975;319.9296154845933;321.77636542602886;4365.039691267916;5043.798248070073;5130.050940575996;5188.948110518893;5226.268322434862;4292.399064131024;4992.326035985141;5140.742962851852;5179.482051794828;5209.400918894328;3237.7247933925646;3409.3863104641277;3541.7874927504304;3817.7284515713177;4015.5783642717533;3537.7700449470703;3675.8345874435713;4006.95819087038;4033.156624579679;4037.3540233562635;3319.850606722703;3475.5308033415417;3653.4107663166606;3688.944665830015;3975.5030621172355;3905.921881562353;3923.234969181011;3925.1953349563414;3945.3490174121234;3950.644442000234;3181.4909614461576;3237.045071703853;3374.96625033749;3443.707284880788;3468.687818096358;4570.382998295221;5022.698638081727;5024.196128619404;5030.144875371196;5089.491050894918;4160.729552579103;4435.578620031092;4462.2191116554795;4515.954840451586;4541.636669066466;4417.889552761175;4663.953360466419;5157.819476318311;5325.227841962933;5448.291722076105;3457.083872773717;3721.5906250312714;3736.8318425670636;3753.6434038766274;3756.6806821420214;4435.645148388121;5298.894022119536;5677.432256774331;5696.515796157331;5734.655920644733;3559.327116255356;3799.7340186187253;3827.8468861245797;3843.9807800960816;3856.5950575189763;3029.727324540802;3771.9245615088093;3801.3917773156163;3906.7069969751815;3934.9213015739524;3972.7219094663096;4485.708428952173;4497.482789479252;4521.344405061313;4521.728696278234;5427.728613569306;5460.972695136499;5483.616146869761;5483.835484935458;5578.804741834017;1167.9007284380923;1228.342836582442;1272.7581759465672;1278.6867217531578;1285.9357032148541;656.8128083496181;749.6663984526833;760.2891296637662;769.4729110559285;879.2877769007125;4418.403515525427;4955.231939049453;4964.379452568429;4971.676841005323;4977.402711674622;4897.608191344684;5073.959838233153;5148.897022059537;5469.56243500519;5522.364928033247;4618.630509559227;4994.9500504994485;5006.74966251686;5011.4236862761245;5049.419316778617;2534.3664083979006;2629.474105178972;2649.3244222723083;2905.912822615325;2941.57347184657;5326.680399176062;5425;5751.1373294006235;5808.361080281165;5880.382559831241;4941.555260026615;5651.802186923438;5656.822123482489;5712.257406537124;5726.742296596663;5390.299261096033;5401.163604374453;5618.297712785902;5844.129878438859;5896.616719913192;4800.639952003684;4903.141950158779;5348.422215446375;5585.776568937197;5590.7763689453595;5363.704996225245;5427.782888684408;5460.6689919150085;5510.8071217507195;5518.779248830002;4920.876978075434;5002.549770520525;5092.490750924878;5528.640638358494;5845.13588341718;3067.266994148299;3372.6458721833856;3519.046928954455;3774.981125094357;3792.374258247469;4500.572445617661;4780.995990841962;4990.6506544541535;5599.132134519156;5615.971920140537;4355.216061109031;4723.6773703363115;5479.123460183277;5499.560035197094;5534.889302213853;5089.694618322913;5335.866603334947;5389.757460914347;5592.580556458363;5636.351819540805;4924.532169443897;5311.521963023347;5342.572594192377;5392.325959255093;5506.1190209566275;5000.824311699772;5370.919436208538;5373.925214957102;5563.860903477445;5601.943980560142;5107.2594473800345;5440.945590544202;5470.507654311131;5475.260839786578;5488.945110549003 14:51:27 INFO - PROCESS | 1949 | |5;yui.html;783.7139444102904;789.2856964447177;791.4262159934046;821.2854816309807;874.2044739287248;14737.778933316009;15229.61925951849;15237.923810380933;15238.390464381411;15241.085534867902;13774.173549587033;13883.652908677263;13895.791563126535;13906;13927.582172534814;862.9525376104327;891.6255172827396;895.8835351404308;896.8027034052498;896.9641214351418;713.914330280367;739.0023468317755;740.1044735869577;740.6815069520104;894.2130924786212;766.769969009295;908.4912449028532;947.0813311088268;947.5593848860278;948.8576713492962;933.5612262236738;936.3866667332908;936.5644975086549;937.7983733497263;939.7180845746317;546.6228302471283;694.9270326615733;696.8118607975869;697.0172057399043;698.0297386632585;13900.860991390058;14375.137491750424;14446.205458699767;14771;14810.185439800807;13854.861451385457;14156;14158.716825663532;14288.499902503363;14306.785398219035;11450.370229637367;11502.309861408343;11511.251768635011;11553.075753939665;11842.348670823101;3805.8667946622013;3813.1992281620614;3819.2170605025904;3844.730868839182;3962.1481381502826;78172.43655126923;81167;81212.59393703053;81264.18735812625;81507.59246203791;10865.239433239756;10867.510962006729;11029.172812038969;11101.888981110247;11516;21940.561188776133;21949.451263718536;21960.34118976433;21981.900904955008;21987;7907.841843163104;7923.564203968837;7969.481983671043;8041.276285134366;8148.429609927367;4363.825446982149;4423.380726698265;4457.9331310030375;4529.297958816389;4629.675922685447;3347.41420251455;3444.380011597886;3449.0515108344903;3449.292894956528;3459.4810778383444;614.088079202384;630.0706457974543;633.9333177665318;639.6513899924553;649.243631169682;622.2501885228289;673.4841646911777;701.0675801184515;703.4864548879347;713.3722324354535;8070.757877263692;9113.453192808453;9532.714018579454;9549.713508594754;9574.760630984141;586.6274915428734;671.9059331693567;677.2947816615448;698.9965050174717;701.5930760159198;3659.5608526976935;4267.381229721673;4457.888552786141;4467.039586488855;4569.131864945716;91063.54468227616;91933;93433;94555;94783;77151;80166.79749803757;81309.59345203236;82950.58524707338;83786.5810670955;71740.2825971744;74598.62700686461;75046.62476687582;75504.62247688726;75718.62140689262;9400.529973501434;9636.855447168158;9673.467959262309;9692.612295508101;9732.221422286342;43197.568024320615;43702.56297436985;44696;48407.757961209965;49649;52450.16424425127;54917.450825491236;54974.72512637411;63527;66380;12490.500379984698;12516.499340026297;12734.554290599785;12788.296643684687;13160.670983225496;13494.662633434242;14174.14955102697;14244.643883902985;14470.493532726305;14809.85190148128;13267.734645307233;13313.400896959854;13318.468376136869;13413.401450985415;13542.119762215425;13140.60578182656;13163.144395614254;13241.470341186245;13336.133151345131;13399.263040532474;12888.033397495414;12917.547885823951;13069.477220911058;13172.73654526885;13409.463621455032;11063.612773552886;11120.499577518869;11351.432428378714;11513.078953683853;11569.6529104127;10793.29843560166;10889.292196007234;11054.557817687202;11196.216264861707;11300.60447884353 14:51:27 INFO - PROCESS | 1949 | __end_tp_report 14:51:27 INFO - PROCESS | 1949 | __start_cc_report 14:51:27 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1377 14:51:27 INFO - PROCESS | 1949 | __end_cc_report 14:51:27 INFO - PROCESS | 1949 | __startTimestamp1464731487089__endTimestamp 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | ------- Summary: start ------- 14:51:27 INFO - PROCESS | 1949 | Number of tests: 6 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5726.96 Median:5992.91 stddev:630.30 (10.5%) stddev-sans-first:605.86 14:51:27 INFO - PROCESS | 1949 | Values: 3426.8 3455.4 3460.9 3500.0 3509.8 5581.6 5661.2 5723.7 5754.8 5772.6 5696.7 5760.4 5768.3 5774.0 5779.3 5143.6 5189.0 5196.4 5213.1 5230.3 5241.3 5278.9 5282.3 5284.4 5285.7 5156.0 5165.1 5185.9 5191.7 5273.1 5261.4 5279.7 5284.3 5285.8 5343.7 5001.2 5084.8 5087.4 5190.5 5190.8 5489.1 5551.9 5600.5 5604.1 5630.6 6107.9 6140.4 6147.4 6151.8 6158.7 6046.7 6106.3 6152.1 6183.0 6196.6 5921.6 5926.3 5926.6 5929.6 5933.1 6275.8 6310.8 6314.3 6315.8 6321.3 6028.5 6066.5 6107.6 6113.1 6182.5 6192.7 6193.5 6196.1 6200.4 6206.0 6141.7 6185.1 6205.9 6209.0 6230.7 6138.5 6144.9 6188.1 6194.3 6215.3 4911.6 4932.2 4942.7 4943.4 4979.9 4397.6 4415.9 4418.9 4429.1 4457.0 5213.5 5596.8 5745.7 5786.3 5811.1 6066.8 6191.7 6203.3 6206.9 6219.3 5731.5 5754.7 5756.5 5768.8 5772.0 4455.4 5267.7 5319.5 5400.1 5418.4 5327.7 6242.9 6296.0 6388.5 6401.7 6193.8 6197.6 6202.1 6225.4 6231.2 6210.8 6376.4 6397.4 6403.2 6408.1 6101.9 6161.1 6169.5 6219.3 6257.1 6141.4 6188.8 6198.8 6199.8 6204.9 5969.4 6022.3 6126.2 6159.9 6181.2 5028.1 6049.3 6119.2 6128.6 6132.7 5977.9 6003.3 6024.5 6047.2 6062.6 5992.9 6041.2 6124.1 6128.2 6136.2 5903.5 5955.5 5992.2 6017.8 6030.8 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:65052.50 Median:38625.23 stddev:136152.22 (352.5%) stddev-sans-first:136447.88 14:51:27 INFO - PROCESS | 1949 | Values: 495.7 499.7 507.7 512.0 520.5 30193.8 31207.8 31985.5 32083.7 32152.5 42492.4 43734.0 43822.0 46143.3 46380.3 8737.7 9529.8 9565.1 9732.9 9781.6 8644.6 8718.9 8936.7 9227.7 9667.5 9017.8 11615.2 11887.7 11900.5 12196.2 11833.9 11930.0 11940.7 11947.6 11955.7 8581.4 11420.3 11771.8 11911.3 11922.8 38844.8 41061.8 41092.6 41236.4 41236.4 3495.8 3535.9 3600.8 3606.2 3863.7 30838.0 32298.0 32607.2 32733.0 32781.8 3219.8 3246.3 3273.5 3274.9 3291.4 385103.1 386992.0 387000.0 388954.0 388970.0 59362.4 59821.7 59885.0 60031.4 60067.7 64444.7 64624.7 64627.0 64635.7 64704.0 16411.1 16884.4 16907.8 16913.9 16983.6 13091.5 13147.3 13155.5 13167.1 13172.9 14419.3 14721.4 16253.2 16405.3 16607.6 6041.5 6899.8 7053.3 7075.4 7089.9 897.4 1034.5 1037.7 1171.0 1182.1 29895.1 30056.7 30173.8 30862.7 30901.0 1083.5 1108.4 1124.9 1168.5 1262.8 14182.4 14200.4 15512.6 15654.1 15890.4 628474.0 630990.0 636645.0 636876.0 640197.0 57983.7 59664.4 59774.7 59846.7 59859.1 1295.6 1333.1 1413.7 1427.7 1447.9 29357.6 31073.0 31270.8 31564.8 31697.5 49263.5 50032.0 50040.5 50071.7 50143.0 472741.0 477386.0 481392.0 482623.0 482998.0 39459.4 40388.8 40604.8 40862.4 40876.6 47891.8 47980.5 48034.3 48574.5 49159.0 39355.0 39689.0 39693.4 40116.0 40812.8 38524.4 38728.6 39070.0 39128.6 39517.4 37969.9 38128.6 38623.4 38627.0 38676.4 33782.8 33915.2 34733.0 34836.8 34860.0 21655.6 23637.5 24897.9 25510.4 25975.7 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:33151.91 Median:21722.78 stddev:92098.77 (424.0%) stddev-sans-first:92324.27 14:51:27 INFO - PROCESS | 1949 | Values: 383.5 384.7 386.3 390.8 411.1 19833.9 20066.2 20975.0 21047.8 21163.5 20530.8 20700.9 21691.2 22061.9 22076.9 5847.4 6135.2 6142.9 6144.8 6147.5 5620.2 5998.1 6038.5 6140.6 6142.9 5751.2 6024.6 6025.2 6029.2 6039.3 6100.1 6112.7 6126.6 6128.6 6133.2 5248.0 5547.6 5865.6 5905.2 5918.4 13322.5 13476.8 13558.5 13629.9 22158.4 1874.9 2072.5 2094.9 2155.0 2431.5 9208.6 9215.7 10162.9 10183.4 10339.1 1606.4 1621.9 1714.3 1812.9 1836.2 116669.4 117002.0 117715.0 119389.0 122778.4 9578.3 11451.9 11571.2 11577.0 11585.0 14849.6 14959.1 14980.9 14991.6 15052.8 7679.6 8033.7 8712.6 8713.3 8850.0 8676.0 8683.9 8684.5 8842.4 8846.3 4431.6 4505.5 4510.0 4564.5 4605.3 1686.7 1715.7 1840.1 1857.5 1864.9 927.3 942.2 945.2 977.4 991.8 20428.4 21378.7 21642.9 21713.7 21731.9 850.6 990.9 995.9 997.6 1050.3 11078.5 11914.2 11998.9 12175.9 12258.5 540408.0 546016.0 548930.0 553981.0 554568.0 110329.9 134097.3 134945.0 135547.3 138888.0 1044.4 1053.4 1054.7 1084.0 1086.6 16233.0 18246.8 19004.9 19118.8 19277.6 45634.0 46495.3 47627.5 48073.5 48351.0 69280.7 69343.3 69554.7 69746.7 70144.3 5782.9 7834.9 8233.3 8257.1 8465.5 8066.3 12359.2 13019.0 13383.6 13482.0 11343.0 11596.0 11635.2 11734.1 11747.0 10201.7 10262.0 11431.6 11493.8 11647.0 11553.8 11587.8 11764.2 11846.1 12028.0 8079.1 8096.4 8355.2 8360.8 8529.4 7353.8 10970.3 11405.8 11418.9 11512.7 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5645.88 Median:4990.35 stddev:3123.93 (62.6%) stddev-sans-first:3115.96 14:51:27 INFO - PROCESS | 1949 | Values: 1343.6 1533.4 1568.5 1802.3 1961.1 7947.6 8645.5 8863.4 8878.3 9024.4 8548.7 8827.3 8832.4 8832.9 8836.0 2354.4 2385.3 2385.7 2386.1 2391.6 1896.9 2343.2 2369.3 2395.5 2443.5 2347.6 2368.1 2377.1 2385.4 2387.6 2321.2 2349.0 2359.4 2361.2 2374.9 2146.6 2225.1 2341.7 2349.2 2356.4 7934.6 8521.3 8745.7 8815.1 8821.3 5300.6 5479.5 5482.4 5482.9 5485.4 3604.7 3695.9 3712.4 3769.7 3774.4 1060.7 1077.3 1097.8 1104.8 1109.0 10491.5 10508.6 10515.7 10538.6 10559.3 4308.5 4308.6 4440.5 4449.6 4454.7 8328.4 8370.3 8376.2 8379.1 8407.3 4983.9 4996.8 5008.7 5026.4 5036.8 4417.4 4558.3 4582.5 4655.7 4916.2 3963.5 4066.8 4123.1 4157.1 4157.9 1294.9 1312.4 1315.8 1323.6 1330.6 572.9 685.8 787.8 978.9 993.9 5666.7 5926.2 5974.6 6054.7 6056.4 476.8 481.3 489.1 495.2 567.4 3850.0 4059.4 4059.7 4087.8 4091.0 10358.4 10503.0 10759.6 10785.8 10956.2 10736.4 10863.8 10866.1 10880.8 10901.9 7969.3 8211.8 8311.7 8337.2 8360.4 5861.7 5908.9 5958.5 5958.9 6031.2 9909.2 9943.7 10113.8 10119.9 10146.8 8667.6 9924.2 10172.7 10243.6 10599.4 7575.2 7589.8 7671.4 7679.7 7691.8 7941.8 8322.1 8346.8 8353.0 8433.2 7480.4 7557.2 7590.3 7608.8 7680.9 7435.5 7444.7 7448.1 7575.9 7577.2 6910.6 7102.2 7116.6 7141.2 7181.2 5080.4 5089.1 5146.6 5146.9 5203.4 5057.9 5067.5 5169.5 5265.5 5269.4 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4365.36 Median:4974.54 stddev:1361.00 (27.4%) stddev-sans-first:1330.19 14:51:27 INFO - PROCESS | 1949 | Values: 300.7 308.4 317.3 319.9 321.8 4365.0 5043.8 5130.1 5188.9 5226.3 4292.4 4992.3 5140.7 5179.5 5209.4 3237.7 3409.4 3541.8 3817.7 4015.6 3537.8 3675.8 4007.0 4033.2 4037.4 3319.9 3475.5 3653.4 3688.9 3975.5 3905.9 3923.2 3925.2 3945.3 3950.6 3181.5 3237.0 3375.0 3443.7 3468.7 4570.4 5022.7 5024.2 5030.1 5089.5 4160.7 4435.6 4462.2 4516.0 4541.6 4417.9 4664.0 5157.8 5325.2 5448.3 3457.1 3721.6 3736.8 3753.6 3756.7 4435.6 5298.9 5677.4 5696.5 5734.7 3559.3 3799.7 3827.8 3844.0 3856.6 3029.7 3771.9 3801.4 3906.7 3934.9 3972.7 4485.7 4497.5 4521.3 4521.7 5427.7 5461.0 5483.6 5483.8 5578.8 1167.9 1228.3 1272.8 1278.7 1285.9 656.8 749.7 760.3 769.5 879.3 4418.4 4955.2 4964.4 4971.7 4977.4 4897.6 5074.0 5148.9 5469.6 5522.4 4618.6 4995.0 5006.7 5011.4 5049.4 2534.4 2629.5 2649.3 2905.9 2941.6 5326.7 5425.0 5751.1 5808.4 5880.4 4941.6 5651.8 5656.8 5712.3 5726.7 5390.3 5401.2 5618.3 5844.1 5896.6 4800.6 4903.1 5348.4 5585.8 5590.8 5363.7 5427.8 5460.7 5510.8 5518.8 4920.9 5002.5 5092.5 5528.6 5845.1 3067.3 3372.6 3519.0 3775.0 3792.4 4500.6 4781.0 4990.7 5599.1 5616.0 4355.2 4723.7 5479.1 5499.6 5534.9 5089.7 5335.9 5389.8 5592.6 5636.4 4924.5 5311.5 5342.6 5392.3 5506.1 5000.8 5370.9 5373.9 5563.9 5601.9 5107.3 5440.9 5470.5 5475.3 5488.9 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:18747.04 Median:4549.21 stddev:25487.35 (560.3%) stddev-sans-first:25523.16 14:51:27 INFO - PROCESS | 1949 | Values: 783.7 789.3 791.4 821.3 874.2 14737.8 15229.6 15237.9 15238.4 15241.1 13774.2 13883.7 13895.8 13906.0 13927.6 863.0 891.6 895.9 896.8 897.0 713.9 739.0 740.1 740.7 894.2 766.8 908.5 947.1 947.6 948.9 933.6 936.4 936.6 937.8 939.7 546.6 694.9 696.8 697.0 698.0 13900.9 14375.1 14446.2 14771.0 14810.2 13854.9 14156.0 14158.7 14288.5 14306.8 11450.4 11502.3 11511.3 11553.1 11842.3 3805.9 3813.2 3819.2 3844.7 3962.1 78172.4 81167.0 81212.6 81264.2 81507.6 10865.2 10867.5 11029.2 11101.9 11516.0 21940.6 21949.5 21960.3 21981.9 21987.0 7907.8 7923.6 7969.5 8041.3 8148.4 4363.8 4423.4 4457.9 4529.3 4629.7 3347.4 3444.4 3449.1 3449.3 3459.5 614.1 630.1 633.9 639.7 649.2 622.3 673.5 701.1 703.5 713.4 8070.8 9113.5 9532.7 9549.7 9574.8 586.6 671.9 677.3 699.0 701.6 3659.6 4267.4 4457.9 4467.0 4569.1 91063.5 91933.0 93433.0 94555.0 94783.0 77151.0 80166.8 81309.6 82950.6 83786.6 71740.3 74598.6 75046.6 75504.6 75718.6 9400.5 9636.9 9673.5 9692.6 9732.2 43197.6 43702.6 44696.0 48407.8 49649.0 52450.2 54917.5 54974.7 63527.0 66380.0 12490.5 12516.5 12734.6 12788.3 13160.7 13494.7 14174.1 14244.6 14470.5 14809.9 13267.7 13313.4 13318.5 13413.4 13542.1 13140.6 13163.1 13241.5 13336.1 13399.3 12888.0 12917.5 13069.5 13172.7 13409.5 11063.6 11120.5 11351.4 11513.1 11569.7 10793.3 10889.3 11054.6 11196.2 11300.6 14:51:27 INFO - PROCESS | 1949 | -------- Summary: end -------- 14:51:27 INFO - PROCESS | 1949 | 14:51:27 INFO - TEST-INFO | 1949: exit 0 14:51:27 INFO - TEST-OK | dromaeo_css | took 1116690ms 14:51:27 INFO - TEST-START | kraken 14:51:27 INFO - operating with platform_type : mac_ 14:51:27 INFO - Initialising browser for kraken test... 14:51:27 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpl32zYd/profile http://localhost:52099/getInfo.html) 14:51:29 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 14:51:29 INFO - PROCESS | 1952 | colorDepth:24 14:51:29 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 14:51:29 INFO - PROCESS | 1952 | __metrics 14:51:29 INFO - PROCESS | 1952 | 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:51:29 ERROR - PROCESS | 1952 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:51:30 INFO - TEST-INFO | 1952: exit 0 14:51:30 INFO - Browser initialized. 14:51:30 INFO - Running cycle 1/1 for kraken test... 14:51:30 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpl32zYd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:51:42 INFO - PROCESS | 1953 | RSS: Main: 241917952 14:51:42 INFO - PROCESS | 1953 | 14:51:45 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52099/tests/kraken/driver.html?testName=audio-beat-detection) 14:51:46 INFO - PROCESS | 1953 | RSS: Main: 291815424 14:51:46 INFO - PROCESS | 1953 | 14:51:48 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52099/tests/kraken/driver.html?testName=audio-dft) 14:51:49 INFO - PROCESS | 1953 | RSS: Main: 301330432 14:51:49 INFO - PROCESS | 1953 | 14:51:53 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52099/tests/kraken/driver.html?testName=audio-fft) 14:51:53 INFO - PROCESS | 1953 | RSS: Main: 383959040 14:51:53 INFO - PROCESS | 1953 | 14:51:55 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52099/tests/kraken/driver.html?testName=audio-oscillator) 14:51:56 INFO - PROCESS | 1953 | RSS: Main: 287035392 14:51:56 INFO - PROCESS | 1953 | 14:51:58 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52099/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:51:59 INFO - PROCESS | 1953 | RSS: Main: 291094528 14:51:59 INFO - PROCESS | 1953 | 14:52:01 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52099/tests/kraken/driver.html?testName=imaging-darkroom) 14:52:02 INFO - PROCESS | 1953 | RSS: Main: 326828032 14:52:02 INFO - PROCESS | 1953 | 14:52:05 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52099/tests/kraken/driver.html?testName=imaging-desaturate) 14:52:05 INFO - PROCESS | 1953 | RSS: Main: 328830976 14:52:05 INFO - PROCESS | 1953 | 14:52:08 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52099/tests/kraken/driver.html?testName=json-parse-financial) 14:52:09 INFO - PROCESS | 1953 | RSS: Main: 327450624 14:52:09 INFO - PROCESS | 1953 | 14:52:11 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52099/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:52:11 INFO - PROCESS | 1953 | RSS: Main: 309592064 14:52:11 INFO - PROCESS | 1953 | 14:52:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:52:14 INFO - PROCESS | 1953 | RSS: Main: 316407808 14:52:14 INFO - PROCESS | 1953 | 14:52:16 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:52:17 INFO - PROCESS | 1953 | RSS: Main: 319524864 14:52:17 INFO - PROCESS | 1953 | 14:52:20 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:52:20 INFO - PROCESS | 1953 | RSS: Main: 322744320 14:52:20 INFO - PROCESS | 1953 | 14:52:23 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:52:24 INFO - PROCESS | 1953 | RSS: Main: 323002368 14:52:24 INFO - PROCESS | 1953 | 14:52:25 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52099/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52099/tests/kraken/driver.html?testName=ai-astar) 14:52:26 INFO - PROCESS | 1953 | RSS: Main: 366534656 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | __start_tp_report 14:52:26 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 14:52:26 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 14:52:26 INFO - PROCESS | 1953 | |i|pagename|runs| 14:52:26 INFO - PROCESS | 1953 | |0;ai-astar;113;108;113;110;113;113;112;113;113;120 14:52:26 INFO - PROCESS | 1953 | |1;audio-beat-detection;157;119;118;175;119;121;139;115;151;119 14:52:26 INFO - PROCESS | 1953 | |2;audio-dft;185;189;262;187;185;185;183;185;182;182 14:52:26 INFO - PROCESS | 1953 | |3;audio-fft;80;74;58;107;56;57;62;59;83;57 14:52:26 INFO - PROCESS | 1953 | |4;audio-oscillator;83;95;93;72;70;69;96;71;68;104 14:52:26 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;90;86;89;86;87;83;86;84;86;83 14:52:26 INFO - PROCESS | 1953 | |6;imaging-darkroom;94;94;103;130;95;96;96;95;94;95 14:52:26 INFO - PROCESS | 1953 | |7;imaging-desaturate;85;83;83;84;92;82;98;143;135;86 14:52:26 INFO - PROCESS | 1953 | |8;json-parse-financial;55;55;56;59;55;55;57;55;55;55 14:52:26 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;65;72;80;77;72;69;69;69;70;70 14:52:26 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;87;87;86;89;95;105;107;105;122;86 14:52:26 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;140;138;139;139;138;140;141;140;155;140 14:52:26 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;164;169;166;173;165;165;166;168;157;229 14:52:26 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;66;66;67;66;64;65;67;64;67;66 14:52:26 INFO - PROCESS | 1953 | __end_tp_report 14:52:26 INFO - PROCESS | 1953 | __start_cc_report 14:52:26 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2051 14:52:26 INFO - PROCESS | 1953 | __end_cc_report 14:52:26 INFO - PROCESS | 1953 | __startTimestamp1464731546207__endTimestamp 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | ------- Summary: start ------- 14:52:26 INFO - PROCESS | 1953 | Number of tests: 14 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:3.05 (2.7%) stddev-sans-first:3.23 14:52:26 INFO - PROCESS | 1953 | Values: 113.0 108.0 113.0 110.0 113.0 113.0 112.0 113.0 113.0 120.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:130.00 stddev:21.03 (16.2%) stddev-sans-first:20.48 14:52:26 INFO - PROCESS | 1953 | Values: 157.0 119.0 118.0 175.0 119.0 121.0 139.0 115.0 151.0 119.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:192.50 Median:185.00 stddev:24.51 (13.3%) stddev-sans-first:25.85 14:52:26 INFO - PROCESS | 1953 | Values: 185.0 189.0 262.0 187.0 185.0 185.0 183.0 185.0 182.0 182.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:69.30 Median:60.50 stddev:16.71 (27.6%) stddev-sans-first:17.27 14:52:26 INFO - PROCESS | 1953 | Values: 80.0 74.0 58.0 107.0 56.0 57.0 62.0 59.0 83.0 57.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:77.50 stddev:13.75 (17.7%) stddev-sans-first:14.58 14:52:26 INFO - PROCESS | 1953 | Values: 83.0 95.0 93.0 72.0 70.0 69.0 96.0 71.0 68.0 104.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:86.00 stddev:2.31 (2.7%) stddev-sans-first:1.94 14:52:26 INFO - PROCESS | 1953 | Values: 90.0 86.0 89.0 86.0 87.0 83.0 86.0 84.0 86.0 83.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:95.00 stddev:11.14 (11.7%) stddev-sans-first:11.66 14:52:26 INFO - PROCESS | 1953 | Values: 94.0 94.0 103.0 130.0 95.0 96.0 96.0 95.0 94.0 95.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:84.50 stddev:22.70 (26.9%) stddev-sans-first:23.65 14:52:26 INFO - PROCESS | 1953 | Values: 85.0 83.0 83.0 84.0 92.0 82.0 98.0 143.0 135.0 86.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:1.34 (2.4%) stddev-sans-first:1.39 14:52:26 INFO - PROCESS | 1953 | Values: 55.0 55.0 56.0 59.0 55.0 55.0 57.0 55.0 55.0 55.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:4.32 (6.1%) stddev-sans-first:3.94 14:52:26 INFO - PROCESS | 1953 | Values: 65.0 72.0 80.0 77.0 72.0 69.0 69.0 69.0 70.0 70.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:86.50 stddev:12.31 (14.2%) stddev-sans-first:12.52 14:52:26 INFO - PROCESS | 1953 | Values: 87.0 87.0 86.0 89.0 95.0 105.0 107.0 105.0 122.0 86.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:140.00 stddev:5.01 (3.6%) stddev-sans-first:5.30 14:52:26 INFO - PROCESS | 1953 | Values: 140.0 138.0 139.0 139.0 138.0 140.0 141.0 140.0 155.0 140.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:167.00 stddev:20.37 (12.2%) stddev-sans-first:21.39 14:52:26 INFO - PROCESS | 1953 | Values: 164.0 169.0 166.0 173.0 165.0 165.0 166.0 168.0 157.0 229.0 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 14:52:26 INFO - PROCESS | 1953 | Values: 66.0 66.0 67.0 66.0 64.0 65.0 67.0 64.0 67.0 66.0 14:52:26 INFO - PROCESS | 1953 | -------- Summary: end -------- 14:52:26 INFO - PROCESS | 1953 | 14:52:26 INFO - TEST-INFO | 1953: exit 0 14:52:27 INFO - TEST-OK | kraken | took 59368ms 14:52:27 INFO - SUITE-END | took 1176s 14:52:27 INFO - Completed test suite (00:19:36) 14:52:27 INFO - javascript benchmark 14:52:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3426.79439233646, 3455.4471284594456, 3460.8615655373787, 3499.9825000875026, 3509.7543171977954, 5581.609287349887, 5661.179129026299, 5723.713814309289, 5754.769809207627, 5772.567057470691, 5696.658200507973, 5760.36635970043, 5768.336641286253, 5774.047282198443, 5779.2775903012125, 5143.562797162245, 5188.974055129719, 5196.350456192976, 5213.087709650815, 5230.293910322115, 5241.318628578299, 5278.868028299285, 5282.313299271109, 5284.44513326101, 5285.709285989269, 5156.046131465672, 5165.147750621143, 5185.87035324118, 5191.688498690071, 5273.050850846846, 5261.39493958194, 5279.70961597112, 5284.339457567804, 5285.814996475105, 5343.652662576959, 5001.224810154432, 5084.79660813567, 5087.440381558025, 5190.480951904817, 5190.844274671766, 5489.149181876815, 5551.916721249185, 5600.4959553640365, 5604.075327570987, 5630.577706672016, 6107.877842443127, 6140.416660417253, 6147.385261473817, 6151.753929842801, 6158.6612736299485, 6046.72789724459, 6106.278657334058, 6152.077188421727, 6183.0, 6196.628202307876, 5921.555883308769, 5926.348101708809, 5926.61477004002, 5929.584929054968, 5933.050711886121, 6275.811725648238, 6310.842228944313, 6314.273858506239, 6315.810525684236, 6321.336259692666, 6028.517718582503, 6066.500092264219, 6107.572469927062, 6113.083037544315, 6182.505399568114, 6192.659403732838, 6193.504519638418, 6196.132541444202, 6200.410960958701, 6206.007038873759, 6141.662208578571, 6185.072239164071, 6205.906911396332, 6209.0, 6230.688465576703, 6138.51101655979, 6144.8771024579255, 6188.0717892315615, 6194.318624951252, 6215.254169499689, 4911.582515486224, 4932.235503496964, 4942.678725882806, 4943.431505376928, 4979.850604481871, 4397.560243975577, 4415.933760993587, 4418.867433976986, 4429.092036132585, 4456.955430445719, 5213.504717051874, 5596.776128954796, 5745.712714364348, 5786.3056433228285, 5811.128330750421, 6066.787662431793, 6191.690415479208, 6203.2556093269095, 6206.937930620727, 6219.346968568326, 5731.484166425042, 5754.712264386764, 5756.510696590753, 5768.769249229984, 5772.0, 4455.376247325377, 5267.72649487863, 5319.494648008433, 5400.108982017923, 5418.431064738145, 5327.706976116351, 6242.906356404657, 6296.0, 6388.520860935356, 6401.679916004088, 6193.752249910133, 6197.566170368031, 6202.100695399052, 6225.439710426083, 6231.158793562811, 6210.7515699371515, 6376.426121649074, 6397.424231819158, 6403.167588213502, 6408.1349017881985, 6101.883383140012, 6161.075838624242, 6169.537284703575, 6219.253689557282, 6257.061440783918, 6141.385861414002, 6188.845278868064, 6198.783042593487, 6199.752009919733, 6204.906926396198, 5969.3732158122775, 6022.337542870368, 6126.23422072241, 6159.938400615936, 6181.165542651865, 5028.069807085701, 6049.274087109575, 6119.173911521888, 6128.601640893327, 6132.661461321697, 5977.850553736192, 6003.339632640319, 6024.5481588881885, 6047.153398524122, 6062.605930614496, 5992.910106348496, 6041.154238406714, 6124.096685771244, 6128.1726966858905, 6136.20229370179, 5903.498202652822, 5955.464008239279, 5992.161097446274, 6017.759289628366, 6030.849228769141], "value": 5688.259629832844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.70010143862964, 499.70517394737095, 507.72814100677806, 512.0, 520.489919878519, 30193.79224831007, 31207.843960780192, 31985.520217196703, 32083.679163208362, 32152.517712234276, 42492.36261456073, 43734.0, 43822.0, 46143.30785038227, 46380.30429543551, 8737.650493980233, 9529.809403811902, 9565.091316324939, 9732.902670973306, 9781.608735650565, 8644.567771611426, 8718.86921696175, 8936.687215947442, 9227.708120863084, 9667.468289244089, 9017.819643607125, 11615.186936914419, 11887.702807429798, 11900.52397904087, 12196.207246528991, 11833.940830295836, 11930.0, 11940.70148246292, 11947.641570752892, 11955.701107472296, 8581.356398270154, 11420.31478111316, 11771.764564708657, 11911.344876031815, 11922.821157682642, 38844.805775971225, 41061.794691026655, 41092.58907410917, 41236.38145427791, 41236.38145427821, 3495.8426870790877, 3535.8939231823088, 3600.7839529628304, 3606.170580766412, 3863.6715879150306, 30838.0, 32298.0, 32607.184820379443, 32733.0, 32781.836090819634, 3219.806811591289, 3246.3152883299244, 3273.4762438010043, 3274.8526316315824, 3291.374638818617, 385103.07448462857, 386992.0, 387000.0, 388954.0, 388970.0, 59362.40637593569, 59821.70089149527, 59885.0, 60031.39968600258, 60067.69966150229, 64444.67777661081, 64624.67687661532, 64627.0, 64635.67682161559, 64704.0, 16411.097389643684, 16884.409045683344, 16907.83092169087, 16913.91543042277, 16983.575410614834, 13091.476340946256, 13147.342632868318, 13155.473781048651, 13167.078304518593, 13172.868271317357, 14419.279036048156, 14721.411143554138, 16253.187340633158, 16405.261763220682, 16607.584810379838, 6041.516678665784, 6899.827504312432, 7053.259407762213, 7075.433965282765, 7089.901065334881, 897.3852910756023, 1034.4672493665769, 1037.725002874239, 1170.9824352634716, 1182.1429463638792, 29895.10314690563, 30056.699433005826, 30173.849130754206, 30862.691373086433, 30901.0, 1083.544911137324, 1108.3682301088463, 1124.9268797528134, 1168.456667649547, 1262.753763016203, 14182.361793719101, 14200.431982720782, 15512.612184695472, 15654.125215049697, 15890.443834465737, 628474.0, 630990.0, 636645.0, 636876.0, 640197.0, 57983.71008145017, 59664.40335596675, 59774.70112649496, 59846.70076649676, 59859.10211346833, 1295.5530342031984, 1333.0735139078442, 1413.6889884225454, 1427.7358688642635, 1447.9420823167166, 29357.55963660504, 31073.0, 31270.843645781624, 31564.842175788974, 31697.5245371315, 49263.507364927325, 50032.0, 50040.49959500504, 50071.74964125156, 50143.0, 472741.0, 477386.0, 481392.0, 482623.0, 482998.0, 39459.40810887782, 40388.79805600953, 40604.79697601493, 40862.38706419346, 40876.59123408728, 47891.76054119707, 47980.520194799, 48034.27948580704, 48574.514254857, 49159.0, 39355.01612459597, 39689.007774804704, 39693.404598930465, 40115.99710007157, 40812.79593602013, 38524.42213366858, 38728.61271387363, 39070.02324941899, 39128.608713912494, 39517.41930322755, 37969.86090417292, 38128.61871381251, 38623.42064869085, 38627.034324140994, 38676.41985370278, 33782.83108584442, 33915.15212119618, 34732.9580112597, 34836.82581587076, 34860.0, 21655.566888661822, 23637.52724945526, 24897.875510622333, 25510.362240944123, 25975.740242598087], "value": 20249.373226722833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5186840515153, 384.69801206053233, 386.33935969492165, 390.8253743374291, 411.12225398773626, 19833.900830495844, 20066.197352105897, 20975.0, 21047.842368669717, 21163.47091322718, 20530.794692053074, 20700.861452620098, 21691.215057901536, 22061.889690551565, 22076.88961555194, 5847.356790753003, 6135.171751813493, 6142.8771424571705, 6144.815655530341, 6147.47746441553, 5620.185073164389, 5998.070299103646, 6038.456538911497, 6140.600860944024, 6142.907856382158, 5751.223584816059, 6024.608400453956, 6025.15647809307, 6029.186059881904, 6039.275286965571, 6100.0849872519475, 6112.6638034908065, 6126.571140020201, 6128.601640893372, 6133.2026836511195, 5247.950409917993, 5547.611667183299, 5865.56008299375, 5905.202797622309, 5918.437748413937, 13322.467101315935, 13476.797848032287, 13558.525451609146, 13629.863701362958, 22158.446038848997, 1874.9343772967882, 2072.5336799220204, 2094.86383385081, 2155.0302363936294, 2431.4529230923076, 9208.585613647336, 9215.677451289173, 10162.898371016268, 10183.388996660224, 10339.069483746423, 1606.4377467886147, 1621.9351225950948, 1714.2800023989955, 1812.8821626594229, 1836.2104295153079, 116669.41665291705, 117002.0, 117715.0, 119389.0, 122778.38610806978, 9578.281628877861, 11451.942740286246, 11571.190016698918, 11577.0, 11585.0, 14849.554513364616, 14959.102453852804, 14980.925095374454, 14991.625209369635, 15052.849471505364, 7679.6160191990175, 8033.678652853937, 8712.564371781385, 8713.302935765123, 8849.955750221208, 8676.0, 8683.872451249485, 8684.522351270614, 8842.425242359226, 8846.336524760669, 4431.645468362523, 4505.549445055533, 4510.0, 4564.475085365226, 4605.286180642006, 1686.6626674665115, 1715.6568686262797, 1840.1351364858763, 1857.4984754116583, 1864.8601354898437, 927.2813569483704, 942.189716843514, 945.2201933404968, 977.4233202410612, 991.8363470027509, 20428.387148385573, 21378.679319810217, 21642.89178554097, 21713.67429488559, 21731.891340543196, 850.59596691571, 990.9058639429243, 995.9452230127413, 997.6209040564539, 1050.3172937590627, 11078.501467433985, 11914.225575337749, 11998.940005299917, 12175.93912030452, 12258.509659613694, 540408.0, 546016.0, 548930.0, 553981.0, 554568.0, 110329.93185374881, 134097.3295133518, 134945.0, 135547.32226338805, 138888.0, 1044.4255659387459, 1053.3627155570769, 1054.651964851597, 1083.9729006774894, 1086.625114335553, 16233.026018438932, 18246.813957092745, 19004.859708417538, 19118.808811911702, 19277.614447711247, 45634.0, 46495.30257046215, 47627.5237247637, 48073.5192648083, 48351.0, 69280.65359673371, 69343.3065669357, 69554.65222673854, 69746.65126674334, 70144.29855701582, 5782.884342313046, 7834.903113563992, 8233.259006689426, 8257.091719910684, 8465.534395608302, 8066.274035336613, 12359.196652217577, 13019.0, 13383.598492045254, 13482.0, 11343.0, 11596.0, 11635.185537012328, 11734.119941004288, 11747.001504872182, 10201.693949181536, 10262.0, 11431.59989400367, 11493.82759258595, 11647.0, 11553.768924621292, 11587.768244635228, 11764.235324703866, 11846.052315814542, 12028.0, 8079.070906845788, 8096.433249672466, 8355.164483551596, 8360.832783344178, 8529.3602979778, 7353.8161545961775, 10970.341779493256, 11405.771884562428, 11418.942905285421, 11512.712182195512], "value": 9414.0875036831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.5633419138783, 1533.394309247847, 1568.4902406717815, 1802.287826564763, 1961.1272983522329, 7947.562884041383, 8645.481271123728, 8863.423877447962, 8878.334124940639, 9024.368294219406, 8548.658053677846, 8827.29381649468, 8832.381733278673, 8832.91167088329, 8835.983861855888, 2354.411397150708, 2385.272491889973, 2385.725641551218, 2386.1052105460453, 2391.5575618510543, 1896.924123035077, 2343.2033108743026, 2369.2655276864225, 2395.508920671267, 2443.499082688045, 2347.647852822073, 2368.0764501844296, 2377.084822343402, 2385.355953892448, 2387.594109001474, 2321.1991862807304, 2348.9647655285185, 2359.398353419884, 2361.208994986673, 2374.9406264843346, 2146.5921474919874, 2225.143319821864, 2341.683872677184, 2349.2247558305717, 2356.352003199134, 7934.563599002053, 8521.318294536484, 8745.693900713508, 8815.118488151198, 8821.25019373354, 5300.575953923677, 5479.506844383985, 5482.369527504348, 5482.945170548323, 5485.396606373296, 3604.675579197885, 3695.9445608315896, 3712.3688972874675, 3769.717271204643, 3774.433834924756, 1060.734816295926, 1077.3320541264388, 1097.7639807441358, 1104.7624760676492, 1108.9944550277196, 10491.527881245285, 10508.632197873038, 10515.73710657232, 10538.578456861716, 10559.313644613076, 4308.5045219799495, 4308.569143085666, 4440.489343725449, 4449.599536041707, 4454.710443821141, 8328.375371847136, 8370.3303735701, 8376.162383761575, 8379.078301386842, 8407.327413806881, 4983.900321993612, 4996.800127994912, 5008.699478031257, 5026.396832380138, 5036.7733451994745, 4417.425734654475, 4558.270676691714, 4582.518835522222, 4655.6973796703105, 4916.164252077156, 3963.454252841394, 4066.8169932353117, 4123.092919557693, 4157.147784704189, 4157.937630935538, 1294.8510921244178, 1312.4356526693614, 1315.8420989481324, 1323.636000099961, 1330.5808670268834, 572.9272517879817, 685.8114018644912, 787.775483987061, 978.9291470184512, 993.9254555908336, 5666.688332141689, 5926.170336152942, 5974.581779275409, 6054.666993315322, 6056.364081771439, 476.7949781593879, 481.2708746249435, 489.11470238867764, 495.2447517535715, 567.3928896081153, 3849.9615003849603, 4059.411385349108, 4059.7361171523758, 4087.816048277835, 4090.9795451022555, 10358.378497290187, 10503.0, 10759.56961721538, 10785.78428431427, 10956.233063685466, 10736.355818650905, 10863.83704244437, 10866.130709543218, 10880.836787448194, 10901.890981090246, 7969.3226075784205, 8211.8357632847, 8311.6675332986, 8337.166283371615, 8360.414770966096, 5861.677607731445, 5908.940910590839, 5958.493528050164, 5958.851028724316, 6031.185789918481, 9909.207263418764, 9943.701688949342, 10113.797724045331, 10119.949400252952, 10146.847797283195, 8667.566621668766, 9924.156446701822, 10172.694819155437, 10243.590256389662, 10599.417032063002, 7575.166731659623, 7589.772306830804, 7671.3862890967475, 7679.654415551426, 7691.807704807425, 7941.801454963672, 8322.126176751353, 8346.791330216793, 8352.958235208785, 8433.241008309282, 7480.4015678746655, 7557.206493318123, 7590.316871481593, 7608.771736847903, 7680.884786728092, 7435.4795164337975, 7444.702211911679, 7448.068991376078, 7575.886361704689, 7577.242275772379, 6910.585364878123, 7102.18324892644, 7116.608586527584, 7141.178764442156, 7181.245969173162, 5080.390353157644, 5089.083964886356, 5146.614003949644, 5146.8970620588125, 5203.375594928632, 5057.8735531612, 5067.46791586878, 5169.53474187325, 5265.4997775211295, 5269.394019687632], "value": 4486.050681441194, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7023047183288, 308.42940559964063, 317.31618362428975, 319.9296154845933, 321.77636542602886, 4365.039691267916, 5043.798248070073, 5130.050940575996, 5188.948110518893, 5226.268322434862, 4292.399064131024, 4992.326035985141, 5140.742962851852, 5179.482051794828, 5209.400918894328, 3237.7247933925646, 3409.3863104641277, 3541.7874927504304, 3817.7284515713177, 4015.5783642717533, 3537.7700449470703, 3675.8345874435713, 4006.95819087038, 4033.156624579679, 4037.3540233562635, 3319.850606722703, 3475.5308033415417, 3653.4107663166606, 3688.944665830015, 3975.5030621172355, 3905.921881562353, 3923.234969181011, 3925.1953349563414, 3945.3490174121234, 3950.644442000234, 3181.4909614461576, 3237.045071703853, 3374.96625033749, 3443.707284880788, 3468.687818096358, 4570.382998295221, 5022.698638081727, 5024.196128619404, 5030.144875371196, 5089.491050894918, 4160.729552579103, 4435.578620031092, 4462.2191116554795, 4515.954840451586, 4541.636669066466, 4417.889552761175, 4663.953360466419, 5157.819476318311, 5325.227841962933, 5448.291722076105, 3457.083872773717, 3721.5906250312714, 3736.8318425670636, 3753.6434038766274, 3756.6806821420214, 4435.645148388121, 5298.894022119536, 5677.432256774331, 5696.515796157331, 5734.655920644733, 3559.327116255356, 3799.7340186187253, 3827.8468861245797, 3843.9807800960816, 3856.5950575189763, 3029.727324540802, 3771.9245615088093, 3801.3917773156163, 3906.7069969751815, 3934.9213015739524, 3972.7219094663096, 4485.708428952173, 4497.482789479252, 4521.344405061313, 4521.728696278234, 5427.728613569306, 5460.972695136499, 5483.616146869761, 5483.835484935458, 5578.804741834017, 1167.9007284380923, 1228.342836582442, 1272.7581759465672, 1278.6867217531578, 1285.9357032148541, 656.8128083496181, 749.6663984526833, 760.2891296637662, 769.4729110559285, 879.2877769007125, 4418.403515525427, 4955.231939049453, 4964.379452568429, 4971.676841005323, 4977.402711674622, 4897.608191344684, 5073.959838233153, 5148.897022059537, 5469.56243500519, 5522.364928033247, 4618.630509559227, 4994.9500504994485, 5006.74966251686, 5011.4236862761245, 5049.419316778617, 2534.3664083979006, 2629.474105178972, 2649.3244222723083, 2905.912822615325, 2941.57347184657, 5326.680399176062, 5425.0, 5751.1373294006235, 5808.361080281165, 5880.382559831241, 4941.555260026615, 5651.802186923438, 5656.822123482489, 5712.257406537124, 5726.742296596663, 5390.299261096033, 5401.163604374453, 5618.297712785902, 5844.129878438859, 5896.616719913192, 4800.639952003684, 4903.141950158779, 5348.422215446375, 5585.776568937197, 5590.7763689453595, 5363.704996225245, 5427.782888684408, 5460.6689919150085, 5510.8071217507195, 5518.779248830002, 4920.876978075434, 5002.549770520525, 5092.490750924878, 5528.640638358494, 5845.13588341718, 3067.266994148299, 3372.6458721833856, 3519.046928954455, 3774.981125094357, 3792.374258247469, 4500.572445617661, 4780.995990841962, 4990.6506544541535, 5599.132134519156, 5615.971920140537, 4355.216061109031, 4723.6773703363115, 5479.123460183277, 5499.560035197094, 5534.889302213853, 5089.694618322913, 5335.866603334947, 5389.757460914347, 5592.580556458363, 5636.351819540805, 4924.532169443897, 5311.521963023347, 5342.572594192377, 5392.325959255093, 5506.1190209566275, 5000.824311699772, 5370.919436208538, 5373.925214957102, 5563.860903477445, 5601.943980560142, 5107.2594473800345, 5440.945590544202, 5470.507654311131, 5475.260839786578, 5488.945110549003], "value": 3928.681775172799, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.7139444102904, 789.2856964447177, 791.4262159934046, 821.2854816309807, 874.2044739287248, 14737.778933316009, 15229.61925951849, 15237.923810380933, 15238.390464381411, 15241.085534867902, 13774.173549587033, 13883.652908677263, 13895.791563126535, 13906.0, 13927.582172534814, 862.9525376104327, 891.6255172827396, 895.8835351404308, 896.8027034052498, 896.9641214351418, 713.914330280367, 739.0023468317755, 740.1044735869577, 740.6815069520104, 894.2130924786212, 766.769969009295, 908.4912449028532, 947.0813311088268, 947.5593848860278, 948.8576713492962, 933.5612262236738, 936.3866667332908, 936.5644975086549, 937.7983733497263, 939.7180845746317, 546.6228302471283, 694.9270326615733, 696.8118607975869, 697.0172057399043, 698.0297386632585, 13900.860991390058, 14375.137491750424, 14446.205458699767, 14771.0, 14810.185439800807, 13854.861451385457, 14156.0, 14158.716825663532, 14288.499902503363, 14306.785398219035, 11450.370229637367, 11502.309861408343, 11511.251768635011, 11553.075753939665, 11842.348670823101, 3805.8667946622013, 3813.1992281620614, 3819.2170605025904, 3844.730868839182, 3962.1481381502826, 78172.43655126923, 81167.0, 81212.59393703053, 81264.18735812625, 81507.59246203791, 10865.239433239756, 10867.510962006729, 11029.172812038969, 11101.888981110247, 11516.0, 21940.561188776133, 21949.451263718536, 21960.34118976433, 21981.900904955008, 21987.0, 7907.841843163104, 7923.564203968837, 7969.481983671043, 8041.276285134366, 8148.429609927367, 4363.825446982149, 4423.380726698265, 4457.9331310030375, 4529.297958816389, 4629.675922685447, 3347.41420251455, 3444.380011597886, 3449.0515108344903, 3449.292894956528, 3459.4810778383444, 614.088079202384, 630.0706457974543, 633.9333177665318, 639.6513899924553, 649.243631169682, 622.2501885228289, 673.4841646911777, 701.0675801184515, 703.4864548879347, 713.3722324354535, 8070.757877263692, 9113.453192808453, 9532.714018579454, 9549.713508594754, 9574.760630984141, 586.6274915428734, 671.9059331693567, 677.2947816615448, 698.9965050174717, 701.5930760159198, 3659.5608526976935, 4267.381229721673, 4457.888552786141, 4467.039586488855, 4569.131864945716, 91063.54468227616, 91933.0, 93433.0, 94555.0, 94783.0, 77151.0, 80166.79749803757, 81309.59345203236, 82950.58524707338, 83786.5810670955, 71740.2825971744, 74598.62700686461, 75046.62476687582, 75504.62247688726, 75718.62140689262, 9400.529973501434, 9636.855447168158, 9673.467959262309, 9692.612295508101, 9732.221422286342, 43197.568024320615, 43702.56297436985, 44696.0, 48407.757961209965, 49649.0, 52450.16424425127, 54917.450825491236, 54974.72512637411, 63527.0, 66380.0, 12490.500379984698, 12516.499340026297, 12734.554290599785, 12788.296643684687, 13160.670983225496, 13494.662633434242, 14174.14955102697, 14244.643883902985, 14470.493532726305, 14809.85190148128, 13267.734645307233, 13313.400896959854, 13318.468376136869, 13413.401450985415, 13542.119762215425, 13140.60578182656, 13163.144395614254, 13241.470341186245, 13336.133151345131, 13399.263040532474, 12888.033397495414, 12917.547885823951, 13069.477220911058, 13172.73654526885, 13409.463621455032, 11063.612773552886, 11120.499577518869, 11351.432428378714, 11513.078953683853, 11569.6529104127, 10793.29843560166, 10889.292196007234, 11054.557817687202, 11196.216264861707, 11300.60447884353], "value": 7310.047917833378, "unit": "score"}], "name": "dromaeo_css", "value": 7203.4669873565335, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 110.0, 113.0, 113.0, 112.0, 113.0, 113.0, 120.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 118.0, 175.0, 119.0, 121.0, 139.0, 115.0, 151.0, 119.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 262.0, 187.0, 185.0, 185.0, 183.0, 185.0, 182.0, 182.0], "value": 192.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 107.0, 56.0, 57.0, 62.0, 59.0, 83.0, 57.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 95.0, 93.0, 72.0, 70.0, 69.0, 96.0, 71.0, 68.0, 104.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 86.0, 87.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 103.0, 130.0, 95.0, 96.0, 96.0, 95.0, 94.0, 95.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 84.0, 92.0, 82.0, 98.0, 143.0, 135.0, 86.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 59.0, 55.0, 55.0, 57.0, 55.0, 55.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 80.0, 77.0, 72.0, 69.0, 69.0, 69.0, 70.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 89.0, 95.0, 105.0, 107.0, 105.0, 122.0, 86.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 139.0, 139.0, 138.0, 140.0, 141.0, 140.0, 155.0, 140.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 166.0, 173.0, 165.0, 165.0, 166.0, 168.0, 157.0, 229.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 64.0, 65.0, 67.0, 64.0, 67.0, 66.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1475.2, "alertThreshold": 2.0}]} 14:52:27 INFO - Return code: 0 14:52:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:52:27 ERROR - # TBPL SUCCESS # 14:52:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:52:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:52:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:52:27 INFO - 2016-05-31 14:52:27.529 screenresolution[1957:18476] starting screenresolution argv=screenresolution get 14:52:27 INFO - 2016-05-31 14:52:27.531 screenresolution[1957:18476] Display 0: 1600x1200x32@60 14:52:27 INFO - 2016-05-31 14:52:27.537 screenresolution[1958:18479] starting screenresolution argv=screenresolution list 14:52:27 INFO - Available Modes on Display 0 14:52:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:52:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:52:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:52:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:52:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:52:27 INFO - Intel Iris: 14:52:27 INFO - Chipset Model: Intel Iris 14:52:27 INFO - Type: GPU 14:52:27 INFO - Bus: Built-In 14:52:27 INFO - VRAM (Dynamic, Max): 1536 MB 14:52:27 INFO - Vendor: Intel (0x8086) 14:52:27 INFO - Device ID: 0x0a2e 14:52:27 INFO - Revision ID: 0x0009 14:52:27 INFO - Displays: 14:52:27 INFO - Display: 14:52:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:52:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:52:27 INFO - Display Serial Number: 1600x1200 60 14:52:27 INFO - Main Display: Yes 14:52:27 INFO - Mirror: Off 14:52:27 INFO - Online: Yes 14:52:27 INFO - Rotation: Supported 14:52:27 INFO - Return code: 0 14:52:27 INFO - Running post-action listener: _resource_record_post_action 14:52:27 INFO - ##### 14:52:27 INFO - ##### Finished run-tests step (success) 14:52:27 INFO - ##### 14:52:27 INFO - Running post-run listener: _resource_record_post_run 14:52:27 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 946176; Write bytes: 696092672; Read time: 11; Write time: 2291 14:52:27 INFO - TinderboxPrint: CPU usage
53.6% 14:52:27 INFO - TinderboxPrint: I/O read bytes / time
946,176 / 11 14:52:27 INFO - TinderboxPrint: I/O write bytes / time
696,092,672 / 2,291 14:52:27 INFO - TinderboxPrint: CPU idle
2,252.2 (46.4%) 14:52:27 INFO - TinderboxPrint: CPU system
964.8 (19.9%) 14:52:27 INFO - TinderboxPrint: CPU user
1,636.7 (33.7%) 14:52:27 INFO - TinderboxPrint: Swap in / out
399,851,520 / 0 14:52:27 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 400095744; Write bytes: 401081856; Read time: 30497; Write time: 744 14:52:27 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 946176; Write bytes: 294162944; Read time: 11; Write time: 1544 14:52:28 INFO - Running post-run listener: _upload_blobber_files 14:52:28 INFO - Blob upload gear active. 14:52:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:52:28 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:52:28 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:52:28 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:52:28 INFO - (blobuploader) - INFO - Open directory for files ... 14:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:52:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:52:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:52:29 INFO - (blobuploader) - INFO - Done attempting. 14:52:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:52:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:52:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:52:30 INFO - (blobuploader) - INFO - Done attempting. 14:52:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:52:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:52:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:52:33 INFO - (blobuploader) - INFO - Done attempting. 14:52:33 INFO - (blobuploader) - INFO - Iteration through files over. 14:52:33 INFO - Return code: 0 14:52:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:52:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:52:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9e90503f06b216dfbad5b71e206c257e3b462f28abc1a79bd4068487a825f3e7244edcf7d9c9b25cf5cd60c731a0afdde302e4eac522d9897b8d13a0130ad26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a692a1dcab7f6df222e6dff77fc6ae696fcfee88759d95610f7d60a5eb84f9622f6847b2de3a0307a3859a045ad68c76d47028d76ea4fc134611f56fec5ad04f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ac82fcfc18804cc5cbba28063f36f1e384a37a6329ec146cee3e0dcbc6256018f9c4294b6b89b3451e8c2e5595dde82f9cb48f1bfe549b6094a9692936f4007e"} 14:52:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:52:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:52:33 INFO - Contents: 14:52:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9e90503f06b216dfbad5b71e206c257e3b462f28abc1a79bd4068487a825f3e7244edcf7d9c9b25cf5cd60c731a0afdde302e4eac522d9897b8d13a0130ad26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a692a1dcab7f6df222e6dff77fc6ae696fcfee88759d95610f7d60a5eb84f9622f6847b2de3a0307a3859a045ad68c76d47028d76ea4fc134611f56fec5ad04f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ac82fcfc18804cc5cbba28063f36f1e384a37a6329ec146cee3e0dcbc6256018f9c4294b6b89b3451e8c2e5595dde82f9cb48f1bfe549b6094a9692936f4007e"} 14:52:33 INFO - Running post-run listener: copy_logs_to_upload_dir 14:52:33 INFO - Copying logs to upload dir... 14:52:33 INFO - mkdir: /builds/slave/test/build/upload/logs 14:52:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.007136 ========= master_lag: 2.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-31 14:52:35.574408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:52:35.577510) ========= 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.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/a9e90503f06b216dfbad5b71e206c257e3b462f28abc1a79bd4068487a825f3e7244edcf7d9c9b25cf5cd60c731a0afdde302e4eac522d9897b8d13a0130ad26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a692a1dcab7f6df222e6dff77fc6ae696fcfee88759d95610f7d60a5eb84f9622f6847b2de3a0307a3859a045ad68c76d47028d76ea4fc134611f56fec5ad04f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ac82fcfc18804cc5cbba28063f36f1e384a37a6329ec146cee3e0dcbc6256018f9c4294b6b89b3451e8c2e5595dde82f9cb48f1bfe549b6094a9692936f4007e"} build_url:https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/ONSKr1uVRT-7-V_mAEEQHQ/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/a9e90503f06b216dfbad5b71e206c257e3b462f28abc1a79bd4068487a825f3e7244edcf7d9c9b25cf5cd60c731a0afdde302e4eac522d9897b8d13a0130ad26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a692a1dcab7f6df222e6dff77fc6ae696fcfee88759d95610f7d60a5eb84f9622f6847b2de3a0307a3859a045ad68c76d47028d76ea4fc134611f56fec5ad04f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ac82fcfc18804cc5cbba28063f36f1e384a37a6329ec146cee3e0dcbc6256018f9c4294b6b89b3451e8c2e5595dde82f9cb48f1bfe549b6094a9692936f4007e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:52:35.613277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:52:35.613606) ========= 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.9f7BxOF9Hn/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.geMf89JION/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005077 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:52:35.661967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:52:35.662298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:52:35.662651) ========= ========= Total master_lag: 2.42 =========