builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1462832980.6 results: success (0) buildid: 20160509142802 builduid: a4d3cdb17fab4cf5b05b6a4ff252e1a3 revision: a3033ed384eb846bf37d9a4bf59a3728f15e9f74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.601149) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.601677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.602038) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.622710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.623011) ========= 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.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-09 15:29:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-05-09 15:29:40 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.738739) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.739091) ========= 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.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.030026 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:40.787660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:29:40.788045) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a3033ed384eb846bf37d9a4bf59a3728f15e9f74 --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 a3033ed384eb846bf37d9a4bf59a3728f15e9f74 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-09 15:29:40,859 truncating revision to first 12 chars 2016-05-09 15:29:40,860 Setting DEBUG logging. 2016-05-09 15:29:40,860 attempt 1/10 2016-05-09 15:29:40,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a3033ed384eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 15:29:41,861 unpacking tar archive at: fx-team-a3033ed384eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241950 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:29:42.051194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:42.052005) ========= script_repo_revision: a3033ed384eb846bf37d9a4bf59a3728f15e9f74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:42.052968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:42.053704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:29:42.069290) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-09 15:29:42.070008) ========= /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.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:29:42 INFO - MultiFileLogger online at 20160509 15:29:42 in /builds/slave/test 15:29:42 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 15:29:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:42 INFO - {'append_to_log': False, 15:29:42 INFO - 'base_work_dir': '/builds/slave/test', 15:29:42 INFO - 'blob_upload_branch': 'Fx-Team', 15:29:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:42 INFO - 'branch': 'Fx-Team', 15:29:42 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:42 INFO - 'config_files': ('talos/mac_config.py',), 15:29:42 INFO - 'default_actions': ('clobber', 15:29:42 INFO - 'read-buildbot-config', 15:29:42 INFO - 'download-and-extract', 15:29:42 INFO - 'populate-webroot', 15:29:42 INFO - 'create-virtualenv', 15:29:42 INFO - 'install', 15:29:42 INFO - 'run-tests'), 15:29:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:42 INFO - 'download_minidump_stackwalk': True, 15:29:42 INFO - 'download_symbols': 'ondemand', 15:29:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:42 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:42 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:42 INFO - 'installer_path': 'installer.exe', 15:29:42 INFO - 'log_level': 'info', 15:29:42 INFO - 'log_name': 'talos', 15:29:42 INFO - 'log_to_console': True, 15:29:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:29:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:29:42 INFO - 'opt_config_files': (), 15:29:42 INFO - 'pip_index': False, 15:29:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:42 INFO - 'cmd': ('bash', 15:29:42 INFO - '-c', 15:29:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:42 INFO - 'enabled': True, 15:29:42 INFO - 'halt_on_failure': False, 15:29:42 INFO - 'name': 'check_screen_resolution'},), 15:29:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:42 INFO - 'cmd': ('bash', 15:29:42 INFO - '-c', 15:29:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:42 INFO - 'enabled': True, 15:29:42 INFO - 'halt_on_failure': False, 15:29:42 INFO - 'name': 'check_screen_resolution'},), 15:29:42 INFO - 'run_cmd_checks_enabled': True, 15:29:42 INFO - 'sps_profile': False, 15:29:42 INFO - 'sps_profile_interval': 0, 15:29:42 INFO - 'suite': 'dromaeojs', 15:29:42 INFO - 'system_bits': '32', 15:29:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:42 INFO - 'title': 't-yosemite-r7-0170', 15:29:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:42 INFO - 'use_talos_json': True, 15:29:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:42 INFO - 'work_dir': 'build'} 15:29:42 INFO - ##### 15:29:42 INFO - ##### Running clobber step. 15:29:42 INFO - ##### 15:29:42 INFO - Running pre-action listener: _resource_record_pre_action 15:29:42 INFO - Running main action method: clobber 15:29:42 INFO - rmtree: /builds/slave/test/build 15:29:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:44 INFO - Running post-action listener: _resource_record_post_action 15:29:44 INFO - ##### 15:29:44 INFO - ##### Finished clobber step (success) 15:29:44 INFO - ##### 15:29:44 INFO - ##### 15:29:44 INFO - ##### Running read-buildbot-config step. 15:29:44 INFO - ##### 15:29:44 INFO - Running pre-action listener: _resource_record_pre_action 15:29:44 INFO - Running main action method: read_buildbot_config 15:29:44 INFO - Using buildbot properties: 15:29:44 INFO - { 15:29:44 INFO - "project": "", 15:29:44 INFO - "product": "firefox", 15:29:44 INFO - "script_repo_revision": "production", 15:29:44 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:29:44 INFO - "repository": "", 15:29:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:29:44 INFO - "buildid": "20160509142802", 15:29:44 INFO - "pgo_build": "False", 15:29:44 INFO - "basedir": "/builds/slave/test", 15:29:44 INFO - "buildnumber": 6, 15:29:44 INFO - "slavename": "t-yosemite-r7-0170", 15:29:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:29:44 INFO - "platform": "yosemite_r7", 15:29:44 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:29:44 INFO - "branch": "fx-team", 15:29:44 INFO - "repo_path": "integration/fx-team", 15:29:44 INFO - "revision": "a3033ed384eb846bf37d9a4bf59a3728f15e9f74", 15:29:44 INFO - "stage_platform": "macosx64", 15:29:44 INFO - "builduid": "a4d3cdb17fab4cf5b05b6a4ff252e1a3", 15:29:44 INFO - "slavebuilddir": "test" 15:29:44 INFO - } 15:29:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:29:44 INFO - Running post-action listener: _resource_record_post_action 15:29:44 INFO - ##### 15:29:44 INFO - ##### Finished read-buildbot-config step (success) 15:29:44 INFO - ##### 15:29:44 INFO - ##### 15:29:44 INFO - ##### Running download-and-extract step. 15:29:44 INFO - ##### 15:29:44 INFO - Running pre-action listener: _resource_record_pre_action 15:29:44 INFO - Running main action method: download_and_extract 15:29:44 INFO - mkdir: /builds/slave/test/build/tests 15:29:44 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')]} 15:29:44 INFO - https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:45 INFO - Downloaded 1199 bytes. 15:29:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:29:45 INFO - Using the following test package requirements: 15:29:45 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:29:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:29:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:29:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'jsshell-mac.zip'], 15:29:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:29:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:29:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:29:45 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:29:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:29:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:29:45 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:29:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:29: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')]} 15:29:45 INFO - https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:46 INFO - Downloaded 18146507 bytes. 15:29:46 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'] 15:29:46 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 15:29:49 INFO - Return code: 0 15:29:49 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 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:49 INFO - https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:29:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:50 INFO - Downloaded 18146507 bytes. 15:29:50 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'] 15:29:50 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 15:29:54 INFO - Return code: 0 15:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:54 INFO - https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:29:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:29:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:29:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:55 INFO - Downloaded 10840152 bytes. 15:29:55 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'] 15:29:55 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 15:29:55 INFO - Return code: 0 15:29: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')]} 15:29:55 INFO - https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:29:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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 15:29:58 INFO - Downloaded 110413978 bytes. 15:29:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:58 INFO - mkdir: /builds/slave/test/properties 15:29:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:58 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:58 INFO - Contents: 15:29:58 INFO - build_url:https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:29:59 INFO - Running post-action listener: _resource_record_post_action 15:29:59 INFO - Running post-action listener: set_extra_try_arguments 15:29:59 INFO - ##### 15:29:59 INFO - ##### Finished download-and-extract step (success) 15:29:59 INFO - ##### 15:29:59 INFO - ##### 15:29:59 INFO - ##### Running populate-webroot step. 15:29:59 INFO - ##### 15:29:59 INFO - Running pre-action listener: _resource_record_pre_action 15:29:59 INFO - Running main action method: populate_webroot 15:29:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:59 INFO - '--tppagecycles', 15:29:59 INFO - '7'], 15:29:59 INFO - 'tests': ['tsvgm']}}, 15:29:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['damp', 'tps']}, 15:29:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'tests': ['damp', 'tps']}, 15:29:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['dromaeo_dom']}, 15:29:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['a11yr', 15:29:59 INFO - 'ts_paint', 15:29:59 INFO - 'tpaint', 15:29:59 INFO - 'sessionrestore', 15:29:59 INFO - 'sessionrestore_no_auto_restore', 15:29:59 INFO - 'tabpaint']}, 15:29:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['tsvgx', 15:29:59 INFO - 'tsvgr_opacity', 15:29:59 INFO - 'tart', 15:29:59 INFO - 'tscrollx', 15:29:59 INFO - 'cart']}, 15:29:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:59 INFO - 'tsvgr_opacity', 15:29:59 INFO - 'tart', 15:29:59 INFO - 'tscrollx', 15:29:59 INFO - 'cart']}, 15:29:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'talos_options': ['--disable-e10s'], 15:29:59 INFO - 'tests': ['tp5o']}, 15:29:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'tests': ['tp5o']}, 15:29:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'talos_options': ['--disable-e10s', 15:29:59 INFO - '--xperf_path', 15:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:59 INFO - 'tests': ['tp5n']}, 15:29:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:59 INFO - 'talos_options': ['--xperf_path', 15:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:59 INFO - 'tests': ['tp5n']}}, 15:29:59 INFO - 'talos.zip': {'path': '', 15:29:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:59 INFO - Running post-action listener: _resource_record_post_action 15:29:59 INFO - ##### 15:29:59 INFO - ##### Finished populate-webroot step (success) 15:29:59 INFO - ##### 15:29:59 INFO - ##### 15:29:59 INFO - ##### Running create-virtualenv step. 15:29:59 INFO - ##### 15:29:59 INFO - Running pre-action listener: _resource_record_pre_action 15:29:59 INFO - Running main action method: create_virtualenv 15:29:59 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:59 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 15:29:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:59 INFO - Using real prefix '/tools/python27' 15:29:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:30:00 INFO - Installing distribute.............................................................................................................................................................................................done. 15:30:03 INFO - Installing pip.................done. 15:30:03 INFO - Return code: 0 15:30:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:03 INFO - 'HOME': '/Users/cltbld', 15:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:03 INFO - 'LOGNAME': 'cltbld', 15:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:03 INFO - 'MOZ_NO_REMOTE': '1', 15:30:03 INFO - 'NO_EM_RESTART': '1', 15:30:03 INFO - 'PAGER': '/bin/cat', 15:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:03 INFO - 'PWD': '/builds/slave/test', 15:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:03 INFO - 'SHELL': '/bin/bash', 15:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:03 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:03 INFO - 'USER': 'cltbld', 15:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:03 INFO - 'XPC_FLAGS': '0x0', 15:30:03 INFO - 'XPC_SERVICE_NAME': '0', 15:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:03 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 15:30:03 INFO - Cleaning up... 15:30:03 INFO - Return code: 0 15:30:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:03 INFO - 'HOME': '/Users/cltbld', 15:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:03 INFO - 'LOGNAME': 'cltbld', 15:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:03 INFO - 'MOZ_NO_REMOTE': '1', 15:30:03 INFO - 'NO_EM_RESTART': '1', 15:30:03 INFO - 'PAGER': '/bin/cat', 15:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:03 INFO - 'PWD': '/builds/slave/test', 15:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:03 INFO - 'SHELL': '/bin/bash', 15:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:03 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:03 INFO - 'USER': 'cltbld', 15:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:03 INFO - 'XPC_FLAGS': '0x0', 15:30:03 INFO - 'XPC_SERVICE_NAME': '0', 15:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:04 INFO - Downloading/unpacking psutil>=0.7.1 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:06 INFO - Installing collected packages: psutil 15:30:06 INFO - Running setup.py install for psutil 15:30:07 INFO - building 'psutil._psutil_osx' extension 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 INFO - building 'psutil._psutil_posix' extension 15:30:07 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 15:30:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:30:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:30:07 INFO - ^ 15:30:08 INFO - 1 warning generated. 15:30:08 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 15:30:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:08 INFO - Successfully installed psutil 15:30:08 INFO - Cleaning up... 15:30:08 INFO - Return code: 0 15:30:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:08 INFO - 'HOME': '/Users/cltbld', 15:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PAGER': '/bin/cat', 15:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:08 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - 'XPC_FLAGS': '0x0', 15:30:08 INFO - 'XPC_SERVICE_NAME': '0', 15:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:30:10 INFO - Installing collected packages: mozsystemmonitor 15:30:10 INFO - Running setup.py install for mozsystemmonitor 15:30:11 INFO - Successfully installed mozsystemmonitor 15:30:11 INFO - Cleaning up... 15:30:11 INFO - Return code: 0 15:30:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:11 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 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:11 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 15:30:11 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 15:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:11 INFO - 'HOME': '/Users/cltbld', 15:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:11 INFO - 'LOGNAME': 'cltbld', 15:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:11 INFO - 'MOZ_NO_REMOTE': '1', 15:30:11 INFO - 'NO_EM_RESTART': '1', 15:30:11 INFO - 'PAGER': '/bin/cat', 15:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:11 INFO - 'PWD': '/builds/slave/test', 15:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:11 INFO - 'SHELL': '/bin/bash', 15:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:11 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:11 INFO - 'USER': 'cltbld', 15:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:11 INFO - 'XPC_FLAGS': '0x0', 15:30:11 INFO - 'XPC_SERVICE_NAME': '0', 15:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:11 INFO - Downloading/unpacking blobuploader==1.2.4 15:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:13 INFO - Downloading blobuploader-1.2.4.tar.gz 15:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:14 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 15:30:15 INFO - Downloading docopt-0.6.1.tar.gz 15:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:15 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:15 INFO - Running setup.py install for blobuploader 15:30:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:15 INFO - Running setup.py install for requests 15:30:15 INFO - Running setup.py install for docopt 15:30:16 INFO - Successfully installed blobuploader requests docopt 15:30:16 INFO - Cleaning up... 15:30:16 INFO - Return code: 0 15:30:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:16 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')]} 15:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:16 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')]} 15:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:16 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 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:16 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 15:30:16 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 15:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:16 INFO - 'HOME': '/Users/cltbld', 15:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:16 INFO - 'LOGNAME': 'cltbld', 15:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:16 INFO - 'MOZ_NO_REMOTE': '1', 15:30:16 INFO - 'NO_EM_RESTART': '1', 15:30:16 INFO - 'PAGER': '/bin/cat', 15:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:16 INFO - 'PWD': '/builds/slave/test', 15:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:16 INFO - 'SHELL': '/bin/bash', 15:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:16 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:16 INFO - 'USER': 'cltbld', 15:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:16 INFO - 'XPC_FLAGS': '0x0', 15:30:16 INFO - 'XPC_SERVICE_NAME': '0', 15:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ZL1Naj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-F6mE6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YO4JMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-X_ht1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-xzFMFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XInW3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-qoEeEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-SzToXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XAIwDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-LDtmPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-e5CDwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-R79H22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6S_t5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5KiJfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-DCpPmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rcDRo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-HQdmQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:18 INFO - Running setup.py install for manifestparser 15:30:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:18 INFO - Running setup.py install for mozcrash 15:30:18 INFO - Running setup.py install for mozdebug 15:30:18 INFO - Running setup.py install for mozdevice 15:30:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:18 INFO - Running setup.py install for mozfile 15:30:18 INFO - Running setup.py install for mozhttpd 15:30:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Running setup.py install for mozinfo 15:30:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Running setup.py install for mozInstall 15:30:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Running setup.py install for mozleak 15:30:19 INFO - Running setup.py install for mozlog 15:30:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Running setup.py install for moznetwork 15:30:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:19 INFO - Running setup.py install for mozprocess 15:30:19 INFO - Running setup.py install for mozprofile 15:30:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Running setup.py install for mozrunner 15:30:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Running setup.py install for mozscreenshot 15:30:20 INFO - Running setup.py install for moztest 15:30:20 INFO - Running setup.py install for mozversion 15:30:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:20 INFO - Cleaning up... 15:30:20 INFO - Return code: 0 15:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:20 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 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:20 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 15:30:20 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 15:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:20 INFO - 'HOME': '/Users/cltbld', 15:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:20 INFO - 'LOGNAME': 'cltbld', 15:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:20 INFO - 'MOZ_NO_REMOTE': '1', 15:30:20 INFO - 'NO_EM_RESTART': '1', 15:30:20 INFO - 'PAGER': '/bin/cat', 15:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:20 INFO - 'PWD': '/builds/slave/test', 15:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:20 INFO - 'SHELL': '/bin/bash', 15:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:20 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:20 INFO - 'USER': 'cltbld', 15:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:20 INFO - 'XPC_FLAGS': '0x0', 15:30:20 INFO - 'XPC_SERVICE_NAME': '0', 15:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:20 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-54IsLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-pUyDFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-M8XMR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-sUDr36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OFO6rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VZM0zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-cvvWUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ChW3JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-cARNL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:21 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)) 15:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:21 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Eg2QXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7O9xVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7jVQXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-N0rH1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-JcIn8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-stDXyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ofZRKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:22 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)) 15:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:22 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-POn2nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:22 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)) 15:30:22 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)) 15:30:22 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)) 15:30:22 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)) 15:30:22 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)) 15:30:22 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)) 15:30:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30:22 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 15:30:22 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 15:30:22 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 15:30:22 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 15:30:22 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 15:30:22 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 15:30:25 INFO - Downloading blessings-1.6.tar.gz 15:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:25 INFO - Installing collected packages: blessings 15:30:25 INFO - Running setup.py install for blessings 15:30:25 INFO - Successfully installed blessings 15:30:25 INFO - Cleaning up... 15:30:25 INFO - Return code: 0 15:30:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:25 INFO - Reading from file tmpfile_stdout 15:30:25 INFO - Current package versions: 15:30:25 INFO - blessings == 1.6 15:30:25 INFO - blobuploader == 1.2.4 15:30:25 INFO - docopt == 0.6.1 15:30:25 INFO - manifestparser == 1.1 15:30:25 INFO - mozInstall == 1.12 15:30:25 INFO - mozcrash == 0.17 15:30:25 INFO - mozdebug == 0.1 15:30:25 INFO - mozdevice == 0.48 15:30:25 INFO - mozfile == 1.2 15:30:25 INFO - mozhttpd == 0.7 15:30:25 INFO - mozinfo == 0.9 15:30:25 INFO - mozleak == 0.1 15:30:25 INFO - mozlog == 3.1 15:30:25 INFO - moznetwork == 0.27 15:30:25 INFO - mozprocess == 0.22 15:30:25 INFO - mozprofile == 0.28 15:30:25 INFO - mozrunner == 6.11 15:30:25 INFO - mozscreenshot == 0.1 15:30:25 INFO - mozsystemmonitor == 0.0 15:30:25 INFO - moztest == 0.7 15:30:25 INFO - mozversion == 1.4 15:30:25 INFO - psutil == 3.1.1 15:30:25 INFO - requests == 1.2.3 15:30:25 INFO - wsgiref == 0.1.2 15:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:25 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 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:25 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 15:30:25 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 15:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:25 INFO - 'HOME': '/Users/cltbld', 15:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:25 INFO - 'LOGNAME': 'cltbld', 15:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:25 INFO - 'MOZ_NO_REMOTE': '1', 15:30:25 INFO - 'NO_EM_RESTART': '1', 15:30:25 INFO - 'PAGER': '/bin/cat', 15:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:25 INFO - 'PWD': '/builds/slave/test', 15:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:25 INFO - 'SHELL': '/bin/bash', 15:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:25 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:25 INFO - 'USER': 'cltbld', 15:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:25 INFO - 'XPC_FLAGS': '0x0', 15:30:25 INFO - 'XPC_SERVICE_NAME': '0', 15:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 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)) 15:30:26 INFO - Cleaning up... 15:30:26 INFO - Return code: 0 15:30:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:26 INFO - 'HOME': '/Users/cltbld', 15:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:26 INFO - 'LOGNAME': 'cltbld', 15:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:26 INFO - 'MOZ_NO_REMOTE': '1', 15:30:26 INFO - 'NO_EM_RESTART': '1', 15:30:26 INFO - 'PAGER': '/bin/cat', 15:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:26 INFO - 'PWD': '/builds/slave/test', 15:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:26 INFO - 'SHELL': '/bin/bash', 15:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:26 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:26 INFO - 'USER': 'cltbld', 15:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:26 INFO - 'XPC_FLAGS': '0x0', 15:30:26 INFO - 'XPC_SERVICE_NAME': '0', 15:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:26 INFO - Downloading/unpacking jsonschema 15:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:30:28 INFO - Downloading/unpacking functools32 (from jsonschema) 15:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:30:29 INFO - warning: no files found matching '*.txt' 15:30:29 INFO - no previously-included directories found matching 'build' 15:30:29 INFO - no previously-included directories found matching 'dist' 15:30:29 INFO - no previously-included directories found matching '.git*' 15:30:29 INFO - Installing collected packages: jsonschema, functools32 15:30:29 INFO - Running setup.py install for functools32 15:30:29 INFO - warning: no files found matching '*.txt' 15:30:29 INFO - no previously-included directories found matching 'build' 15:30:29 INFO - no previously-included directories found matching 'dist' 15:30:29 INFO - no previously-included directories found matching '.git*' 15:30:29 INFO - Successfully installed jsonschema functools32 15:30:29 INFO - Cleaning up... 15:30:29 INFO - Return code: 0 15:30:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:29 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 0x101406a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10140c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff93bca3b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, '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.baOiYFxNHj/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.PznqPVE4Cz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 15:30:29 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 15:30:29 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 15:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:29 INFO - 'HOME': '/Users/cltbld', 15:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:29 INFO - 'LOGNAME': 'cltbld', 15:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:29 INFO - 'MOZ_NO_REMOTE': '1', 15:30:29 INFO - 'NO_EM_RESTART': '1', 15:30:29 INFO - 'PAGER': '/bin/cat', 15:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:29 INFO - 'PWD': '/builds/slave/test', 15:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:29 INFO - 'SHELL': '/bin/bash', 15:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:30:29 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:29 INFO - 'USER': 'cltbld', 15:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:29 INFO - 'XPC_FLAGS': '0x0', 15:30:29 INFO - 'XPC_SERVICE_NAME': '0', 15:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:29 INFO - Downloading/unpacking flake8 15:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:30:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:30:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:30:33 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 15:30:33 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 15:30:33 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 15:30:33 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 15:30:33 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 15:30:33 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 15:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:30:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:33 INFO - no previously-included directories found matching 'docs/_build' 15:30:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:30:33 INFO - Running setup.py install for pep8 15:30:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:33 INFO - no previously-included directories found matching 'docs/_build' 15:30:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:30:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:30:33 INFO - Cleaning up... 15:30:33 INFO - Return code: 0 15:30:33 INFO - Running post-action listener: _resource_record_post_action 15:30:33 INFO - Running post-action listener: _start_resource_monitoring 15:30:33 INFO - Starting resource monitoring. 15:30:33 INFO - ##### 15:30:33 INFO - ##### Finished create-virtualenv step (success) 15:30:33 INFO - ##### 15:30:33 INFO - ##### 15:30:33 INFO - ##### Running install step. 15:30:33 INFO - ##### 15:30:33 INFO - Running pre-action listener: _resource_record_pre_action 15:30:33 INFO - Running main action method: install 15:30:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:34 INFO - Reading from file tmpfile_stdout 15:30:34 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:34 INFO - Reading from file tmpfile_stdout 15:30:34 INFO - Output received: 15:30:34 INFO - Usage: mozinstall [options] installer 15:30:34 INFO - Options: 15:30:34 INFO - -h, --help show this help message and exit 15:30:34 INFO - -d DEST, --destination=DEST 15:30:34 INFO - Directory to install application into. [default: 15:30:34 INFO - "/builds/slave/test"] 15:30:34 INFO - --app=APP Application being installed. [default: firefox] 15:30:34 INFO - mkdir: /builds/slave/test/build/application 15:30:34 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'] 15:30:34 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 15:31:08 INFO - Reading from file tmpfile_stdout 15:31:08 INFO - Output received: 15:31:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:31:08 INFO - Running post-action listener: _resource_record_post_action 15:31:08 INFO - ##### 15:31:08 INFO - ##### Finished install step (success) 15:31:08 INFO - ##### 15:31:08 INFO - ##### 15:31:08 INFO - ##### Running run-tests step. 15:31:08 INFO - ##### 15:31:08 INFO - Running pre-action listener: _resource_record_pre_action 15:31:08 INFO - Running main action method: run_tests 15:31:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:31:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:31:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:31:08 INFO - 2016-05-09 15:31:08.019 screenresolution[1645:7399] starting screenresolution argv=screenresolution get 15:31:08 INFO - 2016-05-09 15:31:08.021 screenresolution[1645:7399] Display 0: 1600x1200x32@60 15:31:08 INFO - 2016-05-09 15:31:08.027 screenresolution[1646:7401] starting screenresolution argv=screenresolution list 15:31:08 INFO - Available Modes on Display 0 15:31:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:31:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:31:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:31:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:31:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:31:08 INFO - Intel Iris: 15:31:08 INFO - Chipset Model: Intel Iris 15:31:08 INFO - Type: GPU 15:31:08 INFO - Bus: Built-In 15:31:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:31:08 INFO - Vendor: Intel (0x8086) 15:31:08 INFO - Device ID: 0x0a2e 15:31:08 INFO - Revision ID: 0x0009 15:31:08 INFO - Displays: 15:31:08 INFO - Display: 15:31:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:31:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:31:08 INFO - Display Serial Number: 1600x1200 60 15:31:08 INFO - Main Display: Yes 15:31:08 INFO - Mirror: Off 15:31:08 INFO - Online: Yes 15:31:08 INFO - Rotation: Supported 15:31:08 INFO - Return code: 0 15:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:31:08 INFO - Python 2.7.3 15:31:08 INFO - Return code: 0 15:31:08 INFO - grabbing minidump binary from tooltool 15:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:08 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012aad30>, '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 0x1010cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:31:08 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 15:31:08 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 15:31:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:08 INFO - Return code: 0 15:31:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:31:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:31:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:31:09 INFO - Return code: 0 15:31:09 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/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 15:31:09 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/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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 15:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baOiYFxNHj/Render', 15:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:09 INFO - 'HOME': '/Users/cltbld', 15:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:09 INFO - 'LOGNAME': 'cltbld', 15:31:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:31:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:09 INFO - 'MOZ_NO_REMOTE': '1', 15:31:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:31:09 INFO - 'NO_EM_RESTART': '1', 15:31:09 INFO - 'PAGER': '/bin/cat', 15:31:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:09 INFO - 'PWD': '/builds/slave/test', 15:31:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:09 INFO - 'SHELL': '/bin/bash', 15:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PznqPVE4Cz/Listeners', 15:31:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 15:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:09 INFO - 'USER': 'cltbld', 15:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:09 INFO - 'XPC_FLAGS': '0x0', 15:31:09 INFO - 'XPC_SERVICE_NAME': '0', 15:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:09 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/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 15:31:09 INFO - mozversion application_buildid: 20160509142802 15:31:09 INFO - mozversion application_changeset: a3033ed384eb846bf37d9a4bf59a3728f15e9f74 15:31:09 INFO - mozversion application_display_name: Nightly 15:31:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:09 INFO - mozversion application_name: Firefox 15:31:09 INFO - mozversion application_remotingname: firefox 15:31:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:31:09 INFO - mozversion application_vendor: Mozilla 15:31:09 INFO - mozversion application_version: 49.0a1 15:31:09 INFO - mozversion platform_buildid: 20160509142802 15:31:09 INFO - mozversion platform_changeset: a3033ed384eb846bf37d9a4bf59a3728f15e9f74 15:31:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:31:09 INFO - mozversion platform_version: 49.0a1 15:31:09 INFO - using testdate: 1462833069 15:31:09 INFO - actual date: 1462833069 15:31:09 INFO - starting webserver on 'localhost:49270' 15:31:09 INFO - SUITE-START | Running 2 tests 15:31:09 INFO - TEST-START | dromaeo_css 15:31:09 INFO - operating with platform_type : mac_ 15:31:09 INFO - Initialising browser for dromaeo_css test... 15:31:09 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpsLGg5M/profile http://localhost:49270/getInfo.html) 15:31:11 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 15:31:11 INFO - PROCESS | 1664 | colorDepth:24 15:31:11 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 15:31:11 INFO - PROCESS | 1664 | __metrics 15:31:12 INFO - PROCESS | 1664 | 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" 15:31:12 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:31:12 INFO - TEST-INFO | 1664: exit 0 15:31:12 INFO - Browser initialized. 15:31:12 INFO - Running cycle 1/1 for dromaeo_css test... 15:31:12 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpsLGg5M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:24 INFO - PROCESS | 1666 | RSS: Main: 238870528 15:31:24 INFO - PROCESS | 1666 | 15:32:15 INFO - PROCESS | 1666 | 1462833135645 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] 15:34:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 15:34:28 INFO - PROCESS | 1666 | RSS: Main: 386781184 15:34:28 INFO - PROCESS | 1666 | 15:37:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 15:37:31 INFO - PROCESS | 1666 | RSS: Main: 375398400 15:37:31 INFO - PROCESS | 1666 | 15:40:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 15:40:34 INFO - PROCESS | 1666 | RSS: Main: 365826048 15:40:34 INFO - PROCESS | 1666 | 15:43:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 15:43:37 INFO - PROCESS | 1666 | RSS: Main: 368750592 15:43:37 INFO - PROCESS | 1666 | 15:46:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 15:46:41 INFO - PROCESS | 1666 | RSS: Main: 337485824 15:46:41 INFO - PROCESS | 1666 | 15:49:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 15:49:45 INFO - PROCESS | 1666 | RSS: Main: 377430016 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | __start_tp_report 15:49:45 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:49:45 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:49:45 INFO - PROCESS | 1666 | |i|pagename|runs| 15:49:45 INFO - PROCESS | 1666 | |0;dojo.html;2670.786337093032;3454.896353109407;3467.1852114753046;3500.7374446916488;3537.787732736036;5611.7755289788465;5648.068068768648;5660.632058916172;5757.2803399575105;5765.423457654238;5684.090545512719;5740.454656807607;5847.44449277319;5856.004479238529;5868.2664666916635;5124.718140502271;5159.535641792238;5196.870078248036;5228.451012643674;5301.655392399501;5268.841934741964;5322.92015619766;5330.546903513206;5342.98483288176;5361.678299302035;5151.793928242884;5215.973920130375;5228.73856307185;5229.267902493616;5259.973700131475;5183.403908550489;5231.372235331746;5268.998890210848;5272.051030814452;5279.868003299948;4259.616634502879;4523.638108951276;5150.793968241266;5155.432902380735;5182.378114626231;5658.377578466366;5692.8861422771315;5713.3429655589725;5722.513586345166;5724.198612194296;6035.6076855004285;6067.804899959568;6198.4731297839735;6281.811545653638;6290.276618188921;6279.591826531261;6285.088662143963;6315.74737010519;6345.048242763576;6372.872542549169;5858.326292476378;5889.469947704684;5946.643201407929;5954.940450595525;5956.37458066901;6247.375262473716;6252.999520076766;6278.183836101278;6285.308616052231;6342.936570634327;6168.814935551941;6183.81448556544;6184.196054512884;6186.257649082139;6195.659238741912;6280.152179455807;6297.27581328144;6299.307076221612;6308.337624549448;6319;6139.907901381483;6155.599886007395;6184;6215.098810672427;6239.1577137085915;6247.562670613013;6305.495560355161;6314.989601663712;6319.146915166394;6327.936720632735;4968.652194346361;5010.724410157476;5013.623978201649;5021.297018417425;5030.421501527371;3883.689304855605;4451.77741112943;4473.664475164375;4477.283634618446;4490.236659767847;5749.913751293734;5761.625494342854;5770.480656740913;5780.450857168562;5780.884382312414;6262.185915830913;6286.685665716695;6300.495960323163;6304.621722696653;6308.589941653778;5647.661140331593;5704.030314846486;5712.685802280915;5727.828165155052;5769.798057067983;5391.460853914656;5414.18787181918;5458.536024437888;5471.398146203915;5473;6323.969274492451;6364.172657554488;6368.076628888785;6369.90445143314;6377.936220637827;6300.59046161998;6308.968455157695;6315.55791094619;6323.9683801580695;6369.9681501592195;6208.596441231305;6241.812745617639;6273.37266273369;6290.21372328459;6360.904586431115;6183.288921774054;6267.467265282502;6269.310375858743;6272.905906411499;6285.654289013965;6102.4812890902995;6325.841853953688;6332.113504109337;6351.364863513611;6351.777687780906;5984.700764961647;6033.607815491979;6078.7872424466705;6091.42131497507;6109.222480666452;6138.109974053827;6178.66017369049;6185.876282474415;6203.100550420254;6210.813675589739;5982.970085149546;6003.309619393825;6006.880916394919;6053.031514957585;6058.697065146813;6053.545984051126;6152.415520525543;6160.199174107337;6178.474829639531;6205.162303089025;5937.881242375042;5975.40245975399;6058;6071.908921366271;6086.421789929949 15:49:45 INFO - PROCESS | 1666 | |1;ext.html;515.3455112007748;539.5252178083286;561.9522340601048;569.9259096317484;588.5526999480394;25561.616575751355;35208.471872921924;42991.355129673044;44123.55876441231;46452.76773616135;44866.55133448665;45135.54864451355;45171.77414112933;45264.86837829056;45327.86680332993;8645.30837532999;9653.806923861519;9828.705138845846;9834.901650983471;9839.114479696862;8617.353698472609;11418.14363922705;11694.064474841993;11702.765944681101;11829.585964491245;11727.47226374811;11789.292642441438;11792.938635522802;11794.997425218866;11806.704832379173;7758;11278.421021057058;11540;11552.768944621144;11676.649700508955;11278.210525263235;11590.536378544932;11593;11599.362035088066;11609.129315301387;33334;40609;40966.79516602398;41001;41165.38251926223;2760.875760590778;3125.578046963676;3195.1053704962646;3800.0309920970253;4016.3172260715455;23401.648975265383;28593.28516787097;29222.70777292221;29268.70731292681;30337;2726.3184203949013;2748.244232835986;2753.366725653111;2831.405404864981;2882.0345184363264;433968;438493;440267;442554;444157.7792111051;34490.48264276038;36076.45885311723;64170.6791466049;64548.67725661342;64719.676401618635;37643.43534846979;38008.04979875525;38031.619683802805;38100.0474988122;38238.808805956345;15126.848731512764;15317.846821531864;15333.769993450107;15341.232938353038;15353.309101090477;11744.236624619543;11841.763164736656;11869.46587403569;12068.336241506626;12796.424160912782;7961.72133975308;8239.588020598947;8320.791980200544;8340.582970851434;8392.20274073962;3442.9483557746653;3473.7221022318154;3476.5480487536965;3479.5824501060033;3505.2463720300257;1056.3186744549723;1056.9418681972566;1097.2703152403562;1115.9776804464027;1189.2685998111099;28179.013734519194;28616.712247948893;29676.10971670853;30163.849180753954;30619.8469007658;1126.8253420719639;1190.6190019193775;1194.7192409783695;1209.4557449147742;1228.9201201921846;15594.9220253898;15626.843731562767;15643.139627320606;15972.281247343897;16097.275622597012;647037;650085;650205.7489712521;655444.722776383;660583;44732.3290150648;44945.10109797851;45060.54939450564;45102.32346514805;45214;1329.6011196640968;1455.657920388708;1485.123776971592;1506.3070987345943;1510.4562357551054;30134.54798177985;30198.698013019588;30227.697723023368;30335.512809626183;30391.240218994702;43838;54033.78611459575;54191.45808541864;54317.45682543124;54374;482246;491031;494982;498027;501827;23835.404114897265;24368.147114850894;24414.145504907243;27777.44445111127;28150.85924570364;45746.542534574226;47479.100835967554;48339;50341.49658503368;51367.20214792465;38044.72262076837;39492.80253598714;39607.45770491485;40417;40499.785006449856;37966.62033379631;38288.42567361548;38471.03822404462;38541.80729096336;39187.6081239184;38026;38333.233335333694;38709.80645096757;38824.80587597157;38902.80548597239;32174;33461.4942327591;34283.142921426166;34355;34390;22259.77740222577;24625.753742462344;24778.752212478365;25165.496690066462;25350.873245633655 15:49:45 INFO - PROCESS | 1666 | |2;jquery.html;355.23801445898545;395.12676983865674;397.7553804410289;406.34375483594005;414.45913083426115;19325.323613673525;21051;21266.14935402586;21328.89335553322;21370.358889233346;20573.794262057374;20709.689354659655;21558.137674493042;21596.89201553994;21787.346379608596;5848.976429124908;5930.229070220866;6071.392860713938;6112.113743507189;6121.418465245817;4153.7507749535125;4413.977930110345;4423.845165419211;5959.284885813709;6033.818985430444;4196.39152322913;5408.188771684259;6585.374389432996;6631.502637302197;6648.202215734119;5634.295713035871;6430.90353644691;6603.735850565971;6607.80176594703;6626.966865165691;3694.704423646102;5726.083826587767;5734.455226753452;5740.138979153118;5744.741486633112;21951.780482195132;24245.515089698107;24843.254702358958;25075.749242507525;25630;2127.478767701923;2154.375231182955;2225.6105181593157;2252;2284.7258329000624;14349.56951291463;14903.329350179269;14939;15057.096574205583;15200.695986080216;1756.0692832798636;1760.683077046131;1775.0680892531395;1782.1534770983678;1791.2297711983842;97129.5143524285;103972.48013759959;104715;105371.47314263457;105936;9484.430934143973;10114.494275286206;10304.845427318596;10373.688789336333;10476.0571548561;12608.495660173674;12637.810432843515;12711.237325760483;12712.110152289251;12717.173383730027;6823.69293381799;8218.506889586644;8239.588020598947;8390.454620449773;8403.159684031549;8646.221840034426;8693.782655433539;8726;8795.164459376478;8806.86789698155;3928.4696565963227;3958.6239307265764;4006.8797936061965;4052.2908491013955;4061.2080644274674;1790.713485842259;1839.733238680384;1929.5947850951177;1964.2339487599847;1975.7332760077493;932.7994481186578;935.1630290889616;951.9148171084969;1003.6186249225248;1018.9592416303265;18781.624367512573;19389.51526211856;20092.99535023243;20146.496337591383;20352;959.5154447004358;969.3795970578835;1002.8445590933417;1035.8549803027581;1037.2479952034714;10658.307210031324;10816;10826.891731082746;10834.67495975122;10844.67465976022;552250;562803;569181;569589;574076;73172;74274;109103;130217;139965.3001734985;1005.9647912323034;1019.8572199892021;1071.421432426481;1080.3949788118678;1081.216118314216;18477.353292634692;18853.24587016504;18942.810571894657;18995.540929494942;19019.04904754729;50667;50780;51165.48834511607;51808.96382072262;53894.730526348685;57522;74439.25560744324;80335.59832200801;83396.58301708453;83435;5239.659422137549;7904.881426778488;7910.169432209535;7975.601219939096;8307.584620769057;10114.342567733074;12164.635060948185;12205.389730513616;12475.93762031184;12621.179623324455;10530.789384212427;10729.839052414063;10788.892111079103;10856.294340867818;10979;10474.214433917334;10552.20858435605;10660.733481663021;10722.088622467178;10848.728781780519;10613;10677.489853262492;10947.507362168562;11085.390303533382;11151.107911367233;6620.702068407029;6950.235474097946;7490;7624.58064806441;7640.694372225049;10264.846027309446;10663.94668026686;10744.140468762324;11107.611233606785;11114.610988615359 15:49:45 INFO - PROCESS | 1666 | |3;mootools.html;1419.0563275421846;1579.9368025278989;1605.3498333175062;1860.5255659806746;2011.5373464103247;7727.768166954987;8530.317574594033;8791.252743516792;8821.558922053895;8913.509756963364;8514.148585141484;8762.824743505125;8765.16730910564;8767.649294028248;8773.122687731224;2069.3067822279554;2138.9412240940756;2191.2111639809655;2303.527776805751;2406.4344878953434;2427.78149966503;2454.619533972228;2455.938601534967;2460.7047154341503;2465.0509553821817;2366.124533922451;2382.797462215714;2420.007796803311;2422.1280339078007;2424.1757802347197;2093.3405977117236;2228.6545585434283;2287.885605719707;2296.311106667993;2352.1414683640432;2395.4131237846664;2407.7953373963232;2411.3368823573483;2412.3124909400913;2414.6498757680038;8627.611757470866;8697.347698922606;8706.129387061306;8706.869396959051;8711.520866352348;5035.647504674675;5076.6192535559985;5202.895942081175;5213.2701421800975;5225.163973764217;3331.700146986783;3398.9830050849587;3449.5170676105367;3453.2748122894222;3474.3746125697367;995.990040099597;996.8056229035341;999.6101520407047;1004.9346792458467;1013.4375421640987;11465.426728663533;11505.712357191054;11507.906748858844;11518.654440366803;11541.192116551845;4622.422197225347;4692.53074692528;4777.331173635694;4826.58973987215;4829.154897892855;9804.852927206097;9841.360311579867;9875.604975800887;9879.308448408543;9880.604775808888;5247.527722504993;5282.128448805904;5285.048691235537;5286;5289.894202115936;4285.97857010713;4457.04173602677;4705.388299521041;4774.809007639656;4789.281607758864;3851.8844434667003;3903.3559462688904;3949.3681011038097;3982.8406863725704;4015.8996025099023;1300.5448093167315;1304.4651692805903;1308.8167656528094;1317.1965101288206;1319.0634649399035;887.7780554861284;920.8204400141908;930.9767255818658;944.6127087893931;963.8120566489467;5102.464241254689;5527.115661494222;5578.24693666358;5633.859153521113;5774.2782152230975;448.0434272827472;457.780265472575;463.49710564037724;463.87707257576767;469.4507426311224;3855.2137625279747;3894.2600905827803;3990.3615421532995;3990.640842324147;3992.48097747291;10140.797184056426;10470.790584188273;10477.790444191225;10561;10584.629537966135;10037.648682296085;10293.53679084443;10454.477276136162;10523.473826308807;10574.788504229873;7971.242731940456;8061.4760040597175;8136.1863813618165;8147;8152.184781521919;5675.914861277001;5687.203791469115;5721.713914304351;5788.247527821524;5837.124431335334;9580;9656.662016829658;9806.509674516388;9826.852597210904;9844.9015509844;9453.149216570542;9466.668666596635;9959.05388988045;10052;10065.496725163859;6920.619365934922;7141.607211603412;7355.742549010973;7379.2251813558805;7600.619969001417;6939.82650433723;8340.958295208486;8408.159184081787;8421.410501264852;8434.620442079999;7333.413326933725;7346.485745997943;7494.025776649;7514.774556763306;7577.128630207597;7293.708251669874;7325.4505912057875;7349.375303099083;7368.926310737039;7489.400847932261;7029.29707029293;7057.682404291717;7093.858122837618;7102.751403701052;7111.431085513043;4830.975845120752;4969.105560998911;5001.4998500149695;5009.148444764472;5154.329937108152;4957.652964292465;4980.402351717817;5110.463401342954;5143.099957507496;5307.310049693515 15:49:45 INFO - PROCESS | 1666 | |4;prototype.html;250.7868311934855;294.6052289931491;306.41933535949374;311.83472759437507;313.17166095676936;4884.902301953959;5058;5114.386273647158;5207.453217412174;5317.76070076847;4595.345675556797;5007.715317276821;5015.664583052266;5213.843584692465;5238.188080847474;4021.959780402188;4043.3126368517424;4045.554988951213;4049.7367671101433;4058.289799285128;3194.872205111793;3514.7012503937194;3750.687259459181;3752.3245815753153;4073.63337299643;3384.475406312013;4012.6589239914642;4016.2369149861433;4022.4368588397647;4044.716869819114;3535.8232088395735;3984.521857377119;4027.677785777131;4028.2144981728575;4035.7982100895133;3212.791168574035;3328.484084966834;3503.6321186275327;3523.594786599548;3525.330187264437;4232.703710740249;4563.794629241659;4746.406699162605;4768.661936644363;5022.648414610979;4509.594136527729;4515.525869783658;4569.200389931749;4570.7257564546235;4585.931211031837;5190.870228244286;5442.945570544283;5474.562035037227;5485.917711234335;5512.283403157604;3464.4630082337276;3530.481892786101;3615.94576081359;3823.235352929425;3894.4742459768136;4898.583620392271;5220.791168353261;5757.0351269096445;5768.307803063659;5784.971075144639;3391.9491207631904;3834.9424758628643;3840.6735427488416;3842.2315536892734;3843.8846834595006;3180;3710.7959062251834;3759.661630453272;3913.6086391360636;3922.5489068756888;3690.0959264979715;3806.705720055139;4275.38729773341;4533.342665313511;4565.817367305271;5468.507834294933;5492.917606235909;5514.889702205934;5537.757208330632;5560.36055853582;1083.2525557365393;1191.8271850581616;1216.2823933879054;1225.4301749686438;1250.0124901328031;777.4713195027323;823.1192623892377;825.195434451405;830.9198042544667;850.8043150075517;4099;4505.526919673387;4777.187878060738;4827.324174615556;4832.347633069561;4540.79566419512;4644.908446515066;5090.956353947432;5139.22911563261;5316;4439.378487011821;4477.776111194493;4780.521947805191;4789.592884604847;4799.048585120196;2536.9238922832346;2775.9028434004713;2803.327201471673;2810.803243772957;2829.0522674903727;4404.493483249404;5475.850071484953;5565.415631358649;5592.133219350926;5633.690147041952;5039.168537191395;5170.741462926839;5171.939752350759;5488.7530061147345;5536.86157846049;5528.645481856971;5545.279113715272;5577.972110139423;5586.608937374426;5598.97200514003;4897.824522114595;5155.891483331102;5254.97372513135;5468.91796623059;5716.199732037435;5099.36257967754;5536.923653629889;5565.517964804745;5633.521150702072;5638.407967163389;5074.873128171826;5210.322658054429;5865.882682346414;5888.764449422147;5975.372585878594;3003;3437.3125374924616;3601.243738814773;3870.3420418528353;3876.8449262029203;4885.606053033916;4978.3571421430115;5634.464725851038;5676.318841739017;5684.640874037228;4522.4573051234065;5499.377683583272;5505.394406615349;5540.168974653673;5549.833504994856;5095.859738909593;5169.61227907916;5258.316418865523;5491.615586908878;5519.834404967857;5026.84919452417;5156.4327923927585;5261.316028916216;5316.920246196233;5454.809081682122;5100.438951715382;5523;5532.28080349552;5537.2524709165355;5545.500904918576;5126.820561280487;5354.491323324278;5422.213779001943;5441.945580544302;5447.673139611636 15:49:45 INFO - PROCESS | 1666 | |5;yui.html;663.3896814930264;767.3860911270982;773.5165521549031;775.3951917504347;794.6875139691165;14857.182854942974;15341.23293835308;15518.146501942389;15525.922370388133;15526.990745601557;13423.42927685012;13639.386227619756;13670.384832682528;13681.768640822324;13695.657608559792;897.878786363839;917.6375331743957;920.7559996600907;923.7044145873323;924.9630014799434;904.8778414914001;916.7066538707617;924.36219008884;925.865749466327;928.4197376639601;980.2011360741009;980.7989362180735;982.1111893736145;983.1348413396237;983.7393090830935;969.1374676537887;973.8587904753806;974.3277138774223;975.5805003848307;975.6146322202691;727.1528669100492;727.5125665803924;727.8689835829549;730.6529398535706;731.6597782031381;13571.1857288563;13605.523806666719;13607.863921360758;13627;13632.182069075887;11012.063974562172;11097.223194376396;11296.887031129665;11354.48904799286;11430.828537571942;9216.815663686688;9338.112879276458;9369.812603747954;9403.059694030611;9704.02959704031;3161.1780936956557;3348.983255083709;3403.064157356722;3460.0830779843363;3467.6705712957228;63624.363756362305;63804.680976595286;66046.67906641889;66732.66534669328;74830.62584687042;9534.90465095354;9724.756881077888;9757.804843903225;9913.80172396548;10039.598416063422;21987;22169.44576385571;22171.445713857283;22181.889090554443;22437.32688019362;7639.656215470317;7682.308592226726;7788.14330423653;7802.609869506502;8001.279884810278;3602.1534939289245;4062.3297155969526;4118.75287482752;4166.250074986531;4391.67062470316;3377.9493307600405;3472.982635086859;3474.2183008822813;3479.965200347964;3481.2515309208643;626.5582764151262;643.0226056394348;645.6190847400056;650.5641220382355;652.1880259077384;480.86776136562725;521.5097808060411;562.1511517608359;585.8740370820294;593.2495393327445;7249.818754531074;7380.261973802577;7425.9628701856145;7606.3914886808925;7746.728864489716;516.8837011672418;530.9973450132802;569.8803251317187;590.3093380744539;596.6151832068292;3571.482030326178;3758.3047136279874;3763.7553559018575;3807.5430948286385;3849.4995650565247;83804;85564;85797.57101214454;86545;87339.56330218309;77963;78108;78565.82151267736;78970.60514697505;79888.60055699684;69723;70103.64948175226;70308.59382812417;70542;70832.64583677049;7326.194118647051;7424.1462231844025;7498.512596681198;7508.0990281166605;7513.586752728653;43570;43952.78023609862;44134.55865441304;45261.77369113133;45629;57469.42530574641;59515.40484595099;59591;60091.39908600858;64266.35733642604;10209.489525523843;10399.53202105892;10473.162147028372;10486.056254936788;10655;10886.29239099456;11173.832392514281;11411.315321080761;11441.713957150805;11881.702957426134;9118;10197.643082492077;10457.843132353173;10478.393909993405;10691.25161238737;10448.05967462932;10612.575496980035;10736.30214036085;10794.08250298702;10816.296940698829;10188.847167292644;10382.532786024496;10494.685159444924;10503.632372866912;10507.527161277914;8365.33077353798;8387.161283871565;8393.412461127908;8690.435121717068;8739.825203496022;8383.455075420079;8424.705135320235;8445.155484451505;8557.229849313591;8618.870716939377 15:49:45 INFO - PROCESS | 1666 | __end_tp_report 15:49:45 INFO - PROCESS | 1666 | __start_cc_report 15:49:45 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1463 15:49:45 INFO - PROCESS | 1666 | __end_cc_report 15:49:45 INFO - PROCESS | 1666 | __startTimestamp1462834185086__endTimestamp 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:49:45 INFO - PROCESS | 1666 | Number of tests: 6 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5774.59 Median:6035.61 stddev:673.30 (11.2%) stddev-sans-first:629.81 15:49:45 INFO - PROCESS | 1666 | Values: 2670.8 3454.9 3467.2 3500.7 3537.8 5611.8 5648.1 5660.6 5757.3 5765.4 5684.1 5740.5 5847.4 5856.0 5868.3 5124.7 5159.5 5196.9 5228.5 5301.7 5268.8 5322.9 5330.5 5343.0 5361.7 5151.8 5216.0 5228.7 5229.3 5260.0 5183.4 5231.4 5269.0 5272.1 5279.9 4259.6 4523.6 5150.8 5155.4 5182.4 5658.4 5692.9 5713.3 5722.5 5724.2 6035.6 6067.8 6198.5 6281.8 6290.3 6279.6 6285.1 6315.7 6345.0 6372.9 5858.3 5889.5 5946.6 5954.9 5956.4 6247.4 6253.0 6278.2 6285.3 6342.9 6168.8 6183.8 6184.2 6186.3 6195.7 6280.2 6297.3 6299.3 6308.3 6319.0 6139.9 6155.6 6184.0 6215.1 6239.2 6247.6 6305.5 6315.0 6319.1 6327.9 4968.7 5010.7 5013.6 5021.3 5030.4 3883.7 4451.8 4473.7 4477.3 4490.2 5749.9 5761.6 5770.5 5780.5 5780.9 6262.2 6286.7 6300.5 6304.6 6308.6 5647.7 5704.0 5712.7 5727.8 5769.8 5391.5 5414.2 5458.5 5471.4 5473.0 6324.0 6364.2 6368.1 6369.9 6377.9 6300.6 6309.0 6315.6 6324.0 6370.0 6208.6 6241.8 6273.4 6290.2 6360.9 6183.3 6267.5 6269.3 6272.9 6285.7 6102.5 6325.8 6332.1 6351.4 6351.8 5984.7 6033.6 6078.8 6091.4 6109.2 6138.1 6178.7 6185.9 6203.1 6210.8 5983.0 6003.3 6006.9 6053.0 6058.7 6053.5 6152.4 6160.2 6178.5 6205.2 5937.9 5975.4 6058.0 6071.9 6086.4 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:65408.05 Median:3475.14 stddev:143462.09 (4128.2%) stddev-sans-first:143781.80 15:49:45 INFO - PROCESS | 1666 | Values: 515.3 539.5 562.0 569.9 588.6 25561.6 35208.5 42991.4 44123.6 46452.8 44866.6 45135.5 45171.8 45264.9 45327.9 8645.3 9653.8 9828.7 9834.9 9839.1 8617.4 11418.1 11694.1 11702.8 11829.6 11727.5 11789.3 11792.9 11795.0 11806.7 7758.0 11278.4 11540.0 11552.8 11676.6 11278.2 11590.5 11593.0 11599.4 11609.1 33334.0 40609.0 40966.8 41001.0 41165.4 2760.9 3125.6 3195.1 3800.0 4016.3 23401.6 28593.3 29222.7 29268.7 30337.0 2726.3 2748.2 2753.4 2831.4 2882.0 433968.0 438493.0 440267.0 442554.0 444157.8 34490.5 36076.5 64170.7 64548.7 64719.7 37643.4 38008.0 38031.6 38100.0 38238.8 15126.8 15317.8 15333.8 15341.2 15353.3 11744.2 11841.8 11869.5 12068.3 12796.4 7961.7 8239.6 8320.8 8340.6 8392.2 3442.9 3473.7 3476.5 3479.6 3505.2 1056.3 1056.9 1097.3 1116.0 1189.3 28179.0 28616.7 29676.1 30163.8 30619.8 1126.8 1190.6 1194.7 1209.5 1228.9 15594.9 15626.8 15643.1 15972.3 16097.3 647037.0 650085.0 650205.7 655444.7 660583.0 44732.3 44945.1 45060.5 45102.3 45214.0 1329.6 1455.7 1485.1 1506.3 1510.5 30134.5 30198.7 30227.7 30335.5 30391.2 43838.0 54033.8 54191.5 54317.5 54374.0 482246.0 491031.0 494982.0 498027.0 501827.0 23835.4 24368.1 24414.1 27777.4 28150.9 45746.5 47479.1 48339.0 50341.5 51367.2 38044.7 39492.8 39607.5 40417.0 40499.8 37966.6 38288.4 38471.0 38541.8 39187.6 38026.0 38333.2 38709.8 38824.8 38902.8 32174.0 33461.5 34283.1 34355.0 34390.0 22259.8 24625.8 24778.8 25165.5 25350.9 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:32884.50 Median:21577.51 stddev:93948.29 (435.4%) stddev-sans-first:94180.09 15:49:45 INFO - PROCESS | 1666 | Values: 355.2 395.1 397.8 406.3 414.5 19325.3 21051.0 21266.1 21328.9 21370.4 20573.8 20709.7 21558.1 21596.9 21787.3 5849.0 5930.2 6071.4 6112.1 6121.4 4153.8 4414.0 4423.8 5959.3 6033.8 4196.4 5408.2 6585.4 6631.5 6648.2 5634.3 6430.9 6603.7 6607.8 6627.0 3694.7 5726.1 5734.5 5740.1 5744.7 21951.8 24245.5 24843.3 25075.7 25630.0 2127.5 2154.4 2225.6 2252.0 2284.7 14349.6 14903.3 14939.0 15057.1 15200.7 1756.1 1760.7 1775.1 1782.2 1791.2 97129.5 103972.5 104715.0 105371.5 105936.0 9484.4 10114.5 10304.8 10373.7 10476.1 12608.5 12637.8 12711.2 12712.1 12717.2 6823.7 8218.5 8239.6 8390.5 8403.2 8646.2 8693.8 8726.0 8795.2 8806.9 3928.5 3958.6 4006.9 4052.3 4061.2 1790.7 1839.7 1929.6 1964.2 1975.7 932.8 935.2 951.9 1003.6 1019.0 18781.6 19389.5 20093.0 20146.5 20352.0 959.5 969.4 1002.8 1035.9 1037.2 10658.3 10816.0 10826.9 10834.7 10844.7 552250.0 562803.0 569181.0 569589.0 574076.0 73172.0 74274.0 109103.0 130217.0 139965.3 1006.0 1019.9 1071.4 1080.4 1081.2 18477.4 18853.2 18942.8 18995.5 19019.0 50667.0 50780.0 51165.5 51809.0 53894.7 57522.0 74439.3 80335.6 83396.6 83435.0 5239.7 7904.9 7910.2 7975.6 8307.6 10114.3 12164.6 12205.4 12475.9 12621.2 10530.8 10729.8 10788.9 10856.3 10979.0 10474.2 10552.2 10660.7 10722.1 10848.7 10613.0 10677.5 10947.5 11085.4 11151.1 6620.7 6950.2 7490.0 7624.6 7640.7 10264.8 10663.9 10744.1 11107.6 11114.6 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5629.29 Median:5056.13 stddev:3153.49 (62.4%) stddev-sans-first:3146.47 15:49:45 INFO - PROCESS | 1666 | Values: 1419.1 1579.9 1605.3 1860.5 2011.5 7727.8 8530.3 8791.3 8821.6 8913.5 8514.1 8762.8 8765.2 8767.6 8773.1 2069.3 2138.9 2191.2 2303.5 2406.4 2427.8 2454.6 2455.9 2460.7 2465.1 2366.1 2382.8 2420.0 2422.1 2424.2 2093.3 2228.7 2287.9 2296.3 2352.1 2395.4 2407.8 2411.3 2412.3 2414.6 8627.6 8697.3 8706.1 8706.9 8711.5 5035.6 5076.6 5202.9 5213.3 5225.2 3331.7 3399.0 3449.5 3453.3 3474.4 996.0 996.8 999.6 1004.9 1013.4 11465.4 11505.7 11507.9 11518.7 11541.2 4622.4 4692.5 4777.3 4826.6 4829.2 9804.9 9841.4 9875.6 9879.3 9880.6 5247.5 5282.1 5285.0 5286.0 5289.9 4286.0 4457.0 4705.4 4774.8 4789.3 3851.9 3903.4 3949.4 3982.8 4015.9 1300.5 1304.5 1308.8 1317.2 1319.1 887.8 920.8 931.0 944.6 963.8 5102.5 5527.1 5578.2 5633.9 5774.3 448.0 457.8 463.5 463.9 469.5 3855.2 3894.3 3990.4 3990.6 3992.5 10140.8 10470.8 10477.8 10561.0 10584.6 10037.6 10293.5 10454.5 10523.5 10574.8 7971.2 8061.5 8136.2 8147.0 8152.2 5675.9 5687.2 5721.7 5788.2 5837.1 9580.0 9656.7 9806.5 9826.9 9844.9 9453.1 9466.7 9959.1 10052.0 10065.5 6920.6 7141.6 7355.7 7379.2 7600.6 6939.8 8341.0 8408.2 8421.4 8434.6 7333.4 7346.5 7494.0 7514.8 7577.1 7293.7 7325.5 7349.4 7368.9 7489.4 7029.3 7057.7 7093.9 7102.8 7111.4 4831.0 4969.1 5001.5 5009.1 5154.3 4957.7 4980.4 5110.5 5143.1 5307.3 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4373.86 Median:4891.72 stddev:1353.91 (27.7%) stddev-sans-first:1321.87 15:49:45 INFO - PROCESS | 1666 | Values: 250.8 294.6 306.4 311.8 313.2 4884.9 5058.0 5114.4 5207.5 5317.8 4595.3 5007.7 5015.7 5213.8 5238.2 4022.0 4043.3 4045.6 4049.7 4058.3 3194.9 3514.7 3750.7 3752.3 4073.6 3384.5 4012.7 4016.2 4022.4 4044.7 3535.8 3984.5 4027.7 4028.2 4035.8 3212.8 3328.5 3503.6 3523.6 3525.3 4232.7 4563.8 4746.4 4768.7 5022.6 4509.6 4515.5 4569.2 4570.7 4585.9 5190.9 5442.9 5474.6 5485.9 5512.3 3464.5 3530.5 3615.9 3823.2 3894.5 4898.6 5220.8 5757.0 5768.3 5785.0 3391.9 3834.9 3840.7 3842.2 3843.9 3180.0 3710.8 3759.7 3913.6 3922.5 3690.1 3806.7 4275.4 4533.3 4565.8 5468.5 5492.9 5514.9 5537.8 5560.4 1083.3 1191.8 1216.3 1225.4 1250.0 777.5 823.1 825.2 830.9 850.8 4099.0 4505.5 4777.2 4827.3 4832.3 4540.8 4644.9 5091.0 5139.2 5316.0 4439.4 4477.8 4780.5 4789.6 4799.0 2536.9 2775.9 2803.3 2810.8 2829.1 4404.5 5475.9 5565.4 5592.1 5633.7 5039.2 5170.7 5171.9 5488.8 5536.9 5528.6 5545.3 5578.0 5586.6 5599.0 4897.8 5155.9 5255.0 5468.9 5716.2 5099.4 5536.9 5565.5 5633.5 5638.4 5074.9 5210.3 5865.9 5888.8 5975.4 3003.0 3437.3 3601.2 3870.3 3876.8 4885.6 4978.4 5634.5 5676.3 5684.6 4522.5 5499.4 5505.4 5540.2 5549.8 5095.9 5169.6 5258.3 5491.6 5519.8 5026.8 5156.4 5261.3 5316.9 5454.8 5100.4 5523.0 5532.3 5537.3 5545.5 5126.8 5354.5 5422.2 5441.9 5447.7 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17116.97 Median:63714.52 stddev:23883.33 (37.5%) stddev-sans-first:23918.37 15:49:45 INFO - PROCESS | 1666 | Values: 663.4 767.4 773.5 775.4 794.7 14857.2 15341.2 15518.1 15525.9 15527.0 13423.4 13639.4 13670.4 13681.8 13695.7 897.9 917.6 920.8 923.7 925.0 904.9 916.7 924.4 925.9 928.4 980.2 980.8 982.1 983.1 983.7 969.1 973.9 974.3 975.6 975.6 727.2 727.5 727.9 730.7 731.7 13571.2 13605.5 13607.9 13627.0 13632.2 11012.1 11097.2 11296.9 11354.5 11430.8 9216.8 9338.1 9369.8 9403.1 9704.0 3161.2 3349.0 3403.1 3460.1 3467.7 63624.4 63804.7 66046.7 66732.7 74830.6 9534.9 9724.8 9757.8 9913.8 10039.6 21987.0 22169.4 22171.4 22181.9 22437.3 7639.7 7682.3 7788.1 7802.6 8001.3 3602.2 4062.3 4118.8 4166.3 4391.7 3377.9 3473.0 3474.2 3480.0 3481.3 626.6 643.0 645.6 650.6 652.2 480.9 521.5 562.2 585.9 593.2 7249.8 7380.3 7426.0 7606.4 7746.7 516.9 531.0 569.9 590.3 596.6 3571.5 3758.3 3763.8 3807.5 3849.5 83804.0 85564.0 85797.6 86545.0 87339.6 77963.0 78108.0 78565.8 78970.6 79888.6 69723.0 70103.6 70308.6 70542.0 70832.6 7326.2 7424.1 7498.5 7508.1 7513.6 43570.0 43952.8 44134.6 45261.8 45629.0 57469.4 59515.4 59591.0 60091.4 64266.4 10209.5 10399.5 10473.2 10486.1 10655.0 10886.3 11173.8 11411.3 11441.7 11881.7 9118.0 10197.6 10457.8 10478.4 10691.3 10448.1 10612.6 10736.3 10794.1 10816.3 10188.8 10382.5 10494.7 10503.6 10507.5 8365.3 8387.2 8393.4 8690.4 8739.8 8383.5 8424.7 8445.2 8557.2 8618.9 15:49:45 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:49:45 INFO - PROCESS | 1666 | 15:49:45 INFO - TEST-INFO | 1666: exit 0 15:49:45 INFO - TEST-OK | dromaeo_css | took 1116014ms 15:49:45 INFO - TEST-START | kraken 15:49:45 INFO - operating with platform_type : mac_ 15:49:45 INFO - Initialising browser for kraken test... 15:49:45 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpqQcnYP/profile http://localhost:49270/getInfo.html) 15:49:48 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 15:49:48 INFO - PROCESS | 1676 | colorDepth:24 15:49:48 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 15:49:48 INFO - PROCESS | 1676 | __metrics 15:49:48 INFO - PROCESS | 1676 | 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" 15:49:48 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:49:48 INFO - TEST-INFO | 1676: exit 0 15:49:48 INFO - Browser initialized. 15:49:48 INFO - Running cycle 1/1 for kraken test... 15:49:48 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpqQcnYP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:00 INFO - PROCESS | 1678 | RSS: Main: 239120384 15:50:00 INFO - PROCESS | 1678 | 15:50:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:04 INFO - PROCESS | 1678 | RSS: Main: 286584832 15:50:04 INFO - PROCESS | 1678 | 15:50:07 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 15:50:07 INFO - PROCESS | 1678 | RSS: Main: 295346176 15:50:07 INFO - PROCESS | 1678 | 15:50:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 15:50:11 INFO - PROCESS | 1678 | RSS: Main: 381792256 15:50:11 INFO - PROCESS | 1678 | 15:50:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 15:50:14 INFO - PROCESS | 1678 | RSS: Main: 283111424 15:50:14 INFO - PROCESS | 1678 | 15:50:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:17 INFO - PROCESS | 1678 | RSS: Main: 286449664 15:50:17 INFO - PROCESS | 1678 | 15:50:20 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:21 INFO - PROCESS | 1678 | RSS: Main: 323530752 15:50:21 INFO - PROCESS | 1678 | 15:50:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 15:50:24 INFO - PROCESS | 1678 | RSS: Main: 327499776 15:50:24 INFO - PROCESS | 1678 | 15:50:28 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 15:50:28 INFO - PROCESS | 1678 | RSS: Main: 325115904 15:50:28 INFO - PROCESS | 1678 | 15:50:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:31 INFO - PROCESS | 1678 | RSS: Main: 316014592 15:50:31 INFO - PROCESS | 1678 | 15:50:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:33 INFO - PROCESS | 1678 | RSS: Main: 322310144 15:50:33 INFO - PROCESS | 1678 | 15:50:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:36 INFO - PROCESS | 1678 | RSS: Main: 324546560 15:50:36 INFO - PROCESS | 1678 | 15:50:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:50:40 INFO - PROCESS | 1678 | RSS: Main: 329240576 15:50:40 INFO - PROCESS | 1678 | 15:50:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:50:43 INFO - PROCESS | 1678 | RSS: Main: 326885376 15:50:43 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 15:50:45 INFO - PROCESS | 1678 | RSS: Main: 373268480 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | __start_tp_report 15:50:45 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 15:50:45 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 15:50:45 INFO - PROCESS | 1678 | |i|pagename|runs| 15:50:45 INFO - PROCESS | 1678 | |0;ai-astar;114;106;112;112;111;113;111;112;112;111 15:50:45 INFO - PROCESS | 1678 | |1;audio-beat-detection;155;115;140;191;118;139;135;115;166;117 15:50:45 INFO - PROCESS | 1678 | |2;audio-dft;185;184;186;185;183;185;186;186;184;181 15:50:45 INFO - PROCESS | 1678 | |3;audio-fft;76;76;58;124;57;62;73;83;113;57 15:50:45 INFO - PROCESS | 1678 | |4;audio-oscillator;90;101;132;80;76;76;101;79;103;139 15:50:45 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;85;88;86;89;84;88;85;88;85 15:50:45 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;94;95;94;97;94;95;137;152;93 15:50:45 INFO - PROCESS | 1678 | |7;imaging-desaturate;85;110;140;122;84;85;83;84;99;85 15:50:45 INFO - PROCESS | 1678 | |8;json-parse-financial;60;61;61;59;60;60;59;121;60;57 15:50:45 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;64;63;76;77;71;67;68;68;67;68 15:50:45 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;85;85;85;84;85;84;80;85;80;85 15:50:45 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;143;148;142;138;137;136;147;151;228;139 15:50:45 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;169;167;167;169;168;167;165;169;167;166 15:50:45 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;68;67;66;66;65;64;66;66;66;65 15:50:45 INFO - PROCESS | 1678 | __end_tp_report 15:50:45 INFO - PROCESS | 1678 | __start_cc_report 15:50:45 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2253 15:50:45 INFO - PROCESS | 1678 | __end_cc_report 15:50:45 INFO - PROCESS | 1678 | __startTimestamp1462834245714__endTimestamp 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | ------- Summary: start ------- 15:50:45 INFO - PROCESS | 1678 | Number of tests: 14 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.12 (1.9%) stddev-sans-first:2.03 15:50:45 INFO - PROCESS | 1678 | Values: 114.0 106.0 112.0 112.0 111.0 113.0 111.0 112.0 112.0 111.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:139.50 stddev:25.35 (18.2%) stddev-sans-first:26.22 15:50:45 INFO - PROCESS | 1678 | Values: 155.0 115.0 140.0 191.0 118.0 139.0 135.0 115.0 166.0 117.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 15:50:45 INFO - PROCESS | 1678 | Values: 185.0 184.0 186.0 185.0 183.0 185.0 186.0 186.0 184.0 181.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:77.90 Median:67.50 stddev:23.42 (34.7%) stddev-sans-first:24.83 15:50:45 INFO - PROCESS | 1678 | Values: 76.0 76.0 58.0 124.0 57.0 62.0 73.0 83.0 113.0 57.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:76.00 stddev:22.60 (29.7%) stddev-sans-first:23.80 15:50:45 INFO - PROCESS | 1678 | Values: 90.0 101.0 132.0 80.0 76.0 76.0 101.0 79.0 103.0 139.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 15:50:45 INFO - PROCESS | 1678 | Values: 88.0 85.0 88.0 86.0 89.0 84.0 88.0 85.0 88.0 85.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:94.00 stddev:21.46 (22.8%) stddev-sans-first:22.36 15:50:45 INFO - PROCESS | 1678 | Values: 93.0 94.0 95.0 94.0 97.0 94.0 95.0 137.0 152.0 93.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:84.50 stddev:20.02 (23.7%) stddev-sans-first:20.70 15:50:45 INFO - PROCESS | 1678 | Values: 85.0 110.0 140.0 122.0 84.0 85.0 83.0 84.0 99.0 85.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:60.00 stddev:19.43 (32.4%) stddev-sans-first:20.49 15:50:45 INFO - PROCESS | 1678 | Values: 60.0 61.0 61.0 59.0 60.0 60.0 59.0 121.0 60.0 57.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:68.00 stddev:4.58 (6.7%) stddev-sans-first:4.50 15:50:45 INFO - PROCESS | 1678 | Values: 64.0 63.0 76.0 77.0 71.0 67.0 68.0 68.0 67.0 68.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:85.00 stddev:2.04 (2.4%) stddev-sans-first:2.12 15:50:45 INFO - PROCESS | 1678 | Values: 85.0 85.0 85.0 84.0 85.0 84.0 80.0 85.0 80.0 85.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:145.00 stddev:27.55 (19.0%) stddev-sans-first:29.08 15:50:45 INFO - PROCESS | 1678 | Values: 143.0 148.0 142.0 138.0 137.0 136.0 147.0 151.0 228.0 139.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.50 stddev:1.35 (0.8%) stddev-sans-first:1.30 15:50:45 INFO - PROCESS | 1678 | Values: 169.0 167.0 167.0 169.0 168.0 167.0 165.0 169.0 167.0 166.0 15:50:45 INFO - PROCESS | 1678 | 15:50:45 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:0.87 15:50:45 INFO - PROCESS | 1678 | Values: 68.0 67.0 66.0 66.0 65.0 64.0 66.0 66.0 66.0 65.0 15:50:45 INFO - PROCESS | 1678 | -------- Summary: end -------- 15:50:45 INFO - PROCESS | 1678 | 15:50:46 INFO - TEST-INFO | 1678: exit 0 15:50:46 INFO - TEST-OK | kraken | took 60922ms 15:50:46 INFO - SUITE-END | took 1176s 15:50:46 INFO - Completed test suite (00:19:37) 15:50:46 INFO - javascript benchmark 15:50:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2670.786337093032, 3454.896353109407, 3467.1852114753046, 3500.7374446916488, 3537.787732736036, 5611.7755289788465, 5648.068068768648, 5660.632058916172, 5757.2803399575105, 5765.423457654238, 5684.090545512719, 5740.454656807607, 5847.44449277319, 5856.004479238529, 5868.2664666916635, 5124.718140502271, 5159.535641792238, 5196.870078248036, 5228.451012643674, 5301.655392399501, 5268.841934741964, 5322.92015619766, 5330.546903513206, 5342.98483288176, 5361.678299302035, 5151.793928242884, 5215.973920130375, 5228.73856307185, 5229.267902493616, 5259.973700131475, 5183.403908550489, 5231.372235331746, 5268.998890210848, 5272.051030814452, 5279.868003299948, 4259.616634502879, 4523.638108951276, 5150.793968241266, 5155.432902380735, 5182.378114626231, 5658.377578466366, 5692.8861422771315, 5713.3429655589725, 5722.513586345166, 5724.198612194296, 6035.6076855004285, 6067.804899959568, 6198.4731297839735, 6281.811545653638, 6290.276618188921, 6279.591826531261, 6285.088662143963, 6315.74737010519, 6345.048242763576, 6372.872542549169, 5858.326292476378, 5889.469947704684, 5946.643201407929, 5954.940450595525, 5956.37458066901, 6247.375262473716, 6252.999520076766, 6278.183836101278, 6285.308616052231, 6342.936570634327, 6168.814935551941, 6183.81448556544, 6184.196054512884, 6186.257649082139, 6195.659238741912, 6280.152179455807, 6297.27581328144, 6299.307076221612, 6308.337624549448, 6319.0, 6139.907901381483, 6155.599886007395, 6184.0, 6215.098810672427, 6239.1577137085915, 6247.562670613013, 6305.495560355161, 6314.989601663712, 6319.146915166394, 6327.936720632735, 4968.652194346361, 5010.724410157476, 5013.623978201649, 5021.297018417425, 5030.421501527371, 3883.689304855605, 4451.77741112943, 4473.664475164375, 4477.283634618446, 4490.236659767847, 5749.913751293734, 5761.625494342854, 5770.480656740913, 5780.450857168562, 5780.884382312414, 6262.185915830913, 6286.685665716695, 6300.495960323163, 6304.621722696653, 6308.589941653778, 5647.661140331593, 5704.030314846486, 5712.685802280915, 5727.828165155052, 5769.798057067983, 5391.460853914656, 5414.18787181918, 5458.536024437888, 5471.398146203915, 5473.0, 6323.969274492451, 6364.172657554488, 6368.076628888785, 6369.90445143314, 6377.936220637827, 6300.59046161998, 6308.968455157695, 6315.55791094619, 6323.9683801580695, 6369.9681501592195, 6208.596441231305, 6241.812745617639, 6273.37266273369, 6290.21372328459, 6360.904586431115, 6183.288921774054, 6267.467265282502, 6269.310375858743, 6272.905906411499, 6285.654289013965, 6102.4812890902995, 6325.841853953688, 6332.113504109337, 6351.364863513611, 6351.777687780906, 5984.700764961647, 6033.607815491979, 6078.7872424466705, 6091.42131497507, 6109.222480666452, 6138.109974053827, 6178.66017369049, 6185.876282474415, 6203.100550420254, 6210.813675589739, 5982.970085149546, 6003.309619393825, 6006.880916394919, 6053.031514957585, 6058.697065146813, 6053.545984051126, 6152.415520525543, 6160.199174107337, 6178.474829639531, 6205.162303089025, 5937.881242375042, 5975.40245975399, 6058.0, 6071.908921366271, 6086.421789929949], "value": 5728.977581028919, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.3455112007748, 539.5252178083286, 561.9522340601048, 569.9259096317484, 588.5526999480394, 25561.616575751355, 35208.471872921924, 42991.355129673044, 44123.55876441231, 46452.76773616135, 44866.55133448665, 45135.54864451355, 45171.77414112933, 45264.86837829056, 45327.86680332993, 8645.30837532999, 9653.806923861519, 9828.705138845846, 9834.901650983471, 9839.114479696862, 8617.353698472609, 11418.14363922705, 11694.064474841993, 11702.765944681101, 11829.585964491245, 11727.47226374811, 11789.292642441438, 11792.938635522802, 11794.997425218866, 11806.704832379173, 7758.0, 11278.421021057058, 11540.0, 11552.768944621144, 11676.649700508955, 11278.210525263235, 11590.536378544932, 11593.0, 11599.362035088066, 11609.129315301387, 33334.0, 40609.0, 40966.79516602398, 41001.0, 41165.38251926223, 2760.875760590778, 3125.578046963676, 3195.1053704962646, 3800.0309920970253, 4016.3172260715455, 23401.648975265383, 28593.28516787097, 29222.70777292221, 29268.70731292681, 30337.0, 2726.3184203949013, 2748.244232835986, 2753.366725653111, 2831.405404864981, 2882.0345184363264, 433968.0, 438493.0, 440267.0, 442554.0, 444157.7792111051, 34490.48264276038, 36076.45885311723, 64170.6791466049, 64548.67725661342, 64719.676401618635, 37643.43534846979, 38008.04979875525, 38031.619683802805, 38100.0474988122, 38238.808805956345, 15126.848731512764, 15317.846821531864, 15333.769993450107, 15341.232938353038, 15353.309101090477, 11744.236624619543, 11841.763164736656, 11869.46587403569, 12068.336241506626, 12796.424160912782, 7961.72133975308, 8239.588020598947, 8320.791980200544, 8340.582970851434, 8392.20274073962, 3442.9483557746653, 3473.7221022318154, 3476.5480487536965, 3479.5824501060033, 3505.2463720300257, 1056.3186744549723, 1056.9418681972566, 1097.2703152403562, 1115.9776804464027, 1189.2685998111099, 28179.013734519194, 28616.712247948893, 29676.10971670853, 30163.849180753954, 30619.8469007658, 1126.8253420719639, 1190.6190019193775, 1194.7192409783695, 1209.4557449147742, 1228.9201201921846, 15594.9220253898, 15626.843731562767, 15643.139627320606, 15972.281247343897, 16097.275622597012, 647037.0, 650085.0, 650205.7489712521, 655444.722776383, 660583.0, 44732.3290150648, 44945.10109797851, 45060.54939450564, 45102.32346514805, 45214.0, 1329.6011196640968, 1455.657920388708, 1485.123776971592, 1506.3070987345943, 1510.4562357551054, 30134.54798177985, 30198.698013019588, 30227.697723023368, 30335.512809626183, 30391.240218994702, 43838.0, 54033.78611459575, 54191.45808541864, 54317.45682543124, 54374.0, 482246.0, 491031.0, 494982.0, 498027.0, 501827.0, 23835.404114897265, 24368.147114850894, 24414.145504907243, 27777.44445111127, 28150.85924570364, 45746.542534574226, 47479.100835967554, 48339.0, 50341.49658503368, 51367.20214792465, 38044.72262076837, 39492.80253598714, 39607.45770491485, 40417.0, 40499.785006449856, 37966.62033379631, 38288.42567361548, 38471.03822404462, 38541.80729096336, 39187.6081239184, 38026.0, 38333.233335333694, 38709.80645096757, 38824.80587597157, 38902.80548597239, 32174.0, 33461.4942327591, 34283.142921426166, 34355.0, 34390.0, 22259.77740222577, 24625.753742462344, 24778.752212478365, 25165.496690066462, 25350.873245633655], "value": 18908.762490392535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.23801445898545, 395.12676983865674, 397.7553804410289, 406.34375483594005, 414.45913083426115, 19325.323613673525, 21051.0, 21266.14935402586, 21328.89335553322, 21370.358889233346, 20573.794262057374, 20709.689354659655, 21558.137674493042, 21596.89201553994, 21787.346379608596, 5848.976429124908, 5930.229070220866, 6071.392860713938, 6112.113743507189, 6121.418465245817, 4153.7507749535125, 4413.977930110345, 4423.845165419211, 5959.284885813709, 6033.818985430444, 4196.39152322913, 5408.188771684259, 6585.374389432996, 6631.502637302197, 6648.202215734119, 5634.295713035871, 6430.90353644691, 6603.735850565971, 6607.80176594703, 6626.966865165691, 3694.704423646102, 5726.083826587767, 5734.455226753452, 5740.138979153118, 5744.741486633112, 21951.780482195132, 24245.515089698107, 24843.254702358958, 25075.749242507525, 25630.0, 2127.478767701923, 2154.375231182955, 2225.6105181593157, 2252.0, 2284.7258329000624, 14349.56951291463, 14903.329350179269, 14939.0, 15057.096574205583, 15200.695986080216, 1756.0692832798636, 1760.683077046131, 1775.0680892531395, 1782.1534770983678, 1791.2297711983842, 97129.5143524285, 103972.48013759959, 104715.0, 105371.47314263457, 105936.0, 9484.430934143973, 10114.494275286206, 10304.845427318596, 10373.688789336333, 10476.0571548561, 12608.495660173674, 12637.810432843515, 12711.237325760483, 12712.110152289251, 12717.173383730027, 6823.69293381799, 8218.506889586644, 8239.588020598947, 8390.454620449773, 8403.159684031549, 8646.221840034426, 8693.782655433539, 8726.0, 8795.164459376478, 8806.86789698155, 3928.4696565963227, 3958.6239307265764, 4006.8797936061965, 4052.2908491013955, 4061.2080644274674, 1790.713485842259, 1839.733238680384, 1929.5947850951177, 1964.2339487599847, 1975.7332760077493, 932.7994481186578, 935.1630290889616, 951.9148171084969, 1003.6186249225248, 1018.9592416303265, 18781.624367512573, 19389.51526211856, 20092.99535023243, 20146.496337591383, 20352.0, 959.5154447004358, 969.3795970578835, 1002.8445590933417, 1035.8549803027581, 1037.2479952034714, 10658.307210031324, 10816.0, 10826.891731082746, 10834.67495975122, 10844.67465976022, 552250.0, 562803.0, 569181.0, 569589.0, 574076.0, 73172.0, 74274.0, 109103.0, 130217.0, 139965.3001734985, 1005.9647912323034, 1019.8572199892021, 1071.421432426481, 1080.3949788118678, 1081.216118314216, 18477.353292634692, 18853.24587016504, 18942.810571894657, 18995.540929494942, 19019.04904754729, 50667.0, 50780.0, 51165.48834511607, 51808.96382072262, 53894.730526348685, 57522.0, 74439.25560744324, 80335.59832200801, 83396.58301708453, 83435.0, 5239.659422137549, 7904.881426778488, 7910.169432209535, 7975.601219939096, 8307.584620769057, 10114.342567733074, 12164.635060948185, 12205.389730513616, 12475.93762031184, 12621.179623324455, 10530.789384212427, 10729.839052414063, 10788.892111079103, 10856.294340867818, 10979.0, 10474.214433917334, 10552.20858435605, 10660.733481663021, 10722.088622467178, 10848.728781780519, 10613.0, 10677.489853262492, 10947.507362168562, 11085.390303533382, 11151.107911367233, 6620.702068407029, 6950.235474097946, 7490.0, 7624.58064806441, 7640.694372225049, 10264.846027309446, 10663.94668026686, 10744.140468762324, 11107.611233606785, 11114.610988615359], "value": 9349.38977703668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.0563275421846, 1579.9368025278989, 1605.3498333175062, 1860.5255659806746, 2011.5373464103247, 7727.768166954987, 8530.317574594033, 8791.252743516792, 8821.558922053895, 8913.509756963364, 8514.148585141484, 8762.824743505125, 8765.16730910564, 8767.649294028248, 8773.122687731224, 2069.3067822279554, 2138.9412240940756, 2191.2111639809655, 2303.527776805751, 2406.4344878953434, 2427.78149966503, 2454.619533972228, 2455.938601534967, 2460.7047154341503, 2465.0509553821817, 2366.124533922451, 2382.797462215714, 2420.007796803311, 2422.1280339078007, 2424.1757802347197, 2093.3405977117236, 2228.6545585434283, 2287.885605719707, 2296.311106667993, 2352.1414683640432, 2395.4131237846664, 2407.7953373963232, 2411.3368823573483, 2412.3124909400913, 2414.6498757680038, 8627.611757470866, 8697.347698922606, 8706.129387061306, 8706.869396959051, 8711.520866352348, 5035.647504674675, 5076.6192535559985, 5202.895942081175, 5213.2701421800975, 5225.163973764217, 3331.700146986783, 3398.9830050849587, 3449.5170676105367, 3453.2748122894222, 3474.3746125697367, 995.990040099597, 996.8056229035341, 999.6101520407047, 1004.9346792458467, 1013.4375421640987, 11465.426728663533, 11505.712357191054, 11507.906748858844, 11518.654440366803, 11541.192116551845, 4622.422197225347, 4692.53074692528, 4777.331173635694, 4826.58973987215, 4829.154897892855, 9804.852927206097, 9841.360311579867, 9875.604975800887, 9879.308448408543, 9880.604775808888, 5247.527722504993, 5282.128448805904, 5285.048691235537, 5286.0, 5289.894202115936, 4285.97857010713, 4457.04173602677, 4705.388299521041, 4774.809007639656, 4789.281607758864, 3851.8844434667003, 3903.3559462688904, 3949.3681011038097, 3982.8406863725704, 4015.8996025099023, 1300.5448093167315, 1304.4651692805903, 1308.8167656528094, 1317.1965101288206, 1319.0634649399035, 887.7780554861284, 920.8204400141908, 930.9767255818658, 944.6127087893931, 963.8120566489467, 5102.464241254689, 5527.115661494222, 5578.24693666358, 5633.859153521113, 5774.2782152230975, 448.0434272827472, 457.780265472575, 463.49710564037724, 463.87707257576767, 469.4507426311224, 3855.2137625279747, 3894.2600905827803, 3990.3615421532995, 3990.640842324147, 3992.48097747291, 10140.797184056426, 10470.790584188273, 10477.790444191225, 10561.0, 10584.629537966135, 10037.648682296085, 10293.53679084443, 10454.477276136162, 10523.473826308807, 10574.788504229873, 7971.242731940456, 8061.4760040597175, 8136.1863813618165, 8147.0, 8152.184781521919, 5675.914861277001, 5687.203791469115, 5721.713914304351, 5788.247527821524, 5837.124431335334, 9580.0, 9656.662016829658, 9806.509674516388, 9826.852597210904, 9844.9015509844, 9453.149216570542, 9466.668666596635, 9959.05388988045, 10052.0, 10065.496725163859, 6920.619365934922, 7141.607211603412, 7355.742549010973, 7379.2251813558805, 7600.619969001417, 6939.82650433723, 8340.958295208486, 8408.159184081787, 8421.410501264852, 8434.620442079999, 7333.413326933725, 7346.485745997943, 7494.025776649, 7514.774556763306, 7577.128630207597, 7293.708251669874, 7325.4505912057875, 7349.375303099083, 7368.926310737039, 7489.400847932261, 7029.29707029293, 7057.682404291717, 7093.858122837618, 7102.751403701052, 7111.431085513043, 4830.975845120752, 4969.105560998911, 5001.4998500149695, 5009.148444764472, 5154.329937108152, 4957.652964292465, 4980.402351717817, 5110.463401342954, 5143.099957507496, 5307.310049693515], "value": 4463.698681854306, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.7868311934855, 294.6052289931491, 306.41933535949374, 311.83472759437507, 313.17166095676936, 4884.902301953959, 5058.0, 5114.386273647158, 5207.453217412174, 5317.76070076847, 4595.345675556797, 5007.715317276821, 5015.664583052266, 5213.843584692465, 5238.188080847474, 4021.959780402188, 4043.3126368517424, 4045.554988951213, 4049.7367671101433, 4058.289799285128, 3194.872205111793, 3514.7012503937194, 3750.687259459181, 3752.3245815753153, 4073.63337299643, 3384.475406312013, 4012.6589239914642, 4016.2369149861433, 4022.4368588397647, 4044.716869819114, 3535.8232088395735, 3984.521857377119, 4027.677785777131, 4028.2144981728575, 4035.7982100895133, 3212.791168574035, 3328.484084966834, 3503.6321186275327, 3523.594786599548, 3525.330187264437, 4232.703710740249, 4563.794629241659, 4746.406699162605, 4768.661936644363, 5022.648414610979, 4509.594136527729, 4515.525869783658, 4569.200389931749, 4570.7257564546235, 4585.931211031837, 5190.870228244286, 5442.945570544283, 5474.562035037227, 5485.917711234335, 5512.283403157604, 3464.4630082337276, 3530.481892786101, 3615.94576081359, 3823.235352929425, 3894.4742459768136, 4898.583620392271, 5220.791168353261, 5757.0351269096445, 5768.307803063659, 5784.971075144639, 3391.9491207631904, 3834.9424758628643, 3840.6735427488416, 3842.2315536892734, 3843.8846834595006, 3180.0, 3710.7959062251834, 3759.661630453272, 3913.6086391360636, 3922.5489068756888, 3690.0959264979715, 3806.705720055139, 4275.38729773341, 4533.342665313511, 4565.817367305271, 5468.507834294933, 5492.917606235909, 5514.889702205934, 5537.757208330632, 5560.36055853582, 1083.2525557365393, 1191.8271850581616, 1216.2823933879054, 1225.4301749686438, 1250.0124901328031, 777.4713195027323, 823.1192623892377, 825.195434451405, 830.9198042544667, 850.8043150075517, 4099.0, 4505.526919673387, 4777.187878060738, 4827.324174615556, 4832.347633069561, 4540.79566419512, 4644.908446515066, 5090.956353947432, 5139.22911563261, 5316.0, 4439.378487011821, 4477.776111194493, 4780.521947805191, 4789.592884604847, 4799.048585120196, 2536.9238922832346, 2775.9028434004713, 2803.327201471673, 2810.803243772957, 2829.0522674903727, 4404.493483249404, 5475.850071484953, 5565.415631358649, 5592.133219350926, 5633.690147041952, 5039.168537191395, 5170.741462926839, 5171.939752350759, 5488.7530061147345, 5536.86157846049, 5528.645481856971, 5545.279113715272, 5577.972110139423, 5586.608937374426, 5598.97200514003, 4897.824522114595, 5155.891483331102, 5254.97372513135, 5468.91796623059, 5716.199732037435, 5099.36257967754, 5536.923653629889, 5565.517964804745, 5633.521150702072, 5638.407967163389, 5074.873128171826, 5210.322658054429, 5865.882682346414, 5888.764449422147, 5975.372585878594, 3003.0, 3437.3125374924616, 3601.243738814773, 3870.3420418528353, 3876.8449262029203, 4885.606053033916, 4978.3571421430115, 5634.464725851038, 5676.318841739017, 5684.640874037228, 4522.4573051234065, 5499.377683583272, 5505.394406615349, 5540.168974653673, 5549.833504994856, 5095.859738909593, 5169.61227907916, 5258.316418865523, 5491.615586908878, 5519.834404967857, 5026.84919452417, 5156.4327923927585, 5261.316028916216, 5316.920246196233, 5454.809081682122, 5100.438951715382, 5523.0, 5532.28080349552, 5537.2524709165355, 5545.500904918576, 5126.820561280487, 5354.491323324278, 5422.213779001943, 5441.945580544302, 5447.673139611636], "value": 3935.4670370761214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.3896814930264, 767.3860911270982, 773.5165521549031, 775.3951917504347, 794.6875139691165, 14857.182854942974, 15341.23293835308, 15518.146501942389, 15525.922370388133, 15526.990745601557, 13423.42927685012, 13639.386227619756, 13670.384832682528, 13681.768640822324, 13695.657608559792, 897.878786363839, 917.6375331743957, 920.7559996600907, 923.7044145873323, 924.9630014799434, 904.8778414914001, 916.7066538707617, 924.36219008884, 925.865749466327, 928.4197376639601, 980.2011360741009, 980.7989362180735, 982.1111893736145, 983.1348413396237, 983.7393090830935, 969.1374676537887, 973.8587904753806, 974.3277138774223, 975.5805003848307, 975.6146322202691, 727.1528669100492, 727.5125665803924, 727.8689835829549, 730.6529398535706, 731.6597782031381, 13571.1857288563, 13605.523806666719, 13607.863921360758, 13627.0, 13632.182069075887, 11012.063974562172, 11097.223194376396, 11296.887031129665, 11354.48904799286, 11430.828537571942, 9216.815663686688, 9338.112879276458, 9369.812603747954, 9403.059694030611, 9704.02959704031, 3161.1780936956557, 3348.983255083709, 3403.064157356722, 3460.0830779843363, 3467.6705712957228, 63624.363756362305, 63804.680976595286, 66046.67906641889, 66732.66534669328, 74830.62584687042, 9534.90465095354, 9724.756881077888, 9757.804843903225, 9913.80172396548, 10039.598416063422, 21987.0, 22169.44576385571, 22171.445713857283, 22181.889090554443, 22437.32688019362, 7639.656215470317, 7682.308592226726, 7788.14330423653, 7802.609869506502, 8001.279884810278, 3602.1534939289245, 4062.3297155969526, 4118.75287482752, 4166.250074986531, 4391.67062470316, 3377.9493307600405, 3472.982635086859, 3474.2183008822813, 3479.965200347964, 3481.2515309208643, 626.5582764151262, 643.0226056394348, 645.6190847400056, 650.5641220382355, 652.1880259077384, 480.86776136562725, 521.5097808060411, 562.1511517608359, 585.8740370820294, 593.2495393327445, 7249.818754531074, 7380.261973802577, 7425.9628701856145, 7606.3914886808925, 7746.728864489716, 516.8837011672418, 530.9973450132802, 569.8803251317187, 590.3093380744539, 596.6151832068292, 3571.482030326178, 3758.3047136279874, 3763.7553559018575, 3807.5430948286385, 3849.4995650565247, 83804.0, 85564.0, 85797.57101214454, 86545.0, 87339.56330218309, 77963.0, 78108.0, 78565.82151267736, 78970.60514697505, 79888.60055699684, 69723.0, 70103.64948175226, 70308.59382812417, 70542.0, 70832.64583677049, 7326.194118647051, 7424.1462231844025, 7498.512596681198, 7508.0990281166605, 7513.586752728653, 43570.0, 43952.78023609862, 44134.55865441304, 45261.77369113133, 45629.0, 57469.42530574641, 59515.40484595099, 59591.0, 60091.39908600858, 64266.35733642604, 10209.489525523843, 10399.53202105892, 10473.162147028372, 10486.056254936788, 10655.0, 10886.29239099456, 11173.832392514281, 11411.315321080761, 11441.713957150805, 11881.702957426134, 9118.0, 10197.643082492077, 10457.843132353173, 10478.393909993405, 10691.25161238737, 10448.05967462932, 10612.575496980035, 10736.30214036085, 10794.08250298702, 10816.296940698829, 10188.847167292644, 10382.532786024496, 10494.685159444924, 10503.632372866912, 10507.527161277914, 8365.33077353798, 8387.161283871565, 8393.412461127908, 8690.435121717068, 8739.825203496022, 8383.455075420079, 8424.705135320235, 8445.155484451505, 8557.229849313591, 8618.870716939377], "value": 6627.691904557759, "unit": "score"}], "name": "dromaeo_css", "value": 7002.780926267735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 112.0, 112.0, 111.0, 113.0, 111.0, 112.0, 112.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 115.0, 140.0, 191.0, 118.0, 139.0, 135.0, 115.0, 166.0, 117.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 185.0, 183.0, 185.0, 186.0, 186.0, 184.0, 181.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 58.0, 124.0, 57.0, 62.0, 73.0, 83.0, 113.0, 57.0], "value": 77.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 132.0, 80.0, 76.0, 76.0, 101.0, 79.0, 103.0, 139.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 89.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 97.0, 94.0, 95.0, 137.0, 152.0, 93.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 110.0, 140.0, 122.0, 84.0, 85.0, 83.0, 84.0, 99.0, 85.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 59.0, 60.0, 60.0, 59.0, 121.0, 60.0, 57.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 76.0, 77.0, 71.0, 67.0, 68.0, 68.0, 67.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 80.0, 85.0, 80.0, 85.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 148.0, 142.0, 138.0, 137.0, 136.0, 147.0, 151.0, 228.0, 139.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 167.0, 169.0, 168.0, 167.0, 165.0, 169.0, 167.0, 166.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 65.0, 64.0, 66.0, 66.0, 66.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1502.0000000000002, "alertThreshold": 2.0}]} 15:50:46 INFO - Return code: 0 15:50:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:50:47 ERROR - # TBPL SUCCESS # 15:50:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:50:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:50:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:50:47 INFO - 2016-05-09 15:50:47.033 screenresolution[1682:9609] starting screenresolution argv=screenresolution get 15:50:47 INFO - 2016-05-09 15:50:47.036 screenresolution[1682:9609] Display 0: 1600x1200x32@60 15:50:47 INFO - 2016-05-09 15:50:47.042 screenresolution[1683:9612] starting screenresolution argv=screenresolution list 15:50:47 INFO - Available Modes on Display 0 15:50:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:50:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:50:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:50:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:50:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:50:47 INFO - Intel Iris: 15:50:47 INFO - Chipset Model: Intel Iris 15:50:47 INFO - Type: GPU 15:50:47 INFO - Bus: Built-In 15:50:47 INFO - VRAM (Dynamic, Max): 1536 MB 15:50:47 INFO - Vendor: Intel (0x8086) 15:50:47 INFO - Device ID: 0x0a2e 15:50:47 INFO - Revision ID: 0x0009 15:50:47 INFO - Displays: 15:50:47 INFO - Display: 15:50:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:50:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:50:47 INFO - Display Serial Number: 1600x1200 60 15:50:47 INFO - Main Display: Yes 15:50:47 INFO - Mirror: Off 15:50:47 INFO - Online: Yes 15:50:47 INFO - Rotation: Supported 15:50:47 INFO - Return code: 0 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - ##### 15:50:47 INFO - ##### Finished run-tests step (success) 15:50:47 INFO - ##### 15:50:47 INFO - Running post-run listener: _resource_record_post_run 15:50:48 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37635584; Write bytes: 692966912; Read time: 416; Write time: 1855 15:50:48 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397697536; Write bytes: 398723072; Read time: 29952; Write time: 725 15:50:48 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35821056; Write bytes: 293395968; Read time: 397; Write time: 1128 15:50:48 INFO - Running post-run listener: _upload_blobber_files 15:50:48 INFO - Blob upload gear active. 15:50:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:50:48 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 15:50:48 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'] 15:50:48 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 15:50:48 INFO - (blobuploader) - INFO - Open directory for files ... 15:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:50:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:49 INFO - (blobuploader) - INFO - Done attempting. 15:50:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:50:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:50 INFO - (blobuploader) - INFO - Done attempting. 15:50:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:50:50 INFO - Return code: 0 15:50:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:50:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:50:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b184b2a561e08c2b7eb9dfb4ec0eae3d734045511e32ab393fcfa34db4b8d96ccf27bae1c0852fc3bfe2d509ff26f61c7722d8f13f125603dc92eaa3e4899e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a360934d13ae0a42d29c7bbefb677df5d98b45ed82ca2808b7c06c3d7a2579a80b98c7d89832055ef086bf5f5f2c9bc68e59eef77c639bd05917f6d7304e1f9"} 15:50:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:50:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:50:50 INFO - Contents: 15:50:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63b184b2a561e08c2b7eb9dfb4ec0eae3d734045511e32ab393fcfa34db4b8d96ccf27bae1c0852fc3bfe2d509ff26f61c7722d8f13f125603dc92eaa3e4899e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a360934d13ae0a42d29c7bbefb677df5d98b45ed82ca2808b7c06c3d7a2579a80b98c7d89832055ef086bf5f5f2c9bc68e59eef77c639bd05917f6d7304e1f9"} 15:50:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:50:50 INFO - Copying logs to upload dir... 15:50:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:50:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.808926 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-09 15:50:50.910743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 15:50:50.913968) ========= 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.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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/63b184b2a561e08c2b7eb9dfb4ec0eae3d734045511e32ab393fcfa34db4b8d96ccf27bae1c0852fc3bfe2d509ff26f61c7722d8f13f125603dc92eaa3e4899e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a360934d13ae0a42d29c7bbefb677df5d98b45ed82ca2808b7c06c3d7a2579a80b98c7d89832055ef086bf5f5f2c9bc68e59eef77c639bd05917f6d7304e1f9"} build_url:https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011075 build_url: 'https://queue.taskcluster.net/v1/task/a1RXsWmcRCOiZPvpVXq-sw/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/63b184b2a561e08c2b7eb9dfb4ec0eae3d734045511e32ab393fcfa34db4b8d96ccf27bae1c0852fc3bfe2d509ff26f61c7722d8f13f125603dc92eaa3e4899e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a360934d13ae0a42d29c7bbefb677df5d98b45ed82ca2808b7c06c3d7a2579a80b98c7d89832055ef086bf5f5f2c9bc68e59eef77c639bd05917f6d7304e1f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 15:50:50.940950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:50:50.941246) ========= 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.baOiYFxNHj/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.PznqPVE4Cz/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.004871 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:50:50.989162) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 15:50:50.989448) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 15:50:50.989724) ========= ========= Total master_lag: 0.15 =========