builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0302 starttime: 1463376309.32 results: success (0) buildid: 20160515204333 builduid: d37ac373503e492d851f410c6d1a2cff revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.318805) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.319218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.319485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.374695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.375025) ========= 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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-15 22:25:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-05-15 22:25:09 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.530746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.923162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.923467) ========= 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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.025168 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:09.971175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-15 22:25:09.971494) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --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 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-15 22:25:10,051 truncating revision to first 12 chars 2016-05-15 22:25:10,051 Setting DEBUG logging. 2016-05-15 22:25:10,052 attempt 1/10 2016-05-15 22:25:10,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/668a9155cc8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-15 22:25:12,079 unpacking tar archive at: fx-team-668a9155cc8b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.278828 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-15 22:25:12.267950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:12.268247) ========= script_repo_revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:12.268631) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:12.268904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 22:25:12.283335) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-15 22:25:12.283623) ========= /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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:12 INFO - MultiFileLogger online at 20160515 22:25:12 in /builds/slave/test 22:25:12 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 22:25:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:25:12 INFO - {'append_to_log': False, 22:25:12 INFO - 'base_work_dir': '/builds/slave/test', 22:25:12 INFO - 'blob_upload_branch': 'Fx-Team', 22:25:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:25:12 INFO - 'branch': 'Fx-Team', 22:25:12 INFO - 'buildbot_json_path': 'buildprops.json', 22:25:12 INFO - 'config_files': ('talos/mac_config.py',), 22:25:12 INFO - 'default_actions': ('clobber', 22:25:12 INFO - 'read-buildbot-config', 22:25:12 INFO - 'download-and-extract', 22:25:12 INFO - 'populate-webroot', 22:25:12 INFO - 'create-virtualenv', 22:25:12 INFO - 'install', 22:25:12 INFO - 'run-tests'), 22:25:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:25:12 INFO - 'download_minidump_stackwalk': True, 22:25:12 INFO - 'download_symbols': 'ondemand', 22:25:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:25:12 INFO - 'tooltool.py': '/tools/tooltool.py', 22:25:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:25:12 INFO - '/tools/misc-python/virtualenv.py')}, 22:25:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:25:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:25:12 INFO - 'installer_path': 'installer.exe', 22:25:12 INFO - 'log_level': 'info', 22:25:12 INFO - 'log_name': 'talos', 22:25:12 INFO - 'log_to_console': True, 22:25:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:25:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:25:12 INFO - 'opt_config_files': (), 22:25:12 INFO - 'pip_index': False, 22:25:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:25:12 INFO - 'cmd': ('bash', 22:25:12 INFO - '-c', 22:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:25:12 INFO - 'enabled': True, 22:25:12 INFO - 'halt_on_failure': False, 22:25:12 INFO - 'name': 'check_screen_resolution'},), 22:25:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:25:12 INFO - 'cmd': ('bash', 22:25:12 INFO - '-c', 22:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:25:12 INFO - 'enabled': True, 22:25:12 INFO - 'halt_on_failure': False, 22:25:12 INFO - 'name': 'check_screen_resolution'},), 22:25:12 INFO - 'run_cmd_checks_enabled': True, 22:25:12 INFO - 'sps_profile': False, 22:25:12 INFO - 'sps_profile_interval': 0, 22:25:12 INFO - 'suite': 'dromaeojs', 22:25:12 INFO - 'system_bits': '32', 22:25:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:25:12 INFO - 'title': 't-yosemite-r7-0302', 22:25:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:25:12 INFO - 'use_talos_json': True, 22:25:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:25:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:25:12 INFO - 'work_dir': 'build'} 22:25:12 INFO - ##### 22:25:12 INFO - ##### Running clobber step. 22:25:12 INFO - ##### 22:25:12 INFO - Running pre-action listener: _resource_record_pre_action 22:25:12 INFO - Running main action method: clobber 22:25:12 INFO - rmtree: /builds/slave/test/build 22:25:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:25:14 INFO - Running post-action listener: _resource_record_post_action 22:25:14 INFO - ##### 22:25:14 INFO - ##### Finished clobber step (success) 22:25:14 INFO - ##### 22:25:14 INFO - ##### 22:25:14 INFO - ##### Running read-buildbot-config step. 22:25:14 INFO - ##### 22:25:14 INFO - Running pre-action listener: _resource_record_pre_action 22:25:14 INFO - Running main action method: read_buildbot_config 22:25:14 INFO - Using buildbot properties: 22:25:14 INFO - { 22:25:14 INFO - "project": "", 22:25:14 INFO - "product": "firefox", 22:25:14 INFO - "script_repo_revision": "production", 22:25:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:25:14 INFO - "repository": "", 22:25:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:25:14 INFO - "buildid": "20160515204333", 22:25:14 INFO - "pgo_build": "False", 22:25:14 INFO - "basedir": "/builds/slave/test", 22:25:14 INFO - "buildnumber": 703, 22:25:14 INFO - "slavename": "t-yosemite-r7-0302", 22:25:14 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:25:14 INFO - "platform": "yosemite_r7", 22:25:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:25:14 INFO - "branch": "fx-team", 22:25:14 INFO - "repo_path": "integration/fx-team", 22:25:14 INFO - "revision": "668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1", 22:25:14 INFO - "stage_platform": "macosx64", 22:25:14 INFO - "builduid": "d37ac373503e492d851f410c6d1a2cff", 22:25:14 INFO - "slavebuilddir": "test" 22:25:14 INFO - } 22:25:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:25:14 INFO - Running post-action listener: _resource_record_post_action 22:25:14 INFO - ##### 22:25:14 INFO - ##### Finished read-buildbot-config step (success) 22:25:14 INFO - ##### 22:25:14 INFO - ##### 22:25:14 INFO - ##### Running download-and-extract step. 22:25:14 INFO - ##### 22:25:14 INFO - Running pre-action listener: _resource_record_pre_action 22:25:14 INFO - Running main action method: download_and_extract 22:25:14 INFO - mkdir: /builds/slave/test/build/tests 22:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:14 INFO - https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:25:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:15 INFO - Downloaded 1199 bytes. 22:25:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:15 INFO - Using the following test package requirements: 22:25:15 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:25:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:25:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:25:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'jsshell-mac.zip'], 22:25:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:25:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:25:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:25:15 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:25:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:25:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:15 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:25:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:15 INFO - https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:25:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:17 INFO - Downloaded 18267720 bytes. 22:25:17 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'] 22:25:17 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 22:25:20 INFO - Return code: 0 22:25:20 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 22:25: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')]} 22:25:20 INFO - https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:25:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:24 INFO - Downloaded 18267720 bytes. 22:25:24 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'] 22:25:24 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 22:25:27 INFO - Return code: 0 22:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:27 INFO - https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:25:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:25:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:25:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:29 INFO - Downloaded 10840194 bytes. 22:25:29 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'] 22:25:29 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 22:25:29 INFO - Return code: 0 22:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:29 INFO - https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:25:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:25:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I0tUUEhdSaSkdb11BcTkHg/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 22:25:33 INFO - Downloaded 110050325 bytes. 22:25:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:33 INFO - mkdir: /builds/slave/test/properties 22:25:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:25:33 INFO - Writing to file /builds/slave/test/properties/build_url 22:25:33 INFO - Contents: 22:25:33 INFO - build_url:https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:34 INFO - Running post-action listener: _resource_record_post_action 22:25:34 INFO - Running post-action listener: set_extra_try_arguments 22:25:34 INFO - ##### 22:25:34 INFO - ##### Finished download-and-extract step (success) 22:25:34 INFO - ##### 22:25:34 INFO - ##### 22:25:34 INFO - ##### Running populate-webroot step. 22:25:34 INFO - ##### 22:25:34 INFO - Running pre-action listener: _resource_record_pre_action 22:25:34 INFO - Running main action method: populate_webroot 22:25:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:25:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:25:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:25:34 INFO - '--tppagecycles', 22:25:34 INFO - '7'], 22:25:34 INFO - 'tests': ['tsvgm']}}, 22:25:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:25:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:25:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:25:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:25:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:25:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:25:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['damp', 'tps']}, 22:25:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'tests': ['damp', 'tps']}, 22:25:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['dromaeo_dom']}, 22:25:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:25:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['a11yr', 22:25:34 INFO - 'ts_paint', 22:25:34 INFO - 'tpaint', 22:25:34 INFO - 'sessionrestore', 22:25:34 INFO - 'sessionrestore_no_auto_restore', 22:25:34 INFO - 'tabpaint']}, 22:25:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['tsvgx', 22:25:34 INFO - 'tsvgr_opacity', 22:25:34 INFO - 'tart', 22:25:34 INFO - 'tscrollx', 22:25:34 INFO - 'cart']}, 22:25:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:25:34 INFO - 'tsvgr_opacity', 22:25:34 INFO - 'tart', 22:25:34 INFO - 'tscrollx', 22:25:34 INFO - 'cart']}, 22:25:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'talos_options': ['--disable-e10s'], 22:25:34 INFO - 'tests': ['tp5o']}, 22:25:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'tests': ['tp5o']}, 22:25:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'talos_options': ['--disable-e10s', 22:25:34 INFO - '--xperf_path', 22:25:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:25:34 INFO - 'tests': ['tp5n']}, 22:25:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:25:34 INFO - 'talos_options': ['--xperf_path', 22:25:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:25:34 INFO - 'tests': ['tp5n']}}, 22:25:34 INFO - 'talos.zip': {'path': '', 22:25:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:25:34 INFO - Running post-action listener: _resource_record_post_action 22:25:34 INFO - ##### 22:25:34 INFO - ##### Finished populate-webroot step (success) 22:25:34 INFO - ##### 22:25:34 INFO - ##### 22:25:34 INFO - ##### Running create-virtualenv step. 22:25:34 INFO - ##### 22:25:34 INFO - Running pre-action listener: _resource_record_pre_action 22:25:34 INFO - Running main action method: create_virtualenv 22:25:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:25:34 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 22:25:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:25:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:25:34 INFO - Using real prefix '/tools/python27' 22:25:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:25:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:25:38 INFO - Installing pip.................done. 22:25:38 INFO - Return code: 0 22:25:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:38 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:38 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 22:25:38 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 22:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:38 INFO - 'HOME': '/Users/cltbld', 22:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:38 INFO - 'LOGNAME': 'cltbld', 22:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:38 INFO - 'MOZ_NO_REMOTE': '1', 22:25:38 INFO - 'NO_EM_RESTART': '1', 22:25:38 INFO - 'PAGER': '/bin/cat', 22:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:38 INFO - 'PWD': '/builds/slave/test', 22:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:38 INFO - 'SHELL': '/bin/bash', 22:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:38 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:38 INFO - 'USER': 'cltbld', 22:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:38 INFO - 'XPC_FLAGS': '0x0', 22:25:38 INFO - 'XPC_SERVICE_NAME': '0', 22:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:38 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 22:25:38 INFO - Cleaning up... 22:25:38 INFO - Return code: 0 22:25:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:38 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:38 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 22:25:38 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 22:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:38 INFO - 'HOME': '/Users/cltbld', 22:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:38 INFO - 'LOGNAME': 'cltbld', 22:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:38 INFO - 'MOZ_NO_REMOTE': '1', 22:25:38 INFO - 'NO_EM_RESTART': '1', 22:25:38 INFO - 'PAGER': '/bin/cat', 22:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:38 INFO - 'PWD': '/builds/slave/test', 22:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:38 INFO - 'SHELL': '/bin/bash', 22:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:38 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:38 INFO - 'USER': 'cltbld', 22:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:38 INFO - 'XPC_FLAGS': '0x0', 22:25:38 INFO - 'XPC_SERVICE_NAME': '0', 22:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:38 INFO - Downloading/unpacking psutil>=3.1.1 22:25:38 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 22:25:38 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 22:25:38 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 22:25:38 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 22:25:38 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 22:25:38 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 22:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:25:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:25:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:25:41 INFO - Installing collected packages: psutil 22:25:41 INFO - Running setup.py install for psutil 22:25:41 INFO - building 'psutil._psutil_osx' extension 22:25:41 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 22:25:42 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 22:25:42 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 22:25:42 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 22:25:42 INFO - building 'psutil._psutil_posix' extension 22:25:42 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 22:25:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:25:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:25:42 INFO - ^ 22:25:42 INFO - 1 warning generated. 22:25:42 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 22:25:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:25:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:25:42 INFO - Successfully installed psutil 22:25:42 INFO - Cleaning up... 22:25:42 INFO - Return code: 0 22:25:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:25: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')]} 22:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25: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')]} 22:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:25: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 mozsystemmonitor==0.3 22:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:42 INFO - 'HOME': '/Users/cltbld', 22:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:42 INFO - 'LOGNAME': 'cltbld', 22:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:42 INFO - 'MOZ_NO_REMOTE': '1', 22:25:42 INFO - 'NO_EM_RESTART': '1', 22:25:42 INFO - 'PAGER': '/bin/cat', 22:25:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:42 INFO - 'PWD': '/builds/slave/test', 22:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:42 INFO - 'SHELL': '/bin/bash', 22:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:42 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:42 INFO - 'USER': 'cltbld', 22:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:42 INFO - 'XPC_FLAGS': '0x0', 22:25:42 INFO - 'XPC_SERVICE_NAME': '0', 22:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:25: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 22:25: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 22:25: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 22:25: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 22:25: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 22:25: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 22:25:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:25:45 INFO - Installing collected packages: mozsystemmonitor 22:25:45 INFO - Running setup.py install for mozsystemmonitor 22:25:45 INFO - Successfully installed mozsystemmonitor 22:25:45 INFO - Cleaning up... 22:25:45 INFO - Return code: 0 22:25:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:25:45 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')]} 22:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:45 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')]} 22:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:45 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:45 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 22:25:45 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 22:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:45 INFO - 'HOME': '/Users/cltbld', 22:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:45 INFO - 'LOGNAME': 'cltbld', 22:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:45 INFO - 'MOZ_NO_REMOTE': '1', 22:25:45 INFO - 'NO_EM_RESTART': '1', 22:25:45 INFO - 'PAGER': '/bin/cat', 22:25:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:45 INFO - 'PWD': '/builds/slave/test', 22:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:45 INFO - 'SHELL': '/bin/bash', 22:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:45 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:45 INFO - 'USER': 'cltbld', 22:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:45 INFO - 'XPC_FLAGS': '0x0', 22:25:45 INFO - 'XPC_SERVICE_NAME': '0', 22:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:25:46 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 22:25:46 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 22:25:46 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 22:25:46 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 22:25:46 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 22:25:46 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 22:25:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:25:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:25:48 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 22:25:48 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 22:25:48 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 22:25:48 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 22:25:48 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 22:25:48 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 22:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:25:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:49 INFO - Downloading docopt-0.6.1.tar.gz 22:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:25:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:25:49 INFO - Running setup.py install for blobuploader 22:25:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:25:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:25:49 INFO - Running setup.py install for requests 22:25:50 INFO - Running setup.py install for docopt 22:25:50 INFO - Successfully installed blobuploader requests docopt 22:25:50 INFO - Cleaning up... 22:25:50 INFO - Return code: 0 22:25:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:25: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')]} 22:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25: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')]} 22:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:50 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:50 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 22:25:50 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 22:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:50 INFO - 'HOME': '/Users/cltbld', 22:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:50 INFO - 'LOGNAME': 'cltbld', 22:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:50 INFO - 'MOZ_NO_REMOTE': '1', 22:25:50 INFO - 'NO_EM_RESTART': '1', 22:25:50 INFO - 'PAGER': '/bin/cat', 22:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:50 INFO - 'PWD': '/builds/slave/test', 22:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:50 INFO - 'SHELL': '/bin/bash', 22:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:50 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:50 INFO - 'USER': 'cltbld', 22:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:50 INFO - 'XPC_FLAGS': '0x0', 22:25:50 INFO - 'XPC_SERVICE_NAME': '0', 22:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:25:50 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-bPJs1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-9zVDWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-a2_LFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-fEUM7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-YDRceg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-HMnFAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-2ddJna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-G5LZA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-CbpRfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-qZIN6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:25:51 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-bXU7DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-O5ZHbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-fMZtn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-gzRBfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-qhiL2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-y2T4QM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:25:52 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-LyKvG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:25:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:25:52 INFO - Running setup.py install for manifestparser 22:25:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:25:52 INFO - Running setup.py install for mozcrash 22:25:52 INFO - Running setup.py install for mozdebug 22:25:53 INFO - Running setup.py install for mozdevice 22:25:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Running setup.py install for mozfile 22:25:53 INFO - Running setup.py install for mozhttpd 22:25:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Running setup.py install for mozinfo 22:25:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Running setup.py install for mozInstall 22:25:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:25:53 INFO - Running setup.py install for mozleak 22:25:53 INFO - Running setup.py install for mozlog 22:25:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Running setup.py install for moznetwork 22:25:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Running setup.py install for mozprocess 22:25:54 INFO - Running setup.py install for mozprofile 22:25:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Running setup.py install for mozrunner 22:25:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:25:54 INFO - Running setup.py install for mozscreenshot 22:25:54 INFO - Running setup.py install for moztest 22:25:55 INFO - Running setup.py install for mozversion 22:25:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:25:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:25:55 INFO - Cleaning up... 22:25:55 INFO - Return code: 0 22:25:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:25: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')]} 22:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25: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')]} 22:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:55 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:25:55 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 22:25:55 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 22:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:55 INFO - 'HOME': '/Users/cltbld', 22:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:55 INFO - 'LOGNAME': 'cltbld', 22:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:55 INFO - 'MOZ_NO_REMOTE': '1', 22:25:55 INFO - 'NO_EM_RESTART': '1', 22:25:55 INFO - 'PAGER': '/bin/cat', 22:25:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:55 INFO - 'PWD': '/builds/slave/test', 22:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:55 INFO - 'SHELL': '/bin/bash', 22:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:25:55 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:55 INFO - 'USER': 'cltbld', 22:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:55 INFO - 'XPC_FLAGS': '0x0', 22:25:55 INFO - 'XPC_SERVICE_NAME': '0', 22:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:25:55 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-a1nYWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:25:55 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)) 22:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:25:55 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-fZ9sYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:25:55 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)) 22:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:25:55 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Wc5vQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:25:55 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)) 22:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:25:55 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-iFdabS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:25:55 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)) 22:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:25:55 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-hfxxxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-97wW_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-VCiFm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-wAntaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-WyXi5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-zPBZQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-PPYlcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-kTnaap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-t5gO04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:25:56 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)) 22:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:25:56 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-V9LCRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:25:57 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)) 22:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:57 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-lNNYvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:57 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)) 22:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:25:57 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-xk5e5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:25:57 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)) 22:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:25:57 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-WHGqVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:25:57 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)) 22:25:57 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)) 22:25:57 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)) 22:25:57 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)) 22:25:57 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)) 22:25:57 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)) 22:25:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:25:57 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 22:25:57 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 22:25:57 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 22:25:57 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 22:25:57 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 22:25:57 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 22:25:59 INFO - Downloading blessings-1.6.tar.gz 22:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:25:59 INFO - Installing collected packages: blessings 22:25:59 INFO - Running setup.py install for blessings 22:26:00 INFO - Successfully installed blessings 22:26:00 INFO - Cleaning up... 22:26:00 INFO - Return code: 0 22:26:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:26:00 INFO - Reading from file tmpfile_stdout 22:26:00 INFO - Current package versions: 22:26:00 INFO - blessings == 1.6 22:26:00 INFO - blobuploader == 1.2.4 22:26:00 INFO - docopt == 0.6.1 22:26:00 INFO - manifestparser == 1.1 22:26:00 INFO - mozInstall == 1.12 22:26:00 INFO - mozcrash == 0.17 22:26:00 INFO - mozdebug == 0.1 22:26:00 INFO - mozdevice == 0.48 22:26:00 INFO - mozfile == 1.2 22:26:00 INFO - mozhttpd == 0.7 22:26:00 INFO - mozinfo == 0.9 22:26:00 INFO - mozleak == 0.1 22:26:00 INFO - mozlog == 3.2 22:26:00 INFO - moznetwork == 0.27 22:26:00 INFO - mozprocess == 0.22 22:26:00 INFO - mozprofile == 0.28 22:26:00 INFO - mozrunner == 6.11 22:26:00 INFO - mozscreenshot == 0.1 22:26:00 INFO - mozsystemmonitor == 0.3 22:26:00 INFO - moztest == 0.7 22:26:00 INFO - mozversion == 1.4 22:26:00 INFO - psutil == 3.1.1 22:26:00 INFO - requests == 1.2.3 22:26:00 INFO - wsgiref == 0.1.2 22:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:26: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')]} 22:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26: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')]} 22:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:00 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:26:00 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 22:26:00 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 22:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:00 INFO - 'HOME': '/Users/cltbld', 22:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:00 INFO - 'LOGNAME': 'cltbld', 22:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:00 INFO - 'MOZ_NO_REMOTE': '1', 22:26:00 INFO - 'NO_EM_RESTART': '1', 22:26:00 INFO - 'PAGER': '/bin/cat', 22:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:00 INFO - 'PWD': '/builds/slave/test', 22:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:00 INFO - 'SHELL': '/bin/bash', 22:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:26:00 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:00 INFO - 'USER': 'cltbld', 22:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:00 INFO - 'XPC_FLAGS': '0x0', 22:26:00 INFO - 'XPC_SERVICE_NAME': '0', 22:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 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)) 22:26:00 INFO - Cleaning up... 22:26:00 INFO - Return code: 0 22:26:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:26: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')]} 22:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26: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')]} 22:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:00 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 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:26:00 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 22:26:00 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 22:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:00 INFO - 'HOME': '/Users/cltbld', 22:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:00 INFO - 'LOGNAME': 'cltbld', 22:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:00 INFO - 'MOZ_NO_REMOTE': '1', 22:26:00 INFO - 'NO_EM_RESTART': '1', 22:26:00 INFO - 'PAGER': '/bin/cat', 22:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:00 INFO - 'PWD': '/builds/slave/test', 22:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:00 INFO - 'SHELL': '/bin/bash', 22:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:26:00 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:00 INFO - 'USER': 'cltbld', 22:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:00 INFO - 'XPC_FLAGS': '0x0', 22:26:00 INFO - 'XPC_SERVICE_NAME': '0', 22:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:00 INFO - Downloading/unpacking jsonschema 22:26:00 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 22:26:00 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 22:26:00 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 22:26:00 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 22:26:00 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 22:26:00 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 22:26:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:26:03 INFO - Downloading/unpacking functools32 (from jsonschema) 22:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:26:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:26:03 INFO - warning: no files found matching '*.txt' 22:26:03 INFO - no previously-included directories found matching 'build' 22:26:03 INFO - no previously-included directories found matching 'dist' 22:26:03 INFO - no previously-included directories found matching '.git*' 22:26:03 INFO - Installing collected packages: jsonschema, functools32 22:26:04 INFO - Running setup.py install for functools32 22:26:04 INFO - warning: no files found matching '*.txt' 22:26:04 INFO - no previously-included directories found matching 'build' 22:26:04 INFO - no previously-included directories found matching 'dist' 22:26:04 INFO - no previously-included directories found matching '.git*' 22:26:04 INFO - Successfully installed jsonschema functools32 22:26:04 INFO - Cleaning up... 22:26:04 INFO - Return code: 0 22:26:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:26:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9b0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95004ab680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, '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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 22:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:04 INFO - 'HOME': '/Users/cltbld', 22:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:04 INFO - 'LOGNAME': 'cltbld', 22:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:04 INFO - 'MOZ_NO_REMOTE': '1', 22:26:04 INFO - 'NO_EM_RESTART': '1', 22:26:04 INFO - 'PAGER': '/bin/cat', 22:26:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:04 INFO - 'PWD': '/builds/slave/test', 22:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:04 INFO - 'SHELL': '/bin/bash', 22:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:26:04 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:04 INFO - 'USER': 'cltbld', 22:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:04 INFO - 'XPC_FLAGS': '0x0', 22:26:04 INFO - 'XPC_SERVICE_NAME': '0', 22:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:04 INFO - Downloading/unpacking flake8 22:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:26:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:26:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:26:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:26:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:26:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:26:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:26:08 INFO - no previously-included directories found matching 'docs/_build' 22:26:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:26:08 INFO - Running setup.py install for pep8 22:26:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:26:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:26:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:26:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:26:08 INFO - no previously-included directories found matching 'docs/_build' 22:26:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:26:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:26:08 INFO - Cleaning up... 22:26:08 INFO - Return code: 0 22:26:08 INFO - Running post-action listener: _resource_record_post_action 22:26:08 INFO - Running post-action listener: _start_resource_monitoring 22:26:08 INFO - Starting resource monitoring. 22:26:08 INFO - ##### 22:26:08 INFO - ##### Finished create-virtualenv step (success) 22:26:08 INFO - ##### 22:26:08 INFO - ##### 22:26:08 INFO - ##### Running install step. 22:26:08 INFO - ##### 22:26:08 INFO - Running pre-action listener: _resource_record_pre_action 22:26:08 INFO - Running main action method: install 22:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:26:08 INFO - Reading from file tmpfile_stdout 22:26:08 INFO - Detecting whether we're running mozinstall >=1.0... 22:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:26:08 INFO - Reading from file tmpfile_stdout 22:26:08 INFO - Output received: 22:26:08 INFO - Usage: mozinstall [options] installer 22:26:08 INFO - Options: 22:26:08 INFO - -h, --help show this help message and exit 22:26:08 INFO - -d DEST, --destination=DEST 22:26:08 INFO - Directory to install application into. [default: 22:26:08 INFO - "/builds/slave/test"] 22:26:08 INFO - --app=APP Application being installed. [default: firefox] 22:26:08 INFO - mkdir: /builds/slave/test/build/application 22:26:08 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'] 22:26:08 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 22:26:41 INFO - Reading from file tmpfile_stdout 22:26:41 INFO - Output received: 22:26:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:26:41 INFO - Running post-action listener: _resource_record_post_action 22:26:41 INFO - ##### 22:26:41 INFO - ##### Finished install step (success) 22:26:41 INFO - ##### 22:26:41 INFO - ##### 22:26:41 INFO - ##### Running run-tests step. 22:26:41 INFO - ##### 22:26:41 INFO - Running pre-action listener: _resource_record_pre_action 22:26:41 INFO - Running main action method: run_tests 22:26:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:26:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:26:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:26:41 INFO - 2016-05-15 22:26:41.849 screenresolution[1671:7652] starting screenresolution argv=screenresolution get 22:26:41 INFO - 2016-05-15 22:26:41.852 screenresolution[1671:7652] Display 0: 1600x1200x32@60 22:26:41 INFO - 2016-05-15 22:26:41.857 screenresolution[1672:7654] starting screenresolution argv=screenresolution list 22:26:41 INFO - Available Modes on Display 0 22:26:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:26:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:26:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:26:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:26:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:26:42 INFO - Intel Iris: 22:26:42 INFO - Chipset Model: Intel Iris 22:26:42 INFO - Type: GPU 22:26:42 INFO - Bus: Built-In 22:26:42 INFO - VRAM (Dynamic, Max): 1536 MB 22:26:42 INFO - Vendor: Intel (0x8086) 22:26:42 INFO - Device ID: 0x0a2e 22:26:42 INFO - Revision ID: 0x0009 22:26:42 INFO - Displays: 22:26:42 INFO - Display: 22:26:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:26:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:26:42 INFO - Display Serial Number: 1600x1200 60 22:26:42 INFO - Main Display: Yes 22:26:42 INFO - Mirror: Off 22:26:42 INFO - Online: Yes 22:26:42 INFO - Rotation: Supported 22:26:42 INFO - Return code: 0 22:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:26:42 INFO - Python 2.7.3 22:26:42 INFO - Return code: 0 22:26:42 INFO - grabbing minidump binary from tooltool 22:26: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')]} 22:26:42 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 0x10c84ed30>, '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 0x10c672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c845b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:26:42 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 22:26:42 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 22:26:42 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 22:26:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:26:42 INFO - Return code: 0 22:26:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:26:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:26:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:26:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:26:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:26:43 INFO - Return code: 0 22:26:43 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/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 22:26:43 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/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0302 --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 22:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIWGVGlnWR/Render', 22:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:43 INFO - 'HOME': '/Users/cltbld', 22:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:43 INFO - 'LOGNAME': 'cltbld', 22:26:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:26:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:43 INFO - 'MOZ_NO_REMOTE': '1', 22:26:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:26:43 INFO - 'NO_EM_RESTART': '1', 22:26:43 INFO - 'PAGER': '/bin/cat', 22:26:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:43 INFO - 'PWD': '/builds/slave/test', 22:26:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:43 INFO - 'SHELL': '/bin/bash', 22:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xA1ufXGUqE/Listeners', 22:26:43 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 22:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:43 INFO - 'USER': 'cltbld', 22:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:43 INFO - 'XPC_FLAGS': '0x0', 22:26:43 INFO - 'XPC_SERVICE_NAME': '0', 22:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:43 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/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 22:26:43 INFO - mozversion application_buildid: 20160515204333 22:26:43 INFO - mozversion application_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 22:26:43 INFO - mozversion application_display_name: Nightly 22:26:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:26:43 INFO - mozversion application_name: Firefox 22:26:43 INFO - mozversion application_remotingname: firefox 22:26:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:26:43 INFO - mozversion application_vendor: Mozilla 22:26:43 INFO - mozversion application_version: 49.0a1 22:26:43 INFO - mozversion platform_buildid: 20160515204333 22:26:43 INFO - mozversion platform_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 22:26:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:26:43 INFO - mozversion platform_version: 49.0a1 22:26:43 INFO - using testdate: 1463376403 22:26:43 INFO - actual date: 1463376403 22:26:43 INFO - starting webserver on 'localhost:49277' 22:26:43 INFO - SUITE-START | Running 2 tests 22:26:43 INFO - TEST-START | dromaeo_css 22:26:43 INFO - operating with platform_type : mac_ 22:26:43 INFO - Initialising browser for dromaeo_css test... 22:26:43 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpQDzzhu/profile http://localhost:49277/getInfo.html) 22:26:45 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 22:26:45 INFO - PROCESS | 1690 | colorDepth:24 22:26:45 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 22:26:45 INFO - PROCESS | 1690 | __metrics 22:26:46 INFO - PROCESS | 1690 | 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" 22:26:46 ERROR - PROCESS | 1690 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:26:46 INFO - TEST-INFO | 1690: exit 0 22:26:46 INFO - Browser initialized. 22:26:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:26:46 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpQDzzhu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:26:58 INFO - PROCESS | 1692 | RSS: Main: 239316992 22:26:58 INFO - PROCESS | 1692 | 22:27:50 INFO - PROCESS | 1692 | 1463376470350 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] 22:30:00 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 22:30:02 INFO - PROCESS | 1692 | RSS: Main: 366292992 22:30:02 INFO - PROCESS | 1692 | 22:33:05 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 22:33:05 INFO - PROCESS | 1692 | RSS: Main: 371789824 22:33:05 INFO - PROCESS | 1692 | 22:36:08 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 22:36:08 INFO - PROCESS | 1692 | RSS: Main: 369139712 22:36:08 INFO - PROCESS | 1692 | 22:39:11 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 22:39:11 INFO - PROCESS | 1692 | RSS: Main: 361394176 22:39:11 INFO - PROCESS | 1692 | 22:42:15 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 22:42:15 INFO - PROCESS | 1692 | RSS: Main: 331800576 22:42:15 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 22:45:18 INFO - PROCESS | 1692 | RSS: Main: 373280768 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | __start_tp_report 22:45:18 INFO - PROCESS | 1692 | _x_x_mozilla_page_load 22:45:18 INFO - PROCESS | 1692 | _x_x_mozilla_page_load_details 22:45:18 INFO - PROCESS | 1692 | |i|pagename|runs| 22:45:18 INFO - PROCESS | 1692 | |0;dojo.html;3450.792952422854;3459.6367381424934;3486.790792552447;3488.7557870949045;3527.6472352764713;4598.471175814782;5654.349749778777;5686.516646085082;5738.167965644984;5760.337561180466;5684.687342196177;5684.744186511606;5711.05767548354;5768.39431859655;5779.82660520184;5079.187330027196;5085.593152547787;5161.045206636784;5169.844904652867;5272.156454967206;5235.502627250406;5243.68537887728;5287.339082614673;5309.548688361494;5336.066188417031;5073.188289873639;5106.106431374513;5111.795528178868;5170.25031370453;5171.405288391846;5144.0740666679985;5152.793888244466;5162.432132465426;5193.792248310063;5216.243644671539;5140.691558506502;5171.3535808024;5188.792448302063;5197.402298735656;5213.165893457029;5288.735563221823;5484.670919744828;5537.750801213955;5671.574631902583;5759.366469688331;5149.227615857614;6107.26712794463;6128.59561757964;6138.754449822001;6143.846403839895;5605.943940560624;6208.286047104551;6220.595661282002;6226.719797609118;6241.282252540925;4598.402207712976;5633.12686533592;5689.77240910359;5817.447342502455;5820.447057529613;5290.259363689087;6320.5891617044745;6329.936700633027;6346.174997250328;6347.39699728525;6155.599886007395;6182.814515564541;6189.535784816157;6203.565750397519;6216.285127210338;6131.957816600026;6144.262688477322;6203.96898015516;6232.407921247474;6279.0267508535335;6104.847378815565;6171;6178.505719542426;6181.196444462191;6185.443310102112;6160.322364539897;6200.038993955944;6245.125682404467;6249.281332646713;6258.186435763321;4220.345846393752;4504.414426124583;4679.619512243896;4852.4662287148385;4866.926996095061;4404.405405270311;4405.977970110193;4406.096750166208;4425;4453.732776033448;5715.428457154251;5731.656100633975;5739.512141467939;5740.655560666373;5787.045137552257;6145.617236121837;6206;6233.532485063638;6273.749050038039;6292.685365731695;5712.6286791358425;5726.742296596663;5727.7422515986045;5735.799247026334;5769.826905192851;5258.1849813279005;5374.623776335619;5387.138057910794;5387.24578559002;5389.003034438641;6253.093301471352;6304;6307.369263073656;6307.842303942254;6317.115603815378;6272.184616000073;6277.278113016879;6287.056941458818;6301.527385446201;6302.558820882494;6261.279952805486;6263.937360626518;6296.087067375113;6298.307186209605;6321.810345689637;6122.062734707273;6180.6909654517995;6185.567010309235;6194.845128871634;6246.9062964054665;6292.779752708633;6308.053791931247;6311.747530098744;6315.621062736067;6324.272708638381;4652.953470465252;5949.762009519571;6120.296165940974;6141.6007959482495;6145.139680444652;5863.941360586339;6052.636841789506;6064.332923378513;6083.6045657032155;6105.297890742621;5944.64332140073;5948.583599148018;5949.0481522956115;5959.940400595939;6030.276366836008;5961.940380596138;6038.849028774316;6072.834254363149;6110.175126358063;6158.261008678987;5942.227510423791;5973.940260597338;5979.521638268841;5990.4908082813445;6023.759049637965 22:45:18 INFO - PROCESS | 1692 | |1;ext.html;507.0847120946691;525.684589246452;529.2167591963895;537.8601563593467;543.22861536618;30897.845510772444;41665.166696666085;42832.357514637224;42964;42964.57035429653;45117;45377;45382.638520844346;45397.54602453975;45492;8616.483011019327;9677.709668709933;9861.359011664255;9879.407235565854;9928.255380846429;8738;11926.7614647707;12114.030877529776;12126.878731212662;12136.393180340992;11882.702932426715;11884.46519906602;11927.986121179667;11943;11954.940225298862;8607.956960215222;11723.531058757639;11828.645140645795;11882.762344753142;11884.227525210878;11376.431178441127;11717.53129874804;11731.706707332385;11738.237014594024;11739;40589.955753318616;40984;41024.38463423051;41055.589444105775;41066.1786764266;3303.4549299365576;3356.580427446569;3733.3653278942643;3839.347310957116;3884.6309600587897;24471.143509977243;29033.70966290331;29633.70366296331;30421.847890760626;30705.846470767505;2740.3149212696826;2761.9171424857286;2809.6909339972585;2854.9143525694053;2860.256333353322;401375;523541;524144;527664.361678193;528061;35552.822235888656;36339;36574.268514630094;37962;62912;36148.81925590355;36284;36293.27413451716;36417.45373819395;36453.27093458169;15081.396744130112;15241.695166096615;15246.847531524765;15282.235888205767;15290.388384464719;10940.617078402218;11816.586419475278;12633.115681902178;12741.490340386466;12906.548270810477;8197.426180167451;8210.096889342167;8231.46495477792;8279.213474719892;8291.95854020726;3420.6237313895454;3440.8795692150657;3441.9311613767586;3452.602950660656;3480.4083305838067;958.5782255807422;1009.7324209084599;1018.6332920148741;1057.751428414322;1060.0088916862603;29956.850215748782;30035;30725.846370768002;30831.383372332428;30885.845570772002;1071.314358810362;1086.8804431512526;1133.28602980122;1152.8270759385991;1211.5880600595663;15505.689886202439;15517.767233491506;15545.533633990999;15942.36230550765;15981.920090399473;611967;620627;623383;623974;625578;61920.69039654863;62601.68699156475;62629.373706263264;62633.68683156555;62744.686276568325;1341.677997280665;1384.4946594492937;1462.436961769705;1481.6814384907298;1503.4737841755516;24483.265502034967;29682.962845505124;31006.22484437817;31159.75360985535;31839.840800795846;51736.74131629318;51925.480745192064;52227.73886130545;52232.47767522276;52246.69383265297;516144;516559;518450;518960;523725;27207.86396068007;27239.182824515297;27728.584071238543;28330.150095497167;29410.411791763618;46751;47399.76300118477;47609;48880.51119488759;48939.75530122327;37900.62099378971;38912;39172.6082739169;39730.80807575777;40241.59758402379;37948;37999.24001520009;38168.975014365074;38791.224175516894;39594;37843.62156378401;37898.43152352662;38900.6109938897;38925;39011;28519.714802851708;33638.66361336453;33656.1585960353;34222.48666269958;34417.82791086029;24656.383590410383;25161;25172.118975835758;25789.871050644626;25867 22:45:18 INFO - PROCESS | 1692 | |2;jquery.html;398.6870306809154;402.21969379403953;407.4601153471651;409.68658975883454;425.2091110534407;18521.981291028987;19980.90009549952;20617.793822061776;21074.262400815955;21161.788382116192;18126.912385256896;20719.378418647426;21120.366389008315;21277.148914043457;21547.353579392642;5209.661372010826;6037.516998640098;6154.3537928517535;6159.168512250856;6174.135621013062;5970.8805823883495;6037.245344331958;6048.879022419549;6092.939070609282;6134.4172303631085;5827.621204621707;5965.761369545213;5990.640561566298;6008.579399442041;6013.789517366871;5788.247527821377;5930.60630751773;5992.700364981755;6134.202553668039;6161.784337548209;5964.135200395918;5965.194698715662;5970;5976.46211840932;5984.521238300926;12017.158798884082;14068.22624755638;21970.34088977333;26579.13946023779;26892.731072689414;1931.6493313521546;2051.8461115416253;2085.18677715691;2184.584928863511;2252.7521972583004;8619.353548483889;9762.658306959222;10011.948745381776;10231.488425578691;10252.233525872887;1724.5688577855535;1737.3311275159028;1756.7891852977596;1829.9085045747659;1948.824605785486;122806;127923.36038319749;127945.36027319897;128884;131738.3413082938;11619.825702614467;11734.647960561197;11774.411279436164;11804.940975295069;12007.03943684503;15369.846301537065;15405.9229703853;15420.537383878502;15423.768643470357;15451.845481545266;8771.561421928878;8829.779255518535;8832.470051796785;8849.557522123869;8973.192412682889;7843.764687059397;7847.725329613436;8613.138686131337;8641.697540586048;8755.868661970075;3849.576546579874;4696.365990591295;4720.103180395714;4758.40565606817;4769.093872164347;1721.5868191634167;1938.9709154362695;1944.1737261663736;1992.3524854422371;2029.249177804222;769.0579040675128;997.6009596161448;1017.7506510904876;1028.4307496823374;1054.5676272728144;20023.899880500503;20544.486387840425;20606.17575297001;20731.689024664644;20790.792092078886;926.972190834276;933.7525555727738;941.2611100286242;962.8266911955775;967.6613185385058;9601.807963840683;11048.337099774039;11663.125265605113;11886.465109070112;11908.511436070492;598569;606530;612654;617519;628819;64456.67771661111;86516.70224946631;123631.20028398345;126755.09867351997;130282.78868845329;973.7273563402259;1022.4427686910651;1038.5067093130854;1040.0587468341164;1040.256216804988;16476.505704828873;16962.32150714007;17051.232694529022;18205;18648.906755466134;35826;47374.999999998625;47784.80537986578;47925.76037119792;53985.460145399615;75186.6240668815;75653.62173189319;76031.61984190044;77493;78369.60815195888;7907;8043.919560804317;8173.836523269621;8215.794605134919;8574.91425085766;10636.30863993838;12081.275123492618;12714.936425317814;12736.426860791102;12875.420606072561;10248.077673009466;10706.678799636024;10734.926507349202;10881.238313318308;11213;9041;10775.94612026935;10778.884829514498;10864.45677716127;11074.169437292396;6045.667488288186;8695;11009;11200.439978000904;11233.887661123284;7169.426445884213;7578.848423031398;7631.275028872248;7966.681332746857;8485.618147183268;10606.469676516297;10667.466626668791;11361.772764544497;11685.00677442393;11717.238379505305 22:45:18 INFO - PROCESS | 1692 | |3;mootools.html;1446.9059511131782;1603.486884197057;1646.2180464279468;1885.6040231551374;1997.0613811508586;8067.838643227132;8808.471491710501;9074.682386116487;9091.318151138667;9203.53982300884;8808.955955220232;9060.546972651358;9080.954595227031;9084.27325813935;9087.863682044775;2165.8700477971292;2245.4274160089153;2246.05665620439;2443.8044956403446;2481.553320402327;2250.864948103119;2284;2414.4688168602966;2495.1391769839374;2503.6119401492797;2428.732839387666;2455.4966231922413;2460.6801115854914;2461.396957745346;2464.383904023994;2015.687568426889;2416.2147302126705;2419.2984034629935;2419.8064154867657;2424.9515009699885;2155.8490905636613;2378.9643155352683;2447.5349683560066;2457.533068716938;2467.1611652038214;8899.37704360695;9014.504202268872;9029.41308814925;9030.593623286968;9032.819343613157;5358.089124848785;5521.033819081645;5524.14375771756;5528.115501519737;5536.003519366513;3468.2543253200683;3514.8242587870504;3632.4551317302353;3633.291508155911;3637.399829028207;1024.2727663359024;1037.5123691864887;1037.7353774787457;1045.6601604478499;1052.1425038593522;12093.093018023596;12094.758104837854;12111.212771169934;12137.757244855142;12166.96580790634;4851.684640498356;4868.878278043077;4972.428170760337;4986.32684587576;4991.151504244287;10281.640142594973;10306.433146176882;10317.329373590692;10330.793384132276;10355.896441035493;5445.646033007842;5526.778928842801;5531.419200983919;5532.917006244909;5545.390007099216;4529.275315949432;4954.702717836942;4985.626078044161;4992.2761199626575;5070.112730272188;3827.3684842001317;4032.697547683935;4105.290508264531;4173.916521669549;4188.958110418917;1157.6064138193055;1280.9743805123846;1331.5738963531576;1331.9600411987656;1345.2869978911192;793.6666600028002;820.9917900820915;934.9065093490733;939.4259728344288;974.6929717139078;5818.941810581925;5919.4968427684125;6064.878702425926;6069.241344831896;6210.161628180138;814.722994181981;893.5167621747868;922.7969846633864;937.6577549194632;984.837501812193;3941.349677303213;3964.1278918637854;3991.6428414339266;4037.3338399164395;4071.2875246831686;10718.24972251935;11122;11138.94430527842;11147.498362573704;11178.21752477335;10990.340579565252;11075.224734268684;11096.500657470433;11098.5560577576;11106.333619982826;8138.064122625856;8350.582470876432;8431.325493960469;8491.108433614505;8498.957505212558;5915;5960.1357803117435;5995.190649262294;6012.128241405059;6039.305479869871;9836.06557377048;9995.650152244636;10282.845757313797;10283.023112804272;10419.114375278186;10303.742406439598;10513.684589462327;10547.367157970546;10549.997750213717;10639.308444951053;7009.1589009319205;7256.89114663291;7617.352525035434;7806.102298235548;7819.178986206366;7930.365570754441;8067.757967260992;8176.591170441573;8182.059063207807;8182.631781569725;7190.460715446257;7685.077790665156;7711.961440192763;7769.805754856174;7830.765077047698;6718.22740384862;7500.249975002456;7540.736074237376;7734.497257678233;7765.92234077652;7137.607431591105;7180.425565954815;7216.314450127229;7217.989481472703;7218.169910460154;4658.720476771269;4924.2613607957655;4963.057019166346;4981.227909674007;5013.172826483589;4970.378702662168;5008.398992120969;5034.798608055637;5091;5258.86852828667 22:45:18 INFO - PROCESS | 1692 | |4;prototype.html;310.1919499703273;311.4347459361259;318.3569190235724;319.8736499082864;323.3387722108289;4876.9756151219235;5268.894622107556;5273.6572122811995;5281.8943621127555;5304.3634763828295;5269.394019687737;5274.26160337553;5276.472352764731;5301.867453313669;5325.520703136726;3650;3794.29805485986;3815.809209539526;3892.0853599404118;4077.6533994610495;3984.6015398460213;4030.798460076999;4039.212353591051;4060.817263223162;4109.034376921421;3663.981680091596;3674.9081272968256;3953.3279342511696;3997.7001724870606;4029.7783621900785;3299.3566254580364;3576.8554062699945;3798.335291324035;3855.6722678572355;3895.8636447724193;2956.659984101834;3465.42820434628;3475.391806433864;3511.6839484446523;3516.9472457913153;4075.4498142750645;4827.710337379768;5109.744512774347;5114.28400023997;5115.130427827278;3943.861964831217;4296.312589985588;4631.235846085392;4639.257718765015;4643.582077613031;4608.470025946992;5542.307211598551;5556.888862222773;5606.290081525133;5642.548596112301;3310.93378132436;3577;3600.837962291703;3806.733528652968;3918.9027072419767;5294.464605264508;5349.689326115126;5574.60977731559;5741.483266506035;5888.764449422018;3467.4625433057918;3955.7626542407547;3960.376245739233;3964.306246406925;4030.6170913763144;3864.555576108727;4025.6376926076796;4026.7181297309494;4037.9394309085387;4048.4332193492933;4009.5164789027663;4319.373690814814;4513.774311284423;4668.89899545206;4699.765011749399;5164.664296820694;5627;5651.660900345993;5652.643365592228;5665.036943719578;1197.0064846177652;1231.5320178332177;1299.4925885972227;1352.9705441837243;1363.4205462678322;828.9419740618099;829.792651691787;867.6464715044551;875.913866805157;881.4711173295971;5071.529256515643;5073.289739436482;5215.973920130375;5245.554127899171;5248.317718696545;5285.089034114728;5711.6001879868845;5721.625525406993;5728.570357223225;5761.279840019997;3804.600516945736;4700.012997270543;5142;5144.331236939174;5228.346456692913;2452.9877350613133;2681.7184195659565;2966.7923245372617;2975.226441125323;2997.430488207234;4857.684250523705;5549.694766787866;5792.391798861143;5886.529077673776;5894.263217097863;4771.642126840431;5820.650760954356;5869.941300586939;5894.705264736746;5902.432441887707;5060.569851562658;5381.286912030686;5431.1581704835025;5640.379558248589;5859.367192340691;5034.622403319839;5415.268938693226;5674.914876276942;5710.2862142232225;5718.399568045295;5002.224655178453;5474.5894057945015;5505.889882202414;5616.326040875121;5629.324481062299;4519.909601808011;5552.69460179694;5852.834451253064;5881.588288819741;5893.557983151367;3583.9462408064423;3623.8912832615065;3773.6792372647533;3928.548216955087;3928.6071392860486;5315;5660.830175094754;5702.6293290935955;5707.743151558273;5724.881793736345;4652;4964.726940018334;4997.925031124463;5077.416097148875;5470.206820010996;5124.897502050013;5217.582593392443;5286.15421532553;5542;5568.888622227614;5234.435126787923;5338.3060202173465;5390.541803946709;5398.406175320633;5403.702796346239;4602.631789456902;4971.627127965345;5382.4886635769535;5419.701916394636;5433.870258603411;4609.036228843259;4850.951490485191;5371.274877891591;5392.245085687929;5463.562914966873 22:45:18 INFO - PROCESS | 1692 | |5;yui.html;572.8968785618588;631.7915088020952;680.1532092544779;701.7859552836389;758.4993904023346;15224.619384515392;15299.770503442443;15470.994385364958;15641.061536307829;15667.764983525256;14067.155970641768;14377.784333234984;14459.92770036151;14712.190829504372;14774.482893098744;928.5264515097282;932.5011119051309;943.7216021273741;947.4646824544145;950.4534892436842;923.0815338737965;930.9953450232773;931.2968708624978;933.995954349074;934.4440058165414;989.45579931038;996.1383403356081;996.4868092932146;997.6657819630433;998.1864780204111;982.4645568165367;983.6753871222479;984.4929861121528;985.0002247718536;986.531397586148;728.1735230513352;728.4973368375804;729.2015243308568;729.4055344893894;731.1920328037495;12176.634700958986;12455.564055258023;12495.937520312431;12526;12547.686307842285;11255.212135150543;11297.039751621121;11315.321080735182;11518;11916.523339066427;9282.953585232099;9424.387414818015;9445.66940157098;9478.715638530786;10006.499675016294;4019.839206431739;4200.411942328076;4248.808803603845;4373.343998400233;4395.912081758347;86832.56583717105;87547.56226218892;87898;87919;88488.11511884863;9128.132827381389;9340.906590934004;9436.575354109082;9442.527873606292;9567.37812042215;20451.897740511202;20569.69145462819;20607.58784824295;20616.69074963877;21240.362789116352;7273.454490913203;7371.5945622990175;7445.032145821008;7471.327580517779;7664.846703065908;4014.6788256939376;4048.3927410888014;4075.1849630074103;4156.501219853576;4400.427944367212;3584.1039740064984;3680.3375392429084;3719.497867787868;3722.62773722631;3727.8881633551036;655.1286788571189;655.4821690864259;664.6311297230084;670.9664516774142;673.6160388578561;574.6638216643227;582.3652219081138;603.562417247492;609.5328563936629;625.5902383938527;8066.475679080841;8076.152004039492;8144.796380090545;8176.6320515576945;8267.62795674196;575.9913601295984;578.3782433883592;579.6753817862011;607.259143844509;628.6448156791398;3754.3617585010065;3800.657940785343;3816.7596875295994;3850.4609354690365;4041.4139949707715;86723.56638216895;87029;94104.0589594109;94130.5293473542;94156;49639.50360496421;71004.64497677478;71528.64235678788;75004.62497687477;79693;69730;70383.99999999898;71511.64244178745;71880.28119718841;73096.63451682708;8088.393370497119;8183.6726530938095;8235.588220588827;8265.793355166168;8369.874451883104;43089;44087.45693900698;44154;44469.55530444654;46374.072518550114;47017;58517.70741146267;58757.11864321953;61580.76838463295;62656;11038.11695064409;11279.041281490838;11435.428228588704;11472.31166130035;11589.768204636028;12151.149419540547;12387.814182787446;12390.380480976095;12476.937615311865;12797.488100475877;11418;11466.598669046543;11522.423878806194;11788;12121.69695757613;11234.101271898391;11665.941670291595;11728.824067639163;11741.236819606698;11931.4034298283;11301.773964520828;11322.716932076764;11326.660200194008;11341.773164536498;11515.712107197387;9606.951965240409;9819.803603928025;9911.752206194902;9922.652707155503;10053.748656283651;9320.347575669639;9673.854892176763;9737.707868763948;9772.951135244562;9785 22:45:18 INFO - PROCESS | 1692 | __end_tp_report 22:45:18 INFO - PROCESS | 1692 | __start_cc_report 22:45:18 INFO - PROCESS | 1692 | _x_x_mozilla_cycle_collect,1381 22:45:18 INFO - PROCESS | 1692 | __end_cc_report 22:45:18 INFO - PROCESS | 1692 | __startTimestamp1463377518831__endTimestamp 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | ------- Summary: start ------- 22:45:18 INFO - PROCESS | 1692 | Number of tests: 6 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#0] dojo.html Cycles:165 Average:5695.30 Median:5948.58 stddev:659.54 (11.1%) stddev-sans-first:637.62 22:45:18 INFO - PROCESS | 1692 | Values: 3450.8 3459.6 3486.8 3488.8 3527.6 4598.5 5654.3 5686.5 5738.2 5760.3 5684.7 5684.7 5711.1 5768.4 5779.8 5079.2 5085.6 5161.0 5169.8 5272.2 5235.5 5243.7 5287.3 5309.5 5336.1 5073.2 5106.1 5111.8 5170.3 5171.4 5144.1 5152.8 5162.4 5193.8 5216.2 5140.7 5171.4 5188.8 5197.4 5213.2 5288.7 5484.7 5537.8 5671.6 5759.4 5149.2 6107.3 6128.6 6138.8 6143.8 5605.9 6208.3 6220.6 6226.7 6241.3 4598.4 5633.1 5689.8 5817.4 5820.4 5290.3 6320.6 6329.9 6346.2 6347.4 6155.6 6182.8 6189.5 6203.6 6216.3 6132.0 6144.3 6204.0 6232.4 6279.0 6104.8 6171.0 6178.5 6181.2 6185.4 6160.3 6200.0 6245.1 6249.3 6258.2 4220.3 4504.4 4679.6 4852.5 4866.9 4404.4 4406.0 4406.1 4425.0 4453.7 5715.4 5731.7 5739.5 5740.7 5787.0 6145.6 6206.0 6233.5 6273.7 6292.7 5712.6 5726.7 5727.7 5735.8 5769.8 5258.2 5374.6 5387.1 5387.2 5389.0 6253.1 6304.0 6307.4 6307.8 6317.1 6272.2 6277.3 6287.1 6301.5 6302.6 6261.3 6263.9 6296.1 6298.3 6321.8 6122.1 6180.7 6185.6 6194.8 6246.9 6292.8 6308.1 6311.7 6315.6 6324.3 4653.0 5949.8 6120.3 6141.6 6145.1 5863.9 6052.6 6064.3 6083.6 6105.3 5944.6 5948.6 5949.0 5959.9 6030.3 5961.9 6038.8 6072.8 6110.2 6158.3 5942.2 5973.9 5979.5 5990.5 6023.8 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#1] ext.html Cycles:180 Average:67238.77 Median:35850.82 stddev:147048.38 (410.2%) stddev-sans-first:147375.54 22:45:18 INFO - PROCESS | 1692 | Values: 507.1 525.7 529.2 537.9 543.2 30897.8 41665.2 42832.4 42964.0 42964.6 45117.0 45377.0 45382.6 45397.5 45492.0 8616.5 9677.7 9861.4 9879.4 9928.3 8738.0 11926.8 12114.0 12126.9 12136.4 11882.7 11884.5 11928.0 11943.0 11954.9 8608.0 11723.5 11828.6 11882.8 11884.2 11376.4 11717.5 11731.7 11738.2 11739.0 40590.0 40984.0 41024.4 41055.6 41066.2 3303.5 3356.6 3733.4 3839.3 3884.6 24471.1 29033.7 29633.7 30421.8 30705.8 2740.3 2761.9 2809.7 2854.9 2860.3 401375.0 523541.0 524144.0 527664.4 528061.0 35552.8 36339.0 36574.3 37962.0 62912.0 36148.8 36284.0 36293.3 36417.5 36453.3 15081.4 15241.7 15246.8 15282.2 15290.4 10940.6 11816.6 12633.1 12741.5 12906.5 8197.4 8210.1 8231.5 8279.2 8292.0 3420.6 3440.9 3441.9 3452.6 3480.4 958.6 1009.7 1018.6 1057.8 1060.0 29956.9 30035.0 30725.8 30831.4 30885.8 1071.3 1086.9 1133.3 1152.8 1211.6 15505.7 15517.8 15545.5 15942.4 15981.9 611967.0 620627.0 623383.0 623974.0 625578.0 61920.7 62601.7 62629.4 62633.7 62744.7 1341.7 1384.5 1462.4 1481.7 1503.5 24483.3 29683.0 31006.2 31159.8 31839.8 51736.7 51925.5 52227.7 52232.5 52246.7 516144.0 516559.0 518450.0 518960.0 523725.0 27207.9 27239.2 27728.6 28330.2 29410.4 46751.0 47399.8 47609.0 48880.5 48939.8 37900.6 38912.0 39172.6 39730.8 40241.6 37948.0 37999.2 38169.0 38791.2 39594.0 37843.6 37898.4 38900.6 38925.0 39011.0 28519.7 33638.7 33656.2 34222.5 34417.8 24656.4 25161.0 25172.1 25789.9 25867.0 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#2] jquery.html Cycles:180 Average:34683.57 Median:12111.55 stddev:101999.90 (842.2%) stddev-sans-first:102253.55 22:45:18 INFO - PROCESS | 1692 | Values: 398.7 402.2 407.5 409.7 425.2 18522.0 19980.9 20617.8 21074.3 21161.8 18126.9 20719.4 21120.4 21277.1 21547.4 5209.7 6037.5 6154.4 6159.2 6174.1 5970.9 6037.2 6048.9 6092.9 6134.4 5827.6 5965.8 5990.6 6008.6 6013.8 5788.2 5930.6 5992.7 6134.2 6161.8 5964.1 5965.2 5970.0 5976.5 5984.5 12017.2 14068.2 21970.3 26579.1 26892.7 1931.6 2051.8 2085.2 2184.6 2252.8 8619.4 9762.7 10011.9 10231.5 10252.2 1724.6 1737.3 1756.8 1829.9 1948.8 122806.0 127923.4 127945.4 128884.0 131738.3 11619.8 11734.6 11774.4 11804.9 12007.0 15369.8 15405.9 15420.5 15423.8 15451.8 8771.6 8829.8 8832.5 8849.6 8973.2 7843.8 7847.7 8613.1 8641.7 8755.9 3849.6 4696.4 4720.1 4758.4 4769.1 1721.6 1939.0 1944.2 1992.4 2029.2 769.1 997.6 1017.8 1028.4 1054.6 20023.9 20544.5 20606.2 20731.7 20790.8 927.0 933.8 941.3 962.8 967.7 9601.8 11048.3 11663.1 11886.5 11908.5 598569.0 606530.0 612654.0 617519.0 628819.0 64456.7 86516.7 123631.2 126755.1 130282.8 973.7 1022.4 1038.5 1040.1 1040.3 16476.5 16962.3 17051.2 18205.0 18648.9 35826.0 47375.0 47784.8 47925.8 53985.5 75186.6 75653.6 76031.6 77493.0 78369.6 7907.0 8043.9 8173.8 8215.8 8574.9 10636.3 12081.3 12714.9 12736.4 12875.4 10248.1 10706.7 10734.9 10881.2 11213.0 9041.0 10775.9 10778.9 10864.5 11074.2 6045.7 8695.0 11009.0 11200.4 11233.9 7169.4 7578.8 7631.3 7966.7 8485.6 10606.5 10667.5 11361.8 11685.0 11717.2 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#3] mootools.html Cycles:180 Average:5850.09 Median:4971.40 stddev:3306.20 (66.5%) stddev-sans-first:3298.91 22:45:18 INFO - PROCESS | 1692 | Values: 1446.9 1603.5 1646.2 1885.6 1997.1 8067.8 8808.5 9074.7 9091.3 9203.5 8809.0 9060.5 9081.0 9084.3 9087.9 2165.9 2245.4 2246.1 2443.8 2481.6 2250.9 2284.0 2414.5 2495.1 2503.6 2428.7 2455.5 2460.7 2461.4 2464.4 2015.7 2416.2 2419.3 2419.8 2425.0 2155.8 2379.0 2447.5 2457.5 2467.2 8899.4 9014.5 9029.4 9030.6 9032.8 5358.1 5521.0 5524.1 5528.1 5536.0 3468.3 3514.8 3632.5 3633.3 3637.4 1024.3 1037.5 1037.7 1045.7 1052.1 12093.1 12094.8 12111.2 12137.8 12167.0 4851.7 4868.9 4972.4 4986.3 4991.2 10281.6 10306.4 10317.3 10330.8 10355.9 5445.6 5526.8 5531.4 5532.9 5545.4 4529.3 4954.7 4985.6 4992.3 5070.1 3827.4 4032.7 4105.3 4173.9 4189.0 1157.6 1281.0 1331.6 1332.0 1345.3 793.7 821.0 934.9 939.4 974.7 5818.9 5919.5 6064.9 6069.2 6210.2 814.7 893.5 922.8 937.7 984.8 3941.3 3964.1 3991.6 4037.3 4071.3 10718.2 11122.0 11138.9 11147.5 11178.2 10990.3 11075.2 11096.5 11098.6 11106.3 8138.1 8350.6 8431.3 8491.1 8499.0 5915.0 5960.1 5995.2 6012.1 6039.3 9836.1 9995.7 10282.8 10283.0 10419.1 10303.7 10513.7 10547.4 10550.0 10639.3 7009.2 7256.9 7617.4 7806.1 7819.2 7930.4 8067.8 8176.6 8182.1 8182.6 7190.5 7685.1 7712.0 7769.8 7830.8 6718.2 7500.2 7540.7 7734.5 7765.9 7137.6 7180.4 7216.3 7218.0 7218.2 4658.7 4924.3 4963.1 4981.2 5013.2 4970.4 5008.4 5034.8 5091.0 5258.9 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#4] prototype.html Cycles:180 Average:4461.26 Median:5075.35 stddev:1373.58 (27.1%) stddev-sans-first:1341.63 22:45:18 INFO - PROCESS | 1692 | Values: 310.2 311.4 318.4 319.9 323.3 4877.0 5268.9 5273.7 5281.9 5304.4 5269.4 5274.3 5276.5 5301.9 5325.5 3650.0 3794.3 3815.8 3892.1 4077.7 3984.6 4030.8 4039.2 4060.8 4109.0 3664.0 3674.9 3953.3 3997.7 4029.8 3299.4 3576.9 3798.3 3855.7 3895.9 2956.7 3465.4 3475.4 3511.7 3516.9 4075.4 4827.7 5109.7 5114.3 5115.1 3943.9 4296.3 4631.2 4639.3 4643.6 4608.5 5542.3 5556.9 5606.3 5642.5 3310.9 3577.0 3600.8 3806.7 3918.9 5294.5 5349.7 5574.6 5741.5 5888.8 3467.5 3955.8 3960.4 3964.3 4030.6 3864.6 4025.6 4026.7 4037.9 4048.4 4009.5 4319.4 4513.8 4668.9 4699.8 5164.7 5627.0 5651.7 5652.6 5665.0 1197.0 1231.5 1299.5 1353.0 1363.4 828.9 829.8 867.6 875.9 881.5 5071.5 5073.3 5216.0 5245.6 5248.3 5285.1 5711.6 5721.6 5728.6 5761.3 3804.6 4700.0 5142.0 5144.3 5228.3 2453.0 2681.7 2966.8 2975.2 2997.4 4857.7 5549.7 5792.4 5886.5 5894.3 4771.6 5820.7 5869.9 5894.7 5902.4 5060.6 5381.3 5431.2 5640.4 5859.4 5034.6 5415.3 5674.9 5710.3 5718.4 5002.2 5474.6 5505.9 5616.3 5629.3 4519.9 5552.7 5852.8 5881.6 5893.6 3583.9 3623.9 3773.7 3928.5 3928.6 5315.0 5660.8 5702.6 5707.7 5724.9 4652.0 4964.7 4997.9 5077.4 5470.2 5124.9 5217.6 5286.2 5542.0 5568.9 5234.4 5338.3 5390.5 5398.4 5403.7 4602.6 4971.6 5382.5 5419.7 5433.9 4609.0 4851.0 5371.3 5392.2 5463.6 22:45:18 INFO - PROCESS | 1692 | 22:45:18 INFO - PROCESS | 1692 | [#5] yui.html Cycles:180 Average:17780.89 Median:31103.18 stddev:25054.78 (80.6%) stddev-sans-first:25091.75 22:45:18 INFO - PROCESS | 1692 | Values: 572.9 631.8 680.2 701.8 758.5 15224.6 15299.8 15471.0 15641.1 15667.8 14067.2 14377.8 14459.9 14712.2 14774.5 928.5 932.5 943.7 947.5 950.5 923.1 931.0 931.3 934.0 934.4 989.5 996.1 996.5 997.7 998.2 982.5 983.7 984.5 985.0 986.5 728.2 728.5 729.2 729.4 731.2 12176.6 12455.6 12495.9 12526.0 12547.7 11255.2 11297.0 11315.3 11518.0 11916.5 9283.0 9424.4 9445.7 9478.7 10006.5 4019.8 4200.4 4248.8 4373.3 4395.9 86832.6 87547.6 87898.0 87919.0 88488.1 9128.1 9340.9 9436.6 9442.5 9567.4 20451.9 20569.7 20607.6 20616.7 21240.4 7273.5 7371.6 7445.0 7471.3 7664.8 4014.7 4048.4 4075.2 4156.5 4400.4 3584.1 3680.3 3719.5 3722.6 3727.9 655.1 655.5 664.6 671.0 673.6 574.7 582.4 603.6 609.5 625.6 8066.5 8076.2 8144.8 8176.6 8267.6 576.0 578.4 579.7 607.3 628.6 3754.4 3800.7 3816.8 3850.5 4041.4 86723.6 87029.0 94104.1 94130.5 94156.0 49639.5 71004.6 71528.6 75004.6 79693.0 69730.0 70384.0 71511.6 71880.3 73096.6 8088.4 8183.7 8235.6 8265.8 8369.9 43089.0 44087.5 44154.0 44469.6 46374.1 47017.0 58517.7 58757.1 61580.8 62656.0 11038.1 11279.0 11435.4 11472.3 11589.8 12151.1 12387.8 12390.4 12476.9 12797.5 11418.0 11466.6 11522.4 11788.0 12121.7 11234.1 11665.9 11728.8 11741.2 11931.4 11301.8 11322.7 11326.7 11341.8 11515.7 9607.0 9819.8 9911.8 9922.7 10053.7 9320.3 9673.9 9737.7 9773.0 9785.0 22:45:18 INFO - PROCESS | 1692 | -------- Summary: end -------- 22:45:18 INFO - PROCESS | 1692 | 22:45:19 INFO - TEST-INFO | 1692: exit 0 22:45:19 INFO - TEST-OK | dromaeo_css | took 1115918ms 22:45:19 INFO - TEST-START | kraken 22:45:19 INFO - operating with platform_type : mac_ 22:45:19 INFO - Initialising browser for kraken test... 22:45:19 INFO - TEST-INFO | started process 1811 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmporP7mo/profile http://localhost:49277/getInfo.html) 22:45:21 INFO - PROCESS | 1811 | __metrics Screen width/height:1600/1200 22:45:21 INFO - PROCESS | 1811 | colorDepth:24 22:45:21 INFO - PROCESS | 1811 | Browser inner width/height: 1024/689 22:45:21 INFO - PROCESS | 1811 | __metrics 22:45:21 INFO - PROCESS | 1811 | 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" 22:45:21 ERROR - PROCESS | 1811 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:45:22 INFO - TEST-INFO | 1811: exit 0 22:45:22 INFO - Browser initialized. 22:45:22 INFO - Running cycle 1/1 for kraken test... 22:45:22 INFO - TEST-INFO | started process 1814 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmporP7mo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:45:34 INFO - PROCESS | 1814 | RSS: Main: 239636480 22:45:34 INFO - PROCESS | 1814 | 22:45:37 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 22:45:38 INFO - PROCESS | 1814 | RSS: Main: 289378304 22:45:38 INFO - PROCESS | 1814 | 22:45:40 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 22:45:41 INFO - PROCESS | 1814 | RSS: Main: 298053632 22:45:41 INFO - PROCESS | 1814 | 22:45:45 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 22:45:45 INFO - PROCESS | 1814 | RSS: Main: 386215936 22:45:45 INFO - PROCESS | 1814 | 22:45:47 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 22:45:48 INFO - PROCESS | 1814 | RSS: Main: 285007872 22:45:48 INFO - PROCESS | 1814 | 22:45:50 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:45:51 INFO - PROCESS | 1814 | RSS: Main: 289316864 22:45:51 INFO - PROCESS | 1814 | 22:45:54 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 22:45:54 INFO - PROCESS | 1814 | RSS: Main: 326651904 22:45:54 INFO - PROCESS | 1814 | 22:45:57 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 22:45:58 INFO - PROCESS | 1814 | RSS: Main: 328605696 22:45:58 INFO - PROCESS | 1814 | 22:46:01 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 22:46:02 INFO - PROCESS | 1814 | RSS: Main: 325947392 22:46:02 INFO - PROCESS | 1814 | 22:46:04 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:46:04 INFO - PROCESS | 1814 | RSS: Main: 315822080 22:46:04 INFO - PROCESS | 1814 | 22:46:06 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:46:07 INFO - PROCESS | 1814 | RSS: Main: 323104768 22:46:07 INFO - PROCESS | 1814 | 22:46:09 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:46:10 INFO - PROCESS | 1814 | RSS: Main: 324337664 22:46:10 INFO - PROCESS | 1814 | 22:46:13 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:46:14 INFO - PROCESS | 1814 | RSS: Main: 329093120 22:46:14 INFO - PROCESS | 1814 | 22:46:17 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:46:17 INFO - PROCESS | 1814 | RSS: Main: 326651904 22:46:17 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 22:46:19 INFO - PROCESS | 1814 | RSS: Main: 374038528 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | __start_tp_report 22:46:19 INFO - PROCESS | 1814 | _x_x_mozilla_page_load 22:46:19 INFO - PROCESS | 1814 | _x_x_mozilla_page_load_details 22:46:19 INFO - PROCESS | 1814 | |i|pagename|runs| 22:46:19 INFO - PROCESS | 1814 | |0;ai-astar;111;107;111;112;112;112;111;122;163;144 22:46:19 INFO - PROCESS | 1814 | |1;audio-beat-detection;156;118;139;197;118;138;129;116;172;118 22:46:19 INFO - PROCESS | 1814 | |2;audio-dft;183;182;183;182;183;185;184;177;180;183 22:46:19 INFO - PROCESS | 1814 | |3;audio-fft;73;75;56;125;56;62;57;57;113;56 22:46:19 INFO - PROCESS | 1814 | |4;audio-oscillator;93;95;132;80;78;77;102;81;78;101 22:46:19 INFO - PROCESS | 1814 | |5;imaging-gaussian-blur;86;83;86;83;85;88;135;82;86;89 22:46:19 INFO - PROCESS | 1814 | |6;imaging-darkroom;93;93;92;92;91;92;92;93;92;92 22:46:19 INFO - PROCESS | 1814 | |7;imaging-desaturate;115;142;129;85;83;83;88;71;134;130 22:46:19 INFO - PROCESS | 1814 | |8;json-parse-financial;53;57;58;56;56;56;57;121;88;87 22:46:19 INFO - PROCESS | 1814 | |9;json-stringify-tinderbox;63;69;67;77;71;69;65;67;65;66 22:46:19 INFO - PROCESS | 1814 | |10;stanford-crypto-aes;88;85;85;84;86;74;87;96;107;106 22:46:19 INFO - PROCESS | 1814 | |11;stanford-crypto-ccm;137;138;139;136;133;134;136;137;223;149 22:46:19 INFO - PROCESS | 1814 | |12;stanford-crypto-pbkdf2;183;225;195;168;217;223;162;162;161;161 22:46:19 INFO - PROCESS | 1814 | |13;stanford-crypto-sha256-iterative;65;66;66;65;64;64;64;66;65;64 22:46:19 INFO - PROCESS | 1814 | __end_tp_report 22:46:19 INFO - PROCESS | 1814 | __start_cc_report 22:46:19 INFO - PROCESS | 1814 | _x_x_mozilla_cycle_collect,2266 22:46:19 INFO - PROCESS | 1814 | __end_cc_report 22:46:19 INFO - PROCESS | 1814 | __startTimestamp1463377579791__endTimestamp 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | ------- Summary: start ------- 22:46:19 INFO - PROCESS | 1814 | Number of tests: 14 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#0] ai-astar Cycles:10 Average:120.50 Median:112.00 stddev:18.35 (16.4%) stddev-sans-first:19.14 22:46:19 INFO - PROCESS | 1814 | Values: 111.0 107.0 111.0 112.0 112.0 112.0 111.0 122.0 163.0 144.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:138.50 stddev:27.25 (19.7%) stddev-sans-first:28.29 22:46:19 INFO - PROCESS | 1814 | Values: 156.0 118.0 139.0 197.0 118.0 138.0 129.0 116.0 172.0 118.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#2] audio-dft Cycles:10 Average:182.20 Median:183.00 stddev:2.25 (1.2%) stddev-sans-first:2.37 22:46:19 INFO - PROCESS | 1814 | Values: 183.0 182.0 183.0 182.0 183.0 185.0 184.0 177.0 180.0 183.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#3] audio-fft Cycles:10 Average:73.00 Median:57.00 stddev:25.40 (44.6%) stddev-sans-first:26.94 22:46:19 INFO - PROCESS | 1814 | Values: 73.0 75.0 56.0 125.0 56.0 62.0 57.0 57.0 113.0 56.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:79.00 stddev:17.23 (21.8%) stddev-sans-first:18.27 22:46:19 INFO - PROCESS | 1814 | Values: 93.0 95.0 132.0 80.0 78.0 77.0 102.0 81.0 78.0 101.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:86.00 stddev:15.86 (18.4%) stddev-sans-first:16.75 22:46:19 INFO - PROCESS | 1814 | Values: 86.0 83.0 86.0 83.0 85.0 88.0 135.0 82.0 86.0 89.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#6] imaging-darkroom Cycles:10 Average:92.20 Median:92.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 22:46:19 INFO - PROCESS | 1814 | Values: 93.0 93.0 92.0 92.0 91.0 92.0 92.0 93.0 92.0 92.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#7] imaging-desaturate Cycles:10 Average:106.00 Median:77.00 stddev:26.49 (34.4%) stddev-sans-first:27.89 22:46:19 INFO - PROCESS | 1814 | Values: 115.0 142.0 129.0 85.0 83.0 83.0 88.0 71.0 134.0 130.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:57.00 stddev:22.51 (39.5%) stddev-sans-first:23.13 22:46:19 INFO - PROCESS | 1814 | Values: 53.0 57.0 58.0 56.0 56.0 56.0 57.0 121.0 88.0 87.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:3.96 (5.8%) stddev-sans-first:3.78 22:46:19 INFO - PROCESS | 1814 | Values: 63.0 69.0 67.0 77.0 71.0 69.0 65.0 67.0 65.0 66.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.50 stddev:10.28 (12.0%) stddev-sans-first:10.89 22:46:19 INFO - PROCESS | 1814 | Values: 88.0 85.0 85.0 84.0 86.0 74.0 87.0 96.0 107.0 106.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:137.50 stddev:27.34 (19.9%) stddev-sans-first:28.79 22:46:19 INFO - PROCESS | 1814 | Values: 137.0 138.0 139.0 136.0 133.0 134.0 136.0 137.0 223.0 149.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:189.00 stddev:27.22 (14.4%) stddev-sans-first:28.85 22:46:19 INFO - PROCESS | 1814 | Values: 183.0 225.0 195.0 168.0 217.0 223.0 162.0 162.0 161.0 161.0 22:46:19 INFO - PROCESS | 1814 | 22:46:19 INFO - PROCESS | 1814 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 22:46:19 INFO - PROCESS | 1814 | Values: 65.0 66.0 66.0 65.0 64.0 64.0 64.0 66.0 65.0 64.0 22:46:19 INFO - PROCESS | 1814 | -------- Summary: end -------- 22:46:19 INFO - PROCESS | 1814 | 22:46:20 INFO - TEST-INFO | 1814: exit 0 22:46:20 INFO - TEST-OK | kraken | took 61173ms 22:46:20 INFO - SUITE-END | took 1177s 22:46:20 INFO - Completed test suite (00:19:37) 22:46:20 INFO - javascript benchmark 22:46:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3450.792952422854, 3459.6367381424934, 3486.790792552447, 3488.7557870949045, 3527.6472352764713, 4598.471175814782, 5654.349749778777, 5686.516646085082, 5738.167965644984, 5760.337561180466, 5684.687342196177, 5684.744186511606, 5711.05767548354, 5768.39431859655, 5779.82660520184, 5079.187330027196, 5085.593152547787, 5161.045206636784, 5169.844904652867, 5272.156454967206, 5235.502627250406, 5243.68537887728, 5287.339082614673, 5309.548688361494, 5336.066188417031, 5073.188289873639, 5106.106431374513, 5111.795528178868, 5170.25031370453, 5171.405288391846, 5144.0740666679985, 5152.793888244466, 5162.432132465426, 5193.792248310063, 5216.243644671539, 5140.691558506502, 5171.3535808024, 5188.792448302063, 5197.402298735656, 5213.165893457029, 5288.735563221823, 5484.670919744828, 5537.750801213955, 5671.574631902583, 5759.366469688331, 5149.227615857614, 6107.26712794463, 6128.59561757964, 6138.754449822001, 6143.846403839895, 5605.943940560624, 6208.286047104551, 6220.595661282002, 6226.719797609118, 6241.282252540925, 4598.402207712976, 5633.12686533592, 5689.77240910359, 5817.447342502455, 5820.447057529613, 5290.259363689087, 6320.5891617044745, 6329.936700633027, 6346.174997250328, 6347.39699728525, 6155.599886007395, 6182.814515564541, 6189.535784816157, 6203.565750397519, 6216.285127210338, 6131.957816600026, 6144.262688477322, 6203.96898015516, 6232.407921247474, 6279.0267508535335, 6104.847378815565, 6171.0, 6178.505719542426, 6181.196444462191, 6185.443310102112, 6160.322364539897, 6200.038993955944, 6245.125682404467, 6249.281332646713, 6258.186435763321, 4220.345846393752, 4504.414426124583, 4679.619512243896, 4852.4662287148385, 4866.926996095061, 4404.405405270311, 4405.977970110193, 4406.096750166208, 4425.0, 4453.732776033448, 5715.428457154251, 5731.656100633975, 5739.512141467939, 5740.655560666373, 5787.045137552257, 6145.617236121837, 6206.0, 6233.532485063638, 6273.749050038039, 6292.685365731695, 5712.6286791358425, 5726.742296596663, 5727.7422515986045, 5735.799247026334, 5769.826905192851, 5258.1849813279005, 5374.623776335619, 5387.138057910794, 5387.24578559002, 5389.003034438641, 6253.093301471352, 6304.0, 6307.369263073656, 6307.842303942254, 6317.115603815378, 6272.184616000073, 6277.278113016879, 6287.056941458818, 6301.527385446201, 6302.558820882494, 6261.279952805486, 6263.937360626518, 6296.087067375113, 6298.307186209605, 6321.810345689637, 6122.062734707273, 6180.6909654517995, 6185.567010309235, 6194.845128871634, 6246.9062964054665, 6292.779752708633, 6308.053791931247, 6311.747530098744, 6315.621062736067, 6324.272708638381, 4652.953470465252, 5949.762009519571, 6120.296165940974, 6141.6007959482495, 6145.139680444652, 5863.941360586339, 6052.636841789506, 6064.332923378513, 6083.6045657032155, 6105.297890742621, 5944.64332140073, 5948.583599148018, 5949.0481522956115, 5959.940400595939, 6030.276366836008, 5961.940380596138, 6038.849028774316, 6072.834254363149, 6110.175126358063, 6158.261008678987, 5942.227510423791, 5973.940260597338, 5979.521638268841, 5990.4908082813445, 6023.759049637965], "value": 5655.136413454592, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.0847120946691, 525.684589246452, 529.2167591963895, 537.8601563593467, 543.22861536618, 30897.845510772444, 41665.166696666085, 42832.357514637224, 42964.0, 42964.57035429653, 45117.0, 45377.0, 45382.638520844346, 45397.54602453975, 45492.0, 8616.483011019327, 9677.709668709933, 9861.359011664255, 9879.407235565854, 9928.255380846429, 8738.0, 11926.7614647707, 12114.030877529776, 12126.878731212662, 12136.393180340992, 11882.702932426715, 11884.46519906602, 11927.986121179667, 11943.0, 11954.940225298862, 8607.956960215222, 11723.531058757639, 11828.645140645795, 11882.762344753142, 11884.227525210878, 11376.431178441127, 11717.53129874804, 11731.706707332385, 11738.237014594024, 11739.0, 40589.955753318616, 40984.0, 41024.38463423051, 41055.589444105775, 41066.1786764266, 3303.4549299365576, 3356.580427446569, 3733.3653278942643, 3839.347310957116, 3884.6309600587897, 24471.143509977243, 29033.70966290331, 29633.70366296331, 30421.847890760626, 30705.846470767505, 2740.3149212696826, 2761.9171424857286, 2809.6909339972585, 2854.9143525694053, 2860.256333353322, 401375.0, 523541.0, 524144.0, 527664.361678193, 528061.0, 35552.822235888656, 36339.0, 36574.268514630094, 37962.0, 62912.0, 36148.81925590355, 36284.0, 36293.27413451716, 36417.45373819395, 36453.27093458169, 15081.396744130112, 15241.695166096615, 15246.847531524765, 15282.235888205767, 15290.388384464719, 10940.617078402218, 11816.586419475278, 12633.115681902178, 12741.490340386466, 12906.548270810477, 8197.426180167451, 8210.096889342167, 8231.46495477792, 8279.213474719892, 8291.95854020726, 3420.6237313895454, 3440.8795692150657, 3441.9311613767586, 3452.602950660656, 3480.4083305838067, 958.5782255807422, 1009.7324209084599, 1018.6332920148741, 1057.751428414322, 1060.0088916862603, 29956.850215748782, 30035.0, 30725.846370768002, 30831.383372332428, 30885.845570772002, 1071.314358810362, 1086.8804431512526, 1133.28602980122, 1152.8270759385991, 1211.5880600595663, 15505.689886202439, 15517.767233491506, 15545.533633990999, 15942.36230550765, 15981.920090399473, 611967.0, 620627.0, 623383.0, 623974.0, 625578.0, 61920.69039654863, 62601.68699156475, 62629.373706263264, 62633.68683156555, 62744.686276568325, 1341.677997280665, 1384.4946594492937, 1462.436961769705, 1481.6814384907298, 1503.4737841755516, 24483.265502034967, 29682.962845505124, 31006.22484437817, 31159.75360985535, 31839.840800795846, 51736.74131629318, 51925.480745192064, 52227.73886130545, 52232.47767522276, 52246.69383265297, 516144.0, 516559.0, 518450.0, 518960.0, 523725.0, 27207.86396068007, 27239.182824515297, 27728.584071238543, 28330.150095497167, 29410.411791763618, 46751.0, 47399.76300118477, 47609.0, 48880.51119488759, 48939.75530122327, 37900.62099378971, 38912.0, 39172.6082739169, 39730.80807575777, 40241.59758402379, 37948.0, 37999.24001520009, 38168.975014365074, 38791.224175516894, 39594.0, 37843.62156378401, 37898.43152352662, 38900.6109938897, 38925.0, 39011.0, 28519.714802851708, 33638.66361336453, 33656.1585960353, 34222.48666269958, 34417.82791086029, 24656.383590410383, 25161.0, 25172.118975835758, 25789.871050644626, 25867.0], "value": 19086.02682948, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.6870306809154, 402.21969379403953, 407.4601153471651, 409.68658975883454, 425.2091110534407, 18521.981291028987, 19980.90009549952, 20617.793822061776, 21074.262400815955, 21161.788382116192, 18126.912385256896, 20719.378418647426, 21120.366389008315, 21277.148914043457, 21547.353579392642, 5209.661372010826, 6037.516998640098, 6154.3537928517535, 6159.168512250856, 6174.135621013062, 5970.8805823883495, 6037.245344331958, 6048.879022419549, 6092.939070609282, 6134.4172303631085, 5827.621204621707, 5965.761369545213, 5990.640561566298, 6008.579399442041, 6013.789517366871, 5788.247527821377, 5930.60630751773, 5992.700364981755, 6134.202553668039, 6161.784337548209, 5964.135200395918, 5965.194698715662, 5970.0, 5976.46211840932, 5984.521238300926, 12017.158798884082, 14068.22624755638, 21970.34088977333, 26579.13946023779, 26892.731072689414, 1931.6493313521546, 2051.8461115416253, 2085.18677715691, 2184.584928863511, 2252.7521972583004, 8619.353548483889, 9762.658306959222, 10011.948745381776, 10231.488425578691, 10252.233525872887, 1724.5688577855535, 1737.3311275159028, 1756.7891852977596, 1829.9085045747659, 1948.824605785486, 122806.0, 127923.36038319749, 127945.36027319897, 128884.0, 131738.3413082938, 11619.825702614467, 11734.647960561197, 11774.411279436164, 11804.940975295069, 12007.03943684503, 15369.846301537065, 15405.9229703853, 15420.537383878502, 15423.768643470357, 15451.845481545266, 8771.561421928878, 8829.779255518535, 8832.470051796785, 8849.557522123869, 8973.192412682889, 7843.764687059397, 7847.725329613436, 8613.138686131337, 8641.697540586048, 8755.868661970075, 3849.576546579874, 4696.365990591295, 4720.103180395714, 4758.40565606817, 4769.093872164347, 1721.5868191634167, 1938.9709154362695, 1944.1737261663736, 1992.3524854422371, 2029.249177804222, 769.0579040675128, 997.6009596161448, 1017.7506510904876, 1028.4307496823374, 1054.5676272728144, 20023.899880500503, 20544.486387840425, 20606.17575297001, 20731.689024664644, 20790.792092078886, 926.972190834276, 933.7525555727738, 941.2611100286242, 962.8266911955775, 967.6613185385058, 9601.807963840683, 11048.337099774039, 11663.125265605113, 11886.465109070112, 11908.511436070492, 598569.0, 606530.0, 612654.0, 617519.0, 628819.0, 64456.67771661111, 86516.70224946631, 123631.20028398345, 126755.09867351997, 130282.78868845329, 973.7273563402259, 1022.4427686910651, 1038.5067093130854, 1040.0587468341164, 1040.256216804988, 16476.505704828873, 16962.32150714007, 17051.232694529022, 18205.0, 18648.906755466134, 35826.0, 47374.999999998625, 47784.80537986578, 47925.76037119792, 53985.460145399615, 75186.6240668815, 75653.62173189319, 76031.61984190044, 77493.0, 78369.60815195888, 7907.0, 8043.919560804317, 8173.836523269621, 8215.794605134919, 8574.91425085766, 10636.30863993838, 12081.275123492618, 12714.936425317814, 12736.426860791102, 12875.420606072561, 10248.077673009466, 10706.678799636024, 10734.926507349202, 10881.238313318308, 11213.0, 9041.0, 10775.94612026935, 10778.884829514498, 10864.45677716127, 11074.169437292396, 6045.667488288186, 8695.0, 11009.0, 11200.439978000904, 11233.887661123284, 7169.426445884213, 7578.848423031398, 7631.275028872248, 7966.681332746857, 8485.618147183268, 10606.469676516297, 10667.466626668791, 11361.772764544497, 11685.00677442393, 11717.238379505305], "value": 9437.468184832293, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.9059511131782, 1603.486884197057, 1646.2180464279468, 1885.6040231551374, 1997.0613811508586, 8067.838643227132, 8808.471491710501, 9074.682386116487, 9091.318151138667, 9203.53982300884, 8808.955955220232, 9060.546972651358, 9080.954595227031, 9084.27325813935, 9087.863682044775, 2165.8700477971292, 2245.4274160089153, 2246.05665620439, 2443.8044956403446, 2481.553320402327, 2250.864948103119, 2284.0, 2414.4688168602966, 2495.1391769839374, 2503.6119401492797, 2428.732839387666, 2455.4966231922413, 2460.6801115854914, 2461.396957745346, 2464.383904023994, 2015.687568426889, 2416.2147302126705, 2419.2984034629935, 2419.8064154867657, 2424.9515009699885, 2155.8490905636613, 2378.9643155352683, 2447.5349683560066, 2457.533068716938, 2467.1611652038214, 8899.37704360695, 9014.504202268872, 9029.41308814925, 9030.593623286968, 9032.819343613157, 5358.089124848785, 5521.033819081645, 5524.14375771756, 5528.115501519737, 5536.003519366513, 3468.2543253200683, 3514.8242587870504, 3632.4551317302353, 3633.291508155911, 3637.399829028207, 1024.2727663359024, 1037.5123691864887, 1037.7353774787457, 1045.6601604478499, 1052.1425038593522, 12093.093018023596, 12094.758104837854, 12111.212771169934, 12137.757244855142, 12166.96580790634, 4851.684640498356, 4868.878278043077, 4972.428170760337, 4986.32684587576, 4991.151504244287, 10281.640142594973, 10306.433146176882, 10317.329373590692, 10330.793384132276, 10355.896441035493, 5445.646033007842, 5526.778928842801, 5531.419200983919, 5532.917006244909, 5545.390007099216, 4529.275315949432, 4954.702717836942, 4985.626078044161, 4992.2761199626575, 5070.112730272188, 3827.3684842001317, 4032.697547683935, 4105.290508264531, 4173.916521669549, 4188.958110418917, 1157.6064138193055, 1280.9743805123846, 1331.5738963531576, 1331.9600411987656, 1345.2869978911192, 793.6666600028002, 820.9917900820915, 934.9065093490733, 939.4259728344288, 974.6929717139078, 5818.941810581925, 5919.4968427684125, 6064.878702425926, 6069.241344831896, 6210.161628180138, 814.722994181981, 893.5167621747868, 922.7969846633864, 937.6577549194632, 984.837501812193, 3941.349677303213, 3964.1278918637854, 3991.6428414339266, 4037.3338399164395, 4071.2875246831686, 10718.24972251935, 11122.0, 11138.94430527842, 11147.498362573704, 11178.21752477335, 10990.340579565252, 11075.224734268684, 11096.500657470433, 11098.5560577576, 11106.333619982826, 8138.064122625856, 8350.582470876432, 8431.325493960469, 8491.108433614505, 8498.957505212558, 5915.0, 5960.1357803117435, 5995.190649262294, 6012.128241405059, 6039.305479869871, 9836.06557377048, 9995.650152244636, 10282.845757313797, 10283.023112804272, 10419.114375278186, 10303.742406439598, 10513.684589462327, 10547.367157970546, 10549.997750213717, 10639.308444951053, 7009.1589009319205, 7256.89114663291, 7617.352525035434, 7806.102298235548, 7819.178986206366, 7930.365570754441, 8067.757967260992, 8176.591170441573, 8182.059063207807, 8182.631781569725, 7190.460715446257, 7685.077790665156, 7711.961440192763, 7769.805754856174, 7830.765077047698, 6718.22740384862, 7500.249975002456, 7540.736074237376, 7734.497257678233, 7765.92234077652, 7137.607431591105, 7180.425565954815, 7216.314450127229, 7217.989481472703, 7218.169910460154, 4658.720476771269, 4924.2613607957655, 4963.057019166346, 4981.227909674007, 5013.172826483589, 4970.378702662168, 5008.398992120969, 5034.798608055637, 5091.0, 5258.86852828667], "value": 4678.856227989373, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.1919499703273, 311.4347459361259, 318.3569190235724, 319.8736499082864, 323.3387722108289, 4876.9756151219235, 5268.894622107556, 5273.6572122811995, 5281.8943621127555, 5304.3634763828295, 5269.394019687737, 5274.26160337553, 5276.472352764731, 5301.867453313669, 5325.520703136726, 3650.0, 3794.29805485986, 3815.809209539526, 3892.0853599404118, 4077.6533994610495, 3984.6015398460213, 4030.798460076999, 4039.212353591051, 4060.817263223162, 4109.034376921421, 3663.981680091596, 3674.9081272968256, 3953.3279342511696, 3997.7001724870606, 4029.7783621900785, 3299.3566254580364, 3576.8554062699945, 3798.335291324035, 3855.6722678572355, 3895.8636447724193, 2956.659984101834, 3465.42820434628, 3475.391806433864, 3511.6839484446523, 3516.9472457913153, 4075.4498142750645, 4827.710337379768, 5109.744512774347, 5114.28400023997, 5115.130427827278, 3943.861964831217, 4296.312589985588, 4631.235846085392, 4639.257718765015, 4643.582077613031, 4608.470025946992, 5542.307211598551, 5556.888862222773, 5606.290081525133, 5642.548596112301, 3310.93378132436, 3577.0, 3600.837962291703, 3806.733528652968, 3918.9027072419767, 5294.464605264508, 5349.689326115126, 5574.60977731559, 5741.483266506035, 5888.764449422018, 3467.4625433057918, 3955.7626542407547, 3960.376245739233, 3964.306246406925, 4030.6170913763144, 3864.555576108727, 4025.6376926076796, 4026.7181297309494, 4037.9394309085387, 4048.4332193492933, 4009.5164789027663, 4319.373690814814, 4513.774311284423, 4668.89899545206, 4699.765011749399, 5164.664296820694, 5627.0, 5651.660900345993, 5652.643365592228, 5665.036943719578, 1197.0064846177652, 1231.5320178332177, 1299.4925885972227, 1352.9705441837243, 1363.4205462678322, 828.9419740618099, 829.792651691787, 867.6464715044551, 875.913866805157, 881.4711173295971, 5071.529256515643, 5073.289739436482, 5215.973920130375, 5245.554127899171, 5248.317718696545, 5285.089034114728, 5711.6001879868845, 5721.625525406993, 5728.570357223225, 5761.279840019997, 3804.600516945736, 4700.012997270543, 5142.0, 5144.331236939174, 5228.346456692913, 2452.9877350613133, 2681.7184195659565, 2966.7923245372617, 2975.226441125323, 2997.430488207234, 4857.684250523705, 5549.694766787866, 5792.391798861143, 5886.529077673776, 5894.263217097863, 4771.642126840431, 5820.650760954356, 5869.941300586939, 5894.705264736746, 5902.432441887707, 5060.569851562658, 5381.286912030686, 5431.1581704835025, 5640.379558248589, 5859.367192340691, 5034.622403319839, 5415.268938693226, 5674.914876276942, 5710.2862142232225, 5718.399568045295, 5002.224655178453, 5474.5894057945015, 5505.889882202414, 5616.326040875121, 5629.324481062299, 4519.909601808011, 5552.69460179694, 5852.834451253064, 5881.588288819741, 5893.557983151367, 3583.9462408064423, 3623.8912832615065, 3773.6792372647533, 3928.548216955087, 3928.6071392860486, 5315.0, 5660.830175094754, 5702.6293290935955, 5707.743151558273, 5724.881793736345, 4652.0, 4964.726940018334, 4997.925031124463, 5077.416097148875, 5470.206820010996, 5124.897502050013, 5217.582593392443, 5286.15421532553, 5542.0, 5568.888622227614, 5234.435126787923, 5338.3060202173465, 5390.541803946709, 5398.406175320633, 5403.702796346239, 4602.631789456902, 4971.627127965345, 5382.4886635769535, 5419.701916394636, 5433.870258603411, 4609.036228843259, 4850.951490485191, 5371.274877891591, 5392.245085687929, 5463.562914966873], "value": 4026.276666822084, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.8968785618588, 631.7915088020952, 680.1532092544779, 701.7859552836389, 758.4993904023346, 15224.619384515392, 15299.770503442443, 15470.994385364958, 15641.061536307829, 15667.764983525256, 14067.155970641768, 14377.784333234984, 14459.92770036151, 14712.190829504372, 14774.482893098744, 928.5264515097282, 932.5011119051309, 943.7216021273741, 947.4646824544145, 950.4534892436842, 923.0815338737965, 930.9953450232773, 931.2968708624978, 933.995954349074, 934.4440058165414, 989.45579931038, 996.1383403356081, 996.4868092932146, 997.6657819630433, 998.1864780204111, 982.4645568165367, 983.6753871222479, 984.4929861121528, 985.0002247718536, 986.531397586148, 728.1735230513352, 728.4973368375804, 729.2015243308568, 729.4055344893894, 731.1920328037495, 12176.634700958986, 12455.564055258023, 12495.937520312431, 12526.0, 12547.686307842285, 11255.212135150543, 11297.039751621121, 11315.321080735182, 11518.0, 11916.523339066427, 9282.953585232099, 9424.387414818015, 9445.66940157098, 9478.715638530786, 10006.499675016294, 4019.839206431739, 4200.411942328076, 4248.808803603845, 4373.343998400233, 4395.912081758347, 86832.56583717105, 87547.56226218892, 87898.0, 87919.0, 88488.11511884863, 9128.132827381389, 9340.906590934004, 9436.575354109082, 9442.527873606292, 9567.37812042215, 20451.897740511202, 20569.69145462819, 20607.58784824295, 20616.69074963877, 21240.362789116352, 7273.454490913203, 7371.5945622990175, 7445.032145821008, 7471.327580517779, 7664.846703065908, 4014.6788256939376, 4048.3927410888014, 4075.1849630074103, 4156.501219853576, 4400.427944367212, 3584.1039740064984, 3680.3375392429084, 3719.497867787868, 3722.62773722631, 3727.8881633551036, 655.1286788571189, 655.4821690864259, 664.6311297230084, 670.9664516774142, 673.6160388578561, 574.6638216643227, 582.3652219081138, 603.562417247492, 609.5328563936629, 625.5902383938527, 8066.475679080841, 8076.152004039492, 8144.796380090545, 8176.6320515576945, 8267.62795674196, 575.9913601295984, 578.3782433883592, 579.6753817862011, 607.259143844509, 628.6448156791398, 3754.3617585010065, 3800.657940785343, 3816.7596875295994, 3850.4609354690365, 4041.4139949707715, 86723.56638216895, 87029.0, 94104.0589594109, 94130.5293473542, 94156.0, 49639.50360496421, 71004.64497677478, 71528.64235678788, 75004.62497687477, 79693.0, 69730.0, 70383.99999999898, 71511.64244178745, 71880.28119718841, 73096.63451682708, 8088.393370497119, 8183.6726530938095, 8235.588220588827, 8265.793355166168, 8369.874451883104, 43089.0, 44087.45693900698, 44154.0, 44469.55530444654, 46374.072518550114, 47017.0, 58517.70741146267, 58757.11864321953, 61580.76838463295, 62656.0, 11038.11695064409, 11279.041281490838, 11435.428228588704, 11472.31166130035, 11589.768204636028, 12151.149419540547, 12387.814182787446, 12390.380480976095, 12476.937615311865, 12797.488100475877, 11418.0, 11466.598669046543, 11522.423878806194, 11788.0, 12121.69695757613, 11234.101271898391, 11665.941670291595, 11728.824067639163, 11741.236819606698, 11931.4034298283, 11301.773964520828, 11322.716932076764, 11326.660200194008, 11341.773164536498, 11515.712107197387, 9606.951965240409, 9819.803603928025, 9911.752206194902, 9922.652707155503, 10053.748656283651, 9320.347575669639, 9673.854892176763, 9737.707868763948, 9772.951135244562, 9785.0], "value": 6896.698063087194, "unit": "score"}], "name": "dromaeo_css", "value": 7138.776855862708, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 111.0, 112.0, 112.0, 112.0, 111.0, 122.0, 163.0, 144.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 139.0, 197.0, 118.0, 138.0, 129.0, 116.0, 172.0, 118.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 183.0, 182.0, 183.0, 185.0, 184.0, 177.0, 180.0, 183.0], "value": 182.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 56.0, 125.0, 56.0, 62.0, 57.0, 57.0, 113.0, 56.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 132.0, 80.0, 78.0, 77.0, 102.0, 81.0, 78.0, 101.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 85.0, 88.0, 135.0, 82.0, 86.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 92.0, 91.0, 92.0, 92.0, 93.0, 92.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 142.0, 129.0, 85.0, 83.0, 83.0, 88.0, 71.0, 134.0, 130.0], "value": 106.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 57.0, 58.0, 56.0, 56.0, 56.0, 57.0, 121.0, 88.0, 87.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 67.0, 77.0, 71.0, 69.0, 65.0, 67.0, 65.0, 66.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 84.0, 86.0, 74.0, 87.0, 96.0, 107.0, 106.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 139.0, 136.0, 133.0, 134.0, 136.0, 137.0, 223.0, 149.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 225.0, 195.0, 168.0, 217.0, 223.0, 162.0, 162.0, 161.0, 161.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 64.0, 64.0, 64.0, 66.0, 65.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1519.4, "alertThreshold": 2.0}]} 22:46:21 INFO - Return code: 0 22:46:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:46:21 ERROR - # TBPL SUCCESS # 22:46:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:46:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:46:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:46:21 INFO - 2016-05-15 22:46:21.080 screenresolution[1824:9983] starting screenresolution argv=screenresolution get 22:46:21 INFO - 2016-05-15 22:46:21.083 screenresolution[1824:9983] Display 0: 1600x1200x32@60 22:46:21 INFO - 2016-05-15 22:46:21.089 screenresolution[1825:9986] starting screenresolution argv=screenresolution list 22:46:21 INFO - Available Modes on Display 0 22:46:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:46:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:46:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:46:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:46:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:46:21 INFO - Intel Iris: 22:46:21 INFO - Chipset Model: Intel Iris 22:46:21 INFO - Type: GPU 22:46:21 INFO - Bus: Built-In 22:46:21 INFO - VRAM (Dynamic, Max): 1536 MB 22:46:21 INFO - Vendor: Intel (0x8086) 22:46:21 INFO - Device ID: 0x0a2e 22:46:21 INFO - Revision ID: 0x0009 22:46:21 INFO - Displays: 22:46:21 INFO - Display: 22:46:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:46:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:46:21 INFO - Display Serial Number: 1600x1200 60 22:46:21 INFO - Main Display: Yes 22:46:21 INFO - Mirror: Off 22:46:21 INFO - Online: Yes 22:46:21 INFO - Rotation: Supported 22:46:21 INFO - Return code: 0 22:46:21 INFO - Running post-action listener: _resource_record_post_action 22:46:21 INFO - ##### 22:46:21 INFO - ##### Finished run-tests step (success) 22:46:21 INFO - ##### 22:46:21 INFO - Running post-run listener: _resource_record_post_run 22:46:21 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36730368; Write bytes: 699126272; Read time: 235; Write time: 2048 22:46:21 INFO - TinderboxPrint: CPU usage
53.7% 22:46:21 INFO - TinderboxPrint: I/O read bytes / time
36,730,368 / 235 22:46:21 INFO - TinderboxPrint: I/O write bytes / time
699,126,272 / 2,048 22:46:21 INFO - TinderboxPrint: CPU idle
2,243.3 (46.3%) 22:46:21 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 22:46:21 INFO - TinderboxPrint: CPU user
1,636.2 (33.7%) 22:46:21 INFO - TinderboxPrint: Swap in / out
431,775,744 / 0 22:46:21 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 396286464; Write bytes: 386185216; Read time: 28800; Write time: 848 22:46:21 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29625856; Write bytes: 299821568; Read time: 181; Write time: 1161 22:46:21 INFO - Running post-run listener: _upload_blobber_files 22:46:21 INFO - Blob upload gear active. 22:46:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:46:21 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 22:46:21 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'] 22:46:21 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 22:46:22 INFO - (blobuploader) - INFO - Open directory for files ... 22:46:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:46:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:46:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:23 INFO - (blobuploader) - INFO - Done attempting. 22:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:24 INFO - (blobuploader) - INFO - Done attempting. 22:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:26 INFO - (blobuploader) - INFO - Done attempting. 22:46:26 INFO - (blobuploader) - INFO - Iteration through files over. 22:46:26 INFO - Return code: 0 22:46:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:46:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:46:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20a8dd2dad045bb7bdc9181f7c20c24241e002f503b60da681e80bb0cebf949369854273d37254b6282f7eca43e6885bb9ba2e40ed164e69b6fffd8fa97fd4a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/548285f7cf148a4191aad491274d6d743d928bbff1c6fc14c90c5a66042e6416c668c3ef8caba1b64d76f9fef9fb5f96f39e28449a67d1f29d9757e1fc8abd39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9717bc62cd708a4061124cab40e1052c24229107cff8192e87e06fd5c9b8f9ab8aa4dc62526e760255b0f2a65c362222d99ab5ee7e0689b3138a290ad06dc37"} 22:46:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:46:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:46:26 INFO - Contents: 22:46:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20a8dd2dad045bb7bdc9181f7c20c24241e002f503b60da681e80bb0cebf949369854273d37254b6282f7eca43e6885bb9ba2e40ed164e69b6fffd8fa97fd4a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/548285f7cf148a4191aad491274d6d743d928bbff1c6fc14c90c5a66042e6416c668c3ef8caba1b64d76f9fef9fb5f96f39e28449a67d1f29d9757e1fc8abd39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9717bc62cd708a4061124cab40e1052c24229107cff8192e87e06fd5c9b8f9ab8aa4dc62526e760255b0f2a65c362222d99ab5ee7e0689b3138a290ad06dc37"} 22:46:26 INFO - Running post-run listener: copy_logs_to_upload_dir 22:46:26 INFO - Copying logs to upload dir... 22:46:26 INFO - mkdir: /builds/slave/test/build/upload/logs 22:46:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.356480 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-15 22:46:26.697189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 22:46:26.701131) ========= 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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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/20a8dd2dad045bb7bdc9181f7c20c24241e002f503b60da681e80bb0cebf949369854273d37254b6282f7eca43e6885bb9ba2e40ed164e69b6fffd8fa97fd4a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/548285f7cf148a4191aad491274d6d743d928bbff1c6fc14c90c5a66042e6416c668c3ef8caba1b64d76f9fef9fb5f96f39e28449a67d1f29d9757e1fc8abd39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9717bc62cd708a4061124cab40e1052c24229107cff8192e87e06fd5c9b8f9ab8aa4dc62526e760255b0f2a65c362222d99ab5ee7e0689b3138a290ad06dc37"} build_url:https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010462 build_url: 'https://queue.taskcluster.net/v1/task/I0tUUEhdSaSkdb11BcTkHg/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/20a8dd2dad045bb7bdc9181f7c20c24241e002f503b60da681e80bb0cebf949369854273d37254b6282f7eca43e6885bb9ba2e40ed164e69b6fffd8fa97fd4a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/548285f7cf148a4191aad491274d6d743d928bbff1c6fc14c90c5a66042e6416c668c3ef8caba1b64d76f9fef9fb5f96f39e28449a67d1f29d9757e1fc8abd39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9717bc62cd708a4061124cab40e1052c24229107cff8192e87e06fd5c9b8f9ab8aa4dc62526e760255b0f2a65c362222d99ab5ee7e0689b3138a290ad06dc37"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 22:46:26.728680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:46:26.729000) ========= 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.XIWGVGlnWR/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.xA1ufXGUqE/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.005113 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 22:46:26.777605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 22:46:26.777922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 22:46:26.778255) ========= ========= Total master_lag: 0.18 =========