builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1462928488.52 results: success (0) buildid: 20160510162116 builduid: c3404a01b02e4442a7ff3c7e95d92257 revision: d8df7fcffeb14796b272feb5cb24b1c15dcf67cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.523037) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.523469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.523768) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.544150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.544546) ========= 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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-10 18:01:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-05-10 18:01:28 (17.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.688638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.688960) ========= 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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.026513 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:28.737997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 18:01:28.738349) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d8df7fcffeb14796b272feb5cb24b1c15dcf67cb --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 d8df7fcffeb14796b272feb5cb24b1c15dcf67cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-10 18:01:28,812 truncating revision to first 12 chars 2016-05-10 18:01:28,812 Setting DEBUG logging. 2016-05-10 18:01:28,813 attempt 1/10 2016-05-10 18:01:28,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d8df7fcffeb1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 18:01:30,366 unpacking tar archive at: fx-team-d8df7fcffeb1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.792766 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 18:01:30.550525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:30.551059) ========= script_repo_revision: d8df7fcffeb14796b272feb5cb24b1c15dcf67cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:30.551695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:30.552172) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:01:30.569050) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-10 18:01:30.569504) ========= /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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:01:30 INFO - MultiFileLogger online at 20160510 18:01:30 in /builds/slave/test 18:01:30 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 18:01:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:30 INFO - {'append_to_log': False, 18:01:30 INFO - 'base_work_dir': '/builds/slave/test', 18:01:30 INFO - 'blob_upload_branch': 'Fx-Team', 18:01:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:30 INFO - 'branch': 'Fx-Team', 18:01:30 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:30 INFO - 'config_files': ('talos/mac_config.py',), 18:01:30 INFO - 'default_actions': ('clobber', 18:01:30 INFO - 'read-buildbot-config', 18:01:30 INFO - 'download-and-extract', 18:01:30 INFO - 'populate-webroot', 18:01:30 INFO - 'create-virtualenv', 18:01:30 INFO - 'install', 18:01:30 INFO - 'run-tests'), 18:01:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:30 INFO - 'download_minidump_stackwalk': True, 18:01:30 INFO - 'download_symbols': 'ondemand', 18:01:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:30 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:30 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:30 INFO - 'installer_path': 'installer.exe', 18:01:30 INFO - 'log_level': 'info', 18:01:30 INFO - 'log_name': 'talos', 18:01:30 INFO - 'log_to_console': True, 18:01:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:30 INFO - 'opt_config_files': (), 18:01:30 INFO - 'pip_index': False, 18:01:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:30 INFO - 'cmd': ('bash', 18:01:30 INFO - '-c', 18:01:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:30 INFO - 'enabled': True, 18:01:30 INFO - 'halt_on_failure': False, 18:01:30 INFO - 'name': 'check_screen_resolution'},), 18:01:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:30 INFO - 'cmd': ('bash', 18:01:30 INFO - '-c', 18:01:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:30 INFO - 'enabled': True, 18:01:30 INFO - 'halt_on_failure': False, 18:01:30 INFO - 'name': 'check_screen_resolution'},), 18:01:30 INFO - 'run_cmd_checks_enabled': True, 18:01:30 INFO - 'sps_profile': False, 18:01:30 INFO - 'sps_profile_interval': 0, 18:01:30 INFO - 'suite': 'dromaeojs', 18:01:30 INFO - 'system_bits': '32', 18:01:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:30 INFO - 'title': 't-yosemite-r7-0131', 18:01:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:30 INFO - 'use_talos_json': True, 18:01:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:30 INFO - 'work_dir': 'build'} 18:01:30 INFO - ##### 18:01:30 INFO - ##### Running clobber step. 18:01:30 INFO - ##### 18:01:30 INFO - Running pre-action listener: _resource_record_pre_action 18:01:30 INFO - Running main action method: clobber 18:01:30 INFO - rmtree: /builds/slave/test/build 18:01:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:32 INFO - Running post-action listener: _resource_record_post_action 18:01:32 INFO - ##### 18:01:32 INFO - ##### Finished clobber step (success) 18:01:32 INFO - ##### 18:01:32 INFO - ##### 18:01:32 INFO - ##### Running read-buildbot-config step. 18:01:32 INFO - ##### 18:01:32 INFO - Running pre-action listener: _resource_record_pre_action 18:01:32 INFO - Running main action method: read_buildbot_config 18:01:32 INFO - Using buildbot properties: 18:01:32 INFO - { 18:01:32 INFO - "project": "", 18:01:32 INFO - "product": "firefox", 18:01:32 INFO - "script_repo_revision": "production", 18:01:32 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:01:32 INFO - "repository": "", 18:01:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:01:32 INFO - "buildid": "20160510162116", 18:01:32 INFO - "pgo_build": "False", 18:01:32 INFO - "basedir": "/builds/slave/test", 18:01:32 INFO - "buildnumber": 7, 18:01:32 INFO - "slavename": "t-yosemite-r7-0131", 18:01:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:01:32 INFO - "platform": "yosemite_r7", 18:01:32 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:01:32 INFO - "branch": "fx-team", 18:01:32 INFO - "repo_path": "integration/fx-team", 18:01:32 INFO - "revision": "d8df7fcffeb14796b272feb5cb24b1c15dcf67cb", 18:01:32 INFO - "stage_platform": "macosx64", 18:01:32 INFO - "builduid": "c3404a01b02e4442a7ff3c7e95d92257", 18:01:32 INFO - "slavebuilddir": "test" 18:01:32 INFO - } 18:01:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:01:32 INFO - Running post-action listener: _resource_record_post_action 18:01:32 INFO - ##### 18:01:32 INFO - ##### Finished read-buildbot-config step (success) 18:01:32 INFO - ##### 18:01:32 INFO - ##### 18:01:32 INFO - ##### Running download-and-extract step. 18:01:32 INFO - ##### 18:01:32 INFO - Running pre-action listener: _resource_record_pre_action 18:01:32 INFO - Running main action method: download_and_extract 18:01:32 INFO - mkdir: /builds/slave/test/build/tests 18:01:32 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')]} 18:01:32 INFO - https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:01:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:34 INFO - Downloaded 1199 bytes. 18:01:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:34 INFO - Using the following test package requirements: 18:01:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:01:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'jsshell-mac.zip'], 18:01:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:01:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:01:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:01:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:01:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:34 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')]} 18:01:34 INFO - https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:01:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:35 INFO - Downloaded 18150484 bytes. 18:01:35 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'] 18:01:35 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 18:01:39 INFO - Return code: 0 18:01:39 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 18:01:39 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')]} 18:01:39 INFO - https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:40 INFO - Downloaded 18150484 bytes. 18:01:40 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'] 18:01:40 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 18:01:43 INFO - Return code: 0 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:43 INFO - https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:45 INFO - Downloaded 10840152 bytes. 18:01:45 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'] 18:01:45 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 18:01:46 INFO - Return code: 0 18:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:46 INFO - https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:01:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1F6DYyoRT-sPf08Sn01vA/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 18:01:52 INFO - Downloaded 109848312 bytes. 18:01:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:52 INFO - mkdir: /builds/slave/test/properties 18:01:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:52 INFO - Contents: 18:01:52 INFO - build_url:https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:53 INFO - Running post-action listener: _resource_record_post_action 18:01:53 INFO - Running post-action listener: set_extra_try_arguments 18:01:53 INFO - ##### 18:01:53 INFO - ##### Finished download-and-extract step (success) 18:01:53 INFO - ##### 18:01:53 INFO - ##### 18:01:53 INFO - ##### Running populate-webroot step. 18:01:53 INFO - ##### 18:01:53 INFO - Running pre-action listener: _resource_record_pre_action 18:01:53 INFO - Running main action method: populate_webroot 18:01:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:53 INFO - '--tppagecycles', 18:01:53 INFO - '7'], 18:01:53 INFO - 'tests': ['tsvgm']}}, 18:01:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['damp', 'tps']}, 18:01:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'tests': ['damp', 'tps']}, 18:01:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['dromaeo_dom']}, 18:01:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['a11yr', 18:01:53 INFO - 'ts_paint', 18:01:53 INFO - 'tpaint', 18:01:53 INFO - 'sessionrestore', 18:01:53 INFO - 'sessionrestore_no_auto_restore', 18:01:53 INFO - 'tabpaint']}, 18:01:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['tsvgx', 18:01:53 INFO - 'tsvgr_opacity', 18:01:53 INFO - 'tart', 18:01:53 INFO - 'tscrollx', 18:01:53 INFO - 'cart']}, 18:01:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:53 INFO - 'tsvgr_opacity', 18:01:53 INFO - 'tart', 18:01:53 INFO - 'tscrollx', 18:01:53 INFO - 'cart']}, 18:01:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'talos_options': ['--disable-e10s'], 18:01:53 INFO - 'tests': ['tp5o']}, 18:01:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'tests': ['tp5o']}, 18:01:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'talos_options': ['--disable-e10s', 18:01:53 INFO - '--xperf_path', 18:01:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:53 INFO - 'tests': ['tp5n']}, 18:01:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:53 INFO - 'talos_options': ['--xperf_path', 18:01:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:53 INFO - 'tests': ['tp5n']}}, 18:01:53 INFO - 'talos.zip': {'path': '', 18:01:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:53 INFO - Running post-action listener: _resource_record_post_action 18:01:53 INFO - ##### 18:01:53 INFO - ##### Finished populate-webroot step (success) 18:01:53 INFO - ##### 18:01:53 INFO - ##### 18:01:53 INFO - ##### Running create-virtualenv step. 18:01:53 INFO - ##### 18:01:53 INFO - Running pre-action listener: _resource_record_pre_action 18:01:53 INFO - Running main action method: create_virtualenv 18:01:53 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:53 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 18:01:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:53 INFO - Using real prefix '/tools/python27' 18:01:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:54 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:56 INFO - Installing pip.................done. 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:56 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')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:56 INFO - 'HOME': '/Users/cltbld', 18:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PAGER': '/bin/cat', 18:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:01:56 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - 'XPC_FLAGS': '0x0', 18:01:56 INFO - 'XPC_SERVICE_NAME': '0', 18:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:57 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 18:01:57 INFO - Cleaning up... 18:01:57 INFO - Return code: 0 18:01:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:01:57 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')]} 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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')]} 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:01:57 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 18:01:57 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 18:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:57 INFO - 'HOME': '/Users/cltbld', 18:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:57 INFO - 'LOGNAME': 'cltbld', 18:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:57 INFO - 'MOZ_NO_REMOTE': '1', 18:01:57 INFO - 'NO_EM_RESTART': '1', 18:01:57 INFO - 'PAGER': '/bin/cat', 18:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:57 INFO - 'PWD': '/builds/slave/test', 18:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:57 INFO - 'SHELL': '/bin/bash', 18:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:01:57 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:57 INFO - 'USER': 'cltbld', 18:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:57 INFO - 'XPC_FLAGS': '0x0', 18:01:57 INFO - 'XPC_SERVICE_NAME': '0', 18:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:57 INFO - Downloading/unpacking psutil>=0.7.1 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:00 INFO - Installing collected packages: psutil 18:02:00 INFO - Running setup.py install for psutil 18:02:00 INFO - building 'psutil._psutil_osx' extension 18:02:00 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 INFO - building 'psutil._psutil_posix' extension 18:02:01 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 18:02:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:02:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:02:01 INFO - ^ 18:02:01 INFO - 1 warning generated. 18:02:01 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 18:02:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:01 INFO - Successfully installed psutil 18:02:01 INFO - Cleaning up... 18:02:01 INFO - Return code: 0 18:02:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:01 INFO - 'HOME': '/Users/cltbld', 18:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:01 INFO - 'LOGNAME': 'cltbld', 18:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:01 INFO - 'MOZ_NO_REMOTE': '1', 18:02:01 INFO - 'NO_EM_RESTART': '1', 18:02:01 INFO - 'PAGER': '/bin/cat', 18:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:01 INFO - 'PWD': '/builds/slave/test', 18:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:01 INFO - 'SHELL': '/bin/bash', 18:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:01 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:01 INFO - 'USER': 'cltbld', 18:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:01 INFO - 'XPC_FLAGS': '0x0', 18:02:01 INFO - 'XPC_SERVICE_NAME': '0', 18:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:02:04 INFO - Installing collected packages: mozsystemmonitor 18:02:04 INFO - Running setup.py install for mozsystemmonitor 18:02:04 INFO - Successfully installed mozsystemmonitor 18:02:04 INFO - Cleaning up... 18:02:04 INFO - Return code: 0 18:02:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:04 INFO - 'HOME': '/Users/cltbld', 18:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:04 INFO - 'LOGNAME': 'cltbld', 18:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:04 INFO - 'MOZ_NO_REMOTE': '1', 18:02:04 INFO - 'NO_EM_RESTART': '1', 18:02:04 INFO - 'PAGER': '/bin/cat', 18:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:04 INFO - 'PWD': '/builds/slave/test', 18:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:04 INFO - 'SHELL': '/bin/bash', 18:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:04 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:04 INFO - 'USER': 'cltbld', 18:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:04 INFO - 'XPC_FLAGS': '0x0', 18:02:04 INFO - 'XPC_SERVICE_NAME': '0', 18:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:07 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:08 INFO - Downloading docopt-0.6.1.tar.gz 18:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:08 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:08 INFO - Running setup.py install for blobuploader 18:02:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:08 INFO - Running setup.py install for requests 18:02:09 INFO - Running setup.py install for docopt 18:02:09 INFO - Successfully installed blobuploader requests docopt 18:02:09 INFO - Cleaning up... 18:02:09 INFO - Return code: 0 18:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:09 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:09 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 18:02:09 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 18:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:09 INFO - 'HOME': '/Users/cltbld', 18:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:09 INFO - 'LOGNAME': 'cltbld', 18:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:09 INFO - 'MOZ_NO_REMOTE': '1', 18:02:09 INFO - 'NO_EM_RESTART': '1', 18:02:09 INFO - 'PAGER': '/bin/cat', 18:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:09 INFO - 'PWD': '/builds/slave/test', 18:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:09 INFO - 'SHELL': '/bin/bash', 18:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:09 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:09 INFO - 'USER': 'cltbld', 18:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:09 INFO - 'XPC_FLAGS': '0x0', 18:02:09 INFO - 'XPC_SERVICE_NAME': '0', 18:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-hfNsuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-7KkavA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-w4jbfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-XpDoix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-DunxZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KaBBZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-AH8G3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-WLCT4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-sV8vUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-knLSqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nQQr2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-CVSisQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-8OeEE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-a5Ij60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-tvGq_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-viqNgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:11 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vz_X1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:11 INFO - Running setup.py install for manifestparser 18:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:11 INFO - Running setup.py install for mozcrash 18:02:11 INFO - Running setup.py install for mozdebug 18:02:12 INFO - Running setup.py install for mozdevice 18:02:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozfile 18:02:12 INFO - Running setup.py install for mozhttpd 18:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozinfo 18:02:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozInstall 18:02:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozleak 18:02:12 INFO - Running setup.py install for mozlog 18:02:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for moznetwork 18:02:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozprocess 18:02:13 INFO - Running setup.py install for mozprofile 18:02:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozrunner 18:02:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozscreenshot 18:02:13 INFO - Running setup.py install for moztest 18:02:14 INFO - Running setup.py install for mozversion 18:02:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:14 INFO - Cleaning up... 18:02:14 INFO - Return code: 0 18:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:14 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:14 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 18:02:14 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 18:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:14 INFO - 'HOME': '/Users/cltbld', 18:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:14 INFO - 'LOGNAME': 'cltbld', 18:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:14 INFO - 'MOZ_NO_REMOTE': '1', 18:02:14 INFO - 'NO_EM_RESTART': '1', 18:02:14 INFO - 'PAGER': '/bin/cat', 18:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:14 INFO - 'PWD': '/builds/slave/test', 18:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:14 INFO - 'SHELL': '/bin/bash', 18:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:14 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:14 INFO - 'USER': 'cltbld', 18:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:14 INFO - 'XPC_FLAGS': '0x0', 18:02:14 INFO - 'XPC_SERVICE_NAME': '0', 18:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ju54Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:14 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)) 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Jwy1XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:14 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)) 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-R07DlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:14 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)) 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-s23vJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:14 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)) 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-TK5_iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:14 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)) 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-3_HSuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-SptYT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-v_9lv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-E4lpHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vj2017-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-BLDKir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-e2EeG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-FKjacj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-t_9O9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:15 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)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-h6NFGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:16 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-jv7pii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:16 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)) 18:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:16 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nmKgeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:16 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)) 18:02:16 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)) 18:02:16 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)) 18:02:16 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)) 18:02:16 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)) 18:02:16 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)) 18:02:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:16 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 18:02:16 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 18:02:16 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 18:02:16 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 18:02:16 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 18:02:16 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 18:02:18 INFO - Downloading blessings-1.6.tar.gz 18:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:18 INFO - Installing collected packages: blessings 18:02:18 INFO - Running setup.py install for blessings 18:02:18 INFO - Successfully installed blessings 18:02:18 INFO - Cleaning up... 18:02:19 INFO - Return code: 0 18:02:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:19 INFO - Reading from file tmpfile_stdout 18:02:19 INFO - Current package versions: 18:02:19 INFO - blessings == 1.6 18:02:19 INFO - blobuploader == 1.2.4 18:02:19 INFO - docopt == 0.6.1 18:02:19 INFO - manifestparser == 1.1 18:02:19 INFO - mozInstall == 1.12 18:02:19 INFO - mozcrash == 0.17 18:02:19 INFO - mozdebug == 0.1 18:02:19 INFO - mozdevice == 0.48 18:02:19 INFO - mozfile == 1.2 18:02:19 INFO - mozhttpd == 0.7 18:02:19 INFO - mozinfo == 0.9 18:02:19 INFO - mozleak == 0.1 18:02:19 INFO - mozlog == 3.1 18:02:19 INFO - moznetwork == 0.27 18:02:19 INFO - mozprocess == 0.22 18:02:19 INFO - mozprofile == 0.28 18:02:19 INFO - mozrunner == 6.11 18:02:19 INFO - mozscreenshot == 0.1 18:02:19 INFO - mozsystemmonitor == 0.0 18:02:19 INFO - moztest == 0.7 18:02:19 INFO - mozversion == 1.4 18:02:19 INFO - psutil == 3.1.1 18:02:19 INFO - requests == 1.2.3 18:02:19 INFO - wsgiref == 0.1.2 18:02:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:19 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')]} 18:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:19 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')]} 18:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:19 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:19 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 18:02:19 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 18:02:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:19 INFO - 'HOME': '/Users/cltbld', 18:02:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:19 INFO - 'LOGNAME': 'cltbld', 18:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:19 INFO - 'MOZ_NO_REMOTE': '1', 18:02:19 INFO - 'NO_EM_RESTART': '1', 18:02:19 INFO - 'PAGER': '/bin/cat', 18:02:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:19 INFO - 'PWD': '/builds/slave/test', 18:02:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:19 INFO - 'SHELL': '/bin/bash', 18:02:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:19 INFO - 'USER': 'cltbld', 18:02:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:19 INFO - 'XPC_FLAGS': '0x0', 18:02:19 INFO - 'XPC_SERVICE_NAME': '0', 18:02:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 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)) 18:02:19 INFO - Cleaning up... 18:02:19 INFO - Return code: 0 18:02:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02:19 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')]} 18:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:19 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')]} 18:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:19 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 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:19 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 18:02:19 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 18:02:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:19 INFO - 'HOME': '/Users/cltbld', 18:02:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:19 INFO - 'LOGNAME': 'cltbld', 18:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:19 INFO - 'MOZ_NO_REMOTE': '1', 18:02:19 INFO - 'NO_EM_RESTART': '1', 18:02:19 INFO - 'PAGER': '/bin/cat', 18:02:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:19 INFO - 'PWD': '/builds/slave/test', 18:02:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:19 INFO - 'SHELL': '/bin/bash', 18:02:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:19 INFO - 'USER': 'cltbld', 18:02:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:19 INFO - 'XPC_FLAGS': '0x0', 18:02:19 INFO - 'XPC_SERVICE_NAME': '0', 18:02:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:19 INFO - Downloading/unpacking jsonschema 18:02:19 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 18:02:19 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 18:02:19 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 18:02:19 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 18:02:19 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 18:02:19 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 18:02:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:22 INFO - Downloading/unpacking functools32 (from jsonschema) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:22 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:22 INFO - warning: no files found matching '*.txt' 18:02:22 INFO - no previously-included directories found matching 'build' 18:02:22 INFO - no previously-included directories found matching 'dist' 18:02:22 INFO - no previously-included directories found matching '.git*' 18:02:22 INFO - Installing collected packages: jsonschema, functools32 18:02:22 INFO - Running setup.py install for functools32 18:02:22 INFO - warning: no files found matching '*.txt' 18:02:22 INFO - no previously-included directories found matching 'build' 18:02:22 INFO - no previously-included directories found matching 'dist' 18:02:22 INFO - no previously-included directories found matching '.git*' 18:02:22 INFO - Successfully installed jsonschema functools32 18:02:22 INFO - Cleaning up... 18:02:23 INFO - Return code: 0 18:02:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93f94a1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, '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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 18:02:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:23 INFO - 'HOME': '/Users/cltbld', 18:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:23 INFO - 'LOGNAME': 'cltbld', 18:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:23 INFO - 'MOZ_NO_REMOTE': '1', 18:02:23 INFO - 'NO_EM_RESTART': '1', 18:02:23 INFO - 'PAGER': '/bin/cat', 18:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:23 INFO - 'PWD': '/builds/slave/test', 18:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:23 INFO - 'SHELL': '/bin/bash', 18:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:02:23 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:23 INFO - 'USER': 'cltbld', 18:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:23 INFO - 'XPC_FLAGS': '0x0', 18:02:23 INFO - 'XPC_SERVICE_NAME': '0', 18:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:23 INFO - Downloading/unpacking flake8 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:02:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:02:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:26 INFO - no previously-included directories found matching 'docs/_build' 18:02:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:02:27 INFO - Running setup.py install for pep8 18:02:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:27 INFO - no previously-included directories found matching 'docs/_build' 18:02:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:02:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:02:27 INFO - Cleaning up... 18:02:27 INFO - Return code: 0 18:02:27 INFO - Running post-action listener: _resource_record_post_action 18:02:27 INFO - Running post-action listener: _start_resource_monitoring 18:02:27 INFO - Starting resource monitoring. 18:02:27 INFO - ##### 18:02:27 INFO - ##### Finished create-virtualenv step (success) 18:02:27 INFO - ##### 18:02:27 INFO - ##### 18:02:27 INFO - ##### Running install step. 18:02:27 INFO - ##### 18:02:27 INFO - Running pre-action listener: _resource_record_pre_action 18:02:27 INFO - Running main action method: install 18:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:27 INFO - Reading from file tmpfile_stdout 18:02:27 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:27 INFO - Reading from file tmpfile_stdout 18:02:27 INFO - Output received: 18:02:27 INFO - Usage: mozinstall [options] installer 18:02:27 INFO - Options: 18:02:27 INFO - -h, --help show this help message and exit 18:02:27 INFO - -d DEST, --destination=DEST 18:02:27 INFO - Directory to install application into. [default: 18:02:27 INFO - "/builds/slave/test"] 18:02:27 INFO - --app=APP Application being installed. [default: firefox] 18:02:27 INFO - mkdir: /builds/slave/test/build/application 18:02:27 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'] 18:02:27 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 18:03:01 INFO - Reading from file tmpfile_stdout 18:03:01 INFO - Output received: 18:03:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:03:01 INFO - Running post-action listener: _resource_record_post_action 18:03:01 INFO - ##### 18:03:01 INFO - ##### Finished install step (success) 18:03:01 INFO - ##### 18:03:01 INFO - ##### 18:03:01 INFO - ##### Running run-tests step. 18:03:01 INFO - ##### 18:03:01 INFO - Running pre-action listener: _resource_record_pre_action 18:03:01 INFO - Running main action method: run_tests 18:03:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:03:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:03:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:03:01 INFO - 2016-05-10 18:03:01.137 screenresolution[1638:7424] starting screenresolution argv=screenresolution get 18:03:01 INFO - 2016-05-10 18:03:01.140 screenresolution[1638:7424] Display 0: 1600x1200x32@60 18:03:01 INFO - 2016-05-10 18:03:01.145 screenresolution[1639:7426] starting screenresolution argv=screenresolution list 18:03:01 INFO - Available Modes on Display 0 18:03:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:03:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:03:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:03:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:03:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:03:01 INFO - Intel Iris: 18:03:01 INFO - Chipset Model: Intel Iris 18:03:01 INFO - Type: GPU 18:03:01 INFO - Bus: Built-In 18:03:01 INFO - VRAM (Dynamic, Max): 1536 MB 18:03:01 INFO - Vendor: Intel (0x8086) 18:03:01 INFO - Device ID: 0x0a2e 18:03:01 INFO - Revision ID: 0x0009 18:03:01 INFO - Displays: 18:03:01 INFO - Display: 18:03:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:03:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:03:01 INFO - Display Serial Number: 1600x1200 60 18:03:01 INFO - Main Display: Yes 18:03:01 INFO - Mirror: Off 18:03:01 INFO - Online: Yes 18:03:01 INFO - Rotation: Supported 18:03:01 INFO - Return code: 0 18:03:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:01 INFO - Python 2.7.3 18:03:01 INFO - Return code: 0 18:03:01 INFO - grabbing minidump binary from tooltool 18:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:03:01 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc09d30>, '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 0x10fa2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc00b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:01 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 18:03:01 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 18:03:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:03:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:01 INFO - Return code: 0 18:03:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:03:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:03:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:03:02 INFO - Return code: 0 18:03:02 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/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 18:03:02 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/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 18:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9WNYWCBX2e/Render', 18:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:02 INFO - 'HOME': '/Users/cltbld', 18:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:02 INFO - 'LOGNAME': 'cltbld', 18:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:02 INFO - 'MOZ_NO_REMOTE': '1', 18:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:02 INFO - 'NO_EM_RESTART': '1', 18:03:02 INFO - 'PAGER': '/bin/cat', 18:03:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:02 INFO - 'PWD': '/builds/slave/test', 18:03:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:02 INFO - 'SHELL': '/bin/bash', 18:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5uvyy6VVo3/Listeners', 18:03:02 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 18:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:02 INFO - 'USER': 'cltbld', 18:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:02 INFO - 'XPC_FLAGS': '0x0', 18:03:02 INFO - 'XPC_SERVICE_NAME': '0', 18:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:02 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/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 18:03:02 INFO - mozversion application_buildid: 20160510162116 18:03:02 INFO - mozversion application_changeset: d8df7fcffeb14796b272feb5cb24b1c15dcf67cb 18:03:02 INFO - mozversion application_display_name: Nightly 18:03:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:02 INFO - mozversion application_name: Firefox 18:03:02 INFO - mozversion application_remotingname: firefox 18:03:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:03:02 INFO - mozversion application_vendor: Mozilla 18:03:02 INFO - mozversion application_version: 49.0a1 18:03:02 INFO - mozversion platform_buildid: 20160510162116 18:03:02 INFO - mozversion platform_changeset: d8df7fcffeb14796b272feb5cb24b1c15dcf67cb 18:03:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:03:02 INFO - mozversion platform_version: 49.0a1 18:03:02 INFO - using testdate: 1462928582 18:03:02 INFO - actual date: 1462928582 18:03:02 INFO - starting webserver on 'localhost:49278' 18:03:02 INFO - SUITE-START | Running 2 tests 18:03:02 INFO - TEST-START | dromaeo_css 18:03:02 INFO - operating with platform_type : mac_ 18:03:02 INFO - Initialising browser for dromaeo_css test... 18:03:02 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpTCckvS/profile http://localhost:49278/getInfo.html) 18:03:05 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:03:05 INFO - PROCESS | 1657 | colorDepth:24 18:03:05 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:03:05 INFO - PROCESS | 1657 | __metrics 18:03:05 INFO - PROCESS | 1657 | 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" 18:03:05 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:03:06 INFO - TEST-INFO | 1657: exit 0 18:03:06 INFO - Browser initialized. 18:03:06 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:06 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpTCckvS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:18 INFO - PROCESS | 1659 | RSS: Main: 238440448 18:03:18 INFO - PROCESS | 1659 | 18:04:09 INFO - PROCESS | 1659 | 1462928649830 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] 18:06:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 18:06:21 INFO - PROCESS | 1659 | RSS: Main: 327536640 18:06:21 INFO - PROCESS | 1659 | 18:09:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 18:09:24 INFO - PROCESS | 1659 | RSS: Main: 373493760 18:09:24 INFO - PROCESS | 1659 | 18:12:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 18:12:27 INFO - PROCESS | 1659 | RSS: Main: 368758784 18:12:27 INFO - PROCESS | 1659 | 18:15:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 18:15:30 INFO - PROCESS | 1659 | RSS: Main: 369532928 18:15:30 INFO - PROCESS | 1659 | 18:18:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 18:18:34 INFO - PROCESS | 1659 | RSS: Main: 337534976 18:18:34 INFO - PROCESS | 1659 | 18:21:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 18:21:38 INFO - PROCESS | 1659 | RSS: Main: 379387904 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | __start_tp_report 18:21:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 18:21:38 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 18:21:38 INFO - PROCESS | 1659 | |i|pagename|runs| 18:21:38 INFO - PROCESS | 1659 | |0;dojo.html;3406.182516196113;3426.1434641339665;3437.312537492501;3445.8966231013046;3488.2325888304563;5551.722413879305;5641.464060914217;5681.687507187103;5686.431356864311;5700.087985922248;5700.600957932947;5762.769489220436;5803.448672376117;5806.651600903938;5860.472557469838;5184.040952423795;5231.686098834063;5282.762275697583;5301.814436494724;5383.596230282725;5333.546648534879;5372.7313634318325;5393.056215162351;5397.865053373658;5427.3215848019;5161.174212126042;5175.9741201293755;5206.219067139959;5208.401033881114;5269.736513174345;5238.319018527567;5241.8165364212455;5247.212918062283;5255.763490642892;5262.2632831403635;5251.894962100775;5275.525202731773;5277.973610131925;5288.286081379003;5298.947010529922;5542.584306177013;5560.749766260528;5606;5619.9884020876225;5691.231683722727;6209.72056257465;6242.469390101847;6244.157038799749;6247.812565623039;6272.3100458949475;6262.436380725711;6324.177856878622;6347;6350.714217860207;6359.65021923794;5698.7435565399655;5755.625884317506;5783.942160578425;5846.269216347957;5851.180834683148;6343.1436756038265;6358.968205158944;6364.872702545923;6379.936200638027;6387.169667943138;6235.251769787564;6236.812895613139;6241.7191226394925;6242.406971337716;6266.248050234001;6146.385361463817;6205.069239614094;6261.686915654199;6281.937180628135;6333.240011198685;6270.7805226816845;6287.717052732638;6292.968535157295;6298.811035668938;6313.9368606314265;6298;6306.558540902093;6321;6328;6342.746290148342;4835.685680430761;4846;4903.485134060944;4925.87685307863;4942.950570494321;4407.757573333433;4465.464144302705;4508.774561271924;4509;4516.412866327422;5665.971670141623;5693.68684722336;5709.200711900337;5710.486056254957;5717.542596592262;6245.843853903689;6283.874322513524;6322.336154703782;6323.462505687068;6347.55567110307;5766.682832444172;5773.364929857712;5775.740091695884;5784.88430231393;5832.387599302097;5273.841784746463;5419.566434685215;5429.4027656957705;5435.42927992563;5439.347278326626;6286;6297.275813281532;6352.333005034498;6454.419102280911;6457.741690332522;6351.110844481684;6357.650329231937;6360.681965901779;6364.268109167319;6378.043293506012;6287.245530536365;6299.055141728777;6413.1021656969;6441.710123044568;6445.16212892321;6117.888303246575;6271;6271.404216599416;6276.905846412216;6290.842728931813;5686.57350698691;6080.543959202989;6282.528810339334;6351.936480635134;6366.395192456709;5971.134185543159;6114.540688159306;6131.356207598138;6196.907046394217;6204.565680402328;6199.845003874939;6218.471429928326;6219.968900155652;6228.159198508143;6251.624902505864;6036.456718895321;6108.389161083856;6110.602810817282;6114.6636934969;6120.898238317146;6030.879382412415;6099.400579572161;6124.3809352984035;6164.044573091178;6201.844953876009;6060.939390606214;6069.45374916242;6078.756849725962;6082.996305609525;6124.816255512342 18:21:38 INFO - PROCESS | 1659 | |1;ext.html;504.75325944916045;514.2337916504408;544.7194694732211;546.7129756877639;575.4906907386962;31067.378652426938;31464.52803207951;43707;44388.3341749874;44453.555464445344;43419.78290108553;46132.53867461324;46294.53705462944;46362.768186159024;46660.83347916304;9771.56027978739;9967.152792012652;9973.50132493376;9979.700608981742;9987;9694.612215511372;9831.705048848511;9835.409875407498;9837.409755414697;9843.950780246088;9827;9857.162141218005;9885.950570247174;9890;9890.15933645641;9313.580888860017;9798.559064842098;9859.112679858847;9870.161036311922;9876.40741555509;8920.107989201093;9872;9873.851892221623;9879.90120098797;9899.505024748805;42096;42511;42596.787016065035;42609.360859587134;42637;3242.400155971129;3572.8928132156075;3856.421536769479;3867.3232184367903;3922.941155882664;26109.73890261092;29460.348568628928;29509.557356639667;30157.396852063055;30878.84560577183;2694.420699549606;2816.1551534539763;2821.238265668258;2830.207541888254;2844.900428485014;486433.1356686423;486464.5676771629;486583.1341686573;486881;488801;60591.39408605946;65180.34819651743;65985.01022484667;66038.66980665163;66176.33823661799;69826.65086674636;69898;70012.94980575296;70014.9497757534;70047.64976175087;17259.482215533553;17510;17523.12384380776;17532.82467175312;17533.123343832758;13323.533676321282;13365.732685346238;13390.397432115578;13393.330333483482;13401.128926619738;18057.088171245003;18709.812901870806;19001.809981900282;19037.42887713371;19209.598800149983;5569.721513924287;6585.374389432996;6828.624425656637;6920.8961865572055;6959.4432445404245;928.4800511713462;1106.6624679472677;1127.1490025031048;1157.6469176901126;1164.057113737875;28201.858990704914;30396.088117356514;30954.69045309563;31166.84416577948;31310.68689313123;1041.4844651897358;1120.277421063426;1182.3910685996718;1195.3784032303154;1213.648042067808;15599.412135208966;15619.60950976212;15628.687426251412;15912.681746365239;15955.441559545361;571675;580346;589154;591620;592294;62143.6892815533;62307.68846155831;62410.68794655998;62483.68758156271;62540.68729656414;1281.9294938778457;1309.1948451702135;1435.346917152693;1468.067776961637;1486.2494440307576;31168.376632467676;31274.374512510076;31431.057068287988;31551.053468395985;31813.681863182;54391.45608543864;55015;55057.449425505234;55085.34743957688;55147.17279240734;469008;476512;476958;478205;479374;35820.820895895355;40489.98775030648;40887;41360.58639413567;42407.575924241595;46637.53362466468;47016.7649161752;48728;48829;48960.02079958452;37473.4236347478;38891;39299.60700393074;39964.40053399259;40004;37878.2424351517;37999.43000854934;38588;39151.804240978614;39522.80238598789;38388;39255;39392.40911386274;40182.79908600439;42256.788716056224;27919;31786.52320215245;34325.719891050954;34889;35140;22612.547749045258;23893.402664933517;25040.74959250457;25433.61849572221;25452.49095018126 18:21:38 INFO - PROCESS | 1659 | |2;jquery.html;394.2135439797604;396.42914203546906;398.0983073338888;401.8894803928919;421.515257453928;14326.498572549963;20143.29498467554;20162.89104099274;21324.253651122213;21368;15623.84376156238;17956.19197136132;20258.392248232576;20840.791592084075;21178.25876094337;5218.7651555679895;5841.970790146054;6138.416850399227;6176.320604733476;6188.907166392508;6025.728842202111;6052.213212282397;6061.909071363933;6089.725962331706;6140.35526269742;5878.588498805086;5988.251468566429;6029.728662210189;6037.245344331958;6052.334243233241;5818.883622327572;5944.197533333009;5997.250343707036;6004.18943442634;6039.456448919596;5188.662736922126;5200.609954253408;5242.60680448964;5244.659097158673;5247.16045432729;24076.879615601985;24353.14763983252;25022.3511413982;25224.49551008988;25367.873160634263;2133.615949129156;2433.525462534789;2496.987515062413;2761.254461295459;2764.543850264704;14480.927595362062;14492.20292883891;14970.850291497054;15319.4638187664;15389.307481163263;1656.8177500475058;1711.5036639374566;1715.3567412220418;1756.3589289909091;1759.3138675916402;128922;132335.33832330778;132785.33607331902;135177;136103.27793444175;11598.94200528992;11609;11654.88345116538;11674.76650467003;11808.291502509877;14758.33587488544;14868.479603213835;14890.329935152944;14893.627659308388;14903.776443353358;6461.935380646133;8159.632816523271;8548.401611887233;8711.956440217886;8849.247813935759;8636.913630863612;8647.437916535406;8663.653453861774;8789.055666351716;8805.075467075992;4493.1013797240685;4587.7017993830295;4597.517260687647;4629;4638.512956139624;1671.707451196036;1931.4012656076661;1974.9210031598575;1980.8712433691765;1994.9401517954484;836.5148214035845;843.7679638099572;869.964741957069;934.7476181430976;1057.0750593230923;19657;19960.50098747543;20004.59990800205;20156;20180.09189586472;905.8958219804807;944.367273926471;949.1647350331664;970.8640790289365;1039.1167507618463;10273.74315642115;10512.737181570368;10551.522786809854;10562.31344962575;10597.364158150536;586098;601786;602929;608321;610105;94679.52660236793;132825.33587332195;133565;142820;148928;983.7147227304145;1019.1337363241333;1035.4305132177271;1035.9741006474899;1052.7157667429904;17343.959362438294;17654.735178972584;17748.3788067417;17843.553911151805;18158.27366905309;51170;56355.15467267912;56444.15333769914;57808.71095644495;58008;82267.17732822834;82887;83554.16445835464;84714;86245;7896.052473703192;7914.248146426311;8005.479643823133;8091.919080809116;8264;8939.150780675825;11113.110951123728;11453.370064646191;12479.750404992032;12663.81004284918;10372.896271037192;10616.566763487139;10710.464476776286;10819.459027048773;10937.781244375226;10480.94759526228;10553.050225479743;10678.46607669629;10776.407297598395;10837.620683276047;9118.544072796467;10358.792824143626;10460.947695261475;10693.770216425211;11057.72355691082;7843.176466471166;8325.750227493185;11428.885711142782;11919.582814601446;11977.64067077989;10338.638147664795;11289.830652540382;11338.60314888975;11583.826242606536;11664.008559272557 18:21:38 INFO - PROCESS | 1659 | |3;mootools.html;1389.6386939395754;1622.1240530113735;1644.457329081403;1767.6729804986078;1887.245101959216;5623.887522249553;8579.142085791418;8793.692220772275;8818.118188181179;9025.548722563879;8679.045305016443;8766.34252431067;8854.911450885505;8864.77838054049;8873.866891996626;2160.9891950540227;2234.7765223477604;2442.877856107201;2478.033566908907;2479.4049428137296;2437.5124975004983;2504.724480307165;2505.7744802967727;2510.083819405922;2516.144510866305;2438.6951631046118;2464.864432456214;2468.395243165427;2475.6905386826647;2476.108600903673;2149.9462513437134;2379.393254720035;2445.755424457549;2459.3605662527693;2480.8139389545854;2063.2881655828714;2187.1798075721604;2356.198892376583;2467.6668649732237;2498;8789.165029322205;8846.292296616319;8848.867266991001;8884.777880552972;8892.955535222283;5257.106291930343;5435.2934118564335;5473.2063850741815;5486.286782718221;5488.4237155098535;3348.3972884880714;3465.4801779733243;3634.00064982132;3636.9272614547826;3644.4351125575577;905.2803021597846;1053.462734005655;1055.9155267578576;1060.1518784972068;1069.2461814420815;11925.40372981356;11997.880021199851;12012.519499220021;12022.69943251417;12053.337066461341;4738.674567783001;4831.227003679394;4841.878953026132;4877.804887804448;4897.7795999180125;10080.495975201211;10106.191504679755;10121.1903047756;10125.443100629536;10162.949185254027;5381.569474442035;5411.215373770781;5416.891662166813;5425.294711687456;5437.157240627709;4433.7561434121435;4630.398048253705;4795.256735206049;4872.829450969199;4961.90076198474;4018.5378681452007;4030.8186131624147;4104.794760261975;4139.447707109834;4231.576842315744;1338.1235290884485;1344.5764584155959;1344.865513448667;1355.5187908292612;1372.883304919093;916.7158180963922;920.7145784806731;929.0987741890356;932.9720108396623;962.7785609309758;5836.9124463133085;5879.941200588025;5960.135780311831;6007.579469437097;6017.939820601826;450.91779728650846;451.27795527156286;464.1297567061759;474.9073930583503;475.46985111600316;3831.63599458051;3863.632954869283;3899.1811719539214;3938.5470670873215;3951.9012024699614;9994.600215991424;10333.483325833678;10531;10532.789344213226;10545.420001899816;10201.948990255001;10410.167186625053;10416.166706663449;10425.739356516147;10431.478426078666;7895.842083158304;7968.243016913384;7972.840543189103;8023;8104.675812967415;5569.916451253315;5674.148877668383;5677.489025987681;5702.885942281048;5866.6480011199465;9618;9787.119159275415;9987.350822196824;10008.799824003625;10170.898291016994;9839.5080245986;10317.9484102579;10351;10440.68677939663;10483.895161048596;6977.988191712275;6995.230524642387;7269.454790890599;7425.183229844821;7624.923750762421;8243.175682431709;8395.874061889199;8474.576271186539;8486.405951583576;8495.575221238789;7319.158296795937;7415.258474152542;7492.712096673727;7531.246875312425;7539.32146106853;7316.743913962986;7401.777946661608;7412.518186317872;7535.6985720570565;7555.73554925575;6958.582485050913;7004.649767511706;7040.014397984389;7042;7051.330123638246;4628.907421851612;4801.399825021872;4823.782929768098;4836.25038119078;4958.876028099182;4741.525847415231;4776.522347765195;4807.110684523374;4859.222524396079;5011.498850114959 18:21:38 INFO - PROCESS | 1659 | |4;prototype.html;311.59492659542593;316.3562151022668;321.8149564000699;324.90252924122774;329.1770573566085;4530.569595888393;4734.4081989751285;4915.852524424264;5085.889355917295;5110.054639891623;4923.6307276954285;5096.898062038757;5099.107656160167;5125.2312153176945;5175.171972484403;3729;3937.665298449621;3941.8029098544957;3951.565327813938;3968.503937007874;3365.7643964922463;3917.7061720370943;3960.940585891214;3967.8611248606303;3999.6000399959917;3241.6434192238835;3934.8229329680235;3939.5469521005166;3939.684825213976;3965.790433917654;3454.5509083818943;3955.180616916222;3974.1654252606986;3975.7018223633345;4004.5594984551676;2828.6747024092288;3219.388316219934;3407.6081250656243;3410.5054767058637;3461.2558299965626;4315.870523884289;4542.432195975503;4920.761053720585;4939.802407903716;5006.549410553068;3782;4394.956050439487;4474.7986340614425;4476.306172543261;4477.395551600524;4995.877576514678;5276.3140791697215;5317.12267475866;5321.973390133025;5385.219143224211;3789.621037896216;3817.3510503214243;3823.445600387956;3852.056246219694;3873.9418908716393;4114.362273847559;4747.715137091785;5079.8824258663035;5153.948460515347;5711.7715291388295;3325.567676202078;3472.531208286849;3909.8691213288826;3915.197384536163;3927.546807681119;3936.389859571771;3937.9015524612114;3953.6639385651965;3955.4264631628253;3978;4335.544767799399;4499.437570303712;4503.301988191835;4515.68390212682;4531.84138555149;5365.651232669864;5445.53712934405;5472.890542189134;5476.385585513542;5533.418991005967;1163.66253786401;1166.9008134308508;1312.6324629103867;1317.4005827348533;1359.4833963094159;716.8100952419063;820.7906983719114;824.1017291152673;831.8585840407145;860.3074525007304;3806.4583843542855;4116.903680837526;4148.875533733993;4160.771157586301;4165.542330025239;3975.2447035063824;4314.590113939171;4570.771461426915;4575.787416334674;4671.505118362092;3582.4268117101137;3883.3204189266767;3922.882313530599;3963.128111815396;4123;1942.5527981653477;1962.3426152238874;2267.614505534063;2399.6520504526748;2429.6962879640046;4060.9796951015055;4103.543242149054;4442.555744425532;4848.878778030577;4871.269309603588;4162.375643653476;4265.398107154633;4662.906741865144;4664.440267167894;4677.4854765975715;4500.774961251925;4706.835260765857;4751.738654373973;4767.713937163781;4866.562009419169;4244.684147914157;4272.401863739141;4422.933655995227;4570.268756998864;4647.697899636512;4121.8969525762095;4299.742015479082;4335.371371151103;4503.127310513021;4580.4884388152495;4756.833510827105;4917.877053073702;5335.38643056038;5442.918356224739;5553.148361198696;3237.5791147151663;3709.5919448860063;3793.336166170854;3826.559945606291;3828.636279553438;4661.417322834645;5380;5411.242426060276;5448.427915068861;5503.807366742145;4593.954060459353;5000.799488327546;5353.009693206769;5359.866003349792;5397.433269506802;4307.461567304087;5266.499682530153;5277.393099793573;5280.76236569363;5322.813701520583;4244.91510169801;4676.433394812776;5193.610479213999;5231.7705339244485;5235.110031294613;4480.932786008278;4897.3878265216845;5274.551663108678;5309.7345132743985;5314.62797604164;5172.767020612117;5239.69453440485;5244.396894357045;5255.763490642854;5302.886393857333 18:21:38 INFO - PROCESS | 1659 | |5;yui.html;579.7420148034124;712.8823744082226;718.515002373398;733.7285204474346;734.2767374136471;14972.32624531896;15050.849491505081;15461;15469.922650386732;15771.21143942804;14260.215688137148;14351.35418906149;14414.279286035682;14423.567292981228;14481.63795905103;713.2083387439937;729.2015243308568;732.0300601702738;910.2808781062969;924.944503329799;740.707420568875;741.1365758890886;888.5468411110315;908.2960705453261;909.995450022749;958.396210387455;960.4813400763614;961.8653388525612;962.1821451765978;966.2849491376401;910.4127837544779;960.5149399553251;960.6781728121088;961.8605302231173;963;556.6632187526528;697.0659316515843;708.3589351636725;710.073354272674;723.8262816923954;14865.851341486556;14883.255837208204;14924.850751492455;14936.103833770008;14937.775933361008;12058.517659293619;12080.214786038967;12222.20555663879;12412.875871241353;12526.373681315898;10766.515506802212;10809.35143891369;11059.557617695282;11077.944610276978;11478.598249061242;4092.9795351023054;4176.394422808676;4181.996320882997;4186.790660466995;4267.231898258312;71980.56038879193;83647.5817620914;83993.32013359759;84227;84230.57884710598;8674.52533069367;9373.90626093744;9397.530123493934;9444.574994125282;9795.314327997114;19505;21248.575028499345;21377;21460.57078858414;21880.89059554692;7277.636118194069;7338.376238019828;7363.410927125817;7535.246475352531;7665.655045522964;3358.932821343559;3945.467361906163;3960.4653371795016;4032.7983600819844;4114.156597897424;3354.9329013419597;3428.6571342866014;3434.3303055903857;3436.329915666421;3437.5187473753695;620.9782657606962;631.3370960491466;631.3528633151057;636.8344230500011;639.5139693832721;549.3081309566526;552.585560829378;560.4451592923058;573.4695406748824;593.3769541980903;7647.847043059219;7779.649915753805;7806.765797026098;7881.724139655084;7888.921110788933;542.8697112692926;551.8258063230937;552.8341497550718;562.4207066721284;564.9435056494318;3655.9268814624093;3678.3562876496508;3689.8155092245815;3760.2103558253075;3899.8245078971513;82069;82922.58538707388;83975;90792.54603726939;91973.5401322989;80480;81586.59206703928;82434;83352.58323708343;85541.14458855457;71127.2887271131;71821.92267115998;72728.27271727215;72891.63554182301;73969.5206095875;6624.171978502687;6665;6668.66656667155;6671.5997040177745;6859.279775623488;44944.55055449404;45592.772036139606;46945.29582056204;47853.80365490751;47874.04251915012;61891;62357.68821155865;63597.0460443103;64418.71162576816;65604.67197663982;9818.607255709692;9848.753781155528;9879.209663227068;10313.587456501657;10427.739306517398;10600.628977985734;10666.253362264568;10984.286021408583;11033.062189713964;11102.555897764;9986.400815951067;10059.195264378686;10192.13366863825;10311.845322320321;10355.792884142425;9776.1690256329;9781;10012.799744004933;10150;10835.566577337133;9535.380200287236;9573.521323933915;9630.422174669262;9683.080107389787;9843.16333111665;8292.212239837447;8360.958195208985;8589.44168629037;8653.091425400495;8697.434666746642;8358.247757701836;8458;8473.576321183793;8561.957190214009;8724.3892927497 18:21:38 INFO - PROCESS | 1659 | __end_tp_report 18:21:38 INFO - PROCESS | 1659 | __start_cc_report 18:21:38 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1259 18:21:38 INFO - PROCESS | 1659 | __end_cc_report 18:21:38 INFO - PROCESS | 1659 | __startTimestamp1462929698132__endTimestamp 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | ------- Summary: start ------- 18:21:38 INFO - PROCESS | 1659 | Number of tests: 6 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5800.90 Median:6080.54 stddev:645.89 (10.6%) stddev-sans-first:619.94 18:21:38 INFO - PROCESS | 1659 | Values: 3406.2 3426.1 3437.3 3445.9 3488.2 5551.7 5641.5 5681.7 5686.4 5700.1 5700.6 5762.8 5803.4 5806.7 5860.5 5184.0 5231.7 5282.8 5301.8 5383.6 5333.5 5372.7 5393.1 5397.9 5427.3 5161.2 5176.0 5206.2 5208.4 5269.7 5238.3 5241.8 5247.2 5255.8 5262.3 5251.9 5275.5 5278.0 5288.3 5298.9 5542.6 5560.7 5606.0 5620.0 5691.2 6209.7 6242.5 6244.2 6247.8 6272.3 6262.4 6324.2 6347.0 6350.7 6359.7 5698.7 5755.6 5783.9 5846.3 5851.2 6343.1 6359.0 6364.9 6379.9 6387.2 6235.3 6236.8 6241.7 6242.4 6266.2 6146.4 6205.1 6261.7 6281.9 6333.2 6270.8 6287.7 6293.0 6298.8 6313.9 6298.0 6306.6 6321.0 6328.0 6342.7 4835.7 4846.0 4903.5 4925.9 4943.0 4407.8 4465.5 4508.8 4509.0 4516.4 5666.0 5693.7 5709.2 5710.5 5717.5 6245.8 6283.9 6322.3 6323.5 6347.6 5766.7 5773.4 5775.7 5784.9 5832.4 5273.8 5419.6 5429.4 5435.4 5439.3 6286.0 6297.3 6352.3 6454.4 6457.7 6351.1 6357.7 6360.7 6364.3 6378.0 6287.2 6299.1 6413.1 6441.7 6445.2 6117.9 6271.0 6271.4 6276.9 6290.8 5686.6 6080.5 6282.5 6351.9 6366.4 5971.1 6114.5 6131.4 6196.9 6204.6 6199.8 6218.5 6220.0 6228.2 6251.6 6036.5 6108.4 6110.6 6114.7 6120.9 6030.9 6099.4 6124.4 6164.0 6201.8 6060.9 6069.5 6078.8 6083.0 6124.8 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:67037.58 Median:42332.18 stddev:137864.38 (325.7%) stddev-sans-first:138160.62 18:21:38 INFO - PROCESS | 1659 | Values: 504.8 514.2 544.7 546.7 575.5 31067.4 31464.5 43707.0 44388.3 44453.6 43419.8 46132.5 46294.5 46362.8 46660.8 9771.6 9967.2 9973.5 9979.7 9987.0 9694.6 9831.7 9835.4 9837.4 9844.0 9827.0 9857.2 9886.0 9890.0 9890.2 9313.6 9798.6 9859.1 9870.2 9876.4 8920.1 9872.0 9873.9 9879.9 9899.5 42096.0 42511.0 42596.8 42609.4 42637.0 3242.4 3572.9 3856.4 3867.3 3922.9 26109.7 29460.3 29509.6 30157.4 30878.8 2694.4 2816.2 2821.2 2830.2 2844.9 486433.1 486464.6 486583.1 486881.0 488801.0 60591.4 65180.3 65985.0 66038.7 66176.3 69826.7 69898.0 70012.9 70014.9 70047.6 17259.5 17510.0 17523.1 17532.8 17533.1 13323.5 13365.7 13390.4 13393.3 13401.1 18057.1 18709.8 19001.8 19037.4 19209.6 5569.7 6585.4 6828.6 6920.9 6959.4 928.5 1106.7 1127.1 1157.6 1164.1 28201.9 30396.1 30954.7 31166.8 31310.7 1041.5 1120.3 1182.4 1195.4 1213.6 15599.4 15619.6 15628.7 15912.7 15955.4 571675.0 580346.0 589154.0 591620.0 592294.0 62143.7 62307.7 62410.7 62483.7 62540.7 1281.9 1309.2 1435.3 1468.1 1486.2 31168.4 31274.4 31431.1 31551.1 31813.7 54391.5 55015.0 55057.4 55085.3 55147.2 469008.0 476512.0 476958.0 478205.0 479374.0 35820.8 40490.0 40887.0 41360.6 42407.6 46637.5 47016.8 48728.0 48829.0 48960.0 37473.4 38891.0 39299.6 39964.4 40004.0 37878.2 37999.4 38588.0 39151.8 39522.8 38388.0 39255.0 39392.4 40182.8 42256.8 27919.0 31786.5 34325.7 34889.0 35140.0 22612.5 23893.4 25040.7 25433.6 25452.5 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35866.02 Median:11750.81 stddev:100997.86 (859.5%) stddev-sans-first:101246.07 18:21:38 INFO - PROCESS | 1659 | Values: 394.2 396.4 398.1 401.9 421.5 14326.5 20143.3 20162.9 21324.3 21368.0 15623.8 17956.2 20258.4 20840.8 21178.3 5218.8 5842.0 6138.4 6176.3 6188.9 6025.7 6052.2 6061.9 6089.7 6140.4 5878.6 5988.3 6029.7 6037.2 6052.3 5818.9 5944.2 5997.3 6004.2 6039.5 5188.7 5200.6 5242.6 5244.7 5247.2 24076.9 24353.1 25022.4 25224.5 25367.9 2133.6 2433.5 2497.0 2761.3 2764.5 14480.9 14492.2 14970.9 15319.5 15389.3 1656.8 1711.5 1715.4 1756.4 1759.3 128922.0 132335.3 132785.3 135177.0 136103.3 11598.9 11609.0 11654.9 11674.8 11808.3 14758.3 14868.5 14890.3 14893.6 14903.8 6461.9 8159.6 8548.4 8712.0 8849.2 8636.9 8647.4 8663.7 8789.1 8805.1 4493.1 4587.7 4597.5 4629.0 4638.5 1671.7 1931.4 1974.9 1980.9 1994.9 836.5 843.8 870.0 934.7 1057.1 19657.0 19960.5 20004.6 20156.0 20180.1 905.9 944.4 949.2 970.9 1039.1 10273.7 10512.7 10551.5 10562.3 10597.4 586098.0 601786.0 602929.0 608321.0 610105.0 94679.5 132825.3 133565.0 142820.0 148928.0 983.7 1019.1 1035.4 1036.0 1052.7 17344.0 17654.7 17748.4 17843.6 18158.3 51170.0 56355.2 56444.2 57808.7 58008.0 82267.2 82887.0 83554.2 84714.0 86245.0 7896.1 7914.2 8005.5 8091.9 8264.0 8939.2 11113.1 11453.4 12479.8 12663.8 10372.9 10616.6 10710.5 10819.5 10937.8 10480.9 10553.1 10678.5 10776.4 10837.6 9118.5 10358.8 10460.9 10693.8 11057.7 7843.2 8325.8 11428.9 11919.6 11977.6 10338.6 11289.8 11338.6 11583.8 11664.0 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5700.51 Median:4798.33 stddev:3206.24 (66.8%) stddev-sans-first:3198.87 18:21:38 INFO - PROCESS | 1659 | Values: 1389.6 1622.1 1644.5 1767.7 1887.2 5623.9 8579.1 8793.7 8818.1 9025.5 8679.0 8766.3 8854.9 8864.8 8873.9 2161.0 2234.8 2442.9 2478.0 2479.4 2437.5 2504.7 2505.8 2510.1 2516.1 2438.7 2464.9 2468.4 2475.7 2476.1 2149.9 2379.4 2445.8 2459.4 2480.8 2063.3 2187.2 2356.2 2467.7 2498.0 8789.2 8846.3 8848.9 8884.8 8893.0 5257.1 5435.3 5473.2 5486.3 5488.4 3348.4 3465.5 3634.0 3636.9 3644.4 905.3 1053.5 1055.9 1060.2 1069.2 11925.4 11997.9 12012.5 12022.7 12053.3 4738.7 4831.2 4841.9 4877.8 4897.8 10080.5 10106.2 10121.2 10125.4 10162.9 5381.6 5411.2 5416.9 5425.3 5437.2 4433.8 4630.4 4795.3 4872.8 4961.9 4018.5 4030.8 4104.8 4139.4 4231.6 1338.1 1344.6 1344.9 1355.5 1372.9 916.7 920.7 929.1 933.0 962.8 5836.9 5879.9 5960.1 6007.6 6017.9 450.9 451.3 464.1 474.9 475.5 3831.6 3863.6 3899.2 3938.5 3951.9 9994.6 10333.5 10531.0 10532.8 10545.4 10201.9 10410.2 10416.2 10425.7 10431.5 7895.8 7968.2 7972.8 8023.0 8104.7 5569.9 5674.1 5677.5 5702.9 5866.6 9618.0 9787.1 9987.4 10008.8 10170.9 9839.5 10317.9 10351.0 10440.7 10483.9 6978.0 6995.2 7269.5 7425.2 7624.9 8243.2 8395.9 8474.6 8486.4 8495.6 7319.2 7415.3 7492.7 7531.2 7539.3 7316.7 7401.8 7412.5 7535.7 7555.7 6958.6 7004.6 7040.0 7042.0 7051.3 4628.9 4801.4 4823.8 4836.3 4958.9 4741.5 4776.5 4807.1 4859.2 5011.5 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4123.27 Median:4475.55 stddev:1236.51 (27.6%) stddev-sans-first:1206.43 18:21:38 INFO - PROCESS | 1659 | Values: 311.6 316.4 321.8 324.9 329.2 4530.6 4734.4 4915.9 5085.9 5110.1 4923.6 5096.9 5099.1 5125.2 5175.2 3729.0 3937.7 3941.8 3951.6 3968.5 3365.8 3917.7 3960.9 3967.9 3999.6 3241.6 3934.8 3939.5 3939.7 3965.8 3454.6 3955.2 3974.2 3975.7 4004.6 2828.7 3219.4 3407.6 3410.5 3461.3 4315.9 4542.4 4920.8 4939.8 5006.5 3782.0 4395.0 4474.8 4476.3 4477.4 4995.9 5276.3 5317.1 5322.0 5385.2 3789.6 3817.4 3823.4 3852.1 3873.9 4114.4 4747.7 5079.9 5153.9 5711.8 3325.6 3472.5 3909.9 3915.2 3927.5 3936.4 3937.9 3953.7 3955.4 3978.0 4335.5 4499.4 4503.3 4515.7 4531.8 5365.7 5445.5 5472.9 5476.4 5533.4 1163.7 1166.9 1312.6 1317.4 1359.5 716.8 820.8 824.1 831.9 860.3 3806.5 4116.9 4148.9 4160.8 4165.5 3975.2 4314.6 4570.8 4575.8 4671.5 3582.4 3883.3 3922.9 3963.1 4123.0 1942.6 1962.3 2267.6 2399.7 2429.7 4061.0 4103.5 4442.6 4848.9 4871.3 4162.4 4265.4 4662.9 4664.4 4677.5 4500.8 4706.8 4751.7 4767.7 4866.6 4244.7 4272.4 4422.9 4570.3 4647.7 4121.9 4299.7 4335.4 4503.1 4580.5 4756.8 4917.9 5335.4 5442.9 5553.1 3237.6 3709.6 3793.3 3826.6 3828.6 4661.4 5380.0 5411.2 5448.4 5503.8 4594.0 5000.8 5353.0 5359.9 5397.4 4307.5 5266.5 5277.4 5280.8 5322.8 4244.9 4676.4 5193.6 5231.8 5235.1 4480.9 4897.4 5274.6 5309.7 5314.6 5172.8 5239.7 5244.4 5255.8 5302.9 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17874.55 Median:36114.42 stddev:25528.74 (70.7%) stddev-sans-first:25567.33 18:21:38 INFO - PROCESS | 1659 | Values: 579.7 712.9 718.5 733.7 734.3 14972.3 15050.8 15461.0 15469.9 15771.2 14260.2 14351.4 14414.3 14423.6 14481.6 713.2 729.2 732.0 910.3 924.9 740.7 741.1 888.5 908.3 910.0 958.4 960.5 961.9 962.2 966.3 910.4 960.5 960.7 961.9 963.0 556.7 697.1 708.4 710.1 723.8 14865.9 14883.3 14924.9 14936.1 14937.8 12058.5 12080.2 12222.2 12412.9 12526.4 10766.5 10809.4 11059.6 11077.9 11478.6 4093.0 4176.4 4182.0 4186.8 4267.2 71980.6 83647.6 83993.3 84227.0 84230.6 8674.5 9373.9 9397.5 9444.6 9795.3 19505.0 21248.6 21377.0 21460.6 21880.9 7277.6 7338.4 7363.4 7535.2 7665.7 3358.9 3945.5 3960.5 4032.8 4114.2 3354.9 3428.7 3434.3 3436.3 3437.5 621.0 631.3 631.4 636.8 639.5 549.3 552.6 560.4 573.5 593.4 7647.8 7779.6 7806.8 7881.7 7888.9 542.9 551.8 552.8 562.4 564.9 3655.9 3678.4 3689.8 3760.2 3899.8 82069.0 82922.6 83975.0 90792.5 91973.5 80480.0 81586.6 82434.0 83352.6 85541.1 71127.3 71821.9 72728.3 72891.6 73969.5 6624.2 6665.0 6668.7 6671.6 6859.3 44944.6 45592.8 46945.3 47853.8 47874.0 61891.0 62357.7 63597.0 64418.7 65604.7 9818.6 9848.8 9879.2 10313.6 10427.7 10600.6 10666.3 10984.3 11033.1 11102.6 9986.4 10059.2 10192.1 10311.8 10355.8 9776.2 9781.0 10012.8 10150.0 10835.6 9535.4 9573.5 9630.4 9683.1 9843.2 8292.2 8361.0 8589.4 8653.1 8697.4 8358.2 8458.0 8473.6 8562.0 8724.4 18:21:38 INFO - PROCESS | 1659 | -------- Summary: end -------- 18:21:38 INFO - PROCESS | 1659 | 18:21:38 INFO - TEST-INFO | 1659: exit 0 18:21:38 INFO - TEST-OK | dromaeo_css | took 1115896ms 18:21:38 INFO - TEST-START | kraken 18:21:38 INFO - operating with platform_type : mac_ 18:21:38 INFO - Initialising browser for kraken test... 18:21:38 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpNAW8IL/profile http://localhost:49278/getInfo.html) 18:21:40 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 18:21:40 INFO - PROCESS | 1668 | colorDepth:24 18:21:40 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 18:21:40 INFO - PROCESS | 1668 | __metrics 18:21:40 INFO - PROCESS | 1668 | 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" 18:21:40 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:21:41 INFO - TEST-INFO | 1668: exit 0 18:21:41 INFO - Browser initialized. 18:21:41 INFO - Running cycle 1/1 for kraken test... 18:21:41 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpNAW8IL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:53 INFO - PROCESS | 1670 | RSS: Main: 238731264 18:21:53 INFO - PROCESS | 1670 | 18:21:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:56 INFO - PROCESS | 1670 | RSS: Main: 287383552 18:21:56 INFO - PROCESS | 1670 | 18:21:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 18:22:00 INFO - PROCESS | 1670 | RSS: Main: 295837696 18:22:00 INFO - PROCESS | 1670 | 18:22:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 18:22:04 INFO - PROCESS | 1670 | RSS: Main: 385429504 18:22:04 INFO - PROCESS | 1670 | 18:22:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 18:22:07 INFO - PROCESS | 1670 | RSS: Main: 285364224 18:22:07 INFO - PROCESS | 1670 | 18:22:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:10 INFO - PROCESS | 1670 | RSS: Main: 289857536 18:22:10 INFO - PROCESS | 1670 | 18:22:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:13 INFO - PROCESS | 1670 | RSS: Main: 327634944 18:22:13 INFO - PROCESS | 1670 | 18:22:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:17 INFO - PROCESS | 1670 | RSS: Main: 329465856 18:22:17 INFO - PROCESS | 1670 | 18:22:20 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 18:22:21 INFO - PROCESS | 1670 | RSS: Main: 327852032 18:22:21 INFO - PROCESS | 1670 | 18:22:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:23 INFO - PROCESS | 1670 | RSS: Main: 315711488 18:22:23 INFO - PROCESS | 1670 | 18:22:25 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:26 INFO - PROCESS | 1670 | RSS: Main: 322887680 18:22:26 INFO - PROCESS | 1670 | 18:22:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:29 INFO - PROCESS | 1670 | RSS: Main: 325144576 18:22:29 INFO - PROCESS | 1670 | 18:22:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:32 INFO - PROCESS | 1670 | RSS: Main: 329371648 18:22:32 INFO - PROCESS | 1670 | 18:22:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:36 INFO - PROCESS | 1670 | RSS: Main: 327114752 18:22:36 INFO - PROCESS | 1670 | 18:22:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 18:22:38 INFO - PROCESS | 1670 | RSS: Main: 373903360 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | __start_tp_report 18:22:38 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 18:22:38 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 18:22:38 INFO - PROCESS | 1670 | |i|pagename|runs| 18:22:38 INFO - PROCESS | 1670 | |0;ai-astar;113;105;111;112;111;111;111;112;111;110 18:22:38 INFO - PROCESS | 1670 | |1;audio-beat-detection;158;133;168;250;120;136;135;118;166;118 18:22:38 INFO - PROCESS | 1670 | |2;audio-dft;187;186;183;187;186;184;185;182;180;185 18:22:38 INFO - PROCESS | 1670 | |3;audio-fft;77;72;59;125;57;59;57;58;112;55 18:22:38 INFO - PROCESS | 1670 | |4;audio-oscillator;94;100;150;116;120;86;103;80;77;102 18:22:38 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;86;85;88;85;86;84;89;85;87;84 18:22:38 INFO - PROCESS | 1670 | |6;imaging-darkroom;93;94;94;94;93;93;93;94;92;93 18:22:38 INFO - PROCESS | 1670 | |7;imaging-desaturate;83;84;83;100;141;82;83;86;83;84 18:22:38 INFO - PROCESS | 1670 | |8;json-parse-financial;59;59;60;60;59;59;59;121;57;57 18:22:38 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;62;70;75;75;70;70;66;67;91;100 18:22:38 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;84;86;85;84;85;82;83;84;86;85 18:22:38 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;145;124;128;131;151;143;144;132;214;139 18:22:38 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;168;167;167;169;166;164;172;165;166;167 18:22:38 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;66;67;67;66;64;66;67;66;66 18:22:38 INFO - PROCESS | 1670 | __end_tp_report 18:22:38 INFO - PROCESS | 1670 | __start_cc_report 18:22:38 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2210 18:22:38 INFO - PROCESS | 1670 | __end_cc_report 18:22:38 INFO - PROCESS | 1670 | __startTimestamp1462929758124__endTimestamp 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | ------- Summary: start ------- 18:22:38 INFO - PROCESS | 1670 | Number of tests: 14 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.16 (1.9%) stddev-sans-first:2.13 18:22:38 INFO - PROCESS | 1670 | Values: 113.0 105.0 111.0 112.0 111.0 111.0 111.0 112.0 111.0 110.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:147.00 stddev:39.86 (27.1%) stddev-sans-first:42.18 18:22:38 INFO - PROCESS | 1670 | Values: 158.0 133.0 168.0 250.0 120.0 136.0 135.0 118.0 166.0 118.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.27 (1.2%) stddev-sans-first:2.22 18:22:38 INFO - PROCESS | 1670 | Values: 187.0 186.0 183.0 187.0 186.0 184.0 185.0 182.0 180.0 185.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:73.10 Median:58.50 stddev:25.15 (43.0%) stddev-sans-first:26.64 18:22:38 INFO - PROCESS | 1670 | Values: 77.0 72.0 59.0 125.0 57.0 59.0 57.0 58.0 112.0 55.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:113.50 stddev:21.73 (19.1%) stddev-sans-first:22.82 18:22:38 INFO - PROCESS | 1670 | Values: 94.0 100.0 150.0 116.0 120.0 86.0 103.0 80.0 77.0 102.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.76 18:22:38 INFO - PROCESS | 1670 | Values: 86.0 85.0 88.0 85.0 86.0 84.0 89.0 85.0 87.0 84.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 18:22:38 INFO - PROCESS | 1670 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 93.0 94.0 92.0 93.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:83.00 stddev:18.38 (22.1%) stddev-sans-first:19.27 18:22:38 INFO - PROCESS | 1670 | Values: 83.0 84.0 83.0 100.0 141.0 82.0 83.0 86.0 83.0 84.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:59.00 stddev:19.70 (33.4%) stddev-sans-first:20.78 18:22:38 INFO - PROCESS | 1670 | Values: 59.0 59.0 60.0 60.0 59.0 59.0 59.0 121.0 57.0 57.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:70.00 stddev:11.87 (17.0%) stddev-sans-first:11.68 18:22:38 INFO - PROCESS | 1670 | Values: 62.0 70.0 75.0 75.0 70.0 70.0 66.0 67.0 91.0 100.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 18:22:38 INFO - PROCESS | 1670 | Values: 84.0 86.0 85.0 84.0 85.0 82.0 83.0 84.0 86.0 85.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:143.50 stddev:25.68 (17.9%) stddev-sans-first:27.23 18:22:38 INFO - PROCESS | 1670 | Values: 145.0 124.0 128.0 131.0 151.0 143.0 144.0 132.0 214.0 139.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:2.23 (1.3%) stddev-sans-first:2.35 18:22:38 INFO - PROCESS | 1670 | Values: 168.0 167.0 167.0 169.0 166.0 164.0 172.0 165.0 166.0 167.0 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:0.92 (1.4%) stddev-sans-first:0.93 18:22:38 INFO - PROCESS | 1670 | Values: 67.0 66.0 67.0 67.0 66.0 64.0 66.0 67.0 66.0 66.0 18:22:38 INFO - PROCESS | 1670 | -------- Summary: end -------- 18:22:38 INFO - PROCESS | 1670 | 18:22:38 INFO - TEST-INFO | 1670: exit 0 18:22:38 INFO - TEST-OK | kraken | took 60224ms 18:22:38 INFO - SUITE-END | took 1176s 18:22:39 INFO - Completed test suite (00:19:36) 18:22:39 INFO - javascript benchmark 18:22:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.182516196113, 3426.1434641339665, 3437.312537492501, 3445.8966231013046, 3488.2325888304563, 5551.722413879305, 5641.464060914217, 5681.687507187103, 5686.431356864311, 5700.087985922248, 5700.600957932947, 5762.769489220436, 5803.448672376117, 5806.651600903938, 5860.472557469838, 5184.040952423795, 5231.686098834063, 5282.762275697583, 5301.814436494724, 5383.596230282725, 5333.546648534879, 5372.7313634318325, 5393.056215162351, 5397.865053373658, 5427.3215848019, 5161.174212126042, 5175.9741201293755, 5206.219067139959, 5208.401033881114, 5269.736513174345, 5238.319018527567, 5241.8165364212455, 5247.212918062283, 5255.763490642892, 5262.2632831403635, 5251.894962100775, 5275.525202731773, 5277.973610131925, 5288.286081379003, 5298.947010529922, 5542.584306177013, 5560.749766260528, 5606.0, 5619.9884020876225, 5691.231683722727, 6209.72056257465, 6242.469390101847, 6244.157038799749, 6247.812565623039, 6272.3100458949475, 6262.436380725711, 6324.177856878622, 6347.0, 6350.714217860207, 6359.65021923794, 5698.7435565399655, 5755.625884317506, 5783.942160578425, 5846.269216347957, 5851.180834683148, 6343.1436756038265, 6358.968205158944, 6364.872702545923, 6379.936200638027, 6387.169667943138, 6235.251769787564, 6236.812895613139, 6241.7191226394925, 6242.406971337716, 6266.248050234001, 6146.385361463817, 6205.069239614094, 6261.686915654199, 6281.937180628135, 6333.240011198685, 6270.7805226816845, 6287.717052732638, 6292.968535157295, 6298.811035668938, 6313.9368606314265, 6298.0, 6306.558540902093, 6321.0, 6328.0, 6342.746290148342, 4835.685680430761, 4846.0, 4903.485134060944, 4925.87685307863, 4942.950570494321, 4407.757573333433, 4465.464144302705, 4508.774561271924, 4509.0, 4516.412866327422, 5665.971670141623, 5693.68684722336, 5709.200711900337, 5710.486056254957, 5717.542596592262, 6245.843853903689, 6283.874322513524, 6322.336154703782, 6323.462505687068, 6347.55567110307, 5766.682832444172, 5773.364929857712, 5775.740091695884, 5784.88430231393, 5832.387599302097, 5273.841784746463, 5419.566434685215, 5429.4027656957705, 5435.42927992563, 5439.347278326626, 6286.0, 6297.275813281532, 6352.333005034498, 6454.419102280911, 6457.741690332522, 6351.110844481684, 6357.650329231937, 6360.681965901779, 6364.268109167319, 6378.043293506012, 6287.245530536365, 6299.055141728777, 6413.1021656969, 6441.710123044568, 6445.16212892321, 6117.888303246575, 6271.0, 6271.404216599416, 6276.905846412216, 6290.842728931813, 5686.57350698691, 6080.543959202989, 6282.528810339334, 6351.936480635134, 6366.395192456709, 5971.134185543159, 6114.540688159306, 6131.356207598138, 6196.907046394217, 6204.565680402328, 6199.845003874939, 6218.471429928326, 6219.968900155652, 6228.159198508143, 6251.624902505864, 6036.456718895321, 6108.389161083856, 6110.602810817282, 6114.6636934969, 6120.898238317146, 6030.879382412415, 6099.400579572161, 6124.3809352984035, 6164.044573091178, 6201.844953876009, 6060.939390606214, 6069.45374916242, 6078.756849725962, 6082.996305609525, 6124.816255512342], "value": 5759.073729407455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.75325944916045, 514.2337916504408, 544.7194694732211, 546.7129756877639, 575.4906907386962, 31067.378652426938, 31464.52803207951, 43707.0, 44388.3341749874, 44453.555464445344, 43419.78290108553, 46132.53867461324, 46294.53705462944, 46362.768186159024, 46660.83347916304, 9771.56027978739, 9967.152792012652, 9973.50132493376, 9979.700608981742, 9987.0, 9694.612215511372, 9831.705048848511, 9835.409875407498, 9837.409755414697, 9843.950780246088, 9827.0, 9857.162141218005, 9885.950570247174, 9890.0, 9890.15933645641, 9313.580888860017, 9798.559064842098, 9859.112679858847, 9870.161036311922, 9876.40741555509, 8920.107989201093, 9872.0, 9873.851892221623, 9879.90120098797, 9899.505024748805, 42096.0, 42511.0, 42596.787016065035, 42609.360859587134, 42637.0, 3242.400155971129, 3572.8928132156075, 3856.421536769479, 3867.3232184367903, 3922.941155882664, 26109.73890261092, 29460.348568628928, 29509.557356639667, 30157.396852063055, 30878.84560577183, 2694.420699549606, 2816.1551534539763, 2821.238265668258, 2830.207541888254, 2844.900428485014, 486433.1356686423, 486464.5676771629, 486583.1341686573, 486881.0, 488801.0, 60591.39408605946, 65180.34819651743, 65985.01022484667, 66038.66980665163, 66176.33823661799, 69826.65086674636, 69898.0, 70012.94980575296, 70014.9497757534, 70047.64976175087, 17259.482215533553, 17510.0, 17523.12384380776, 17532.82467175312, 17533.123343832758, 13323.533676321282, 13365.732685346238, 13390.397432115578, 13393.330333483482, 13401.128926619738, 18057.088171245003, 18709.812901870806, 19001.809981900282, 19037.42887713371, 19209.598800149983, 5569.721513924287, 6585.374389432996, 6828.624425656637, 6920.8961865572055, 6959.4432445404245, 928.4800511713462, 1106.6624679472677, 1127.1490025031048, 1157.6469176901126, 1164.057113737875, 28201.858990704914, 30396.088117356514, 30954.69045309563, 31166.84416577948, 31310.68689313123, 1041.4844651897358, 1120.277421063426, 1182.3910685996718, 1195.3784032303154, 1213.648042067808, 15599.412135208966, 15619.60950976212, 15628.687426251412, 15912.681746365239, 15955.441559545361, 571675.0, 580346.0, 589154.0, 591620.0, 592294.0, 62143.6892815533, 62307.68846155831, 62410.68794655998, 62483.68758156271, 62540.68729656414, 1281.9294938778457, 1309.1948451702135, 1435.346917152693, 1468.067776961637, 1486.2494440307576, 31168.376632467676, 31274.374512510076, 31431.057068287988, 31551.053468395985, 31813.681863182, 54391.45608543864, 55015.0, 55057.449425505234, 55085.34743957688, 55147.17279240734, 469008.0, 476512.0, 476958.0, 478205.0, 479374.0, 35820.820895895355, 40489.98775030648, 40887.0, 41360.58639413567, 42407.575924241595, 46637.53362466468, 47016.7649161752, 48728.0, 48829.0, 48960.02079958452, 37473.4236347478, 38891.0, 39299.60700393074, 39964.40053399259, 40004.0, 37878.2424351517, 37999.43000854934, 38588.0, 39151.804240978614, 39522.80238598789, 38388.0, 39255.0, 39392.40911386274, 40182.79908600439, 42256.788716056224, 27919.0, 31786.52320215245, 34325.719891050954, 34889.0, 35140.0, 22612.547749045258, 23893.402664933517, 25040.74959250457, 25433.61849572221, 25452.49095018126], "value": 20508.525930382057, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.2135439797604, 396.42914203546906, 398.0983073338888, 401.8894803928919, 421.515257453928, 14326.498572549963, 20143.29498467554, 20162.89104099274, 21324.253651122213, 21368.0, 15623.84376156238, 17956.19197136132, 20258.392248232576, 20840.791592084075, 21178.25876094337, 5218.7651555679895, 5841.970790146054, 6138.416850399227, 6176.320604733476, 6188.907166392508, 6025.728842202111, 6052.213212282397, 6061.909071363933, 6089.725962331706, 6140.35526269742, 5878.588498805086, 5988.251468566429, 6029.728662210189, 6037.245344331958, 6052.334243233241, 5818.883622327572, 5944.197533333009, 5997.250343707036, 6004.18943442634, 6039.456448919596, 5188.662736922126, 5200.609954253408, 5242.60680448964, 5244.659097158673, 5247.16045432729, 24076.879615601985, 24353.14763983252, 25022.3511413982, 25224.49551008988, 25367.873160634263, 2133.615949129156, 2433.525462534789, 2496.987515062413, 2761.254461295459, 2764.543850264704, 14480.927595362062, 14492.20292883891, 14970.850291497054, 15319.4638187664, 15389.307481163263, 1656.8177500475058, 1711.5036639374566, 1715.3567412220418, 1756.3589289909091, 1759.3138675916402, 128922.0, 132335.33832330778, 132785.33607331902, 135177.0, 136103.27793444175, 11598.94200528992, 11609.0, 11654.88345116538, 11674.76650467003, 11808.291502509877, 14758.33587488544, 14868.479603213835, 14890.329935152944, 14893.627659308388, 14903.776443353358, 6461.935380646133, 8159.632816523271, 8548.401611887233, 8711.956440217886, 8849.247813935759, 8636.913630863612, 8647.437916535406, 8663.653453861774, 8789.055666351716, 8805.075467075992, 4493.1013797240685, 4587.7017993830295, 4597.517260687647, 4629.0, 4638.512956139624, 1671.707451196036, 1931.4012656076661, 1974.9210031598575, 1980.8712433691765, 1994.9401517954484, 836.5148214035845, 843.7679638099572, 869.964741957069, 934.7476181430976, 1057.0750593230923, 19657.0, 19960.50098747543, 20004.59990800205, 20156.0, 20180.09189586472, 905.8958219804807, 944.367273926471, 949.1647350331664, 970.8640790289365, 1039.1167507618463, 10273.74315642115, 10512.737181570368, 10551.522786809854, 10562.31344962575, 10597.364158150536, 586098.0, 601786.0, 602929.0, 608321.0, 610105.0, 94679.52660236793, 132825.33587332195, 133565.0, 142820.0, 148928.0, 983.7147227304145, 1019.1337363241333, 1035.4305132177271, 1035.9741006474899, 1052.7157667429904, 17343.959362438294, 17654.735178972584, 17748.3788067417, 17843.553911151805, 18158.27366905309, 51170.0, 56355.15467267912, 56444.15333769914, 57808.71095644495, 58008.0, 82267.17732822834, 82887.0, 83554.16445835464, 84714.0, 86245.0, 7896.052473703192, 7914.248146426311, 8005.479643823133, 8091.919080809116, 8264.0, 8939.150780675825, 11113.110951123728, 11453.370064646191, 12479.750404992032, 12663.81004284918, 10372.896271037192, 10616.566763487139, 10710.464476776286, 10819.459027048773, 10937.781244375226, 10480.94759526228, 10553.050225479743, 10678.46607669629, 10776.407297598395, 10837.620683276047, 9118.544072796467, 10358.792824143626, 10460.947695261475, 10693.770216425211, 11057.72355691082, 7843.176466471166, 8325.750227493185, 11428.885711142782, 11919.582814601446, 11977.64067077989, 10338.638147664795, 11289.830652540382, 11338.60314888975, 11583.826242606536, 11664.008559272557], "value": 9718.130937997314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.6386939395754, 1622.1240530113735, 1644.457329081403, 1767.6729804986078, 1887.245101959216, 5623.887522249553, 8579.142085791418, 8793.692220772275, 8818.118188181179, 9025.548722563879, 8679.045305016443, 8766.34252431067, 8854.911450885505, 8864.77838054049, 8873.866891996626, 2160.9891950540227, 2234.7765223477604, 2442.877856107201, 2478.033566908907, 2479.4049428137296, 2437.5124975004983, 2504.724480307165, 2505.7744802967727, 2510.083819405922, 2516.144510866305, 2438.6951631046118, 2464.864432456214, 2468.395243165427, 2475.6905386826647, 2476.108600903673, 2149.9462513437134, 2379.393254720035, 2445.755424457549, 2459.3605662527693, 2480.8139389545854, 2063.2881655828714, 2187.1798075721604, 2356.198892376583, 2467.6668649732237, 2498.0, 8789.165029322205, 8846.292296616319, 8848.867266991001, 8884.777880552972, 8892.955535222283, 5257.106291930343, 5435.2934118564335, 5473.2063850741815, 5486.286782718221, 5488.4237155098535, 3348.3972884880714, 3465.4801779733243, 3634.00064982132, 3636.9272614547826, 3644.4351125575577, 905.2803021597846, 1053.462734005655, 1055.9155267578576, 1060.1518784972068, 1069.2461814420815, 11925.40372981356, 11997.880021199851, 12012.519499220021, 12022.69943251417, 12053.337066461341, 4738.674567783001, 4831.227003679394, 4841.878953026132, 4877.804887804448, 4897.7795999180125, 10080.495975201211, 10106.191504679755, 10121.1903047756, 10125.443100629536, 10162.949185254027, 5381.569474442035, 5411.215373770781, 5416.891662166813, 5425.294711687456, 5437.157240627709, 4433.7561434121435, 4630.398048253705, 4795.256735206049, 4872.829450969199, 4961.90076198474, 4018.5378681452007, 4030.8186131624147, 4104.794760261975, 4139.447707109834, 4231.576842315744, 1338.1235290884485, 1344.5764584155959, 1344.865513448667, 1355.5187908292612, 1372.883304919093, 916.7158180963922, 920.7145784806731, 929.0987741890356, 932.9720108396623, 962.7785609309758, 5836.9124463133085, 5879.941200588025, 5960.135780311831, 6007.579469437097, 6017.939820601826, 450.91779728650846, 451.27795527156286, 464.1297567061759, 474.9073930583503, 475.46985111600316, 3831.63599458051, 3863.632954869283, 3899.1811719539214, 3938.5470670873215, 3951.9012024699614, 9994.600215991424, 10333.483325833678, 10531.0, 10532.789344213226, 10545.420001899816, 10201.948990255001, 10410.167186625053, 10416.166706663449, 10425.739356516147, 10431.478426078666, 7895.842083158304, 7968.243016913384, 7972.840543189103, 8023.0, 8104.675812967415, 5569.916451253315, 5674.148877668383, 5677.489025987681, 5702.885942281048, 5866.6480011199465, 9618.0, 9787.119159275415, 9987.350822196824, 10008.799824003625, 10170.898291016994, 9839.5080245986, 10317.9484102579, 10351.0, 10440.68677939663, 10483.895161048596, 6977.988191712275, 6995.230524642387, 7269.454790890599, 7425.183229844821, 7624.923750762421, 8243.175682431709, 8395.874061889199, 8474.576271186539, 8486.405951583576, 8495.575221238789, 7319.158296795937, 7415.258474152542, 7492.712096673727, 7531.246875312425, 7539.32146106853, 7316.743913962986, 7401.777946661608, 7412.518186317872, 7535.6985720570565, 7555.73554925575, 6958.582485050913, 7004.649767511706, 7040.014397984389, 7042.0, 7051.330123638246, 4628.907421851612, 4801.399825021872, 4823.782929768098, 4836.25038119078, 4958.876028099182, 4741.525847415231, 4776.522347765195, 4807.110684523374, 4859.222524396079, 5011.498850114959], "value": 4522.034855134388, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.59492659542593, 316.3562151022668, 321.8149564000699, 324.90252924122774, 329.1770573566085, 4530.569595888393, 4734.4081989751285, 4915.852524424264, 5085.889355917295, 5110.054639891623, 4923.6307276954285, 5096.898062038757, 5099.107656160167, 5125.2312153176945, 5175.171972484403, 3729.0, 3937.665298449621, 3941.8029098544957, 3951.565327813938, 3968.503937007874, 3365.7643964922463, 3917.7061720370943, 3960.940585891214, 3967.8611248606303, 3999.6000399959917, 3241.6434192238835, 3934.8229329680235, 3939.5469521005166, 3939.684825213976, 3965.790433917654, 3454.5509083818943, 3955.180616916222, 3974.1654252606986, 3975.7018223633345, 4004.5594984551676, 2828.6747024092288, 3219.388316219934, 3407.6081250656243, 3410.5054767058637, 3461.2558299965626, 4315.870523884289, 4542.432195975503, 4920.761053720585, 4939.802407903716, 5006.549410553068, 3782.0, 4394.956050439487, 4474.7986340614425, 4476.306172543261, 4477.395551600524, 4995.877576514678, 5276.3140791697215, 5317.12267475866, 5321.973390133025, 5385.219143224211, 3789.621037896216, 3817.3510503214243, 3823.445600387956, 3852.056246219694, 3873.9418908716393, 4114.362273847559, 4747.715137091785, 5079.8824258663035, 5153.948460515347, 5711.7715291388295, 3325.567676202078, 3472.531208286849, 3909.8691213288826, 3915.197384536163, 3927.546807681119, 3936.389859571771, 3937.9015524612114, 3953.6639385651965, 3955.4264631628253, 3978.0, 4335.544767799399, 4499.437570303712, 4503.301988191835, 4515.68390212682, 4531.84138555149, 5365.651232669864, 5445.53712934405, 5472.890542189134, 5476.385585513542, 5533.418991005967, 1163.66253786401, 1166.9008134308508, 1312.6324629103867, 1317.4005827348533, 1359.4833963094159, 716.8100952419063, 820.7906983719114, 824.1017291152673, 831.8585840407145, 860.3074525007304, 3806.4583843542855, 4116.903680837526, 4148.875533733993, 4160.771157586301, 4165.542330025239, 3975.2447035063824, 4314.590113939171, 4570.771461426915, 4575.787416334674, 4671.505118362092, 3582.4268117101137, 3883.3204189266767, 3922.882313530599, 3963.128111815396, 4123.0, 1942.5527981653477, 1962.3426152238874, 2267.614505534063, 2399.6520504526748, 2429.6962879640046, 4060.9796951015055, 4103.543242149054, 4442.555744425532, 4848.878778030577, 4871.269309603588, 4162.375643653476, 4265.398107154633, 4662.906741865144, 4664.440267167894, 4677.4854765975715, 4500.774961251925, 4706.835260765857, 4751.738654373973, 4767.713937163781, 4866.562009419169, 4244.684147914157, 4272.401863739141, 4422.933655995227, 4570.268756998864, 4647.697899636512, 4121.8969525762095, 4299.742015479082, 4335.371371151103, 4503.127310513021, 4580.4884388152495, 4756.833510827105, 4917.877053073702, 5335.38643056038, 5442.918356224739, 5553.148361198696, 3237.5791147151663, 3709.5919448860063, 3793.336166170854, 3826.559945606291, 3828.636279553438, 4661.417322834645, 5380.0, 5411.242426060276, 5448.427915068861, 5503.807366742145, 4593.954060459353, 5000.799488327546, 5353.009693206769, 5359.866003349792, 5397.433269506802, 4307.461567304087, 5266.499682530153, 5277.393099793573, 5280.76236569363, 5322.813701520583, 4244.91510169801, 4676.433394812776, 5193.610479213999, 5231.7705339244485, 5235.110031294613, 4480.932786008278, 4897.3878265216845, 5274.551663108678, 5309.7345132743985, 5314.62797604164, 5172.767020612117, 5239.69453440485, 5244.396894357045, 5255.763490642854, 5302.886393857333], "value": 3740.6595306998634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.7420148034124, 712.8823744082226, 718.515002373398, 733.7285204474346, 734.2767374136471, 14972.32624531896, 15050.849491505081, 15461.0, 15469.922650386732, 15771.21143942804, 14260.215688137148, 14351.35418906149, 14414.279286035682, 14423.567292981228, 14481.63795905103, 713.2083387439937, 729.2015243308568, 732.0300601702738, 910.2808781062969, 924.944503329799, 740.707420568875, 741.1365758890886, 888.5468411110315, 908.2960705453261, 909.995450022749, 958.396210387455, 960.4813400763614, 961.8653388525612, 962.1821451765978, 966.2849491376401, 910.4127837544779, 960.5149399553251, 960.6781728121088, 961.8605302231173, 963.0, 556.6632187526528, 697.0659316515843, 708.3589351636725, 710.073354272674, 723.8262816923954, 14865.851341486556, 14883.255837208204, 14924.850751492455, 14936.103833770008, 14937.775933361008, 12058.517659293619, 12080.214786038967, 12222.20555663879, 12412.875871241353, 12526.373681315898, 10766.515506802212, 10809.35143891369, 11059.557617695282, 11077.944610276978, 11478.598249061242, 4092.9795351023054, 4176.394422808676, 4181.996320882997, 4186.790660466995, 4267.231898258312, 71980.56038879193, 83647.5817620914, 83993.32013359759, 84227.0, 84230.57884710598, 8674.52533069367, 9373.90626093744, 9397.530123493934, 9444.574994125282, 9795.314327997114, 19505.0, 21248.575028499345, 21377.0, 21460.57078858414, 21880.89059554692, 7277.636118194069, 7338.376238019828, 7363.410927125817, 7535.246475352531, 7665.655045522964, 3358.932821343559, 3945.467361906163, 3960.4653371795016, 4032.7983600819844, 4114.156597897424, 3354.9329013419597, 3428.6571342866014, 3434.3303055903857, 3436.329915666421, 3437.5187473753695, 620.9782657606962, 631.3370960491466, 631.3528633151057, 636.8344230500011, 639.5139693832721, 549.3081309566526, 552.585560829378, 560.4451592923058, 573.4695406748824, 593.3769541980903, 7647.847043059219, 7779.649915753805, 7806.765797026098, 7881.724139655084, 7888.921110788933, 542.8697112692926, 551.8258063230937, 552.8341497550718, 562.4207066721284, 564.9435056494318, 3655.9268814624093, 3678.3562876496508, 3689.8155092245815, 3760.2103558253075, 3899.8245078971513, 82069.0, 82922.58538707388, 83975.0, 90792.54603726939, 91973.5401322989, 80480.0, 81586.59206703928, 82434.0, 83352.58323708343, 85541.14458855457, 71127.2887271131, 71821.92267115998, 72728.27271727215, 72891.63554182301, 73969.5206095875, 6624.171978502687, 6665.0, 6668.66656667155, 6671.5997040177745, 6859.279775623488, 44944.55055449404, 45592.772036139606, 46945.29582056204, 47853.80365490751, 47874.04251915012, 61891.0, 62357.68821155865, 63597.0460443103, 64418.71162576816, 65604.67197663982, 9818.607255709692, 9848.753781155528, 9879.209663227068, 10313.587456501657, 10427.739306517398, 10600.628977985734, 10666.253362264568, 10984.286021408583, 11033.062189713964, 11102.555897764, 9986.400815951067, 10059.195264378686, 10192.13366863825, 10311.845322320321, 10355.792884142425, 9776.1690256329, 9781.0, 10012.799744004933, 10150.0, 10835.566577337133, 9535.380200287236, 9573.521323933915, 9630.422174669262, 9683.080107389787, 9843.16333111665, 8292.212239837447, 8360.958195208985, 8589.44168629037, 8653.091425400495, 8697.434666746642, 8358.247757701836, 8458.0, 8473.576321183793, 8561.957190214009, 8724.3892927497], "value": 6644.721976924883, "unit": "score"}], "name": "dromaeo_css", "value": 7108.535785363616, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 111.0, 112.0, 111.0, 111.0, 111.0, 112.0, 111.0, 110.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 133.0, 168.0, 250.0, 120.0, 136.0, 135.0, 118.0, 166.0, 118.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 183.0, 187.0, 186.0, 184.0, 185.0, 182.0, 180.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 59.0, 125.0, 57.0, 59.0, 57.0, 58.0, 112.0, 55.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 150.0, 116.0, 120.0, 86.0, 103.0, 80.0, 77.0, 102.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 85.0, 86.0, 84.0, 89.0, 85.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 92.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 100.0, 141.0, 82.0, 83.0, 86.0, 83.0, 84.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 121.0, 57.0, 57.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 75.0, 75.0, 70.0, 70.0, 66.0, 67.0, 91.0, 100.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 85.0, 82.0, 83.0, 84.0, 86.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 124.0, 128.0, 131.0, 151.0, 143.0, 144.0, 132.0, 214.0, 139.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 169.0, 166.0, 164.0, 172.0, 165.0, 166.0, 167.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 66.0, 64.0, 66.0, 67.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1493.7999999999997, "alertThreshold": 2.0}]} 18:22:39 INFO - Return code: 0 18:22:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:22:39 ERROR - # TBPL SUCCESS # 18:22:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:39 INFO - 2016-05-10 18:22:39.449 screenresolution[1674:9563] starting screenresolution argv=screenresolution get 18:22:39 INFO - 2016-05-10 18:22:39.452 screenresolution[1674:9563] Display 0: 1600x1200x32@60 18:22:39 INFO - 2016-05-10 18:22:39.458 screenresolution[1675:9566] starting screenresolution argv=screenresolution list 18:22:39 INFO - Available Modes on Display 0 18:22:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:39 INFO - Intel Iris: 18:22:39 INFO - Chipset Model: Intel Iris 18:22:39 INFO - Type: GPU 18:22:39 INFO - Bus: Built-In 18:22:39 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:39 INFO - Vendor: Intel (0x8086) 18:22:39 INFO - Device ID: 0x0a2e 18:22:39 INFO - Revision ID: 0x0009 18:22:39 INFO - Displays: 18:22:39 INFO - Display: 18:22:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:39 INFO - Display Serial Number: 1600x1200 60 18:22:39 INFO - Main Display: Yes 18:22:39 INFO - Mirror: Off 18:22:39 INFO - Online: Yes 18:22:39 INFO - Rotation: Supported 18:22:39 INFO - Return code: 0 18:22:39 INFO - Running post-action listener: _resource_record_post_action 18:22:39 INFO - ##### 18:22:39 INFO - ##### Finished run-tests step (success) 18:22:39 INFO - ##### 18:22:39 INFO - Running post-run listener: _resource_record_post_run 18:22:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37828096; Write bytes: 691204608; Read time: 394; Write time: 1910 18:22:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377649664; Write bytes: 372045312; Read time: 28578; Write time: 662 18:22:40 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36861440; Write bytes: 294308864; Read time: 377; Write time: 1195 18:22:40 INFO - Running post-run listener: _upload_blobber_files 18:22:40 INFO - Blob upload gear active. 18:22:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:40 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 18:22:40 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'] 18:22:40 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 18:22:41 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:42 INFO - (blobuploader) - INFO - Done attempting. 18:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:22:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:43 INFO - (blobuploader) - INFO - Done attempting. 18:22:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:43 INFO - Return code: 0 18:22:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf71718c50d1bf4e6f77b67753efe470038390059c2830fc6e074d001678ee77f12879ff57dec733e8859ce9b12f1eebf716c93229fe63123e713d42a861fea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5205ec498b7a3eef8533b6e990bc534a0b7f9cba497ba99c23b39779169f861f4b38d4dbbe5f5a30f0c134e1585a1fc7fd18752397d1fdefc87ae46a6b3b769"} 18:22:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:43 INFO - Contents: 18:22:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf71718c50d1bf4e6f77b67753efe470038390059c2830fc6e074d001678ee77f12879ff57dec733e8859ce9b12f1eebf716c93229fe63123e713d42a861fea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5205ec498b7a3eef8533b6e990bc534a0b7f9cba497ba99c23b39779169f861f4b38d4dbbe5f5a30f0c134e1585a1fc7fd18752397d1fdefc87ae46a6b3b769"} 18:22:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:43 INFO - Copying logs to upload dir... 18:22:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.644159 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-10 18:22:43.245570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:22:43.249643) ========= 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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/bf71718c50d1bf4e6f77b67753efe470038390059c2830fc6e074d001678ee77f12879ff57dec733e8859ce9b12f1eebf716c93229fe63123e713d42a861fea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5205ec498b7a3eef8533b6e990bc534a0b7f9cba497ba99c23b39779169f861f4b38d4dbbe5f5a30f0c134e1585a1fc7fd18752397d1fdefc87ae46a6b3b769"} build_url:https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011071 build_url: 'https://queue.taskcluster.net/v1/task/M1F6DYyoRT-sPf08Sn01vA/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/bf71718c50d1bf4e6f77b67753efe470038390059c2830fc6e074d001678ee77f12879ff57dec733e8859ce9b12f1eebf716c93229fe63123e713d42a861fea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5205ec498b7a3eef8533b6e990bc534a0b7f9cba497ba99c23b39779169f861f4b38d4dbbe5f5a30f0c134e1585a1fc7fd18752397d1fdefc87ae46a6b3b769"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:22:43.278048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:22:43.278481) ========= 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.9WNYWCBX2e/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.5uvyy6VVo3/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.004992 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:22:43.327005) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:22:43.327379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:22:43.327773) ========= ========= Total master_lag: 0.15 =========