builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0068 starttime: 1462582862.37 results: success (0) buildid: 20160506155756 builduid: b2fe4a519c0542b9a3ebfc0a0d5d4d4c revision: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.369958) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.370396) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.370676) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.390151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.390563) ========= 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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-06 18:01:02-- 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% 7.59M=0.002s 2016-05-06 18:01:02 (7.59 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110377 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.518233) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.518540) ========= 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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.026518 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:02.566947) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 18:01:02.567306) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-06 18:01:02,643 truncating revision to first 12 chars 2016-05-06 18:01:02,643 Setting DEBUG logging. 2016-05-06 18:01:02,643 attempt 1/10 2016-05-06 18:01:02,643 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70dfd4254cf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 18:01:04,148 unpacking tar archive at: mozilla-central-70dfd4254cf9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.750712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 18:01:04.335809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:04.336288) ========= script_repo_revision: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:04.336799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:04.337177) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:04.351369) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-06 18:01:04.351749) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:04 INFO - MultiFileLogger online at 20160506 18:01:04 in /builds/slave/test 18:01:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:01:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:04 INFO - {'append_to_log': False, 18:01:04 INFO - 'base_work_dir': '/builds/slave/test', 18:01:04 INFO - 'blob_upload_branch': 'Firefox', 18:01:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:04 INFO - 'branch': 'Firefox', 18:01:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:04 INFO - 'config_files': ('talos/mac_config.py',), 18:01:04 INFO - 'default_actions': ('clobber', 18:01:04 INFO - 'read-buildbot-config', 18:01:04 INFO - 'download-and-extract', 18:01:04 INFO - 'populate-webroot', 18:01:04 INFO - 'create-virtualenv', 18:01:04 INFO - 'install', 18:01:04 INFO - 'run-tests'), 18:01:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:04 INFO - 'download_minidump_stackwalk': True, 18:01:04 INFO - 'download_symbols': 'ondemand', 18:01:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:04 INFO - 'installer_path': 'installer.exe', 18:01:04 INFO - 'log_level': 'info', 18:01:04 INFO - 'log_name': 'talos', 18:01:04 INFO - 'log_to_console': True, 18:01:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:04 INFO - 'opt_config_files': (), 18:01:04 INFO - 'pip_index': False, 18:01:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:04 INFO - 'cmd': ('bash', 18:01:04 INFO - '-c', 18:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:04 INFO - 'enabled': True, 18:01:04 INFO - 'halt_on_failure': False, 18:01:04 INFO - 'name': 'check_screen_resolution'},), 18:01:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:04 INFO - 'cmd': ('bash', 18:01:04 INFO - '-c', 18:01:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:04 INFO - 'enabled': True, 18:01:04 INFO - 'halt_on_failure': False, 18:01:04 INFO - 'name': 'check_screen_resolution'},), 18:01:04 INFO - 'run_cmd_checks_enabled': True, 18:01:04 INFO - 'sps_profile': False, 18:01:04 INFO - 'sps_profile_interval': 0, 18:01:04 INFO - 'suite': 'dromaeojs', 18:01:04 INFO - 'system_bits': '32', 18:01:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:04 INFO - 'title': 't-yosemite-r7-0068', 18:01:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:04 INFO - 'use_talos_json': True, 18:01:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:04 INFO - 'work_dir': 'build'} 18:01:04 INFO - ##### 18:01:04 INFO - ##### Running clobber step. 18:01:04 INFO - ##### 18:01:04 INFO - Running pre-action listener: _resource_record_pre_action 18:01:04 INFO - Running main action method: clobber 18:01:04 INFO - rmtree: /builds/slave/test/build 18:01:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:06 INFO - Running post-action listener: _resource_record_post_action 18:01:06 INFO - ##### 18:01:06 INFO - ##### Finished clobber step (success) 18:01:06 INFO - ##### 18:01:06 INFO - ##### 18:01:06 INFO - ##### Running read-buildbot-config step. 18:01:06 INFO - ##### 18:01:06 INFO - Running pre-action listener: _resource_record_pre_action 18:01:06 INFO - Running main action method: read_buildbot_config 18:01:06 INFO - Using buildbot properties: 18:01:06 INFO - { 18:01:06 INFO - "project": "", 18:01:06 INFO - "product": "firefox", 18:01:06 INFO - "script_repo_revision": "production", 18:01:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:01:06 INFO - "repository": "", 18:01:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:01:06 INFO - "buildid": "20160506155756", 18:01:06 INFO - "pgo_build": "False", 18:01:06 INFO - "basedir": "/builds/slave/test", 18:01:06 INFO - "buildnumber": 1, 18:01:06 INFO - "slavename": "t-yosemite-r7-0068", 18:01:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:01:06 INFO - "platform": "yosemite_r7", 18:01:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:01:06 INFO - "branch": "mozilla-central", 18:01:06 INFO - "repo_path": "mozilla-central", 18:01:06 INFO - "revision": "70dfd4254cf977be2000b41f7a6b97eaada8e2a0", 18:01:06 INFO - "stage_platform": "macosx64", 18:01:06 INFO - "builduid": "b2fe4a519c0542b9a3ebfc0a0d5d4d4c", 18:01:06 INFO - "slavebuilddir": "test" 18:01:06 INFO - } 18:01:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:01:06 INFO - Running post-action listener: _resource_record_post_action 18:01:06 INFO - ##### 18:01:06 INFO - ##### Finished read-buildbot-config step (success) 18:01:06 INFO - ##### 18:01:06 INFO - ##### 18:01:06 INFO - ##### Running download-and-extract step. 18:01:06 INFO - ##### 18:01:06 INFO - Running pre-action listener: _resource_record_pre_action 18:01:06 INFO - Running main action method: download_and_extract 18:01:06 INFO - mkdir: /builds/slave/test/build/tests 18:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:06 INFO - https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:01:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:07 INFO - Downloaded 1199 bytes. 18:01:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:07 INFO - Using the following test package requirements: 18:01:07 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:07 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:07 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:01:07 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'jsshell-mac.zip'], 18:01:07 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:01:07 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:07 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:01:07 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:01:07 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:01:07 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:07 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:07 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:07 INFO - https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:01:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:08 INFO - Downloaded 18145046 bytes. 18:01:08 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:08 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:11 INFO - Return code: 0 18:01:11 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:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:11 INFO - https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:01:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:12 INFO - Downloaded 18145046 bytes. 18:01:12 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:12 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:16 INFO - Return code: 0 18:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:16 INFO - https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:01:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:01:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:01:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:17 INFO - Downloaded 10840152 bytes. 18:01:17 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:17 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:17 INFO - Return code: 0 18:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:17 INFO - https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:01:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:01:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekHv_qvJTQSDWDf2r7FG7w/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:20 INFO - Downloaded 110459719 bytes. 18:01:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:20 INFO - mkdir: /builds/slave/test/properties 18:01:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:20 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:20 INFO - Contents: 18:01:20 INFO - build_url:https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:01:21 INFO - Running post-action listener: _resource_record_post_action 18:01:21 INFO - Running post-action listener: set_extra_try_arguments 18:01:21 INFO - ##### 18:01:21 INFO - ##### Finished download-and-extract step (success) 18:01:21 INFO - ##### 18:01:21 INFO - ##### 18:01:21 INFO - ##### Running populate-webroot step. 18:01:21 INFO - ##### 18:01:21 INFO - Running pre-action listener: _resource_record_pre_action 18:01:21 INFO - Running main action method: populate_webroot 18:01:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:21 INFO - '--tppagecycles', 18:01:21 INFO - '7'], 18:01:21 INFO - 'tests': ['tsvgm']}}, 18:01:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['damp', 'tps']}, 18:01:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'tests': ['damp', 'tps']}, 18:01:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['dromaeo_dom']}, 18:01:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['a11yr', 18:01:21 INFO - 'ts_paint', 18:01:21 INFO - 'tpaint', 18:01:21 INFO - 'sessionrestore', 18:01:21 INFO - 'sessionrestore_no_auto_restore', 18:01:21 INFO - 'tabpaint']}, 18:01:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['tsvgx', 18:01:21 INFO - 'tsvgr_opacity', 18:01:21 INFO - 'tart', 18:01:21 INFO - 'tscrollx', 18:01:21 INFO - 'cart']}, 18:01:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:21 INFO - 'tsvgr_opacity', 18:01:21 INFO - 'tart', 18:01:21 INFO - 'tscrollx', 18:01:21 INFO - 'cart']}, 18:01:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'talos_options': ['--disable-e10s'], 18:01:21 INFO - 'tests': ['tp5o']}, 18:01:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'tests': ['tp5o']}, 18:01:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'talos_options': ['--disable-e10s', 18:01:21 INFO - '--xperf_path', 18:01:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:21 INFO - 'tests': ['tp5n']}, 18:01:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:21 INFO - 'talos_options': ['--xperf_path', 18:01:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:21 INFO - 'tests': ['tp5n']}}, 18:01:21 INFO - 'talos.zip': {'path': '', 18:01:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:21 INFO - Running post-action listener: _resource_record_post_action 18:01:21 INFO - ##### 18:01:21 INFO - ##### Finished populate-webroot step (success) 18:01:21 INFO - ##### 18:01:21 INFO - ##### 18:01:21 INFO - ##### Running create-virtualenv step. 18:01:21 INFO - ##### 18:01:21 INFO - Running pre-action listener: _resource_record_pre_action 18:01:21 INFO - Running main action method: create_virtualenv 18:01:21 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:21 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:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:21 INFO - Using real prefix '/tools/python27' 18:01:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:22 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:25 INFO - Installing pip.................done. 18:01:25 INFO - Return code: 0 18:01:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:25 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:25 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:25 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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:25 INFO - 'HOME': '/Users/cltbld', 18:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:25 INFO - 'LOGNAME': 'cltbld', 18:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:25 INFO - 'MOZ_NO_REMOTE': '1', 18:01:25 INFO - 'NO_EM_RESTART': '1', 18:01:25 INFO - 'PAGER': '/bin/cat', 18:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:25 INFO - 'PWD': '/builds/slave/test', 18:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:25 INFO - 'SHELL': '/bin/bash', 18:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:25 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:25 INFO - 'USER': 'cltbld', 18:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:25 INFO - 'XPC_FLAGS': '0x0', 18:01:25 INFO - 'XPC_SERVICE_NAME': '0', 18:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:25 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:25 INFO - Cleaning up... 18:01:25 INFO - Return code: 0 18:01:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:25 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:25 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:25 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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:25 INFO - 'HOME': '/Users/cltbld', 18:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:25 INFO - 'LOGNAME': 'cltbld', 18:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:25 INFO - 'MOZ_NO_REMOTE': '1', 18:01:25 INFO - 'NO_EM_RESTART': '1', 18:01:25 INFO - 'PAGER': '/bin/cat', 18:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:25 INFO - 'PWD': '/builds/slave/test', 18:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:25 INFO - 'SHELL': '/bin/bash', 18:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:25 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:25 INFO - 'USER': 'cltbld', 18:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:25 INFO - 'XPC_FLAGS': '0x0', 18:01:25 INFO - 'XPC_SERVICE_NAME': '0', 18:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:26 INFO - Downloading/unpacking psutil>=0.7.1 18:01: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:01: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:01: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:01: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:01: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:01: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:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:28 INFO - Installing collected packages: psutil 18:01:28 INFO - Running setup.py install for psutil 18:01:28 INFO - building 'psutil._psutil_osx' extension 18:01:28 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:01:29 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:01:29 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:01:29 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:01:29 INFO - building 'psutil._psutil_posix' extension 18:01:29 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:01:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:01:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:01:29 INFO - ^ 18:01:29 INFO - 1 warning generated. 18:01:29 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:01:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:29 INFO - Successfully installed psutil 18:01:29 INFO - Cleaning up... 18:01:29 INFO - Return code: 0 18:01:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:29 INFO - 'HOME': '/Users/cltbld', 18:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:29 INFO - 'LOGNAME': 'cltbld', 18:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:29 INFO - 'MOZ_NO_REMOTE': '1', 18:01:29 INFO - 'NO_EM_RESTART': '1', 18:01:29 INFO - 'PAGER': '/bin/cat', 18:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:29 INFO - 'PWD': '/builds/slave/test', 18:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:29 INFO - 'SHELL': '/bin/bash', 18:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:29 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:29 INFO - 'USER': 'cltbld', 18:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:29 INFO - 'XPC_FLAGS': '0x0', 18:01:29 INFO - 'XPC_SERVICE_NAME': '0', 18:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:32 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:01:32 INFO - Installing collected packages: mozsystemmonitor 18:01:32 INFO - Running setup.py install for mozsystemmonitor 18:01:32 INFO - Successfully installed mozsystemmonitor 18:01:32 INFO - Cleaning up... 18:01:32 INFO - Return code: 0 18:01:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:32 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:32 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:01:32 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:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:32 INFO - 'HOME': '/Users/cltbld', 18:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:32 INFO - 'LOGNAME': 'cltbld', 18:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:32 INFO - 'MOZ_NO_REMOTE': '1', 18:01:32 INFO - 'NO_EM_RESTART': '1', 18:01:32 INFO - 'PAGER': '/bin/cat', 18:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:32 INFO - 'PWD': '/builds/slave/test', 18:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:32 INFO - 'SHELL': '/bin/bash', 18:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:32 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:32 INFO - 'USER': 'cltbld', 18:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:32 INFO - 'XPC_FLAGS': '0x0', 18:01:32 INFO - 'XPC_SERVICE_NAME': '0', 18:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:33 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:35 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:01:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:01:36 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:36 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:36 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:36 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:36 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:36 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:36 INFO - Downloading docopt-0.6.1.tar.gz 18:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:01:36 INFO - Installing collected packages: blobuploader, requests, docopt 18:01:36 INFO - Running setup.py install for blobuploader 18:01:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:01:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:01:36 INFO - Running setup.py install for requests 18:01:37 INFO - Running setup.py install for docopt 18:01:37 INFO - Successfully installed blobuploader requests docopt 18:01:37 INFO - Cleaning up... 18:01:37 INFO - Return code: 0 18:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:37 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:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:37 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:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:37 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:37 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:01:37 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:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:37 INFO - 'HOME': '/Users/cltbld', 18:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:37 INFO - 'LOGNAME': 'cltbld', 18:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:37 INFO - 'MOZ_NO_REMOTE': '1', 18:01:37 INFO - 'NO_EM_RESTART': '1', 18:01:37 INFO - 'PAGER': '/bin/cat', 18:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:37 INFO - 'PWD': '/builds/slave/test', 18:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:37 INFO - 'SHELL': '/bin/bash', 18:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:37 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:37 INFO - 'USER': 'cltbld', 18:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:37 INFO - 'XPC_FLAGS': '0x0', 18:01:37 INFO - 'XPC_SERVICE_NAME': '0', 18:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PTVbZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-E_1sra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-aIwxiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-udmvim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YQjkt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-etGU1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-dbP8Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-jislmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-QYUN4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-T3rFAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:38 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-06EENc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-A6N68E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-7LG1Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-amXEu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-gyqwJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-cgAIBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:39 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-kvMTZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:01:39 INFO - Running setup.py install for manifestparser 18:01:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:01:39 INFO - Running setup.py install for mozcrash 18:01:39 INFO - Running setup.py install for mozdebug 18:01:40 INFO - Running setup.py install for mozdevice 18:01:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Running setup.py install for mozfile 18:01:40 INFO - Running setup.py install for mozhttpd 18:01:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Running setup.py install for mozinfo 18:01:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Running setup.py install for mozInstall 18:01:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:01:40 INFO - Running setup.py install for mozleak 18:01:40 INFO - Running setup.py install for mozlog 18:01:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Running setup.py install for moznetwork 18:01:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Running setup.py install for mozprocess 18:01:41 INFO - Running setup.py install for mozprofile 18:01:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Running setup.py install for mozrunner 18:01:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:01:41 INFO - Running setup.py install for mozscreenshot 18:01:41 INFO - Running setup.py install for moztest 18:01:42 INFO - Running setup.py install for mozversion 18:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:01:42 INFO - Cleaning up... 18:01:42 INFO - Return code: 0 18:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:42 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:42 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:01:42 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:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:42 INFO - 'HOME': '/Users/cltbld', 18:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:42 INFO - 'LOGNAME': 'cltbld', 18:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:42 INFO - 'MOZ_NO_REMOTE': '1', 18:01:42 INFO - 'NO_EM_RESTART': '1', 18:01:42 INFO - 'PAGER': '/bin/cat', 18:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:42 INFO - 'PWD': '/builds/slave/test', 18:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:42 INFO - 'SHELL': '/bin/bash', 18:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:42 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:42 INFO - 'USER': 'cltbld', 18:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:42 INFO - 'XPC_FLAGS': '0x0', 18:01:42 INFO - 'XPC_SERVICE_NAME': '0', 18:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Xp5pub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:42 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:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-bKgVH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:42 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:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Dnv5I9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:42 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:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-VziP8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:42 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:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:42 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0Eafbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-FWaBmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YB55KX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-TatX_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-LZd25i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-kkXk0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5lqjKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-wXypN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-w84N_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-phT6Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:43 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-VzFtNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:44 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Km4qcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:44 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0wWue7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:01:44 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:44 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:44 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:44 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:44 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:44 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:46 INFO - Downloading blessings-1.6.tar.gz 18:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:01:46 INFO - Installing collected packages: blessings 18:01:46 INFO - Running setup.py install for blessings 18:01:46 INFO - Successfully installed blessings 18:01:46 INFO - Cleaning up... 18:01:47 INFO - Return code: 0 18:01:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:01:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:47 INFO - Reading from file tmpfile_stdout 18:01:47 INFO - Current package versions: 18:01:47 INFO - blessings == 1.6 18:01:47 INFO - blobuploader == 1.2.4 18:01:47 INFO - docopt == 0.6.1 18:01:47 INFO - manifestparser == 1.1 18:01:47 INFO - mozInstall == 1.12 18:01:47 INFO - mozcrash == 0.17 18:01:47 INFO - mozdebug == 0.1 18:01:47 INFO - mozdevice == 0.48 18:01:47 INFO - mozfile == 1.2 18:01:47 INFO - mozhttpd == 0.7 18:01:47 INFO - mozinfo == 0.9 18:01:47 INFO - mozleak == 0.1 18:01:47 INFO - mozlog == 3.1 18:01:47 INFO - moznetwork == 0.27 18:01:47 INFO - mozprocess == 0.22 18:01:47 INFO - mozprofile == 0.28 18:01:47 INFO - mozrunner == 6.11 18:01:47 INFO - mozscreenshot == 0.1 18:01:47 INFO - mozsystemmonitor == 0.0 18:01:47 INFO - moztest == 0.7 18:01:47 INFO - mozversion == 1.4 18:01:47 INFO - psutil == 3.1.1 18:01:47 INFO - requests == 1.2.3 18:01:47 INFO - wsgiref == 0.1.2 18:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:47 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:01:47 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:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:47 INFO - 'HOME': '/Users/cltbld', 18:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:47 INFO - 'LOGNAME': 'cltbld', 18:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:47 INFO - 'MOZ_NO_REMOTE': '1', 18:01:47 INFO - 'NO_EM_RESTART': '1', 18:01:47 INFO - 'PAGER': '/bin/cat', 18:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:47 INFO - 'PWD': '/builds/slave/test', 18:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:47 INFO - 'SHELL': '/bin/bash', 18:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:47 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:47 INFO - 'USER': 'cltbld', 18:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:47 INFO - 'XPC_FLAGS': '0x0', 18:01:47 INFO - 'XPC_SERVICE_NAME': '0', 18:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 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:01:47 INFO - Cleaning up... 18:01:47 INFO - Return code: 0 18:01:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:47 INFO - 'HOME': '/Users/cltbld', 18:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:47 INFO - 'LOGNAME': 'cltbld', 18:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:47 INFO - 'MOZ_NO_REMOTE': '1', 18:01:47 INFO - 'NO_EM_RESTART': '1', 18:01:47 INFO - 'PAGER': '/bin/cat', 18:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:47 INFO - 'PWD': '/builds/slave/test', 18:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:47 INFO - 'SHELL': '/bin/bash', 18:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:47 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:47 INFO - 'USER': 'cltbld', 18:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:47 INFO - 'XPC_FLAGS': '0x0', 18:01:47 INFO - 'XPC_SERVICE_NAME': '0', 18:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:47 INFO - Downloading/unpacking jsonschema 18:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:01:50 INFO - Downloading/unpacking functools32 (from jsonschema) 18:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:01:50 INFO - warning: no files found matching '*.txt' 18:01:50 INFO - no previously-included directories found matching 'build' 18:01:50 INFO - no previously-included directories found matching 'dist' 18:01:50 INFO - no previously-included directories found matching '.git*' 18:01:50 INFO - Installing collected packages: jsonschema, functools32 18:01:50 INFO - Running setup.py install for functools32 18:01:50 INFO - warning: no files found matching '*.txt' 18:01:50 INFO - no previously-included directories found matching 'build' 18:01:50 INFO - no previously-included directories found matching 'dist' 18:01:50 INFO - no previously-included directories found matching '.git*' 18:01:50 INFO - Successfully installed jsonschema functools32 18:01:50 INFO - Cleaning up... 18:01:51 INFO - Return code: 0 18:01:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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 0x10a1daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe54ac653d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, '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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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:51 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:01:51 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:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:51 INFO - 'HOME': '/Users/cltbld', 18:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:51 INFO - 'LOGNAME': 'cltbld', 18:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:51 INFO - 'MOZ_NO_REMOTE': '1', 18:01:51 INFO - 'NO_EM_RESTART': '1', 18:01:51 INFO - 'PAGER': '/bin/cat', 18:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:51 INFO - 'PWD': '/builds/slave/test', 18:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:51 INFO - 'SHELL': '/bin/bash', 18:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:01:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:51 INFO - 'USER': 'cltbld', 18:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:51 INFO - 'XPC_FLAGS': '0x0', 18:01:51 INFO - 'XPC_SERVICE_NAME': '0', 18:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:51 INFO - Downloading/unpacking flake8 18:01:51 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:51 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:51 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:51 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:51 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:51 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:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:01:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:01:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:01:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:01:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:01:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:01:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:01:54 INFO - no previously-included directories found matching 'docs/_build' 18:01:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:01:54 INFO - Running setup.py install for pep8 18:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:01:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:01:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:01:55 INFO - no previously-included directories found matching 'docs/_build' 18:01:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:01:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:01:55 INFO - Cleaning up... 18:01:55 INFO - Return code: 0 18:01:55 INFO - Running post-action listener: _resource_record_post_action 18:01:55 INFO - Running post-action listener: _start_resource_monitoring 18:01:55 INFO - Starting resource monitoring. 18:01:55 INFO - ##### 18:01:55 INFO - ##### Finished create-virtualenv step (success) 18:01:55 INFO - ##### 18:01:55 INFO - ##### 18:01:55 INFO - ##### Running install step. 18:01:55 INFO - ##### 18:01:55 INFO - Running pre-action listener: _resource_record_pre_action 18:01:55 INFO - Running main action method: install 18:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:55 INFO - Reading from file tmpfile_stdout 18:01:55 INFO - Detecting whether we're running mozinstall >=1.0... 18:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:01:55 INFO - Reading from file tmpfile_stdout 18:01:55 INFO - Output received: 18:01:55 INFO - Usage: mozinstall [options] installer 18:01:55 INFO - Options: 18:01:55 INFO - -h, --help show this help message and exit 18:01:55 INFO - -d DEST, --destination=DEST 18:01:55 INFO - Directory to install application into. [default: 18:01:55 INFO - "/builds/slave/test"] 18:01:55 INFO - --app=APP Application being installed. [default: firefox] 18:01:55 INFO - mkdir: /builds/slave/test/build/application 18:01:55 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:01:55 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:02:29 INFO - Reading from file tmpfile_stdout 18:02:29 INFO - Output received: 18:02:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:02:29 INFO - Running post-action listener: _resource_record_post_action 18:02:29 INFO - ##### 18:02:29 INFO - ##### Finished install step (success) 18:02:29 INFO - ##### 18:02:29 INFO - ##### 18:02:29 INFO - ##### Running run-tests step. 18:02:29 INFO - ##### 18:02:29 INFO - Running pre-action listener: _resource_record_pre_action 18:02:29 INFO - Running main action method: run_tests 18:02:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:29 INFO - 2016-05-06 18:02:29.600 screenresolution[1642:7478] starting screenresolution argv=screenresolution get 18:02:29 INFO - 2016-05-06 18:02:29.603 screenresolution[1642:7478] Display 0: 1600x1200x32@60 18:02:29 INFO - 2016-05-06 18:02:29.608 screenresolution[1643:7480] starting screenresolution argv=screenresolution list 18:02:29 INFO - Available Modes on Display 0 18:02:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:29 INFO - Intel Iris: 18:02:29 INFO - Chipset Model: Intel Iris 18:02:29 INFO - Type: GPU 18:02:29 INFO - Bus: Built-In 18:02:29 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:29 INFO - Vendor: Intel (0x8086) 18:02:29 INFO - Device ID: 0x0a2e 18:02:29 INFO - Revision ID: 0x0009 18:02:29 INFO - Displays: 18:02:29 INFO - Display: 18:02:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:29 INFO - Display Serial Number: 1600x1200 60 18:02:29 INFO - Main Display: Yes 18:02:29 INFO - Mirror: Off 18:02:29 INFO - Online: Yes 18:02:29 INFO - Rotation: Supported 18:02:29 INFO - Return code: 0 18:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:29 INFO - Python 2.7.3 18:02:29 INFO - Return code: 0 18:02:29 INFO - grabbing minidump binary from tooltool 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a07ed30>, '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 0x109ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a075b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:29 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:02:29 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:02:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:29 INFO - Return code: 0 18:02:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:02:31 INFO - Return code: 0 18:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hLDcTFdO8a/Render', 18:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:31 INFO - 'HOME': '/Users/cltbld', 18:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:31 INFO - 'LOGNAME': 'cltbld', 18:02:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:31 INFO - 'MOZ_NO_REMOTE': '1', 18:02:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:31 INFO - 'NO_EM_RESTART': '1', 18:02:31 INFO - 'PAGER': '/bin/cat', 18:02:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:31 INFO - 'PWD': '/builds/slave/test', 18:02:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:31 INFO - 'SHELL': '/bin/bash', 18:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cNp3Ce3gV5/Listeners', 18:02:31 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 18:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:31 INFO - 'USER': 'cltbld', 18:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:31 INFO - 'XPC_FLAGS': '0x0', 18:02:31 INFO - 'XPC_SERVICE_NAME': '0', 18:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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:02:31 INFO - mozversion application_buildid: 20160506155756 18:02:31 INFO - mozversion application_changeset: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 18:02:31 INFO - mozversion application_display_name: Nightly 18:02:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:31 INFO - mozversion application_name: Firefox 18:02:31 INFO - mozversion application_remotingname: firefox 18:02:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:31 INFO - mozversion application_vendor: Mozilla 18:02:31 INFO - mozversion application_version: 49.0a1 18:02:31 INFO - mozversion platform_buildid: 20160506155756 18:02:31 INFO - mozversion platform_changeset: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 18:02:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:31 INFO - mozversion platform_version: 49.0a1 18:02:31 INFO - using testdate: 1462582951 18:02:31 INFO - actual date: 1462582951 18:02:31 INFO - starting webserver on 'localhost:49278' 18:02:31 INFO - SUITE-START | Running 2 tests 18:02:31 INFO - TEST-START | dromaeo_css 18:02:31 INFO - operating with platform_type : mac_ 18:02:31 INFO - Initialising browser for dromaeo_css test... 18:02:31 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpnVDz7x/profile http://localhost:49278/getInfo.html) 18:02:33 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 18:02:33 INFO - PROCESS | 1661 | colorDepth:24 18:02:33 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 18:02:33 INFO - PROCESS | 1661 | __metrics 18:02:33 INFO - PROCESS | 1661 | 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:02:33 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:02:34 INFO - TEST-INFO | 1661: exit 0 18:02:34 INFO - Browser initialized. 18:02:34 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:34 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpnVDz7x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:46 INFO - PROCESS | 1663 | RSS: Main: 239071232 18:02:46 INFO - PROCESS | 1663 | 18:03:37 INFO - PROCESS | 1663 | 1462583017812 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:05:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 18:05:49 INFO - PROCESS | 1663 | RSS: Main: 365522944 18:05:49 INFO - PROCESS | 1663 | 18:08:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 18:08:53 INFO - PROCESS | 1663 | RSS: Main: 373723136 18:08:53 INFO - PROCESS | 1663 | 18:11:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 18:11:56 INFO - PROCESS | 1663 | RSS: Main: 366354432 18:11:56 INFO - PROCESS | 1663 | 18:14:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 18:14:59 INFO - PROCESS | 1663 | RSS: Main: 358289408 18:14:59 INFO - PROCESS | 1663 | 18:18:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 18:18:03 INFO - PROCESS | 1663 | RSS: Main: 331395072 18:18:03 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 18:21:06 INFO - PROCESS | 1663 | RSS: Main: 379772928 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | __start_tp_report 18:21:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 18:21:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 18:21:06 INFO - PROCESS | 1663 | |i|pagename|runs| 18:21:06 INFO - PROCESS | 1663 | |0;dojo.html;3102.3174901521666;3402.7958322500644;3434.8454319555617;3461.688448039676;3513.8243087845617;4730.739809310487;5584.776608935637;5591.2731344925205;5671.6880571568545;5703.714814259286;5635.549156067515;5710.000749868766;5717.542596592273;5723.9713801430935;5731.914021289674;5168.405633352166;5192.221166824968;5210.218467229927;5226.7647955841885;5293.497117773805;5221.112410890158;5284.39229488608;5284.550813180865;5288.524032837044;5296.09966305729;5202.089634313998;5210.478952104778;5230.895382092356;5243.030039442715;5292.444293349181;5297.947020529803;5321.707306098163;5326.77259661451;5347;5357.598180136505;5106.387233531963;5116.334876466073;5132.127538318495;5218.556422704075;5242.580593552507;5556.333240011225;5564.888702225933;5615.971920140414;5625.718714064321;5662.405447428002;6290.685465726695;6297.527685423565;6297.590656607306;6302.212223472066;6316.336784637593;6128.3258841527395;6228.626282423023;6301.842453938642;6355.237371515448;6372.203474565679;5834.649921004753;5866.589338746289;5888.941110588925;5892;5907.320658124285;6304.873902522016;6307.211598550181;6312.873742525216;6313.021481670348;6319.58922670025;6054.878902422015;6110.969445152746;6111.480524155408;6119.5104391648565;6154.261488621394;5213.244079608436;6295.622262664255;6305.873882522324;6312.400321969406;6314.431701146918;6199.504039676815;6225.937740622627;6229.345918678564;6241.500679945593;6243.469305109026;6021.879562408727;6039.275286965592;6295.842603934847;6307.842303942346;6308;4847.684900481458;4882.487338829443;4905.583025442806;4909.459959404463;4921.50784921505;4444;4487.753173575419;4490.685652004394;4491.393661855673;4500;4874.317595536557;5566.109422492463;5647.689377084218;5652.491275785199;5665.12190610456;6122.916604401471;6255.937440625627;6270.7805226816845;6315.652639104894;6321.431071203614;5753.424657534213;5755.309362876398;5759.22250496186;5764.193012978187;5795.072788353843;5302.946970530323;5336.599755018389;5414.91877621836;5417.51242388187;5428.267183930119;6276.403741644536;6299.874002520016;6304.148939893147;6306.180196574508;6335.746570137328;6178.474829639531;6259.749610015549;6267.2792628848265;6347.619142851444;6351.523635727246;6319.8104056878365;6327.588706734047;6329.49364050884;6349.936500634934;6389.393007664265;6018.668973206516;6190.535709821691;6198.876022479615;6218.19163508741;6228.688565571794;6049.516038716804;6103.367974696652;6153.876922461615;6161.168242287234;6243.063540468966;6116.232305333735;6120.599512025131;6127.892078906731;6129.083728908156;6129.969350153399;5915.112733090071;6070.332263451105;6081.756729730761;6101.725422355916;6112.480439162543;5834.241548598655;5843.766249350148;5878.147668587945;5910.024845900378;5982.0727787193055;6056.897220812554;6064.818055458343;6065.302490213682;6077.331493535796;6128.4790792783115;5824.068149096124;5923.49650279731;5934.228550288608;5936.1986131871645;5972.283326000907 18:21:06 INFO - PROCESS | 1663 | |1;ext.html;526.0714838310383;526.4524894110125;543.5352773378761;556.21018154221;558.3969313141807;29598.260043498896;29885.85057074714;41452.79273603632;42246.788766056125;42308;43463.78268108663;43799.78100109503;43956.340654890206;43970.560294397044;43996.56003439965;9650.276229282814;9838.409695418262;9843.36018158821;9848.064433878806;9881.407115573089;8568.228859402652;8790.64837406503;11543.307401555934;11746.177767556275;11748.471318790633;11649.359285239309;11698.239614425034;11707.356095414749;11709.59016434425;11746.295222286648;8615.267702245317;11498.080153587693;11590.65228043156;11663.94168029163;11696.824547631792;11309.321440713584;11598.246114002563;11607.361595112265;11614.012808911253;11626.709332266677;39554;39849;39934.801955941366;39945.80027099875;40152.19695606072;3191.952120718191;3193.393255281489;3253.4469140246215;3535.681788639009;4039.5877605180153;28576;28648.14055578336;28813.56779648286;29208.269793255127;29271.121866344045;2654.296611397981;2715.3618899558587;2736.917534186591;2742.3555464465835;2810.5784132380104;427946;429889;430383;431235;431890;59837.70081149566;60284.39715602788;60357.77495575356;60422;60488.697556511936;36150.8192459036;36689;36796.264074718354;38468.038299042746;38502.80748596239;16414.589635259214;16551.503454896374;16736.748948765777;16749.911255768337;16823.495295141165;11685.006774424099;11861.288322700666;12705.301208433624;12825.35873206336;12946.546870859664;19385;19639.607207855763;19733.40799776009;19748.901255493918;19762.209511619374;6611.66941652925;6967.407770339578;7531.698732050657;7650.3278426354545;7950.840983180419;918.2470374293016;953.3088510829732;1011.1708399112804;1035.7514196592763;1055.3931489393628;27579.345239285707;29345;29673;29741.553876691865;29776.553351699742;1009.9798004040025;1096.2600244834864;1139.048894173358;1189.8453201083803;1203.199872085071;14977.925110374596;15083.849161508244;15101.32044057998;15426.424746223476;15486.303116359566;499330;606394;607294;607842;608714;58335.70832145897;59984.700076499335;60001.699991499765;60157;60200.69899650561;1304.7455746129413;1356.5387768158675;1434.8278206614855;1465.7874608181967;1489.501017159242;26441.867790660923;29207;29916.70083299226;29981;30604.08187754282;50060;50295.74852125716;50830.491695084056;50851;50937.23594146165;440488;440716;441199;442422;443191;23254.418639534146;25539.616905746057;26347.077852275077;26596;27665.58501622437;43652.78173609239;44179.558204417546;44203.778981104886;44566.77716611396;44933.876653083935;35608.10979725424;36270.8186459066;36517.45223821592;36965.63034369622;37028.70399536003;28482.430351392442;33785.986420407426;35393.1151721209;35582.28835423225;35945.460818087224;30490.542641860833;35530.28939421145;35832.641673583974;35842.462363064056;36080.639193608775;30252.5462118064;31562.36875262528;31602.841985790845;31640.050798476084;31703;22869;23467.530649386576;23545.41136471602;23937.880310598335;24008.39979000539 18:21:06 INFO - PROCESS | 1663 | |2;jquery.html;390.3091527995448;395.11691369788537;395.6814764114888;398.60338962732084;421.3300851645884;13531.93234033831;19217.039148042593;19858.503537411572;20794.89602551989;20856.478588035287;15737;16250.512484625451;19399.515012124702;19974.50063748407;20249;5661.348944871331;5848.883022339572;5885.764569417218;5912.379200183983;5920.259967504062;5741.885162296751;5827.621204621686;5828.7668493260435;5867.7066146692705;5920.644761314314;5560.610757246995;5680.460356266169;5712.6286791358425;5782.421757824226;5784;4956.553910148086;5505.256790333294;5731.111677689964;5783.161441590946;5786.3056433228285;5059.367579052619;5071.8985620287385;5085.110105731482;5085.186370180753;5091.592672586185;18810.905945470186;20605.587888242302;23856;24086.63870041951;24258.87870560636;2126.362091372582;2236.944076398087;2596.844189348626;2730.194592595189;2813.6482939632547;13540.93229533846;14250.643733906632;14335.354909029014;14854.480093196795;14862.925685371503;1689.5353777711105;1713.802912665047;1735.3492440334874;1752.3165965273554;1753.877228593999;119875.80124198821;123671.88866401765;124529.37735311265;124807;127428;9139.99460059393;9904.356216845881;10651.786964260671;10944.835827462595;11155.609553665745;11373.715657108638;13810.792838107436;13915.373808178656;13923.791143132861;13930.721385572435;7083.291670832876;7509.136449308399;8089.838203235902;8239.917600824036;8537.573121343908;8519.977602687717;8526.802449081595;8534.53060081689;8710.172533609297;8712.738617841474;5745.913811292834;6114.2357205349335;6302.621842689453;6444.484441244689;6445;1479.3786609623983;1585.7086509992948;1687.5359276199144;1886.1700851625242;1889.4237257636278;903.4488961733338;910.3400034974591;939.8879900435594;983.852422136671;1118.6811758648746;19499.8050019498;20034.89982550078;20178.19287228524;20199.293024744064;20221.797782022284;953.3374304858057;967.4872317671645;977.4868194198103;988.8022395520925;998.6804222648828;8296.419250652518;10851.78296434067;10854.565817367375;10881.510332035077;10886.510107045202;593643;601539;616626;617778;618907;119270.8072919277;122001;122504;122707.77292226964;124622;1042.4735508568276;1047.5600247895902;1064.7125276175389;1065.3714308557987;1069.572171131538;17410.868293560878;17936.551586210448;18283.451496455127;18411.937914857408;18438.723419148457;34871.825640872645;39461.21077578375;45894.77052614849;46561.767191163824;49017.509824901295;54333;61923;69261.26846828668;69573.95639065317;71186;7710.537367757953;7735.574543399942;7792.532448053135;7885.369170466234;8107.635156417858;6512.511561632991;8729.607167677597;11915.463804129009;12183;12496.937515312366;7410.666520006721;7433.368163706145;7621.657025433758;7817.257360550739;8355.582220889053;7842.411819113703;7908.920910790818;8005;8013.6794528218215;8037.995250593675;5031.471695471922;5111.948880511296;8068.757937261891;8114.148014458633;8130.552819594979;11683.299002059564;11900.464479098287;12087.214331068453;12208.75582488363;12209;10854.67435976922;10923.453827308762;10943.288686235688;11228.101751859995;11290.266132701348 18:21:06 INFO - PROCESS | 1663 | |3;mootools.html;1423.032338010154;1574.315172899789;1603.9518814435567;1847.556586419259;1985.305143199881;7820.648070836812;8557;8801.559922003906;8818.955905220473;8927.55362231889;7303.744368947088;8787.253083487894;8795.604197811097;8796.69211577595;8905.332100092486;2111.356036408895;2196.5826492966285;2223.83321250906;2225.7440394354694;2430.586800243954;2223.321886824518;2262.1177740681064;2412.191915708231;2482.553140434721;2490.1906880263987;2422.2854257993845;2438.9146379876706;2453.950920981579;2457.0171931227565;2458.2256589174444;2418.987905060481;2421.019487107724;2428.854268743881;2433.051110067075;2436.147348428036;2153.4723992621753;2394.4253379189045;2446.1683027770464;2453.2272334214667;2462.5321188974217;8689.348298877609;8766.561671916443;8771.254443372321;8778.692745753931;8798.120187981214;5240.8689782755355;5370.355557333145;5397.811076612339;5409.323834520685;5425.7829686812875;3536.3634545781747;3592.3713350163616;3651.580068292154;3688.0226739913946;3702.1114932416294;1010.1262408017086;1056.3133962924085;1058.9046985771317;1064.5050051725984;1080.9675709728722;11461.426928653535;11462.828057579143;11467.885321146765;11481.253718508271;11580.710482237928;4753.35829662998;4801.2949097336505;4892.584130348889;4892.804287828518;4893.926591101136;9803.16673082796;9847.45838978863;9871.654492092886;9875.40747555149;9877.901220987842;5422.969635769191;5426.457354264621;5427;5428.131498960148;5433.592480563973;4427.048184640318;4809.014152098881;4813.542713442287;4874.951250487521;4963.45402005779;3750.4374343848094;3888.844446222176;3926.7434420985583;3986.903601509608;4013.678905687538;1299.6750812296925;1313.1530163044783;1317.6837558985967;1329.7898911990042;1338.2505796753655;830.7839961609905;839.7152356893962;890.9910900891037;924.58393722825;953.8998405167496;5882.294124705063;5890.558208134407;5943.673097979653;6034.818955431344;6196.194494715658;906.1482206725657;946.9905300946903;951.1344676344494;957.3824882950458;996.6013594562088;3954.5452272988973;3998.220347032359;4000.19996000801;4063.79681015948;4076.673866090706;10387.68836934893;10605.57577696899;10902.5093870776;10919.508622111865;10924.836127457935;10739.301945373525;10818.24272300947;10826.729331766768;10850.945745271223;10857.294275872044;7741.303282704584;8252.21603947624;8257.917420825836;8272.545010024385;8362.623681934327;6054.606450580698;6098.847528811638;6103.389661033861;6177.104319873683;6194.721237544231;9619.615215391586;9780.706578802647;10003.849942251018;10009.799804004026;10066.647667331608;9678.322517423712;10089.192864570963;10093.798124037332;10286.2799604027;10307.123894469054;6897.379235868796;7135.928640713526;7596.8480630388185;7747.6513556891205;7777.611119444118;7891.84216315682;8124.309433698202;8127.024757029194;8182.263596276363;8229.177082291722;6221.3156552780065;7341.412686985135;7361.779146625609;7439.99560059385;7512.136104347851;7213.71145154209;7226.7109315626785;7305;7316.561006339637;7374.520656157332;6790.25307216196;6929.792106236821;6957.965210174119;6976.337247961435;6980.7905762827195;4406.581374769392;4603.240465323184;4841.346418233635;4848.927266090941;5059.848204553869;4153.169366126726;4709.48195698466;4905.705657660552;4912.729799861042;4965.47862474435 18:21:06 INFO - PROCESS | 1663 | |4;prototype.html;308.134143058007;311.1552135950894;324.95450636910823;329.45968611477167;331.3240988383696;4718.740469274188;4914.950850491494;5175.8188463403785;5326.946730532693;5428.70142142183;4629.953700463003;4787.563730880739;4984.750762461871;5311.787528498865;5318.680879147254;4060.8578699745517;4071.3282308419075;4078.83684652615;4093.754374737518;4134.338505839067;3769.9284772445694;3966.2662407454714;4081.265372232997;4129.0709590342085;4147.502299724038;3818.9427158592503;3852.5569559500595;4059.878203653895;4099.3441049432095;4107.466029416187;3634.6365363463706;4024.8591299304676;4040.656544193747;4052.9392059119136;4058.2492238935893;3023.969760302391;3051.4202301562855;3388.390089783838;3566.233259849119;3576.7138628909624;4136.358864375997;4713.771066819239;4824.421069471651;4850.2724591311235;5105.744712764347;3983.8406463741417;4657.6273898088075;4659.4175728034;4714.151452738506;4788.480466856858;4741.5495527924795;4974.204127339644;5575.358833734092;5595.552355811526;5607.411221821691;3426.9314613707834;3851.8844434667003;3956.9118492414527;3993.881713120331;3993.940090898639;5001.874953126165;5826.679532625704;5865.35481097079;5926.940730592725;5936.821895343103;3513.683768460851;3661.5423072115987;4010.8997275067773;4024.7182697210915;4051.2100140472326;4082.8366865325056;4085.2442298174933;4112.300908845504;4117.1765646870745;4127.29835927893;4179.4357761701785;4653.371794807726;4690.788914498856;4695.95304046962;4718.764061796896;5600.243967064394;5618;5653.971730141323;5655.434456554394;5671.234383358276;1083.5070043130356;1134.387430787382;1311.4885194774047;1328.1499840102342;1333.5665908579751;766.6051983228642;835.0480452284401;913.7395842184945;916.8487199612123;926.8146370725882;4221.936670949938;4262.65898728101;4937.703737775745;4968.8757781055765;4975.502449754996;4844.660873738875;4919.958217339764;4991.103380715305;5429.945700542944;5551.639143455744;4373.1253749249645;4577.473590537064;4597.747123908217;4972.651914366032;4978.6763860348965;2612.8574568853737;2645.10874724569;2745.6842463116996;2865.584490248902;2909.621749172594;5477.452254774491;5712.258116104294;5735.8852822944145;5758.971205143947;5794.275715535558;5625.803096891589;6021.200141933934;6029.638221706711;6058.212432383762;6062.969685151546;5328.307320048369;5350.545203657733;5537.529310008614;6001.369171651176;6062.333143354315;5582.441755824385;5805.245318108618;5859.271514903028;5880.118741816394;5885.705714714333;5734.913976290442;5754.482096611325;5766.509846663081;5776.7400466979925;5790.507806836297;5400.648957817729;5774.82675519735;5953.970230148821;6051.485623722033;6079.513638908965;3884.553276373253;3911.941320880132;3919.7844118573753;3928.4696565963227;3950.7036972227775;5414.972925135349;5598.9082128984455;5853.8048231913035;5863.413658634103;5876.588638795243;4901.779419926183;4973.079980203674;5288.281308574652;5567.66594004361;5673.886522269614;5350.949958523604;5541.6145963509125;5641.6050876439895;5661.603038177225;5678.4605462480995;4396.516383197782;5153.948460515347;5170.543991603958;5532.921080389286;5543.113101903676;4800.895793967337;5220.738963051756;5375.1679427821255;5652.689102099424;5662.2639056922335;5497.257870187474;5516.862078448071;5525.419830917856;5532.391436941853;5594.440555944373 18:21:06 INFO - PROCESS | 1663 | |5;yui.html;653.601303205045;734.7685479074091;751.0349201276364;761.3186198352473;765.0857225615392;14354.354054067566;14582.927085364572;14696.926515367408;15183.620409489768;15292.694146117085;14343.42626294947;14439.63900902478;14492.637684057903;14499.565013049625;14512.201828899406;716.2765606737207;749.0973377080632;895.8118795053047;921.1985572551889;921.272194965976;891.4873947480193;898.5282726568571;926.9443833369986;930.1721467893581;930.9441433514011;852.9189726975927;970.9563069661847;971.2424309038926;972.2125078686287;973.6689525561308;754.9849003019937;786.7089177004488;787.1184273613533;963.7060696487569;964.3683387381242;656.6585375604707;687.5530904911848;695.6174104242697;730.6237287796789;734.2400615363074;14938.925305373403;14974.026688265338;14977.625559360995;14982.175980321077;14987.775183372258;12409.937950310281;12411.379431028412;12414.689632759162;12624.379964015572;12740.617781466572;10711.946440267826;10856.185786066077;11024.944875275572;11106.389148596823;11232.157588180919;3583.229605634801;3584.910377240564;3626.12972886508;3657.963420365789;3692.2430901665093;79576;79762.60118699427;80056;80380.99999999942;80837.99999999942;9505.04949505044;9536.761580960532;9648.403935650891;9816;9930.453825039693;21951.780482195292;22130.114795408324;22130.88934555317;22429.55140897173;22433.887830560743;7286.744963926343;7307.451941104438;7355.963220183972;7391.260873912565;7817.570033648204;4099.795010249476;4100.5899410059355;4120.690948178899;4170.457840480718;4403.14138742942;3215.742740580748;3336.7831090979007;3343.297907439416;3344.364570731553;3347.6652334766814;634.1660716158237;643.7425029988042;648.4552975500602;649.6329573790794;650.408128602969;543.4433559109333;556.1851886985586;572.390404219505;578.1356871477085;579.261441661876;7631.503952243197;7755.72854950074;7815.062192536932;7888.290053895177;7927.762167134995;516.8449465160512;537.7445713286236;553.6899336371645;582.1442479555047;593.4243783530055;3608.440691692792;3655.6093924711145;3661.2494438640015;3702.7037836972977;3868.323043467382;86707.13292867117;87577;88702.55648721715;90749;90764.54617727001;62640.68679656572;69536.60926781537;77232;80452.59773701095;80881;68915;69965.3003469969;70060.69453749085;70102;70423.29576704372;8096.149904260204;8108.270255677017;8312.584370781558;8325.333973282244;8424.831503369776;35084.473732893515;45148.54851451575;45219.09561808811;45335;45432.95551700115;60628.69685651544;63353.04970425355;63458.365416345245;63729.6813515948;63846;10220.591176352862;11003.394813285346;11025.724356891142;11136.832947505633;11352;12024.33866137371;12056.63830085099;12059.819102713642;12407.317597532223;12587.937060315006;11205.775884482428;11292.830607541058;11377.374244416636;11715.238509496856;11994.820077698667;10807.67576972692;11089.500972456097;11108.111351091731;11116.888831111586;12247.571335003233;10772.89227107719;11070.612528561462;11264.7747045057;11482.885171148182;11668.299902005907;9174.54127293646;9286.674966376144;9299.814003720023;9939.155171810477;10103.934743757462;7747.728829491166;9242.630294788401;9301.627934882528;9386.530673466435;9494.810103798023 18:21:06 INFO - PROCESS | 1663 | __end_tp_report 18:21:06 INFO - PROCESS | 1663 | __start_cc_report 18:21:06 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1219 18:21:06 INFO - PROCESS | 1663 | __end_cc_report 18:21:06 INFO - PROCESS | 1663 | __startTimestamp1462584066720__endTimestamp 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | ------- Summary: start ------- 18:21:06 INFO - PROCESS | 1663 | Number of tests: 6 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5737.23 Median:5923.50 stddev:643.09 (10.9%) stddev-sans-first:610.94 18:21:06 INFO - PROCESS | 1663 | Values: 3102.3 3402.8 3434.8 3461.7 3513.8 4730.7 5584.8 5591.3 5671.7 5703.7 5635.5 5710.0 5717.5 5724.0 5731.9 5168.4 5192.2 5210.2 5226.8 5293.5 5221.1 5284.4 5284.6 5288.5 5296.1 5202.1 5210.5 5230.9 5243.0 5292.4 5297.9 5321.7 5326.8 5347.0 5357.6 5106.4 5116.3 5132.1 5218.6 5242.6 5556.3 5564.9 5616.0 5625.7 5662.4 6290.7 6297.5 6297.6 6302.2 6316.3 6128.3 6228.6 6301.8 6355.2 6372.2 5834.6 5866.6 5888.9 5892.0 5907.3 6304.9 6307.2 6312.9 6313.0 6319.6 6054.9 6111.0 6111.5 6119.5 6154.3 5213.2 6295.6 6305.9 6312.4 6314.4 6199.5 6225.9 6229.3 6241.5 6243.5 6021.9 6039.3 6295.8 6307.8 6308.0 4847.7 4882.5 4905.6 4909.5 4921.5 4444.0 4487.8 4490.7 4491.4 4500.0 4874.3 5566.1 5647.7 5652.5 5665.1 6122.9 6255.9 6270.8 6315.7 6321.4 5753.4 5755.3 5759.2 5764.2 5795.1 5302.9 5336.6 5414.9 5417.5 5428.3 6276.4 6299.9 6304.1 6306.2 6335.7 6178.5 6259.7 6267.3 6347.6 6351.5 6319.8 6327.6 6329.5 6349.9 6389.4 6018.7 6190.5 6198.9 6218.2 6228.7 6049.5 6103.4 6153.9 6161.2 6243.1 6116.2 6120.6 6127.9 6129.1 6130.0 5915.1 6070.3 6081.8 6101.7 6112.5 5834.2 5843.8 5878.1 5910.0 5982.1 6056.9 6064.8 6065.3 6077.3 6128.5 5824.1 5923.5 5934.2 5936.2 5972.3 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:62226.45 Median:3223.42 stddev:130977.08 (4063.3%) stddev-sans-first:131262.58 18:21:06 INFO - PROCESS | 1663 | Values: 526.1 526.5 543.5 556.2 558.4 29598.3 29885.9 41452.8 42246.8 42308.0 43463.8 43799.8 43956.3 43970.6 43996.6 9650.3 9838.4 9843.4 9848.1 9881.4 8568.2 8790.6 11543.3 11746.2 11748.5 11649.4 11698.2 11707.4 11709.6 11746.3 8615.3 11498.1 11590.7 11663.9 11696.8 11309.3 11598.2 11607.4 11614.0 11626.7 39554.0 39849.0 39934.8 39945.8 40152.2 3192.0 3193.4 3253.4 3535.7 4039.6 28576.0 28648.1 28813.6 29208.3 29271.1 2654.3 2715.4 2736.9 2742.4 2810.6 427946.0 429889.0 430383.0 431235.0 431890.0 59837.7 60284.4 60357.8 60422.0 60488.7 36150.8 36689.0 36796.3 38468.0 38502.8 16414.6 16551.5 16736.7 16749.9 16823.5 11685.0 11861.3 12705.3 12825.4 12946.5 19385.0 19639.6 19733.4 19748.9 19762.2 6611.7 6967.4 7531.7 7650.3 7950.8 918.2 953.3 1011.2 1035.8 1055.4 27579.3 29345.0 29673.0 29741.6 29776.6 1010.0 1096.3 1139.0 1189.8 1203.2 14977.9 15083.8 15101.3 15426.4 15486.3 499330.0 606394.0 607294.0 607842.0 608714.0 58335.7 59984.7 60001.7 60157.0 60200.7 1304.7 1356.5 1434.8 1465.8 1489.5 26441.9 29207.0 29916.7 29981.0 30604.1 50060.0 50295.7 50830.5 50851.0 50937.2 440488.0 440716.0 441199.0 442422.0 443191.0 23254.4 25539.6 26347.1 26596.0 27665.6 43652.8 44179.6 44203.8 44566.8 44933.9 35608.1 36270.8 36517.5 36965.6 37028.7 28482.4 33786.0 35393.1 35582.3 35945.5 30490.5 35530.3 35832.6 35842.5 36080.6 30252.5 31562.4 31602.8 31640.1 31703.0 22869.0 23467.5 23545.4 23937.9 24008.4 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34289.34 Median:397.14 stddev:101598.40 (25582.4%) stddev-sans-first:101851.53 18:21:06 INFO - PROCESS | 1663 | Values: 390.3 395.1 395.7 398.6 421.3 13531.9 19217.0 19858.5 20794.9 20856.5 15737.0 16250.5 19399.5 19974.5 20249.0 5661.3 5848.9 5885.8 5912.4 5920.3 5741.9 5827.6 5828.8 5867.7 5920.6 5560.6 5680.5 5712.6 5782.4 5784.0 4956.6 5505.3 5731.1 5783.2 5786.3 5059.4 5071.9 5085.1 5085.2 5091.6 18810.9 20605.6 23856.0 24086.6 24258.9 2126.4 2236.9 2596.8 2730.2 2813.6 13540.9 14250.6 14335.4 14854.5 14862.9 1689.5 1713.8 1735.3 1752.3 1753.9 119875.8 123671.9 124529.4 124807.0 127428.0 9140.0 9904.4 10651.8 10944.8 11155.6 11373.7 13810.8 13915.4 13923.8 13930.7 7083.3 7509.1 8089.8 8239.9 8537.6 8520.0 8526.8 8534.5 8710.2 8712.7 5745.9 6114.2 6302.6 6444.5 6445.0 1479.4 1585.7 1687.5 1886.2 1889.4 903.4 910.3 939.9 983.9 1118.7 19499.8 20034.9 20178.2 20199.3 20221.8 953.3 967.5 977.5 988.8 998.7 8296.4 10851.8 10854.6 10881.5 10886.5 593643.0 601539.0 616626.0 617778.0 618907.0 119270.8 122001.0 122504.0 122707.8 124622.0 1042.5 1047.6 1064.7 1065.4 1069.6 17410.9 17936.6 18283.5 18411.9 18438.7 34871.8 39461.2 45894.8 46561.8 49017.5 54333.0 61923.0 69261.3 69574.0 71186.0 7710.5 7735.6 7792.5 7885.4 8107.6 6512.5 8729.6 11915.5 12183.0 12496.9 7410.7 7433.4 7621.7 7817.3 8355.6 7842.4 7908.9 8005.0 8013.7 8038.0 5031.5 5111.9 8068.8 8114.1 8130.6 11683.3 11900.5 12087.2 12208.8 12209.0 10854.7 10923.5 10943.3 11228.1 11290.3 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5700.65 Median:4893.37 stddev:3169.44 (64.8%) stddev-sans-first:3162.03 18:21:06 INFO - PROCESS | 1663 | Values: 1423.0 1574.3 1604.0 1847.6 1985.3 7820.6 8557.0 8801.6 8819.0 8927.6 7303.7 8787.3 8795.6 8796.7 8905.3 2111.4 2196.6 2223.8 2225.7 2430.6 2223.3 2262.1 2412.2 2482.6 2490.2 2422.3 2438.9 2454.0 2457.0 2458.2 2419.0 2421.0 2428.9 2433.1 2436.1 2153.5 2394.4 2446.2 2453.2 2462.5 8689.3 8766.6 8771.3 8778.7 8798.1 5240.9 5370.4 5397.8 5409.3 5425.8 3536.4 3592.4 3651.6 3688.0 3702.1 1010.1 1056.3 1058.9 1064.5 1081.0 11461.4 11462.8 11467.9 11481.3 11580.7 4753.4 4801.3 4892.6 4892.8 4893.9 9803.2 9847.5 9871.7 9875.4 9877.9 5423.0 5426.5 5427.0 5428.1 5433.6 4427.0 4809.0 4813.5 4875.0 4963.5 3750.4 3888.8 3926.7 3986.9 4013.7 1299.7 1313.2 1317.7 1329.8 1338.3 830.8 839.7 891.0 924.6 953.9 5882.3 5890.6 5943.7 6034.8 6196.2 906.1 947.0 951.1 957.4 996.6 3954.5 3998.2 4000.2 4063.8 4076.7 10387.7 10605.6 10902.5 10919.5 10924.8 10739.3 10818.2 10826.7 10850.9 10857.3 7741.3 8252.2 8257.9 8272.5 8362.6 6054.6 6098.8 6103.4 6177.1 6194.7 9619.6 9780.7 10003.8 10009.8 10066.6 9678.3 10089.2 10093.8 10286.3 10307.1 6897.4 7135.9 7596.8 7747.7 7777.6 7891.8 8124.3 8127.0 8182.3 8229.2 6221.3 7341.4 7361.8 7440.0 7512.1 7213.7 7226.7 7305.0 7316.6 7374.5 6790.3 6929.8 6958.0 6976.3 6980.8 4406.6 4603.2 4841.3 4848.9 5059.8 4153.2 4709.5 4905.7 4912.7 4965.5 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4522.05 Median:4970.76 stddev:1396.73 (28.1%) stddev-sans-first:1364.37 18:21:06 INFO - PROCESS | 1663 | Values: 308.1 311.2 325.0 329.5 331.3 4718.7 4915.0 5175.8 5326.9 5428.7 4630.0 4787.6 4984.8 5311.8 5318.7 4060.9 4071.3 4078.8 4093.8 4134.3 3769.9 3966.3 4081.3 4129.1 4147.5 3818.9 3852.6 4059.9 4099.3 4107.5 3634.6 4024.9 4040.7 4052.9 4058.2 3024.0 3051.4 3388.4 3566.2 3576.7 4136.4 4713.8 4824.4 4850.3 5105.7 3983.8 4657.6 4659.4 4714.2 4788.5 4741.5 4974.2 5575.4 5595.6 5607.4 3426.9 3851.9 3956.9 3993.9 3993.9 5001.9 5826.7 5865.4 5926.9 5936.8 3513.7 3661.5 4010.9 4024.7 4051.2 4082.8 4085.2 4112.3 4117.2 4127.3 4179.4 4653.4 4690.8 4696.0 4718.8 5600.2 5618.0 5654.0 5655.4 5671.2 1083.5 1134.4 1311.5 1328.1 1333.6 766.6 835.0 913.7 916.8 926.8 4221.9 4262.7 4937.7 4968.9 4975.5 4844.7 4920.0 4991.1 5429.9 5551.6 4373.1 4577.5 4597.7 4972.7 4978.7 2612.9 2645.1 2745.7 2865.6 2909.6 5477.5 5712.3 5735.9 5759.0 5794.3 5625.8 6021.2 6029.6 6058.2 6063.0 5328.3 5350.5 5537.5 6001.4 6062.3 5582.4 5805.2 5859.3 5880.1 5885.7 5734.9 5754.5 5766.5 5776.7 5790.5 5400.6 5774.8 5954.0 6051.5 6079.5 3884.6 3911.9 3919.8 3928.5 3950.7 5415.0 5598.9 5853.8 5863.4 5876.6 4901.8 4973.1 5288.3 5567.7 5673.9 5350.9 5541.6 5641.6 5661.6 5678.5 4396.5 5153.9 5170.5 5532.9 5543.1 4800.9 5220.7 5375.2 5652.7 5662.3 5497.3 5516.9 5525.4 5532.4 5594.4 18:21:06 INFO - PROCESS | 1663 | 18:21:06 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17773.82 Median:30611.06 stddev:24697.28 (80.7%) stddev-sans-first:24733.10 18:21:06 INFO - PROCESS | 1663 | Values: 653.6 734.8 751.0 761.3 765.1 14354.4 14582.9 14696.9 15183.6 15292.7 14343.4 14439.6 14492.6 14499.6 14512.2 716.3 749.1 895.8 921.2 921.3 891.5 898.5 926.9 930.2 930.9 852.9 971.0 971.2 972.2 973.7 755.0 786.7 787.1 963.7 964.4 656.7 687.6 695.6 730.6 734.2 14938.9 14974.0 14977.6 14982.2 14987.8 12409.9 12411.4 12414.7 12624.4 12740.6 10711.9 10856.2 11024.9 11106.4 11232.2 3583.2 3584.9 3626.1 3658.0 3692.2 79576.0 79762.6 80056.0 80381.0 80838.0 9505.0 9536.8 9648.4 9816.0 9930.5 21951.8 22130.1 22130.9 22429.6 22433.9 7286.7 7307.5 7356.0 7391.3 7817.6 4099.8 4100.6 4120.7 4170.5 4403.1 3215.7 3336.8 3343.3 3344.4 3347.7 634.2 643.7 648.5 649.6 650.4 543.4 556.2 572.4 578.1 579.3 7631.5 7755.7 7815.1 7888.3 7927.8 516.8 537.7 553.7 582.1 593.4 3608.4 3655.6 3661.2 3702.7 3868.3 86707.1 87577.0 88702.6 90749.0 90764.5 62640.7 69536.6 77232.0 80452.6 80881.0 68915.0 69965.3 70060.7 70102.0 70423.3 8096.1 8108.3 8312.6 8325.3 8424.8 35084.5 45148.5 45219.1 45335.0 45433.0 60628.7 63353.0 63458.4 63729.7 63846.0 10220.6 11003.4 11025.7 11136.8 11352.0 12024.3 12056.6 12059.8 12407.3 12587.9 11205.8 11292.8 11377.4 11715.2 11994.8 10807.7 11089.5 11108.1 11116.9 12247.6 10772.9 11070.6 11264.8 11482.9 11668.3 9174.5 9286.7 9299.8 9939.2 10103.9 7747.7 9242.6 9301.6 9386.5 9494.8 18:21:06 INFO - PROCESS | 1663 | -------- Summary: end -------- 18:21:06 INFO - PROCESS | 1663 | 18:21:07 INFO - TEST-INFO | 1663: exit 0 18:21:07 INFO - TEST-OK | dromaeo_css | took 1116093ms 18:21:07 INFO - TEST-START | kraken 18:21:07 INFO - operating with platform_type : mac_ 18:21:07 INFO - Initialising browser for kraken test... 18:21:07 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpqFsSWH/profile http://localhost:49278/getInfo.html) 18:21:09 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 18:21:09 INFO - PROCESS | 1672 | colorDepth:24 18:21:09 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 18:21:09 INFO - PROCESS | 1672 | __metrics 18:21:09 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:21:09 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:21:10 INFO - TEST-INFO | 1672: exit 0 18:21:10 INFO - Browser initialized. 18:21:10 INFO - Running cycle 1/1 for kraken test... 18:21:10 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpqFsSWH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:22 INFO - PROCESS | 1674 | RSS: Main: 239190016 18:21:22 INFO - PROCESS | 1674 | 18:21:24 INFO - PROCESS | 1674 | 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:25 INFO - PROCESS | 1674 | RSS: Main: 286281728 18:21:25 INFO - PROCESS | 1674 | 18:21:28 INFO - PROCESS | 1674 | 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:21:28 INFO - PROCESS | 1674 | RSS: Main: 295919616 18:21:28 INFO - PROCESS | 1674 | 18:21:32 INFO - PROCESS | 1674 | 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:21:33 INFO - PROCESS | 1674 | RSS: Main: 383983616 18:21:33 INFO - PROCESS | 1674 | 18:21:35 INFO - PROCESS | 1674 | 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:21:35 INFO - PROCESS | 1674 | RSS: Main: 284008448 18:21:35 INFO - PROCESS | 1674 | 18:21:37 INFO - PROCESS | 1674 | 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:21:38 INFO - PROCESS | 1674 | RSS: Main: 287862784 18:21:38 INFO - PROCESS | 1674 | 18:21:41 INFO - PROCESS | 1674 | 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:21:41 INFO - PROCESS | 1674 | RSS: Main: 323354624 18:21:41 INFO - PROCESS | 1674 | 18:21:44 INFO - PROCESS | 1674 | 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:21:45 INFO - PROCESS | 1674 | RSS: Main: 325070848 18:21:45 INFO - PROCESS | 1674 | 18:21:48 INFO - PROCESS | 1674 | 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:21:48 INFO - PROCESS | 1674 | RSS: Main: 326107136 18:21:48 INFO - PROCESS | 1674 | 18:21:50 INFO - PROCESS | 1674 | 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:21:51 INFO - PROCESS | 1674 | RSS: Main: 314503168 18:21:51 INFO - PROCESS | 1674 | 18:21:52 INFO - PROCESS | 1674 | 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:21:53 INFO - PROCESS | 1674 | RSS: Main: 321409024 18:21:53 INFO - PROCESS | 1674 | 18:21:55 INFO - PROCESS | 1674 | 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:21:56 INFO - PROCESS | 1674 | RSS: Main: 323063808 18:21:56 INFO - PROCESS | 1674 | 18:21:59 INFO - PROCESS | 1674 | 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:00 INFO - PROCESS | 1674 | RSS: Main: 324243456 18:22:00 INFO - PROCESS | 1674 | 18:22:02 INFO - PROCESS | 1674 | 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:03 INFO - PROCESS | 1674 | RSS: Main: 322125824 18:22:03 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | 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:05 INFO - PROCESS | 1674 | RSS: Main: 368701440 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | __start_tp_report 18:22:05 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 18:22:05 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 18:22:05 INFO - PROCESS | 1674 | |i|pagename|runs| 18:22:05 INFO - PROCESS | 1674 | |0;ai-astar;116;106;112;112;111;112;110;111;112;112 18:22:05 INFO - PROCESS | 1674 | |1;audio-beat-detection;167;142;144;163;118;138;145;145;197;136 18:22:05 INFO - PROCESS | 1674 | |2;audio-dft;186;185;186;184;183;181;183;184;184;183 18:22:05 INFO - PROCESS | 1674 | |3;audio-fft;82;74;57;104;56;62;58;83;141;64 18:22:05 INFO - PROCESS | 1674 | |4;audio-oscillator;96;96;100;80;101;111;138;79;79;99 18:22:05 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;87;88;87;88;86;89;87;89;85 18:22:05 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;94;94;94;94;93;94;94;93;94 18:22:05 INFO - PROCESS | 1674 | |7;imaging-desaturate;87;86;86;99;142;138;85;86;86;85 18:22:05 INFO - PROCESS | 1674 | |8;json-parse-financial;60;60;61;60;60;60;61;76;60;58 18:22:05 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;62;71;78;72;69;69;67;71;67;68 18:22:05 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;86;85;85;85;85;83;83;85;87 18:22:05 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;141;144;142;135;138;135;138;135;189;159 18:22:05 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;164;168;167;167;165;167;184;226;189;165 18:22:05 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;66;67;75;83;83 18:22:05 INFO - PROCESS | 1674 | __end_tp_report 18:22:05 INFO - PROCESS | 1674 | __start_cc_report 18:22:05 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1921 18:22:05 INFO - PROCESS | 1674 | __end_cc_report 18:22:05 INFO - PROCESS | 1674 | __startTimestamp1462584125639__endTimestamp 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | ------- Summary: start ------- 18:22:05 INFO - PROCESS | 1674 | Number of tests: 14 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.46 (2.2%) stddev-sans-first:1.96 18:22:05 INFO - PROCESS | 1674 | Values: 116.0 106.0 112.0 112.0 111.0 112.0 110.0 111.0 112.0 112.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:145.00 stddev:21.55 (14.9%) stddev-sans-first:21.90 18:22:05 INFO - PROCESS | 1674 | Values: 167.0 142.0 144.0 163.0 118.0 138.0 145.0 145.0 197.0 136.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.52 (0.8%) stddev-sans-first:1.41 18:22:05 INFO - PROCESS | 1674 | Values: 186.0 185.0 186.0 184.0 183.0 181.0 183.0 184.0 184.0 183.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:78.10 Median:63.00 stddev:26.83 (42.6%) stddev-sans-first:28.42 18:22:05 INFO - PROCESS | 1674 | Values: 82.0 74.0 57.0 104.0 56.0 62.0 58.0 83.0 141.0 64.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:79.50 stddev:17.75 (22.3%) stddev-sans-first:18.82 18:22:05 INFO - PROCESS | 1674 | Values: 96.0 96.0 100.0 80.0 101.0 111.0 138.0 79.0 79.0 99.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.35 (1.5%) stddev-sans-first:1.32 18:22:05 INFO - PROCESS | 1674 | Values: 89.0 87.0 88.0 87.0 88.0 86.0 89.0 87.0 89.0 85.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:22:05 INFO - PROCESS | 1674 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:86.00 stddev:22.54 (26.2%) stddev-sans-first:23.55 18:22:05 INFO - PROCESS | 1674 | Values: 87.0 86.0 86.0 99.0 142.0 138.0 85.0 86.0 86.0 85.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:60.00 stddev:5.13 (8.5%) stddev-sans-first:5.40 18:22:05 INFO - PROCESS | 1674 | Values: 60.0 60.0 61.0 60.0 60.0 60.0 61.0 76.0 60.0 58.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:4.14 (5.9%) stddev-sans-first:3.42 18:22:05 INFO - PROCESS | 1674 | Values: 62.0 71.0 78.0 72.0 69.0 69.0 67.0 71.0 67.0 68.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.27 18:22:05 INFO - PROCESS | 1674 | Values: 86.0 86.0 85.0 85.0 85.0 85.0 83.0 83.0 85.0 87.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:141.50 stddev:16.84 (11.9%) stddev-sans-first:17.78 18:22:05 INFO - PROCESS | 1674 | Values: 141.0 144.0 142.0 135.0 138.0 135.0 138.0 135.0 189.0 159.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:167.50 stddev:19.51 (11.6%) stddev-sans-first:20.19 18:22:05 INFO - PROCESS | 1674 | Values: 164.0 168.0 167.0 167.0 165.0 167.0 184.0 226.0 189.0 165.0 18:22:05 INFO - PROCESS | 1674 | 18:22:05 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:67.00 stddev:6.87 (10.3%) stddev-sans-first:7.14 18:22:05 INFO - PROCESS | 1674 | Values: 67.0 67.0 67.0 67.0 67.0 66.0 67.0 75.0 83.0 83.0 18:22:05 INFO - PROCESS | 1674 | -------- Summary: end -------- 18:22:05 INFO - PROCESS | 1674 | 18:22:06 INFO - TEST-INFO | 1674: exit 0 18:22:06 INFO - TEST-OK | kraken | took 59102ms 18:22:06 INFO - SUITE-END | took 1175s 18:22:06 INFO - Completed test suite (00:19:35) 18:22:06 INFO - javascript benchmark 18:22:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.3174901521666, 3402.7958322500644, 3434.8454319555617, 3461.688448039676, 3513.8243087845617, 4730.739809310487, 5584.776608935637, 5591.2731344925205, 5671.6880571568545, 5703.714814259286, 5635.549156067515, 5710.000749868766, 5717.542596592273, 5723.9713801430935, 5731.914021289674, 5168.405633352166, 5192.221166824968, 5210.218467229927, 5226.7647955841885, 5293.497117773805, 5221.112410890158, 5284.39229488608, 5284.550813180865, 5288.524032837044, 5296.09966305729, 5202.089634313998, 5210.478952104778, 5230.895382092356, 5243.030039442715, 5292.444293349181, 5297.947020529803, 5321.707306098163, 5326.77259661451, 5347.0, 5357.598180136505, 5106.387233531963, 5116.334876466073, 5132.127538318495, 5218.556422704075, 5242.580593552507, 5556.333240011225, 5564.888702225933, 5615.971920140414, 5625.718714064321, 5662.405447428002, 6290.685465726695, 6297.527685423565, 6297.590656607306, 6302.212223472066, 6316.336784637593, 6128.3258841527395, 6228.626282423023, 6301.842453938642, 6355.237371515448, 6372.203474565679, 5834.649921004753, 5866.589338746289, 5888.941110588925, 5892.0, 5907.320658124285, 6304.873902522016, 6307.211598550181, 6312.873742525216, 6313.021481670348, 6319.58922670025, 6054.878902422015, 6110.969445152746, 6111.480524155408, 6119.5104391648565, 6154.261488621394, 5213.244079608436, 6295.622262664255, 6305.873882522324, 6312.400321969406, 6314.431701146918, 6199.504039676815, 6225.937740622627, 6229.345918678564, 6241.500679945593, 6243.469305109026, 6021.879562408727, 6039.275286965592, 6295.842603934847, 6307.842303942346, 6308.0, 4847.684900481458, 4882.487338829443, 4905.583025442806, 4909.459959404463, 4921.50784921505, 4444.0, 4487.753173575419, 4490.685652004394, 4491.393661855673, 4500.0, 4874.317595536557, 5566.109422492463, 5647.689377084218, 5652.491275785199, 5665.12190610456, 6122.916604401471, 6255.937440625627, 6270.7805226816845, 6315.652639104894, 6321.431071203614, 5753.424657534213, 5755.309362876398, 5759.22250496186, 5764.193012978187, 5795.072788353843, 5302.946970530323, 5336.599755018389, 5414.91877621836, 5417.51242388187, 5428.267183930119, 6276.403741644536, 6299.874002520016, 6304.148939893147, 6306.180196574508, 6335.746570137328, 6178.474829639531, 6259.749610015549, 6267.2792628848265, 6347.619142851444, 6351.523635727246, 6319.8104056878365, 6327.588706734047, 6329.49364050884, 6349.936500634934, 6389.393007664265, 6018.668973206516, 6190.535709821691, 6198.876022479615, 6218.19163508741, 6228.688565571794, 6049.516038716804, 6103.367974696652, 6153.876922461615, 6161.168242287234, 6243.063540468966, 6116.232305333735, 6120.599512025131, 6127.892078906731, 6129.083728908156, 6129.969350153399, 5915.112733090071, 6070.332263451105, 6081.756729730761, 6101.725422355916, 6112.480439162543, 5834.241548598655, 5843.766249350148, 5878.147668587945, 5910.024845900378, 5982.0727787193055, 6056.897220812554, 6064.818055458343, 6065.302490213682, 6077.331493535796, 6128.4790792783115, 5824.068149096124, 5923.49650279731, 5934.228550288608, 5936.1986131871645, 5972.283326000907], "value": 5696.4213334228825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.0714838310383, 526.4524894110125, 543.5352773378761, 556.21018154221, 558.3969313141807, 29598.260043498896, 29885.85057074714, 41452.79273603632, 42246.788766056125, 42308.0, 43463.78268108663, 43799.78100109503, 43956.340654890206, 43970.560294397044, 43996.56003439965, 9650.276229282814, 9838.409695418262, 9843.36018158821, 9848.064433878806, 9881.407115573089, 8568.228859402652, 8790.64837406503, 11543.307401555934, 11746.177767556275, 11748.471318790633, 11649.359285239309, 11698.239614425034, 11707.356095414749, 11709.59016434425, 11746.295222286648, 8615.267702245317, 11498.080153587693, 11590.65228043156, 11663.94168029163, 11696.824547631792, 11309.321440713584, 11598.246114002563, 11607.361595112265, 11614.012808911253, 11626.709332266677, 39554.0, 39849.0, 39934.801955941366, 39945.80027099875, 40152.19695606072, 3191.952120718191, 3193.393255281489, 3253.4469140246215, 3535.681788639009, 4039.5877605180153, 28576.0, 28648.14055578336, 28813.56779648286, 29208.269793255127, 29271.121866344045, 2654.296611397981, 2715.3618899558587, 2736.917534186591, 2742.3555464465835, 2810.5784132380104, 427946.0, 429889.0, 430383.0, 431235.0, 431890.0, 59837.70081149566, 60284.39715602788, 60357.77495575356, 60422.0, 60488.697556511936, 36150.8192459036, 36689.0, 36796.264074718354, 38468.038299042746, 38502.80748596239, 16414.589635259214, 16551.503454896374, 16736.748948765777, 16749.911255768337, 16823.495295141165, 11685.006774424099, 11861.288322700666, 12705.301208433624, 12825.35873206336, 12946.546870859664, 19385.0, 19639.607207855763, 19733.40799776009, 19748.901255493918, 19762.209511619374, 6611.66941652925, 6967.407770339578, 7531.698732050657, 7650.3278426354545, 7950.840983180419, 918.2470374293016, 953.3088510829732, 1011.1708399112804, 1035.7514196592763, 1055.3931489393628, 27579.345239285707, 29345.0, 29673.0, 29741.553876691865, 29776.553351699742, 1009.9798004040025, 1096.2600244834864, 1139.048894173358, 1189.8453201083803, 1203.199872085071, 14977.925110374596, 15083.849161508244, 15101.32044057998, 15426.424746223476, 15486.303116359566, 499330.0, 606394.0, 607294.0, 607842.0, 608714.0, 58335.70832145897, 59984.700076499335, 60001.699991499765, 60157.0, 60200.69899650561, 1304.7455746129413, 1356.5387768158675, 1434.8278206614855, 1465.7874608181967, 1489.501017159242, 26441.867790660923, 29207.0, 29916.70083299226, 29981.0, 30604.08187754282, 50060.0, 50295.74852125716, 50830.491695084056, 50851.0, 50937.23594146165, 440488.0, 440716.0, 441199.0, 442422.0, 443191.0, 23254.418639534146, 25539.616905746057, 26347.077852275077, 26596.0, 27665.58501622437, 43652.78173609239, 44179.558204417546, 44203.778981104886, 44566.77716611396, 44933.876653083935, 35608.10979725424, 36270.8186459066, 36517.45223821592, 36965.63034369622, 37028.70399536003, 28482.430351392442, 33785.986420407426, 35393.1151721209, 35582.28835423225, 35945.460818087224, 30490.542641860833, 35530.28939421145, 35832.641673583974, 35842.462363064056, 36080.639193608775, 30252.5462118064, 31562.36875262528, 31602.841985790845, 31640.050798476084, 31703.0, 22869.0, 23467.530649386576, 23545.41136471602, 23937.880310598335, 24008.39979000539], "value": 19441.589775356755, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.3091527995448, 395.11691369788537, 395.6814764114888, 398.60338962732084, 421.3300851645884, 13531.93234033831, 19217.039148042593, 19858.503537411572, 20794.89602551989, 20856.478588035287, 15737.0, 16250.512484625451, 19399.515012124702, 19974.50063748407, 20249.0, 5661.348944871331, 5848.883022339572, 5885.764569417218, 5912.379200183983, 5920.259967504062, 5741.885162296751, 5827.621204621686, 5828.7668493260435, 5867.7066146692705, 5920.644761314314, 5560.610757246995, 5680.460356266169, 5712.6286791358425, 5782.421757824226, 5784.0, 4956.553910148086, 5505.256790333294, 5731.111677689964, 5783.161441590946, 5786.3056433228285, 5059.367579052619, 5071.8985620287385, 5085.110105731482, 5085.186370180753, 5091.592672586185, 18810.905945470186, 20605.587888242302, 23856.0, 24086.63870041951, 24258.87870560636, 2126.362091372582, 2236.944076398087, 2596.844189348626, 2730.194592595189, 2813.6482939632547, 13540.93229533846, 14250.643733906632, 14335.354909029014, 14854.480093196795, 14862.925685371503, 1689.5353777711105, 1713.802912665047, 1735.3492440334874, 1752.3165965273554, 1753.877228593999, 119875.80124198821, 123671.88866401765, 124529.37735311265, 124807.0, 127428.0, 9139.99460059393, 9904.356216845881, 10651.786964260671, 10944.835827462595, 11155.609553665745, 11373.715657108638, 13810.792838107436, 13915.373808178656, 13923.791143132861, 13930.721385572435, 7083.291670832876, 7509.136449308399, 8089.838203235902, 8239.917600824036, 8537.573121343908, 8519.977602687717, 8526.802449081595, 8534.53060081689, 8710.172533609297, 8712.738617841474, 5745.913811292834, 6114.2357205349335, 6302.621842689453, 6444.484441244689, 6445.0, 1479.3786609623983, 1585.7086509992948, 1687.5359276199144, 1886.1700851625242, 1889.4237257636278, 903.4488961733338, 910.3400034974591, 939.8879900435594, 983.852422136671, 1118.6811758648746, 19499.8050019498, 20034.89982550078, 20178.19287228524, 20199.293024744064, 20221.797782022284, 953.3374304858057, 967.4872317671645, 977.4868194198103, 988.8022395520925, 998.6804222648828, 8296.419250652518, 10851.78296434067, 10854.565817367375, 10881.510332035077, 10886.510107045202, 593643.0, 601539.0, 616626.0, 617778.0, 618907.0, 119270.8072919277, 122001.0, 122504.0, 122707.77292226964, 124622.0, 1042.4735508568276, 1047.5600247895902, 1064.7125276175389, 1065.3714308557987, 1069.572171131538, 17410.868293560878, 17936.551586210448, 18283.451496455127, 18411.937914857408, 18438.723419148457, 34871.825640872645, 39461.21077578375, 45894.77052614849, 46561.767191163824, 49017.509824901295, 54333.0, 61923.0, 69261.26846828668, 69573.95639065317, 71186.0, 7710.537367757953, 7735.574543399942, 7792.532448053135, 7885.369170466234, 8107.635156417858, 6512.511561632991, 8729.607167677597, 11915.463804129009, 12183.0, 12496.937515312366, 7410.666520006721, 7433.368163706145, 7621.657025433758, 7817.257360550739, 8355.582220889053, 7842.411819113703, 7908.920910790818, 8005.0, 8013.6794528218215, 8037.995250593675, 5031.471695471922, 5111.948880511296, 8068.757937261891, 8114.148014458633, 8130.552819594979, 11683.299002059564, 11900.464479098287, 12087.214331068453, 12208.75582488363, 12209.0, 10854.67435976922, 10923.453827308762, 10943.288686235688, 11228.101751859995, 11290.266132701348], "value": 9188.05829114001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.032338010154, 1574.315172899789, 1603.9518814435567, 1847.556586419259, 1985.305143199881, 7820.648070836812, 8557.0, 8801.559922003906, 8818.955905220473, 8927.55362231889, 7303.744368947088, 8787.253083487894, 8795.604197811097, 8796.69211577595, 8905.332100092486, 2111.356036408895, 2196.5826492966285, 2223.83321250906, 2225.7440394354694, 2430.586800243954, 2223.321886824518, 2262.1177740681064, 2412.191915708231, 2482.553140434721, 2490.1906880263987, 2422.2854257993845, 2438.9146379876706, 2453.950920981579, 2457.0171931227565, 2458.2256589174444, 2418.987905060481, 2421.019487107724, 2428.854268743881, 2433.051110067075, 2436.147348428036, 2153.4723992621753, 2394.4253379189045, 2446.1683027770464, 2453.2272334214667, 2462.5321188974217, 8689.348298877609, 8766.561671916443, 8771.254443372321, 8778.692745753931, 8798.120187981214, 5240.8689782755355, 5370.355557333145, 5397.811076612339, 5409.323834520685, 5425.7829686812875, 3536.3634545781747, 3592.3713350163616, 3651.580068292154, 3688.0226739913946, 3702.1114932416294, 1010.1262408017086, 1056.3133962924085, 1058.9046985771317, 1064.5050051725984, 1080.9675709728722, 11461.426928653535, 11462.828057579143, 11467.885321146765, 11481.253718508271, 11580.710482237928, 4753.35829662998, 4801.2949097336505, 4892.584130348889, 4892.804287828518, 4893.926591101136, 9803.16673082796, 9847.45838978863, 9871.654492092886, 9875.40747555149, 9877.901220987842, 5422.969635769191, 5426.457354264621, 5427.0, 5428.131498960148, 5433.592480563973, 4427.048184640318, 4809.014152098881, 4813.542713442287, 4874.951250487521, 4963.45402005779, 3750.4374343848094, 3888.844446222176, 3926.7434420985583, 3986.903601509608, 4013.678905687538, 1299.6750812296925, 1313.1530163044783, 1317.6837558985967, 1329.7898911990042, 1338.2505796753655, 830.7839961609905, 839.7152356893962, 890.9910900891037, 924.58393722825, 953.8998405167496, 5882.294124705063, 5890.558208134407, 5943.673097979653, 6034.818955431344, 6196.194494715658, 906.1482206725657, 946.9905300946903, 951.1344676344494, 957.3824882950458, 996.6013594562088, 3954.5452272988973, 3998.220347032359, 4000.19996000801, 4063.79681015948, 4076.673866090706, 10387.68836934893, 10605.57577696899, 10902.5093870776, 10919.508622111865, 10924.836127457935, 10739.301945373525, 10818.24272300947, 10826.729331766768, 10850.945745271223, 10857.294275872044, 7741.303282704584, 8252.21603947624, 8257.917420825836, 8272.545010024385, 8362.623681934327, 6054.606450580698, 6098.847528811638, 6103.389661033861, 6177.104319873683, 6194.721237544231, 9619.615215391586, 9780.706578802647, 10003.849942251018, 10009.799804004026, 10066.647667331608, 9678.322517423712, 10089.192864570963, 10093.798124037332, 10286.2799604027, 10307.123894469054, 6897.379235868796, 7135.928640713526, 7596.8480630388185, 7747.6513556891205, 7777.611119444118, 7891.84216315682, 8124.309433698202, 8127.024757029194, 8182.263596276363, 8229.177082291722, 6221.3156552780065, 7341.412686985135, 7361.779146625609, 7439.99560059385, 7512.136104347851, 7213.71145154209, 7226.7109315626785, 7305.0, 7316.561006339637, 7374.520656157332, 6790.25307216196, 6929.792106236821, 6957.965210174119, 6976.337247961435, 6980.7905762827195, 4406.581374769392, 4603.240465323184, 4841.346418233635, 4848.927266090941, 5059.848204553869, 4153.169366126726, 4709.48195698466, 4905.705657660552, 4912.729799861042, 4965.47862474435], "value": 4598.45745880974, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.134143058007, 311.1552135950894, 324.95450636910823, 329.45968611477167, 331.3240988383696, 4718.740469274188, 4914.950850491494, 5175.8188463403785, 5326.946730532693, 5428.70142142183, 4629.953700463003, 4787.563730880739, 4984.750762461871, 5311.787528498865, 5318.680879147254, 4060.8578699745517, 4071.3282308419075, 4078.83684652615, 4093.754374737518, 4134.338505839067, 3769.9284772445694, 3966.2662407454714, 4081.265372232997, 4129.0709590342085, 4147.502299724038, 3818.9427158592503, 3852.5569559500595, 4059.878203653895, 4099.3441049432095, 4107.466029416187, 3634.6365363463706, 4024.8591299304676, 4040.656544193747, 4052.9392059119136, 4058.2492238935893, 3023.969760302391, 3051.4202301562855, 3388.390089783838, 3566.233259849119, 3576.7138628909624, 4136.358864375997, 4713.771066819239, 4824.421069471651, 4850.2724591311235, 5105.744712764347, 3983.8406463741417, 4657.6273898088075, 4659.4175728034, 4714.151452738506, 4788.480466856858, 4741.5495527924795, 4974.204127339644, 5575.358833734092, 5595.552355811526, 5607.411221821691, 3426.9314613707834, 3851.8844434667003, 3956.9118492414527, 3993.881713120331, 3993.940090898639, 5001.874953126165, 5826.679532625704, 5865.35481097079, 5926.940730592725, 5936.821895343103, 3513.683768460851, 3661.5423072115987, 4010.8997275067773, 4024.7182697210915, 4051.2100140472326, 4082.8366865325056, 4085.2442298174933, 4112.300908845504, 4117.1765646870745, 4127.29835927893, 4179.4357761701785, 4653.371794807726, 4690.788914498856, 4695.95304046962, 4718.764061796896, 5600.243967064394, 5618.0, 5653.971730141323, 5655.434456554394, 5671.234383358276, 1083.5070043130356, 1134.387430787382, 1311.4885194774047, 1328.1499840102342, 1333.5665908579751, 766.6051983228642, 835.0480452284401, 913.7395842184945, 916.8487199612123, 926.8146370725882, 4221.936670949938, 4262.65898728101, 4937.703737775745, 4968.8757781055765, 4975.502449754996, 4844.660873738875, 4919.958217339764, 4991.103380715305, 5429.945700542944, 5551.639143455744, 4373.1253749249645, 4577.473590537064, 4597.747123908217, 4972.651914366032, 4978.6763860348965, 2612.8574568853737, 2645.10874724569, 2745.6842463116996, 2865.584490248902, 2909.621749172594, 5477.452254774491, 5712.258116104294, 5735.8852822944145, 5758.971205143947, 5794.275715535558, 5625.803096891589, 6021.200141933934, 6029.638221706711, 6058.212432383762, 6062.969685151546, 5328.307320048369, 5350.545203657733, 5537.529310008614, 6001.369171651176, 6062.333143354315, 5582.441755824385, 5805.245318108618, 5859.271514903028, 5880.118741816394, 5885.705714714333, 5734.913976290442, 5754.482096611325, 5766.509846663081, 5776.7400466979925, 5790.507806836297, 5400.648957817729, 5774.82675519735, 5953.970230148821, 6051.485623722033, 6079.513638908965, 3884.553276373253, 3911.941320880132, 3919.7844118573753, 3928.4696565963227, 3950.7036972227775, 5414.972925135349, 5598.9082128984455, 5853.8048231913035, 5863.413658634103, 5876.588638795243, 4901.779419926183, 4973.079980203674, 5288.281308574652, 5567.66594004361, 5673.886522269614, 5350.949958523604, 5541.6145963509125, 5641.6050876439895, 5661.603038177225, 5678.4605462480995, 4396.516383197782, 5153.948460515347, 5170.543991603958, 5532.921080389286, 5543.113101903676, 4800.895793967337, 5220.738963051756, 5375.1679427821255, 5652.689102099424, 5662.2639056922335, 5497.257870187474, 5516.862078448071, 5525.419830917856, 5532.391436941853, 5594.440555944373], "value": 4075.7955764116227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.601303205045, 734.7685479074091, 751.0349201276364, 761.3186198352473, 765.0857225615392, 14354.354054067566, 14582.927085364572, 14696.926515367408, 15183.620409489768, 15292.694146117085, 14343.42626294947, 14439.63900902478, 14492.637684057903, 14499.565013049625, 14512.201828899406, 716.2765606737207, 749.0973377080632, 895.8118795053047, 921.1985572551889, 921.272194965976, 891.4873947480193, 898.5282726568571, 926.9443833369986, 930.1721467893581, 930.9441433514011, 852.9189726975927, 970.9563069661847, 971.2424309038926, 972.2125078686287, 973.6689525561308, 754.9849003019937, 786.7089177004488, 787.1184273613533, 963.7060696487569, 964.3683387381242, 656.6585375604707, 687.5530904911848, 695.6174104242697, 730.6237287796789, 734.2400615363074, 14938.925305373403, 14974.026688265338, 14977.625559360995, 14982.175980321077, 14987.775183372258, 12409.937950310281, 12411.379431028412, 12414.689632759162, 12624.379964015572, 12740.617781466572, 10711.946440267826, 10856.185786066077, 11024.944875275572, 11106.389148596823, 11232.157588180919, 3583.229605634801, 3584.910377240564, 3626.12972886508, 3657.963420365789, 3692.2430901665093, 79576.0, 79762.60118699427, 80056.0, 80380.99999999942, 80837.99999999942, 9505.04949505044, 9536.761580960532, 9648.403935650891, 9816.0, 9930.453825039693, 21951.780482195292, 22130.114795408324, 22130.88934555317, 22429.55140897173, 22433.887830560743, 7286.744963926343, 7307.451941104438, 7355.963220183972, 7391.260873912565, 7817.570033648204, 4099.795010249476, 4100.5899410059355, 4120.690948178899, 4170.457840480718, 4403.14138742942, 3215.742740580748, 3336.7831090979007, 3343.297907439416, 3344.364570731553, 3347.6652334766814, 634.1660716158237, 643.7425029988042, 648.4552975500602, 649.6329573790794, 650.408128602969, 543.4433559109333, 556.1851886985586, 572.390404219505, 578.1356871477085, 579.261441661876, 7631.503952243197, 7755.72854950074, 7815.062192536932, 7888.290053895177, 7927.762167134995, 516.8449465160512, 537.7445713286236, 553.6899336371645, 582.1442479555047, 593.4243783530055, 3608.440691692792, 3655.6093924711145, 3661.2494438640015, 3702.7037836972977, 3868.323043467382, 86707.13292867117, 87577.0, 88702.55648721715, 90749.0, 90764.54617727001, 62640.68679656572, 69536.60926781537, 77232.0, 80452.59773701095, 80881.0, 68915.0, 69965.3003469969, 70060.69453749085, 70102.0, 70423.29576704372, 8096.149904260204, 8108.270255677017, 8312.584370781558, 8325.333973282244, 8424.831503369776, 35084.473732893515, 45148.54851451575, 45219.09561808811, 45335.0, 45432.95551700115, 60628.69685651544, 63353.04970425355, 63458.365416345245, 63729.6813515948, 63846.0, 10220.591176352862, 11003.394813285346, 11025.724356891142, 11136.832947505633, 11352.0, 12024.33866137371, 12056.63830085099, 12059.819102713642, 12407.317597532223, 12587.937060315006, 11205.775884482428, 11292.830607541058, 11377.374244416636, 11715.238509496856, 11994.820077698667, 10807.67576972692, 11089.500972456097, 11108.111351091731, 11116.888831111586, 12247.571335003233, 10772.89227107719, 11070.612528561462, 11264.7747045057, 11482.885171148182, 11668.299902005907, 9174.54127293646, 9286.674966376144, 9299.814003720023, 9939.155171810477, 10103.934743757462, 7747.728829491166, 9242.630294788401, 9301.627934882528, 9386.530673466435, 9494.810103798023], "value": 6801.730769466667, "unit": "score"}], "name": "dromaeo_css", "value": 7114.990838335811, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 106.0, 112.0, 112.0, 111.0, 112.0, 110.0, 111.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 142.0, 144.0, 163.0, 118.0, 138.0, 145.0, 145.0, 197.0, 136.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 184.0, 183.0, 181.0, 183.0, 184.0, 184.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 57.0, 104.0, 56.0, 62.0, 58.0, 83.0, 141.0, 64.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 100.0, 80.0, 101.0, 111.0, 138.0, 79.0, 79.0, 99.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 87.0, 88.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 99.0, 142.0, 138.0, 85.0, 86.0, 86.0, 85.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 61.0, 76.0, 60.0, 58.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 78.0, 72.0, 69.0, 69.0, 67.0, 71.0, 67.0, 68.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 85.0, 85.0, 83.0, 83.0, 85.0, 87.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 142.0, 135.0, 138.0, 135.0, 138.0, 135.0, 189.0, 159.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 167.0, 167.0, 165.0, 167.0, 184.0, 226.0, 189.0, 165.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 75.0, 83.0, 83.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1508.8, "alertThreshold": 2.0}]} 18:22:06 INFO - Return code: 0 18:22:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:22:06 ERROR - # TBPL SUCCESS # 18:22:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:06 INFO - 2016-05-06 18:22:06.901 screenresolution[1678:9691] starting screenresolution argv=screenresolution get 18:22:06 INFO - 2016-05-06 18:22:06.904 screenresolution[1678:9691] Display 0: 1600x1200x32@60 18:22:06 INFO - 2016-05-06 18:22:06.909 screenresolution[1679:9694] starting screenresolution argv=screenresolution list 18:22:06 INFO - Available Modes on Display 0 18:22:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:07 INFO - Intel Iris: 18:22:07 INFO - Chipset Model: Intel Iris 18:22:07 INFO - Type: GPU 18:22:07 INFO - Bus: Built-In 18:22:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:07 INFO - Vendor: Intel (0x8086) 18:22:07 INFO - Device ID: 0x0a2e 18:22:07 INFO - Revision ID: 0x0009 18:22:07 INFO - Displays: 18:22:07 INFO - Display: 18:22:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:07 INFO - Display Serial Number: 1600x1200 60 18:22:07 INFO - Main Display: Yes 18:22:07 INFO - Mirror: Off 18:22:07 INFO - Online: Yes 18:22:07 INFO - Rotation: Supported 18:22:07 INFO - Return code: 0 18:22:07 INFO - Running post-action listener: _resource_record_post_action 18:22:07 INFO - ##### 18:22:07 INFO - ##### Finished run-tests step (success) 18:22:07 INFO - ##### 18:22:07 INFO - Running post-run listener: _resource_record_post_run 18:22:08 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37914112; Write bytes: 694767104; Read time: 395; Write time: 1976 18:22:08 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392708608; Write bytes: 386480128; Read time: 29974; Write time: 726 18:22:08 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36099584; Write bytes: 295101952; Read time: 376; Write time: 1213 18:22:08 INFO - Running post-run listener: _upload_blobber_files 18:22:08 INFO - Blob upload gear active. 18:22:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:08 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:08 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:08 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:08 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:22:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:09 INFO - (blobuploader) - INFO - Done attempting. 18:22:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:22:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:10 INFO - (blobuploader) - INFO - Done attempting. 18:22:10 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:10 INFO - Return code: 0 18:22:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe8dd9ca23b79edb1ae5daf584a32906d748e89b857ec33afa7187d0b0f9f9e1711601d210a465dd776c654ba313ddf4c03bfad8e96877e1f9cc169fd83c4118", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68078371404cfbb3c0906edda5796a12d6eb5978774aed919a4d660efbb9a17bc3f2193110fcd185599a75cabe690c5346eefbc7fa80a52b3edeeb43df1621ad"} 18:22:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:10 INFO - Contents: 18:22:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe8dd9ca23b79edb1ae5daf584a32906d748e89b857ec33afa7187d0b0f9f9e1711601d210a465dd776c654ba313ddf4c03bfad8e96877e1f9cc169fd83c4118", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68078371404cfbb3c0906edda5796a12d6eb5978774aed919a4d660efbb9a17bc3f2193110fcd185599a75cabe690c5346eefbc7fa80a52b3edeeb43df1621ad"} 18:22:10 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:10 INFO - Copying logs to upload dir... 18:22:10 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.515718 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-06 18:22:11.130612) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:22:11.138436) ========= 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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/Firefox/sha512/fe8dd9ca23b79edb1ae5daf584a32906d748e89b857ec33afa7187d0b0f9f9e1711601d210a465dd776c654ba313ddf4c03bfad8e96877e1f9cc169fd83c4118", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68078371404cfbb3c0906edda5796a12d6eb5978774aed919a4d660efbb9a17bc3f2193110fcd185599a75cabe690c5346eefbc7fa80a52b3edeeb43df1621ad"} build_url:https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011076 build_url: 'https://queue.taskcluster.net/v1/task/ekHv_qvJTQSDWDf2r7FG7w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe8dd9ca23b79edb1ae5daf584a32906d748e89b857ec33afa7187d0b0f9f9e1711601d210a465dd776c654ba313ddf4c03bfad8e96877e1f9cc169fd83c4118", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68078371404cfbb3c0906edda5796a12d6eb5978774aed919a4d660efbb9a17bc3f2193110fcd185599a75cabe690c5346eefbc7fa80a52b3edeeb43df1621ad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:22:11.173527) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:22:11.173849) ========= 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.hLDcTFdO8a/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.cNp3Ce3gV5/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.004584 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:22:11.223805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:22:11.226148) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:22:11.226458) ========= ========= Total master_lag: 0.39 =========