builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0100 starttime: 1462931766.32 results: success (0) buildid: 20160510171011 builduid: b85e2bcfa21d4764bc69a40aec7e15af revision: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.318985) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.319419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.319691) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.342338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.342730) ========= 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.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 18:56:06-- 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% 41.8M=0s 2016-05-10 18:56:06 (41.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.516694) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.517149) ========= 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.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.026533 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:06.568964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 18:56:06.571488) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 027b0144a1a6c2d62dfb1656e41597acfe6a22ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 027b0144a1a6c2d62dfb1656e41597acfe6a22ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 18:56:06,648 truncating revision to first 12 chars 2016-05-10 18:56:06,648 Setting DEBUG logging. 2016-05-10 18:56:06,648 attempt 1/10 2016-05-10 18:56:06,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/027b0144a1a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 18:56:08,748 unpacking tar archive at: fx-team-027b0144a1a6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.356389 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 18:56:08.945498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:08.945843) ========= script_repo_revision: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:08.946274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:08.946580) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:56:08.961285) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 18:56:08.961683) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:09 INFO - MultiFileLogger online at 20160510 18:56:09 in /builds/slave/test 18:56:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:56:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:56:09 INFO - {'append_to_log': False, 18:56:09 INFO - 'base_work_dir': '/builds/slave/test', 18:56:09 INFO - 'blob_upload_branch': 'Fx-Team', 18:56:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:56:09 INFO - 'branch': 'Fx-Team', 18:56:09 INFO - 'buildbot_json_path': 'buildprops.json', 18:56:09 INFO - 'config_files': ('talos/mac_config.py',), 18:56:09 INFO - 'default_actions': ('clobber', 18:56:09 INFO - 'read-buildbot-config', 18:56:09 INFO - 'download-and-extract', 18:56:09 INFO - 'populate-webroot', 18:56:09 INFO - 'create-virtualenv', 18:56:09 INFO - 'install', 18:56:09 INFO - 'run-tests'), 18:56:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:56:09 INFO - 'download_minidump_stackwalk': True, 18:56:09 INFO - 'download_symbols': 'ondemand', 18:56:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:56:09 INFO - 'tooltool.py': '/tools/tooltool.py', 18:56:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:56:09 INFO - '/tools/misc-python/virtualenv.py')}, 18:56:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:56:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:56:09 INFO - 'installer_path': 'installer.exe', 18:56:09 INFO - 'log_level': 'info', 18:56:09 INFO - 'log_name': 'talos', 18:56:09 INFO - 'log_to_console': True, 18:56:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:56:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:56:09 INFO - 'opt_config_files': (), 18:56:09 INFO - 'pip_index': False, 18:56:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:56:09 INFO - 'cmd': ('bash', 18:56:09 INFO - '-c', 18:56:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:56:09 INFO - 'enabled': True, 18:56:09 INFO - 'halt_on_failure': False, 18:56:09 INFO - 'name': 'check_screen_resolution'},), 18:56:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:56:09 INFO - 'cmd': ('bash', 18:56:09 INFO - '-c', 18:56:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:56:09 INFO - 'enabled': True, 18:56:09 INFO - 'halt_on_failure': False, 18:56:09 INFO - 'name': 'check_screen_resolution'},), 18:56:09 INFO - 'run_cmd_checks_enabled': True, 18:56:09 INFO - 'sps_profile': False, 18:56:09 INFO - 'sps_profile_interval': 0, 18:56:09 INFO - 'suite': 'dromaeojs', 18:56:09 INFO - 'system_bits': '32', 18:56:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:56:09 INFO - 'title': 't-yosemite-r7-0100', 18:56:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:56:09 INFO - 'use_talos_json': True, 18:56:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:56:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:56:09 INFO - 'work_dir': 'build'} 18:56:09 INFO - ##### 18:56:09 INFO - ##### Running clobber step. 18:56:09 INFO - ##### 18:56:09 INFO - Running pre-action listener: _resource_record_pre_action 18:56:09 INFO - Running main action method: clobber 18:56:09 INFO - rmtree: /builds/slave/test/build 18:56:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:56:11 INFO - Running post-action listener: _resource_record_post_action 18:56:11 INFO - ##### 18:56:11 INFO - ##### Finished clobber step (success) 18:56:11 INFO - ##### 18:56:11 INFO - ##### 18:56:11 INFO - ##### Running read-buildbot-config step. 18:56:11 INFO - ##### 18:56:11 INFO - Running pre-action listener: _resource_record_pre_action 18:56:11 INFO - Running main action method: read_buildbot_config 18:56:11 INFO - Using buildbot properties: 18:56:11 INFO - { 18:56:11 INFO - "project": "", 18:56:11 INFO - "product": "firefox", 18:56:11 INFO - "script_repo_revision": "production", 18:56:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:56:11 INFO - "repository": "", 18:56:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:56:11 INFO - "buildid": "20160510171011", 18:56:11 INFO - "pgo_build": "False", 18:56:11 INFO - "basedir": "/builds/slave/test", 18:56:11 INFO - "buildnumber": 8, 18:56:11 INFO - "slavename": "t-yosemite-r7-0100", 18:56:11 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:56:11 INFO - "platform": "yosemite_r7", 18:56:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:56:11 INFO - "branch": "fx-team", 18:56:11 INFO - "repo_path": "integration/fx-team", 18:56:11 INFO - "revision": "027b0144a1a6c2d62dfb1656e41597acfe6a22ef", 18:56:11 INFO - "stage_platform": "macosx64", 18:56:11 INFO - "builduid": "b85e2bcfa21d4764bc69a40aec7e15af", 18:56:11 INFO - "slavebuilddir": "test" 18:56:11 INFO - } 18:56:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:56:11 INFO - Running post-action listener: _resource_record_post_action 18:56:11 INFO - ##### 18:56:11 INFO - ##### Finished read-buildbot-config step (success) 18:56:11 INFO - ##### 18:56:11 INFO - ##### 18:56:11 INFO - ##### Running download-and-extract step. 18:56:11 INFO - ##### 18:56:11 INFO - Running pre-action listener: _resource_record_pre_action 18:56:11 INFO - Running main action method: download_and_extract 18:56:11 INFO - mkdir: /builds/slave/test/build/tests 18:56: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:56:11 INFO - https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:56:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:56:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:56:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:12 INFO - Downloaded 1199 bytes. 18:56:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:56:12 INFO - Using the following test package requirements: 18:56:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:56:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:56:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:56:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'jsshell-mac.zip'], 18:56:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:56:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:56:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:56:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:56:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:56:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:56:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:56:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:56:12 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:56:12 INFO - https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:56:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:56:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:56:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:13 INFO - Downloaded 18150515 bytes. 18:56:13 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:56:13 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:56:16 INFO - Return code: 0 18:56:16 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:56: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:56:16 INFO - https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:56:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:56:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:56:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:17 INFO - Downloaded 18150515 bytes. 18:56:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:56:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:56:21 INFO - Return code: 0 18:56:21 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:56:21 INFO - https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:56:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:56:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:56:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:22 INFO - Downloaded 10840152 bytes. 18:56:22 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:56:22 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:56:22 INFO - Return code: 0 18:56:22 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:56:22 INFO - https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:56:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:56:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/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:56:25 INFO - Downloaded 109843744 bytes. 18:56:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:25 INFO - mkdir: /builds/slave/test/properties 18:56:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:56:25 INFO - Writing to file /builds/slave/test/properties/build_url 18:56:25 INFO - Contents: 18:56:25 INFO - build_url:https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:26 INFO - Running post-action listener: _resource_record_post_action 18:56:26 INFO - Running post-action listener: set_extra_try_arguments 18:56:26 INFO - ##### 18:56:26 INFO - ##### Finished download-and-extract step (success) 18:56:26 INFO - ##### 18:56:26 INFO - ##### 18:56:26 INFO - ##### Running populate-webroot step. 18:56:26 INFO - ##### 18:56:26 INFO - Running pre-action listener: _resource_record_pre_action 18:56:26 INFO - Running main action method: populate_webroot 18:56:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:56:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:56:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:56:26 INFO - '--tppagecycles', 18:56:26 INFO - '7'], 18:56:26 INFO - 'tests': ['tsvgm']}}, 18:56:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:56:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:56:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:56:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:56:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['damp', 'tps']}, 18:56:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'tests': ['damp', 'tps']}, 18:56:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['dromaeo_dom']}, 18:56:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:56:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'other-e10s': {'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['a11yr', 18:56:26 INFO - 'ts_paint', 18:56:26 INFO - 'tpaint', 18:56:26 INFO - 'sessionrestore', 18:56:26 INFO - 'sessionrestore_no_auto_restore', 18:56:26 INFO - 'tabpaint']}, 18:56:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['tsvgx', 18:56:26 INFO - 'tsvgr_opacity', 18:56:26 INFO - 'tart', 18:56:26 INFO - 'tscrollx', 18:56:26 INFO - 'cart']}, 18:56:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:56:26 INFO - 'tsvgr_opacity', 18:56:26 INFO - 'tart', 18:56:26 INFO - 'tscrollx', 18:56:26 INFO - 'cart']}, 18:56:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'talos_options': ['--disable-e10s'], 18:56:26 INFO - 'tests': ['tp5o']}, 18:56:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'tests': ['tp5o']}, 18:56:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'talos_options': ['--disable-e10s', 18:56:26 INFO - '--xperf_path', 18:56:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:26 INFO - 'tests': ['tp5n']}, 18:56:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:26 INFO - 'talos_options': ['--xperf_path', 18:56:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:26 INFO - 'tests': ['tp5n']}}, 18:56:26 INFO - 'talos.zip': {'path': '', 18:56:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:56:26 INFO - Running post-action listener: _resource_record_post_action 18:56:26 INFO - ##### 18:56:26 INFO - ##### Finished populate-webroot step (success) 18:56:26 INFO - ##### 18:56:26 INFO - ##### 18:56:26 INFO - ##### Running create-virtualenv step. 18:56:26 INFO - ##### 18:56:26 INFO - Running pre-action listener: _resource_record_pre_action 18:56:26 INFO - Running main action method: create_virtualenv 18:56:26 INFO - Creating virtualenv /builds/slave/test/build/venv 18:56:26 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:56:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:56:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:56:26 INFO - Using real prefix '/tools/python27' 18:56:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:56:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:56:30 INFO - Installing pip.................done. 18:56:30 INFO - Return code: 0 18:56:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:56:30 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:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:30 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:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:30 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:30 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:56:30 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:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:30 INFO - 'HOME': '/Users/cltbld', 18:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:30 INFO - 'LOGNAME': 'cltbld', 18:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:30 INFO - 'MOZ_NO_REMOTE': '1', 18:56:30 INFO - 'NO_EM_RESTART': '1', 18:56:30 INFO - 'PAGER': '/bin/cat', 18:56:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:30 INFO - 'PWD': '/builds/slave/test', 18:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:30 INFO - 'SHELL': '/bin/bash', 18:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:30 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:30 INFO - 'USER': 'cltbld', 18:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:30 INFO - 'XPC_FLAGS': '0x0', 18:56:30 INFO - 'XPC_SERVICE_NAME': '0', 18:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:30 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:56:30 INFO - Cleaning up... 18:56:30 INFO - Return code: 0 18:56:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:56:30 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:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:30 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:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:30 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:30 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:56:30 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:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:30 INFO - 'HOME': '/Users/cltbld', 18:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:30 INFO - 'LOGNAME': 'cltbld', 18:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:30 INFO - 'MOZ_NO_REMOTE': '1', 18:56:30 INFO - 'NO_EM_RESTART': '1', 18:56:30 INFO - 'PAGER': '/bin/cat', 18:56:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:30 INFO - 'PWD': '/builds/slave/test', 18:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:30 INFO - 'SHELL': '/bin/bash', 18:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:30 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:30 INFO - 'USER': 'cltbld', 18:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:30 INFO - 'XPC_FLAGS': '0x0', 18:56:30 INFO - 'XPC_SERVICE_NAME': '0', 18:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:30 INFO - Downloading/unpacking psutil>=0.7.1 18:56: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:56: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:56: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:56: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:56: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:56: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:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:56:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:33 INFO - Installing collected packages: psutil 18:56:33 INFO - Running setup.py install for psutil 18:56:33 INFO - building 'psutil._psutil_osx' extension 18:56:33 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:56:34 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:56:34 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:56:34 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:56:34 INFO - building 'psutil._psutil_posix' extension 18:56:34 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:56:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:56:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:56:34 INFO - ^ 18:56:34 INFO - 1 warning generated. 18:56:34 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:56:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:34 INFO - Successfully installed psutil 18:56:34 INFO - Cleaning up... 18:56:34 INFO - Return code: 0 18:56:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:34 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:34 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:56:34 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:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:34 INFO - 'HOME': '/Users/cltbld', 18:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:34 INFO - 'LOGNAME': 'cltbld', 18:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:34 INFO - 'MOZ_NO_REMOTE': '1', 18:56:34 INFO - 'NO_EM_RESTART': '1', 18:56:34 INFO - 'PAGER': '/bin/cat', 18:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:34 INFO - 'PWD': '/builds/slave/test', 18:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:34 INFO - 'SHELL': '/bin/bash', 18:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:34 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:34 INFO - 'USER': 'cltbld', 18:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:34 INFO - 'XPC_FLAGS': '0x0', 18:56:34 INFO - 'XPC_SERVICE_NAME': '0', 18:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:56: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:56: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:56: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:56: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:56: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:56: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:56:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:56:37 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:56:37 INFO - Installing collected packages: mozsystemmonitor 18:56:37 INFO - Running setup.py install for mozsystemmonitor 18:56:37 INFO - Successfully installed mozsystemmonitor 18:56:37 INFO - Cleaning up... 18:56:37 INFO - Return code: 0 18:56:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56: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:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:37 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:37 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:56:37 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:56:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:37 INFO - 'HOME': '/Users/cltbld', 18:56:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:37 INFO - 'LOGNAME': 'cltbld', 18:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:37 INFO - 'MOZ_NO_REMOTE': '1', 18:56:37 INFO - 'NO_EM_RESTART': '1', 18:56:37 INFO - 'PAGER': '/bin/cat', 18:56:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:37 INFO - 'PWD': '/builds/slave/test', 18:56:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:37 INFO - 'SHELL': '/bin/bash', 18:56:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:37 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:37 INFO - 'USER': 'cltbld', 18:56:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:37 INFO - 'XPC_FLAGS': '0x0', 18:56:37 INFO - 'XPC_SERVICE_NAME': '0', 18:56:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:38 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:40 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:40 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:56:40 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:56:40 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:56:40 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:56:40 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:56:40 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:56:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:41 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:56:41 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:56:41 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:56:41 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:56:41 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:56:41 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:56:41 INFO - Downloading docopt-0.6.1.tar.gz 18:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:41 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:41 INFO - Running setup.py install for blobuploader 18:56:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:41 INFO - Running setup.py install for requests 18:56:42 INFO - Running setup.py install for docopt 18:56:42 INFO - Successfully installed blobuploader requests docopt 18:56:42 INFO - Cleaning up... 18:56:42 INFO - Return code: 0 18:56:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56: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:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:42 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:42 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:56:42 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:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:42 INFO - 'HOME': '/Users/cltbld', 18:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:42 INFO - 'LOGNAME': 'cltbld', 18:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:42 INFO - 'MOZ_NO_REMOTE': '1', 18:56:42 INFO - 'NO_EM_RESTART': '1', 18:56:42 INFO - 'PAGER': '/bin/cat', 18:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:42 INFO - 'PWD': '/builds/slave/test', 18:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:42 INFO - 'SHELL': '/bin/bash', 18:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:42 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:42 INFO - 'USER': 'cltbld', 18:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:42 INFO - 'XPC_FLAGS': '0x0', 18:56:42 INFO - 'XPC_SERVICE_NAME': '0', 18:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:42 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-yODgIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:42 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-pzHgOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ORqpsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-AqdKv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-vLDSsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-68I8yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-r_RURT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-2YmU8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-gGnD6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-CrS8MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-7PCoNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:43 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-dDXySo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:44 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-YXczWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:44 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-xI1Q1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:44 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-8vniRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:44 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-orgPHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:44 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-8weUiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:44 INFO - Running setup.py install for manifestparser 18:56:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:44 INFO - Running setup.py install for mozcrash 18:56:44 INFO - Running setup.py install for mozdebug 18:56:45 INFO - Running setup.py install for mozdevice 18:56:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Running setup.py install for mozfile 18:56:45 INFO - Running setup.py install for mozhttpd 18:56:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Running setup.py install for mozinfo 18:56:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Running setup.py install for mozInstall 18:56:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:45 INFO - Running setup.py install for mozleak 18:56:45 INFO - Running setup.py install for mozlog 18:56:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Running setup.py install for moznetwork 18:56:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Running setup.py install for mozprocess 18:56:46 INFO - Running setup.py install for mozprofile 18:56:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Running setup.py install for mozrunner 18:56:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:46 INFO - Running setup.py install for mozscreenshot 18:56:46 INFO - Running setup.py install for moztest 18:56:47 INFO - Running setup.py install for mozversion 18:56:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:47 INFO - Cleaning up... 18:56:47 INFO - Return code: 0 18:56:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56: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:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:47 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:47 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:56:47 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:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:47 INFO - 'HOME': '/Users/cltbld', 18:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:47 INFO - 'LOGNAME': 'cltbld', 18:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:47 INFO - 'MOZ_NO_REMOTE': '1', 18:56:47 INFO - 'NO_EM_RESTART': '1', 18:56:47 INFO - 'PAGER': '/bin/cat', 18:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:47 INFO - 'PWD': '/builds/slave/test', 18:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:47 INFO - 'SHELL': '/bin/bash', 18:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:47 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:47 INFO - 'USER': 'cltbld', 18:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:47 INFO - 'XPC_FLAGS': '0x0', 18:56:47 INFO - 'XPC_SERVICE_NAME': '0', 18:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-6QDSbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:47 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:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-bSZA6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:47 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:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ynZUo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:47 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:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-jzdNca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:47 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:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-WrRMgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:47 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:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:47 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-qcdlhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ppkW44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-GKGze_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-s2cA_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-qt1L8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-cVFkLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-IQYviK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-FsPX94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-9Mk2xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:48 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:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:48 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-I5ZT9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:49 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:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:49 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-TrnifP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:49 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:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:49 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-g3rln7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:49 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:56:49 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:56:49 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:56:49 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:56:49 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:56:49 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:56:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:51 INFO - Downloading blessings-1.6.tar.gz 18:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:51 INFO - Installing collected packages: blessings 18:56:51 INFO - Running setup.py install for blessings 18:56:51 INFO - Successfully installed blessings 18:56:51 INFO - Cleaning up... 18:56:52 INFO - Return code: 0 18:56:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:52 INFO - Reading from file tmpfile_stdout 18:56:52 INFO - Current package versions: 18:56:52 INFO - blessings == 1.6 18:56:52 INFO - blobuploader == 1.2.4 18:56:52 INFO - docopt == 0.6.1 18:56:52 INFO - manifestparser == 1.1 18:56:52 INFO - mozInstall == 1.12 18:56:52 INFO - mozcrash == 0.17 18:56:52 INFO - mozdebug == 0.1 18:56:52 INFO - mozdevice == 0.48 18:56:52 INFO - mozfile == 1.2 18:56:52 INFO - mozhttpd == 0.7 18:56:52 INFO - mozinfo == 0.9 18:56:52 INFO - mozleak == 0.1 18:56:52 INFO - mozlog == 3.1 18:56:52 INFO - moznetwork == 0.27 18:56:52 INFO - mozprocess == 0.22 18:56:52 INFO - mozprofile == 0.28 18:56:52 INFO - mozrunner == 6.11 18:56:52 INFO - mozscreenshot == 0.1 18:56:52 INFO - mozsystemmonitor == 0.0 18:56:52 INFO - moztest == 0.7 18:56:52 INFO - mozversion == 1.4 18:56:52 INFO - psutil == 3.1.1 18:56:52 INFO - requests == 1.2.3 18:56:52 INFO - wsgiref == 0.1.2 18:56:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:52 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:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:52 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:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:52 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:52 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:56:52 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:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:52 INFO - 'HOME': '/Users/cltbld', 18:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:52 INFO - 'LOGNAME': 'cltbld', 18:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:52 INFO - 'MOZ_NO_REMOTE': '1', 18:56:52 INFO - 'NO_EM_RESTART': '1', 18:56:52 INFO - 'PAGER': '/bin/cat', 18:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:52 INFO - 'PWD': '/builds/slave/test', 18:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:52 INFO - 'SHELL': '/bin/bash', 18:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:52 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:52 INFO - 'USER': 'cltbld', 18:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:52 INFO - 'XPC_FLAGS': '0x0', 18:56:52 INFO - 'XPC_SERVICE_NAME': '0', 18:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 INFO - Cleaning up... 18:56:52 INFO - Return code: 0 18:56:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:52 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:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:52 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:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:52 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 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:52 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:56:52 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:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:52 INFO - 'HOME': '/Users/cltbld', 18:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:52 INFO - 'LOGNAME': 'cltbld', 18:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:52 INFO - 'MOZ_NO_REMOTE': '1', 18:56:52 INFO - 'NO_EM_RESTART': '1', 18:56:52 INFO - 'PAGER': '/bin/cat', 18:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:52 INFO - 'PWD': '/builds/slave/test', 18:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:52 INFO - 'SHELL': '/bin/bash', 18:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:52 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:52 INFO - 'USER': 'cltbld', 18:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:52 INFO - 'XPC_FLAGS': '0x0', 18:56:52 INFO - 'XPC_SERVICE_NAME': '0', 18:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:52 INFO - Downloading/unpacking jsonschema 18:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:52 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:56:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:55 INFO - Downloading/unpacking functools32 (from jsonschema) 18:56:55 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:56:55 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:56:55 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:56:55 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:56:55 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:56:55 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:56:55 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:55 INFO - warning: no files found matching '*.txt' 18:56:55 INFO - no previously-included directories found matching 'build' 18:56:55 INFO - no previously-included directories found matching 'dist' 18:56:55 INFO - no previously-included directories found matching '.git*' 18:56:55 INFO - Installing collected packages: jsonschema, functools32 18:56:55 INFO - Running setup.py install for functools32 18:56:55 INFO - warning: no files found matching '*.txt' 18:56:55 INFO - no previously-included directories found matching 'build' 18:56:55 INFO - no previously-included directories found matching 'dist' 18:56:55 INFO - no previously-included directories found matching '.git*' 18:56:56 INFO - Successfully installed jsonschema functools32 18:56:56 INFO - Cleaning up... 18:56:56 INFO - Return code: 0 18:56:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104749a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10474f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, '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.io6dnvUmCR/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.q22dgpw32N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:56 INFO - 'HOME': '/Users/cltbld', 18:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:56 INFO - 'LOGNAME': 'cltbld', 18:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:56 INFO - 'MOZ_NO_REMOTE': '1', 18:56:56 INFO - 'NO_EM_RESTART': '1', 18:56:56 INFO - 'PAGER': '/bin/cat', 18:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:56 INFO - 'PWD': '/builds/slave/test', 18:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:56 INFO - 'SHELL': '/bin/bash', 18:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:56:56 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:56 INFO - 'USER': 'cltbld', 18:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:56 INFO - 'XPC_FLAGS': '0x0', 18:56:56 INFO - 'XPC_SERVICE_NAME': '0', 18:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:56 INFO - Downloading/unpacking flake8 18:56:56 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:56:56 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:56:56 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:56:56 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:56:56 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:56:56 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:56:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:56:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:56:58 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:56:58 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:56:58 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:56:58 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:56:58 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:56:58 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:56:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:56:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:56:59 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:56:59 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:56:59 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:56:59 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:56:59 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:56:59 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:56:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:56:59 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:56:59 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:56:59 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:56:59 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:56:59 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:56:59 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:56:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:57:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:57:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:57:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:57:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:57:00 INFO - no previously-included directories found matching 'docs/_build' 18:57:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:57:00 INFO - Running setup.py install for pep8 18:57:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:57:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:57:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:57:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:57:00 INFO - no previously-included directories found matching 'docs/_build' 18:57:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:57:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:57:00 INFO - Cleaning up... 18:57:00 INFO - Return code: 0 18:57:00 INFO - Running post-action listener: _resource_record_post_action 18:57:00 INFO - Running post-action listener: _start_resource_monitoring 18:57:00 INFO - Starting resource monitoring. 18:57:00 INFO - ##### 18:57:00 INFO - ##### Finished create-virtualenv step (success) 18:57:00 INFO - ##### 18:57:00 INFO - ##### 18:57:00 INFO - ##### Running install step. 18:57:00 INFO - ##### 18:57:00 INFO - Running pre-action listener: _resource_record_pre_action 18:57:00 INFO - Running main action method: install 18:57:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:00 INFO - Reading from file tmpfile_stdout 18:57:00 INFO - Detecting whether we're running mozinstall >=1.0... 18:57:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:57:00 INFO - Reading from file tmpfile_stdout 18:57:00 INFO - Output received: 18:57:00 INFO - Usage: mozinstall [options] installer 18:57:00 INFO - Options: 18:57:00 INFO - -h, --help show this help message and exit 18:57:00 INFO - -d DEST, --destination=DEST 18:57:00 INFO - Directory to install application into. [default: 18:57:00 INFO - "/builds/slave/test"] 18:57:00 INFO - --app=APP Application being installed. [default: firefox] 18:57:00 INFO - mkdir: /builds/slave/test/build/application 18:57:00 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:57:00 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:57:33 INFO - Reading from file tmpfile_stdout 18:57:33 INFO - Output received: 18:57:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:57:33 INFO - Running post-action listener: _resource_record_post_action 18:57:33 INFO - ##### 18:57:33 INFO - ##### Finished install step (success) 18:57:33 INFO - ##### 18:57:33 INFO - ##### 18:57:33 INFO - ##### Running run-tests step. 18:57:33 INFO - ##### 18:57:33 INFO - Running pre-action listener: _resource_record_pre_action 18:57:33 INFO - Running main action method: run_tests 18:57:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:57:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:57:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:57:33 INFO - 2016-05-10 18:57:33.985 screenresolution[1638:7631] starting screenresolution argv=screenresolution get 18:57:33 INFO - 2016-05-10 18:57:33.988 screenresolution[1638:7631] Display 0: 1600x1200x32@60 18:57:33 INFO - 2016-05-10 18:57:33.994 screenresolution[1639:7633] starting screenresolution argv=screenresolution list 18:57:33 INFO - Available Modes on Display 0 18:57:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:57:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:57:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:57:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:57:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:57:34 INFO - Intel Iris: 18:57:34 INFO - Chipset Model: Intel Iris 18:57:34 INFO - Type: GPU 18:57:34 INFO - Bus: Built-In 18:57:34 INFO - VRAM (Dynamic, Max): 1536 MB 18:57:34 INFO - Vendor: Intel (0x8086) 18:57:34 INFO - Device ID: 0x0a2e 18:57:34 INFO - Revision ID: 0x0009 18:57:34 INFO - Displays: 18:57:34 INFO - Display: 18:57:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:57:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:57:34 INFO - Display Serial Number: 1600x1200 60 18:57:34 INFO - Main Display: Yes 18:57:34 INFO - Mirror: Off 18:57:34 INFO - Online: Yes 18:57:34 INFO - Rotation: Supported 18:57:34 INFO - Return code: 0 18:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:57:34 INFO - Python 2.7.3 18:57:34 INFO - Return code: 0 18:57:34 INFO - grabbing minidump binary from tooltool 18:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045edd30>, '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 0x104411db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1045e4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:57:34 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:57:34 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:57:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:57:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:57:34 INFO - Return code: 0 18:57:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:57:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:57:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:57:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:57:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:57:35 INFO - Return code: 0 18:57:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --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:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.io6dnvUmCR/Render', 18:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:35 INFO - 'HOME': '/Users/cltbld', 18:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:35 INFO - 'LOGNAME': 'cltbld', 18:57:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:57:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:35 INFO - 'MOZ_NO_REMOTE': '1', 18:57:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:57:35 INFO - 'NO_EM_RESTART': '1', 18:57:35 INFO - 'PAGER': '/bin/cat', 18:57:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:35 INFO - 'PWD': '/builds/slave/test', 18:57:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:35 INFO - 'SHELL': '/bin/bash', 18:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q22dgpw32N/Listeners', 18:57:35 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 18:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:35 INFO - 'USER': 'cltbld', 18:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:35 INFO - 'XPC_FLAGS': '0x0', 18:57:35 INFO - 'XPC_SERVICE_NAME': '0', 18:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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:57:35 INFO - mozversion application_buildid: 20160510171011 18:57:35 INFO - mozversion application_changeset: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef 18:57:35 INFO - mozversion application_display_name: Nightly 18:57:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:57:35 INFO - mozversion application_name: Firefox 18:57:35 INFO - mozversion application_remotingname: firefox 18:57:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:57:35 INFO - mozversion application_vendor: Mozilla 18:57:35 INFO - mozversion application_version: 49.0a1 18:57:35 INFO - mozversion platform_buildid: 20160510171011 18:57:35 INFO - mozversion platform_changeset: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef 18:57:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:57:35 INFO - mozversion platform_version: 49.0a1 18:57:35 INFO - using testdate: 1462931855 18:57:35 INFO - actual date: 1462931855 18:57:35 INFO - starting webserver on 'localhost:49278' 18:57:35 INFO - SUITE-START | Running 2 tests 18:57:35 INFO - TEST-START | dromaeo_css 18:57:35 INFO - operating with platform_type : mac_ 18:57:35 INFO - Initialising browser for dromaeo_css test... 18:57:35 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmprbhvJF/profile http://localhost:49278/getInfo.html) 18:57:37 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:57:37 INFO - PROCESS | 1657 | colorDepth:24 18:57:37 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:57:37 INFO - PROCESS | 1657 | __metrics 18:57:38 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:57:38 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:57:38 INFO - TEST-INFO | 1657: exit 0 18:57:38 INFO - Browser initialized. 18:57:38 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:38 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmprbhvJF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:50 INFO - PROCESS | 1659 | RSS: Main: 238862336 18:57:50 INFO - PROCESS | 1659 | 18:58:42 INFO - PROCESS | 1659 | 1462931922220 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] 19:00:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 19:00:54 INFO - PROCESS | 1659 | RSS: Main: 359628800 19:00:54 INFO - PROCESS | 1659 | 19:03:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 19:03:57 INFO - PROCESS | 1659 | RSS: Main: 369762304 19:03:57 INFO - PROCESS | 1659 | 19:07:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 19:07:00 INFO - PROCESS | 1659 | RSS: Main: 371175424 19:07:00 INFO - PROCESS | 1659 | 19:10:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 19:10:03 INFO - PROCESS | 1659 | RSS: Main: 372338688 19:10:03 INFO - PROCESS | 1659 | 19:13:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 19:13:07 INFO - PROCESS | 1659 | RSS: Main: 338571264 19:13:07 INFO - PROCESS | 1659 | 19:16:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 19:16:11 INFO - PROCESS | 1659 | RSS: Main: 416956416 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | __start_tp_report 19:16:11 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 19:16:11 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 19:16:11 INFO - PROCESS | 1659 | |i|pagename|runs| 19:16:11 INFO - PROCESS | 1659 | |0;dojo.html;2839.205022593673;3376.848041838117;3378.6452422495636;3424.0926154569042;3460.6712362325584;5488.780448782049;5497.615166938306;5526.50261476467;5552.194931734896;5597.9999999999945;5535.972320138404;5618.438156184379;5621.690807005623;5631.099024156136;5631.2960879890015;5064.290999260115;5112.463191364909;5128.1282182029145;5189.429162792108;5193.844184674465;5102.515261050213;5187.87030324243;5196.454372290912;5203.193505006711;5215.713135777531;5042.672226305297;5091.592672586185;5110.233464980245;5115.309433226523;5198.45416231296;5160.7419629018395;5188.432249667131;5200.141976573861;5203.791848326063;5284.524392804666;4953.405591329064;4978.9544195718945;5012.523810238022;5096.898062038776;5133.743312834381;5380.757865896044;5426.565874730012;5438.83683489496;5441.945580544183;5447.618666693333;6043.576949613529;6083.148359229712;6099.913058250938;6110.229009153409;6111.052786818006;6091.664958427285;6125.8360911427135;6166.075088736726;6172.598781079216;6200.503959683215;5619.915701264485;5635.0702134147405;5674.97162514193;5707.514861236758;5757.568182386338;5521.647196436818;5880.794172203952;6231.470325022333;6262.029385445263;6263.686815659199;5880.94119058804;6058.4111801797135;6076.655052264809;6077.957056285658;6104.969475152596;6054.969725151346;6061.7575296987625;6078.057901025348;6099.09793686549;6110.725017374229;6064.302605200371;6077.209962704908;6087.878242435127;6101.4290607115445;6127.050307202391;6055.757769689163;6093.482054025458;6096.542759292982;6118.357572454917;6133.539984501181;4665.486796452387;4747.976260118747;4770.308305295713;4778.5699287063635;4836.685615434985;4337.392765012901;4346.261135606891;4354.869353919387;4356.716813407118;4377.868663940087;5566.332040155207;5569.053260945568;5597.076482380362;5599.1041433370465;5600.467955544217;6020.488258497989;6082.574219804571;6090.725917333731;6103.23709536308;6168.290646575611;5466.590005749585;5489.176623506506;5496.422875598085;5497;5502.889942201134;5153.716545589954;5168.405633352138;5174.327337446108;5191.5846732261325;5201.219817027476;5128.3845938486875;6177.84555386119;6220.782272620437;6223.3465486123305;6260.655663938527;6174.25908890936;6197.287311959182;6203.875922481435;6205.906911396242;6206.4724498416335;6200.976838821544;6258.12386265914;6267.655278959701;6275.403836635513;6322.178116844781;5867;5951.672658003851;5957.4042595740075;6049.576529642883;6051.576389652682;6109.252844142575;6115.388461153849;6132.325444201224;6135.386461353829;6163.013917773134;5924.911126333022;5930.7627694893445;5939.851503712442;5945.048792193228;5957.582969192288;5736.598438109292;5778.56660750437;5840.240768700041;5853.1805547222575;5894.263217097863;4825.758712064453;5749.8275051748515;5862.618929769551;5863.325717542367;5882.205902203148;5796.797112101056;5946.702664866826;5987.982043052779;6038.5772995889865;6051.818445446644;5677.290338707661;5735.541156707537;5796.594238403271;5808.767649293981;5832.270966129234 19:16:11 INFO - PROCESS | 1659 | |1;ext.html;487.782936593216;508.96437249392534;540.1816248383699;543.2720154992311;573.810642487979;28696.282592935186;43965.560344396545;44897.10205795886;45013.77493112538;45106.872328191814;42358.36462453066;42651.57348426515;42720.57279427205;42839.78580107095;43014.13971720564;8516.659333626647;9612.375195612298;9796.510174491283;9798.90201097987;9807.215422766161;8694.39139260252;11560.826587601192;11779.17545771792;11796.410179491035;11890.286582805016;11750.236234644763;11784.292942423439;11822.467988940476;11825.994790442823;11826.994705450046;8633.877595912554;8835.690750823755;8843.071477494836;11614.767704645901;11701.7659646807;8634.179752923463;11413;11729.354885481294;11765.882341176564;11854.762904741858;35253.647463525296;41211.793941030104;41758.37362439566;41933;41933;2962.4371369439955;3492.4062909305226;3536.6109727929907;3709.4992176056157;4056.1190427915026;28859;29290.267743306376;30244.54633180504;30249.395012099856;30370.240743981358;2665.200439868032;2696.2046196372116;2750.587411888233;2822.957655635167;2842.6588809342807;449853;452359;453075;453872.9999999967;453923;62794.54629995303;63116.68441657762;63392;63422;63494.68252658707;66781.33218667848;66942;66950;66964.33035669678;67027.66486167538;16971.06659133735;17255.74116388255;17262.741058884127;17262.913685431493;17290.91354543219;12187.964023058139;12390;13139.408726607135;13170;13413.463461461628;15407.922960385351;18454.98497582619;18553.907230463763;18589;18755;5427.592930530226;6445.375764310764;6445.6454894981225;6469.5794773339585;6480.51396145291;941.3081385181886;990.6186118344489;1017.3234798858824;1047.4867315015588;1058.1534772182224;24257.878710606336;29324;29534.409311813644;29974;30178.69821301803;1113.9944300278446;1116.7208197950513;1129.88701129888;1206.2641788509002;1234.0929416878587;14019;15219.619509512351;15258.542243732705;15579.814706087242;15657;597626;599201;603110;605851;606027;59983;60526;60544;60565.69717151386;60687.78624427487;1305.3342795174528;1355.8305211848717;1431.2485944879022;1454.992725036368;1467.6404280951235;26002.34994125162;29725.256868578457;29927.401451970403;30695.539566906962;30761.077167685005;53532.46467535275;53575.73212133914;54065.18902216391;54229.45770542244;54356;436038;437231;437619;437659;438315;27104.1868743938;27427.451450971268;27754.86122569374;28608.427831442837;41021.58978410178;46018.769906151596;46581.76709116433;46693.066138677714;47564;48073.03853922972;37293.4405983905;38657.613423865405;38760;39288.6071139285;39992;28547.857260713565;30480;35254;36536.45195322019;37085;36048.81975590105;36442.27115457623;37142;37168.44247336346;37396.43905341368;27280.590791138548;31395.05814825559;31801.68198317987;31941;31963.520547191347;23616.645750314103;23714.525709486057;23994.52010959736;24058.27825165248;24134.51730965406 19:16:11 INFO - PROCESS | 1659 | |2;jquery.html;391.66512631699896;392.42509723255426;397.0767964482579;401.06151605243724;415.80872798512684;20320.983950802474;20370.287039953604;21329.82685952272;21702.674459883114;21723.67414488784;20280.29018984336;20484.795152048475;21450.14199432025;21774.782252177472;21835.235766748166;5643.971780141103;5950.821475355725;5964.359801054723;5966.582339236255;5973.193618861464;5680.204771332017;5897.380775018625;5937.524998000171;5995.6702381369005;6031.396860313956;6013.6993150342305;6019.909701354483;6027.276726792791;6043.335233124375;6053.606515576474;5887.676177810219;5943.762249510014;6015.9398406016035;6036.3360030396625;6036.909446358308;5596.916046259309;5800.129980502916;5807.941920580824;5823.272090988627;5823.883522329572;13684.93157534216;14038;18034.27862885483;24497.652629105392;26268.605970910452;1879.577095153593;1925.2010415677478;2282.577723121228;2530.417479284433;2779.2357101796965;9811.607535698564;9899.25755568328;10144.79710405795;10283.640072597424;10396.064354208082;1664.750287456891;1696.1264948551507;1735.8871673341193;1744.1976690722283;1783.3134243316288;81637.59181204055;100083.9991600082;105442.94557054485;119661.40169299184;128416.3579182098;10860.294080884718;11447.484863181176;11492.597759078391;11709.414529273503;11715.941420292844;14071.648208794863;14119.294035298193;14941.775873361908;14952.850471495145;15250.694986100216;7396.704131834774;8328.45865018784;8661.30709543235;8717.607707653171;8870.689525866565;6833.555818871857;8686.826263474695;8747.825043499095;8762.299016078698;8837.204651581389;4619.607333376701;4685.484596694361;4701.636525407594;4744.857654270378;4757.381540399726;1889.969966368305;1973.082516629774;1984.6427643024106;1986.1558837494297;2002.9699554506694;857.7898414888267;935.8502639577634;952.8189643967625;953.1183655118987;1038.1487180512058;20753.962301884847;21296.467588310417;21557.137714491244;21623.13507459684;21715.457113572287;890.7594949363765;940.2947789158131;969.0406497567358;986.7335819328741;998.2962011781676;11024.559017639365;11377;11385.601503947488;11393.259438136494;11404.315741055563;598282;602402;606842.5894446347;611429.8857011372;615006;73563.63218183875;77034;112896;133447.3327633375;135585;995.9651412200683;996.2030375699411;1044.691815914292;1046.706922061824;1050.5850189175233;15816.920915395349;16045.518634440985;18810.811891880905;19032.714509282094;19140.425787226406;39244.60755392409;53087;54746;54918;55183.44816551783;80228.59885700535;80865;82045;82061;82781.58609206915;8070.596470176585;8212.384071194565;8232.835343292982;8459.027211870467;8570.271526920282;9274.887013558417;12016.158868879094;12556.37218139071;12877.935610322264;13043.217406955613;6393.104965304954;7957.801054973672;10930.01629853317;11176.385298808644;11219.158563107963;10944.124470042218;11171.66485005451;11187.384693841917;11236.831447528457;11329.943350283196;9260.629574816932;11276.492557835081;11646.359450230319;11744.29534227949;11811.409429528661;8082.757517274491;8193.057798353266;8361.414700971118;8386.287165590993;8387.203215694499;10278.794424111626;10863.293885897392;11030.834537482106;11284.43577821122;11435.828462572901 19:16:11 INFO - PROCESS | 1659 | |3;mootools.html;1343.7984302354653;1586.3099551695013;1630.0138416258164;1855.1744473709196;1985.3150663021252;7844.568548729818;8646.524441155734;8878.890138732666;8889.111088891108;8934.419262747933;8548.658053677846;8761.51811650359;8780.253678437324;8783.429077109984;8787.692430764924;2156.3423155937435;2220.2340192633596;2223.9110435582556;2437.159180082873;2472.443700167466;1987.33424302858;2236.3514580771557;2267.4218074391006;2485.689288838895;2490.5392502387026;2422.1159276863905;2457.9016839326405;2461.8030557555444;2462.507498500298;2465.3220364399754;2408.2654790288957;2411.8191135664806;2412.7949124324455;2414.5412371649504;2415.2029830156007;2143.7963393477594;2405.9398515037087;2481.565725997943;2483.3543278747657;2483.807772269303;8779.429337093068;8859.29125669945;8868.157525035176;8875.911240887574;8884.911150888473;5331.920021199685;5454.836354909359;5475.6440831345835;5480.7533660985355;5489;3531.770434921722;3604.333198358312;3637.6907962823193;3667.0832291927018;3668.174660701321;994.6767300627253;1057.7038429239826;1059.7244716373798;1061.3897009219652;1067.0556557446716;11946.880531194576;11947.342896140708;11966.760664786656;11975.341356225405;11989.280643161439;4818.927716084261;4848.369711937426;4858.5384388483035;4963.230699241623;4994.500549944976;8876.51179185151;10140.9859014098;10148;10163.695089147337;10167.28828981979;5524.613277070647;5526.447355264522;5530.529904958124;5534.778608855681;5538.196961440649;4096.877093687194;4560.407147070926;4817.084753896818;5012.097822391932;5018.422881368616;3575.66031227033;3855.9421608675893;3932.842686292516;3950.861719839792;3965.5836137205756;1032.390889375272;1221.6884694403047;1230.4094034863137;1231.359692959656;1236.1285293867802;771.7568965775763;820.7045463633207;915.6932427636683;962.5812771444444;974.3861367338673;5781.103928891029;5796.681182534917;5982.641041537521;6047.818565443044;6058.515318774487;870.9303255739526;969.1665167955553;976.4531862157214;987.3039507147444;994.6568433890296;3886.358750806142;3970.7021973352116;3984.7011474139554;4022.416749571296;4043.454133691973;8824.073472285447;10446.164306855433;11105.722356941142;11108.389038602954;11111.72220694489;9041.954790226138;11031.944840275748;11037.392943388191;11044.950729680668;11087.334759914269;8118.228768267005;8249.381296402793;8324.334053275845;8408.83182336362;8435.662573497115;4828.734419606955;5912.556558258062;5969.313528944053;6137.202163718866;6151.3541078186145;9702.805943881223;9804.705858824247;10213.285070045025;10215.336003159771;10280.485975701335;10019.499025048864;10266.384016959006;10327.535260913126;10388.792224155626;10418.114460270961;7021.613811240431;7157.252030249034;7603.543787372775;7782.766517004499;7787.727429539939;7560.092788865371;8055.879161812461;8555.973283205805;8592.140785921358;8611.009733880433;7472.327490525661;7486.026816513819;7502.624868756649;7530.4352173588295;7634.503757255983;7414.888776668462;7431.108267007994;7461.179270280373;7568.4323675723435;7583.924160758322;5883.352831188651;7197.424206063607;7197.532160409557;7199.316064973819;7204.135503739585;4708.858734237979;4792.616590672807;4847.636427268039;4904.166291730486;5012.122878496176;4722.5513576210205;4947.1342515059005;4977.303177555217;5025.673331233458;5183.740812959412 19:16:11 INFO - PROCESS | 1659 | |4;prototype.html;307.77071082043875;316.91918560767016;321.9790713603616;323.13400087764796;327.22937482229366;4632.837850675227;5306.522412982836;5309.548688361489;5328.280682107914;5337.492938170868;4193.846692159657;4409.228385032622;4514.819407223716;5195;5273.499017593323;3196.6643502432203;3931.19410520844;3958.990457433365;4077.429159917614;4113.56807535209;3399.456087026076;4002.4784385836883;4063;4070.5115386153702;4122.587741225883;3412.8293585320757;4050.9594904050878;4058.7767672777986;4061.8375264989363;4090.1206240658253;3574.535310409656;3810.7141964352786;3865.4781604483455;3955.46601208836;3956.7625942443547;3510.350585141757;3520.8591656333715;3520.9471857922154;3532.8410221539834;3540.5220295260324;4740.312654665088;5033.924491132636;5038.697678139323;5077.746112694387;5152.407473140599;3912.366192682528;4046.7167298289132;4118.608732170439;4471.6646251531265;4648.094940662087;4864.635152363551;5505.614606977513;5517.834464966058;5528.612997090206;5632.7746890124345;3740.9625903740884;3789.621037896216;3895;3908.8436462541463;3935.409688546741;5490.929268792587;5806.535477161817;5816.76054718932;5843.883122337572;5873.383294754075;3353.782004169721;3386.4243078676686;3393.372226138172;3958.3072962231495;3969.285528604879;3313.552670389515;3382.3595556155246;3997.720159588801;4026.3356546169553;4076.5515793262716;4556.691651998521;4635.844332385457;4655.743934083658;4680.15757163707;4695.671303008757;4471.664625153061;4483.256480838471;5558.44415558449;5558.471945165284;5566.721663916869;1336.3919416665394;1375.7549417776927;1376.1880490510646;1377.3251106957518;1378.9931050344685;815.2458975447687;844.9028361738356;859.5229647545547;866.644675682967;874.54960695242;4765.451973023147;4916.3362946001835;5070.340855688737;5119.59043276537;5158.948410515922;4879.5120487951635;5200.985807767524;5586.664800111925;5607.831765047055;5623.662580245198;4763.261694437368;4940.542539950723;5199.896002079937;5207.661502002357;5251.159814429673;2533.987330063375;2680.5979103134296;2987.8954236601617;3012.638483382008;3013.653429855595;5337.866553336197;5969;5973.84941421088;5998.561783388567;6003.609765365237;5199.454057324002;5393.164059570694;5552.305961754781;5814.651120932757;5934.1692163097205;5794.362620111784;6022.427869352405;6033.818985430356;6055.909936211524;6064.605140817548;4986.426560945392;5315.627906046694;5609.3722678789345;5812.505936995402;5816.621919575383;4693.671442999094;5617.719114044363;5723.399043100415;5724;5743.741531631003;5588.636738611977;6007.339192688888;6037.245344331958;6056.697165141637;6089.939100608937;3248.5289633003554;3816.7709937403843;4045.453863728359;4060.7563546187325;4067.328890732996;5242.370915490165;5552.167174923794;5616.971915140398;5737.225474560881;5742.856428589319;4887.584555312696;5473.096939005179;5603.831885043455;5624.971875140598;5627.662340259598;5028.396592408934;5213.504717051874;5217.973575326378;5548.223248745098;5557.888842223214;4793.832215872428;5443.584667986432;5452.164874265281;5456.099743542271;5488.890222195454;4690.319903614025;5499.395066542597;5575.020867591947;5594.664320140804;5666.631668941671;4808.148862687821;5464.644798088111;5492.231087647812;5495.230667706604;5517.44825517445 19:16:11 INFO - PROCESS | 1659 | |5;yui.html;649.9317571654979;651.511366475144;666.8132922781622;742.0427648333648;744.3263846219173;14947.850521494782;15126;15382;15413.229338533056;16199.595010124753;15686.686266274668;16081;16086.919565402157;16114.355425782955;16137.91931040346;727.3453891497642;753.107567532473;918.3112665500857;934.2899396458706;935.6351023100996;908.4594666173616;939.0656296984475;941.3034354577597;942.3074040580202;942.4251206763869;951.4338968313876;955.775392782699;957.6696039866235;958.5255298627189;963.720521048895;763.0652450747845;942.524025367192;949.7245798718363;958;971.868797712314;722.443718336883;725.4776560876161;726.3753172271842;726.6548389514991;726.7855982485127;14682.632934176625;14750.70498590033;14781.482648107372;14781.778273325908;14809.185494797783;11877.524899004029;11979.341136237503;12059.236601542429;12191.268523888595;12204.755904881942;10629.574817007311;10634;11148.721281967935;11266.493007814668;11405.030572401356;4023.6177563131455;4028.2144981728575;4060.898477538085;4155.729877557949;4265.402843601898;77470.22529774743;79281.60359198166;79632;79802.00494987614;80133.19866801315;8874.201321880933;9086.227670647937;9147;9439.528023598792;9442.95278523603;18717.344892928682;18761.530961726065;18763.624727505372;18775.624487510173;20085.196592136443;5859.560532959959;7545.245475452411;7594;7667.961660191663;7792.376609871197;4049.7367671101283;4074.930964997376;4077.041895154636;4162.646175075092;4164;3558.768680035841;3652.7990960497427;3658.140337020798;3665.5601327840295;3669.9633003670156;647.5758378262245;668.8327918020495;669.2771806449024;669.6350488983516;694.2744831650937;569.4647031790104;585.9326177489557;593.890130325891;602.0577795749638;621.8507558186093;7153.6780844862105;7329.973803667491;7403.888941665879;7711.53730776144;7715.57564333967;555.9054960656698;575.114323941126;601.9879602408015;609.8201030695956;622.5175488996065;3671.430928206132;3698.223373091627;3740.345439548068;3802.4774618413594;3849.210911763138;75583.62208188923;80800.59599701964;82656;82974;83057;65105.34894651087;66647;71341;72390.2760972394;73304;63071.68464157742;63319.73360532764;63830;64680;64703.67648161729;8083.7170699025255;8202.179782021989;8284.668613255644;8317.95841020791;8440.198181172778;29375.265618359714;37181.81409092937;39251.41122883102;40178.799106004284;40910.1817963645;52798.47201527935;56078;56976.71511642581;57317.42682573288;57320.42679573151;10226.6420675276;10535.209859260432;10621.203409744145;10724.678259652223;10818.67543973682;7634.847303054019;9299.67451139207;9534.046595340411;11700.765984680089;12162.391880406121;10949.452229552151;11182.105431565293;11307.095432365226;11525.366104863977;11691;11124.332540047622;11620;11624.360660163773;11625.418729063682;11731.941340293586;10917.56329746833;11089.445527723421;11141.665750027189;11266.850376107908;11393.886061139283;7741.264579864904;9257.21313688344;9364.859527107235;9457.716268511957;9529.285303602119;8805.779855503664;9280.118388753057;9345.672901448417;9395.248380129437;9449.527523623929 19:16:11 INFO - PROCESS | 1659 | __end_tp_report 19:16:11 INFO - PROCESS | 1659 | __start_cc_report 19:16:11 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1231 19:16:11 INFO - PROCESS | 1659 | __end_cc_report 19:16:11 INFO - PROCESS | 1659 | __startTimestamp1462932971068__endTimestamp 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | ------- Summary: start ------- 19:16:11 INFO - PROCESS | 1659 | Number of tests: 6 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5604.99 Median:5778.57 stddev:630.80 (10.9%) stddev-sans-first:594.25 19:16:11 INFO - PROCESS | 1659 | Values: 2839.2 3376.8 3378.6 3424.1 3460.7 5488.8 5497.6 5526.5 5552.2 5598.0 5536.0 5618.4 5621.7 5631.1 5631.3 5064.3 5112.5 5128.1 5189.4 5193.8 5102.5 5187.9 5196.5 5203.2 5215.7 5042.7 5091.6 5110.2 5115.3 5198.5 5160.7 5188.4 5200.1 5203.8 5284.5 4953.4 4979.0 5012.5 5096.9 5133.7 5380.8 5426.6 5438.8 5441.9 5447.6 6043.6 6083.1 6099.9 6110.2 6111.1 6091.7 6125.8 6166.1 6172.6 6200.5 5619.9 5635.1 5675.0 5707.5 5757.6 5521.6 5880.8 6231.5 6262.0 6263.7 5880.9 6058.4 6076.7 6078.0 6105.0 6055.0 6061.8 6078.1 6099.1 6110.7 6064.3 6077.2 6087.9 6101.4 6127.1 6055.8 6093.5 6096.5 6118.4 6133.5 4665.5 4748.0 4770.3 4778.6 4836.7 4337.4 4346.3 4354.9 4356.7 4377.9 5566.3 5569.1 5597.1 5599.1 5600.5 6020.5 6082.6 6090.7 6103.2 6168.3 5466.6 5489.2 5496.4 5497.0 5502.9 5153.7 5168.4 5174.3 5191.6 5201.2 5128.4 6177.8 6220.8 6223.3 6260.7 6174.3 6197.3 6203.9 6205.9 6206.5 6201.0 6258.1 6267.7 6275.4 6322.2 5867.0 5951.7 5957.4 6049.6 6051.6 6109.3 6115.4 6132.3 6135.4 6163.0 5924.9 5930.8 5939.9 5945.0 5957.6 5736.6 5778.6 5840.2 5853.2 5894.3 4825.8 5749.8 5862.6 5863.3 5882.2 5796.8 5946.7 5988.0 6038.6 6051.8 5677.3 5735.5 5796.6 5808.8 5832.3 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:64559.75 Median:36245.55 stddev:133932.57 (369.5%) stddev-sans-first:134221.89 19:16:11 INFO - PROCESS | 1659 | Values: 487.8 509.0 540.2 543.3 573.8 28696.3 43965.6 44897.1 45013.8 45106.9 42358.4 42651.6 42720.6 42839.8 43014.1 8516.7 9612.4 9796.5 9798.9 9807.2 8694.4 11560.8 11779.2 11796.4 11890.3 11750.2 11784.3 11822.5 11826.0 11827.0 8633.9 8835.7 8843.1 11614.8 11701.8 8634.2 11413.0 11729.4 11765.9 11854.8 35253.6 41211.8 41758.4 41933.0 41933.0 2962.4 3492.4 3536.6 3709.5 4056.1 28859.0 29290.3 30244.5 30249.4 30370.2 2665.2 2696.2 2750.6 2823.0 2842.7 449853.0 452359.0 453075.0 453873.0 453923.0 62794.5 63116.7 63392.0 63422.0 63494.7 66781.3 66942.0 66950.0 66964.3 67027.7 16971.1 17255.7 17262.7 17262.9 17290.9 12188.0 12390.0 13139.4 13170.0 13413.5 15407.9 18455.0 18553.9 18589.0 18755.0 5427.6 6445.4 6445.6 6469.6 6480.5 941.3 990.6 1017.3 1047.5 1058.2 24257.9 29324.0 29534.4 29974.0 30178.7 1114.0 1116.7 1129.9 1206.3 1234.1 14019.0 15219.6 15258.5 15579.8 15657.0 597626.0 599201.0 603110.0 605851.0 606027.0 59983.0 60526.0 60544.0 60565.7 60687.8 1305.3 1355.8 1431.2 1455.0 1467.6 26002.3 29725.3 29927.4 30695.5 30761.1 53532.5 53575.7 54065.2 54229.5 54356.0 436038.0 437231.0 437619.0 437659.0 438315.0 27104.2 27427.5 27754.9 28608.4 41021.6 46018.8 46581.8 46693.1 47564.0 48073.0 37293.4 38657.6 38760.0 39288.6 39992.0 28547.9 30480.0 35254.0 36536.5 37085.0 36048.8 36442.3 37142.0 37168.4 37396.4 27280.6 31395.1 31801.7 31941.0 31963.5 23616.6 23714.5 23994.5 24058.3 24134.5 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34260.56 Median:392.05 stddev:100674.48 (25679.3%) stddev-sans-first:100924.78 19:16:11 INFO - PROCESS | 1659 | Values: 391.7 392.4 397.1 401.1 415.8 20321.0 20370.3 21329.8 21702.7 21723.7 20280.3 20484.8 21450.1 21774.8 21835.2 5644.0 5950.8 5964.4 5966.6 5973.2 5680.2 5897.4 5937.5 5995.7 6031.4 6013.7 6019.9 6027.3 6043.3 6053.6 5887.7 5943.8 6015.9 6036.3 6036.9 5596.9 5800.1 5807.9 5823.3 5823.9 13684.9 14038.0 18034.3 24497.7 26268.6 1879.6 1925.2 2282.6 2530.4 2779.2 9811.6 9899.3 10144.8 10283.6 10396.1 1664.8 1696.1 1735.9 1744.2 1783.3 81637.6 100084.0 105442.9 119661.4 128416.4 10860.3 11447.5 11492.6 11709.4 11715.9 14071.6 14119.3 14941.8 14952.9 15250.7 7396.7 8328.5 8661.3 8717.6 8870.7 6833.6 8686.8 8747.8 8762.3 8837.2 4619.6 4685.5 4701.6 4744.9 4757.4 1890.0 1973.1 1984.6 1986.2 2003.0 857.8 935.9 952.8 953.1 1038.1 20754.0 21296.5 21557.1 21623.1 21715.5 890.8 940.3 969.0 986.7 998.3 11024.6 11377.0 11385.6 11393.3 11404.3 598282.0 602402.0 606842.6 611429.9 615006.0 73563.6 77034.0 112896.0 133447.3 135585.0 996.0 996.2 1044.7 1046.7 1050.6 15816.9 16045.5 18810.8 19032.7 19140.4 39244.6 53087.0 54746.0 54918.0 55183.4 80228.6 80865.0 82045.0 82061.0 82781.6 8070.6 8212.4 8232.8 8459.0 8570.3 9274.9 12016.2 12556.4 12877.9 13043.2 6393.1 7957.8 10930.0 11176.4 11219.2 10944.1 11171.7 11187.4 11236.8 11329.9 9260.6 11276.5 11646.4 11744.3 11811.4 8082.8 8193.1 8361.4 8386.3 8387.2 10278.8 10863.3 11030.8 11284.4 11435.8 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5744.77 Median:4925.65 stddev:3226.87 (65.5%) stddev-sans-first:3218.97 19:16:11 INFO - PROCESS | 1659 | Values: 1343.8 1586.3 1630.0 1855.2 1985.3 7844.6 8646.5 8878.9 8889.1 8934.4 8548.7 8761.5 8780.3 8783.4 8787.7 2156.3 2220.2 2223.9 2437.2 2472.4 1987.3 2236.4 2267.4 2485.7 2490.5 2422.1 2457.9 2461.8 2462.5 2465.3 2408.3 2411.8 2412.8 2414.5 2415.2 2143.8 2405.9 2481.6 2483.4 2483.8 8779.4 8859.3 8868.2 8875.9 8884.9 5331.9 5454.8 5475.6 5480.8 5489.0 3531.8 3604.3 3637.7 3667.1 3668.2 994.7 1057.7 1059.7 1061.4 1067.1 11946.9 11947.3 11966.8 11975.3 11989.3 4818.9 4848.4 4858.5 4963.2 4994.5 8876.5 10141.0 10148.0 10163.7 10167.3 5524.6 5526.4 5530.5 5534.8 5538.2 4096.9 4560.4 4817.1 5012.1 5018.4 3575.7 3855.9 3932.8 3950.9 3965.6 1032.4 1221.7 1230.4 1231.4 1236.1 771.8 820.7 915.7 962.6 974.4 5781.1 5796.7 5982.6 6047.8 6058.5 870.9 969.2 976.5 987.3 994.7 3886.4 3970.7 3984.7 4022.4 4043.5 8824.1 10446.2 11105.7 11108.4 11111.7 9042.0 11031.9 11037.4 11045.0 11087.3 8118.2 8249.4 8324.3 8408.8 8435.7 4828.7 5912.6 5969.3 6137.2 6151.4 9702.8 9804.7 10213.3 10215.3 10280.5 10019.5 10266.4 10327.5 10388.8 10418.1 7021.6 7157.3 7603.5 7782.8 7787.7 7560.1 8055.9 8556.0 8592.1 8611.0 7472.3 7486.0 7502.6 7530.4 7634.5 7414.9 7431.1 7461.2 7568.4 7583.9 5883.4 7197.4 7197.5 7199.3 7204.1 4708.9 4792.6 4847.6 4904.2 5012.1 4722.6 4947.1 4977.3 5025.7 5183.7 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4497.54 Median:5036.31 stddev:1395.42 (27.7%) stddev-sans-first:1363.44 19:16:11 INFO - PROCESS | 1659 | Values: 307.8 316.9 322.0 323.1 327.2 4632.8 5306.5 5309.5 5328.3 5337.5 4193.8 4409.2 4514.8 5195.0 5273.5 3196.7 3931.2 3959.0 4077.4 4113.6 3399.5 4002.5 4063.0 4070.5 4122.6 3412.8 4051.0 4058.8 4061.8 4090.1 3574.5 3810.7 3865.5 3955.5 3956.8 3510.4 3520.9 3520.9 3532.8 3540.5 4740.3 5033.9 5038.7 5077.7 5152.4 3912.4 4046.7 4118.6 4471.7 4648.1 4864.6 5505.6 5517.8 5528.6 5632.8 3741.0 3789.6 3895.0 3908.8 3935.4 5490.9 5806.5 5816.8 5843.9 5873.4 3353.8 3386.4 3393.4 3958.3 3969.3 3313.6 3382.4 3997.7 4026.3 4076.6 4556.7 4635.8 4655.7 4680.2 4695.7 4471.7 4483.3 5558.4 5558.5 5566.7 1336.4 1375.8 1376.2 1377.3 1379.0 815.2 844.9 859.5 866.6 874.5 4765.5 4916.3 5070.3 5119.6 5158.9 4879.5 5201.0 5586.7 5607.8 5623.7 4763.3 4940.5 5199.9 5207.7 5251.2 2534.0 2680.6 2987.9 3012.6 3013.7 5337.9 5969.0 5973.8 5998.6 6003.6 5199.5 5393.2 5552.3 5814.7 5934.2 5794.4 6022.4 6033.8 6055.9 6064.6 4986.4 5315.6 5609.4 5812.5 5816.6 4693.7 5617.7 5723.4 5724.0 5743.7 5588.6 6007.3 6037.2 6056.7 6089.9 3248.5 3816.8 4045.5 4060.8 4067.3 5242.4 5552.2 5617.0 5737.2 5742.9 4887.6 5473.1 5603.8 5625.0 5627.7 5028.4 5213.5 5218.0 5548.2 5557.9 4793.8 5443.6 5452.2 5456.1 5488.9 4690.3 5499.4 5575.0 5594.7 5666.6 4808.1 5464.6 5492.2 5495.2 5517.4 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:16764.08 Median:615.84 stddev:22859.25 (3711.9%) stddev-sans-first:22891.36 19:16:11 INFO - PROCESS | 1659 | Values: 649.9 651.5 666.8 742.0 744.3 14947.9 15126.0 15382.0 15413.2 16199.6 15686.7 16081.0 16086.9 16114.4 16137.9 727.3 753.1 918.3 934.3 935.6 908.5 939.1 941.3 942.3 942.4 951.4 955.8 957.7 958.5 963.7 763.1 942.5 949.7 958.0 971.9 722.4 725.5 726.4 726.7 726.8 14682.6 14750.7 14781.5 14781.8 14809.2 11877.5 11979.3 12059.2 12191.3 12204.8 10629.6 10634.0 11148.7 11266.5 11405.0 4023.6 4028.2 4060.9 4155.7 4265.4 77470.2 79281.6 79632.0 79802.0 80133.2 8874.2 9086.2 9147.0 9439.5 9443.0 18717.3 18761.5 18763.6 18775.6 20085.2 5859.6 7545.2 7594.0 7668.0 7792.4 4049.7 4074.9 4077.0 4162.6 4164.0 3558.8 3652.8 3658.1 3665.6 3670.0 647.6 668.8 669.3 669.6 694.3 569.5 585.9 593.9 602.1 621.9 7153.7 7330.0 7403.9 7711.5 7715.6 555.9 575.1 602.0 609.8 622.5 3671.4 3698.2 3740.3 3802.5 3849.2 75583.6 80800.6 82656.0 82974.0 83057.0 65105.3 66647.0 71341.0 72390.3 73304.0 63071.7 63319.7 63830.0 64680.0 64703.7 8083.7 8202.2 8284.7 8318.0 8440.2 29375.3 37181.8 39251.4 40178.8 40910.2 52798.5 56078.0 56976.7 57317.4 57320.4 10226.6 10535.2 10621.2 10724.7 10818.7 7634.8 9299.7 9534.0 11700.8 12162.4 10949.5 11182.1 11307.1 11525.4 11691.0 11124.3 11620.0 11624.4 11625.4 11731.9 10917.6 11089.4 11141.7 11266.9 11393.9 7741.3 9257.2 9364.9 9457.7 9529.3 8805.8 9280.1 9345.7 9395.2 9449.5 19:16:11 INFO - PROCESS | 1659 | -------- Summary: end -------- 19:16:11 INFO - PROCESS | 1659 | 19:16:11 INFO - TEST-INFO | 1659: exit 0 19:16:11 INFO - TEST-OK | dromaeo_css | took 1115978ms 19:16:11 INFO - TEST-START | kraken 19:16:11 INFO - operating with platform_type : mac_ 19:16:11 INFO - Initialising browser for kraken test... 19:16:11 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp8NPdYn/profile http://localhost:49278/getInfo.html) 19:16:13 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 19:16:13 INFO - PROCESS | 1669 | colorDepth:24 19:16:13 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 19:16:13 INFO - PROCESS | 1669 | __metrics 19:16:14 INFO - PROCESS | 1669 | 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" 19:16:14 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:16:14 INFO - TEST-INFO | 1669: exit 0 19:16:14 INFO - Browser initialized. 19:16:14 INFO - Running cycle 1/1 for kraken test... 19:16:14 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp8NPdYn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:26 INFO - PROCESS | 1671 | RSS: Main: 238972928 19:16:26 INFO - PROCESS | 1671 | 19:16:29 INFO - PROCESS | 1671 | 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) 19:16:30 INFO - PROCESS | 1671 | RSS: Main: 286748672 19:16:30 INFO - PROCESS | 1671 | 19:16:33 INFO - PROCESS | 1671 | 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) 19:16:33 INFO - PROCESS | 1671 | RSS: Main: 296005632 19:16:33 INFO - PROCESS | 1671 | 19:16:37 INFO - PROCESS | 1671 | 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) 19:16:38 INFO - PROCESS | 1671 | RSS: Main: 385449984 19:16:38 INFO - PROCESS | 1671 | 19:16:40 INFO - PROCESS | 1671 | 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) 19:16:40 INFO - PROCESS | 1671 | RSS: Main: 283213824 19:16:40 INFO - PROCESS | 1671 | 19:16:43 INFO - PROCESS | 1671 | 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) 19:16:43 INFO - PROCESS | 1671 | RSS: Main: 286887936 19:16:43 INFO - PROCESS | 1671 | 19:16:46 INFO - PROCESS | 1671 | 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) 19:16:47 INFO - PROCESS | 1671 | RSS: Main: 326418432 19:16:47 INFO - PROCESS | 1671 | 19:16:50 INFO - PROCESS | 1671 | 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) 19:16:51 INFO - PROCESS | 1671 | RSS: Main: 328056832 19:16:51 INFO - PROCESS | 1671 | 19:16:54 INFO - PROCESS | 1671 | 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) 19:16:54 INFO - PROCESS | 1671 | RSS: Main: 325976064 19:16:54 INFO - PROCESS | 1671 | 19:16:56 INFO - PROCESS | 1671 | 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) 19:16:57 INFO - PROCESS | 1671 | RSS: Main: 315764736 19:16:57 INFO - PROCESS | 1671 | 19:16:58 INFO - PROCESS | 1671 | 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) 19:16:59 INFO - PROCESS | 1671 | RSS: Main: 321327104 19:16:59 INFO - PROCESS | 1671 | 19:17:01 INFO - PROCESS | 1671 | 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) 19:17:02 INFO - PROCESS | 1671 | RSS: Main: 324382720 19:17:02 INFO - PROCESS | 1671 | 19:17:05 INFO - PROCESS | 1671 | 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) 19:17:06 INFO - PROCESS | 1671 | RSS: Main: 329322496 19:17:06 INFO - PROCESS | 1671 | 19:17:09 INFO - PROCESS | 1671 | 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) 19:17:09 INFO - PROCESS | 1671 | RSS: Main: 326893568 19:17:09 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | 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) 19:17:11 INFO - PROCESS | 1671 | RSS: Main: 373624832 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | __start_tp_report 19:17:11 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 19:17:11 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 19:17:11 INFO - PROCESS | 1671 | |i|pagename|runs| 19:17:11 INFO - PROCESS | 1671 | |0;ai-astar;112;107;111;112;112;113;112;112;113;111 19:17:11 INFO - PROCESS | 1671 | |1;audio-beat-detection;177;140;147;196;120;143;139;117;171;118 19:17:11 INFO - PROCESS | 1671 | |2;audio-dft;188;186;186;187;187;185;185;184;186;180 19:17:11 INFO - PROCESS | 1671 | |3;audio-fft;80;75;74;164;82;62;57;49;113;65 19:17:11 INFO - PROCESS | 1671 | |4;audio-oscillator;94;98;132;79;81;78;102;80;79;102 19:17:11 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;104;84;87;84;87;84;86;84;87;91 19:17:11 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;94;93;93;93;93;92;92;94 19:17:11 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;83;84;84;85;84;85;83;86;88 19:17:11 INFO - PROCESS | 1671 | |8;json-parse-financial;57;59;58;60;58;58;58;121;57;56 19:17:11 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;71;67;64;74;70;67;67;68;68;68 19:17:11 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;86;84;84;85;84;82;97;104;106 19:17:11 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;135;139;140;136;138;139;138;129;228;151 19:17:11 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;167;168;169;167;171;166;172;168;165;167 19:17:11 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;67;68;68;65;66;65;67;65 19:17:11 INFO - PROCESS | 1671 | __end_tp_report 19:17:11 INFO - PROCESS | 1671 | __start_cc_report 19:17:11 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2281 19:17:11 INFO - PROCESS | 1671 | __end_cc_report 19:17:11 INFO - PROCESS | 1671 | __startTimestamp1462933031797__endTimestamp 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | ------- Summary: start ------- 19:17:11 INFO - PROCESS | 1671 | Number of tests: 14 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.72 (1.5%) stddev-sans-first:1.81 19:17:11 INFO - PROCESS | 1671 | Values: 112.0 107.0 111.0 112.0 112.0 113.0 112.0 112.0 113.0 111.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:146.80 Median:145.00 stddev:26.82 (18.5%) stddev-sans-first:26.13 19:17:11 INFO - PROCESS | 1671 | Values: 177.0 140.0 147.0 196.0 120.0 143.0 139.0 117.0 171.0 118.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:2.22 (1.2%) stddev-sans-first:2.15 19:17:11 INFO - PROCESS | 1671 | Values: 188.0 186.0 186.0 187.0 187.0 185.0 185.0 184.0 186.0 180.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:82.10 Median:69.50 stddev:33.67 (48.5%) stddev-sans-first:35.71 19:17:11 INFO - PROCESS | 1671 | Values: 80.0 75.0 74.0 164.0 82.0 62.0 57.0 49.0 113.0 65.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:79.50 stddev:17.12 (21.5%) stddev-sans-first:18.15 19:17:11 INFO - PROCESS | 1671 | Values: 94.0 98.0 132.0 79.0 81.0 78.0 102.0 80.0 79.0 102.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:86.50 stddev:6.11 (7.1%) stddev-sans-first:2.35 19:17:11 INFO - PROCESS | 1671 | Values: 104.0 84.0 87.0 84.0 87.0 84.0 86.0 84.0 87.0 91.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:17:11 INFO - PROCESS | 1671 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 93.0 92.0 92.0 94.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.49 (1.8%) stddev-sans-first:1.58 19:17:11 INFO - PROCESS | 1671 | Values: 85.0 83.0 84.0 84.0 85.0 84.0 85.0 83.0 86.0 88.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:58.00 stddev:19.99 (34.5%) stddev-sans-first:21.03 19:17:11 INFO - PROCESS | 1671 | Values: 57.0 59.0 58.0 60.0 58.0 58.0 58.0 121.0 57.0 56.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:2.72 (4.0%) stddev-sans-first:2.71 19:17:11 INFO - PROCESS | 1671 | Values: 71.0 67.0 64.0 74.0 70.0 67.0 67.0 68.0 68.0 68.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:84.00 stddev:9.12 (10.9%) stddev-sans-first:9.44 19:17:11 INFO - PROCESS | 1671 | Values: 84.0 86.0 84.0 84.0 85.0 84.0 82.0 97.0 104.0 106.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:139.00 stddev:28.88 (20.8%) stddev-sans-first:30.28 19:17:11 INFO - PROCESS | 1671 | Values: 135.0 139.0 140.0 136.0 138.0 139.0 138.0 129.0 228.0 151.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:2.16 (1.3%) stddev-sans-first:2.26 19:17:11 INFO - PROCESS | 1671 | Values: 167.0 168.0 169.0 167.0 171.0 166.0 172.0 168.0 165.0 167.0 19:17:11 INFO - PROCESS | 1671 | 19:17:11 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.24 19:17:11 INFO - PROCESS | 1671 | Values: 68.0 67.0 67.0 68.0 68.0 65.0 66.0 65.0 67.0 65.0 19:17:11 INFO - PROCESS | 1671 | -------- Summary: end -------- 19:17:11 INFO - PROCESS | 1671 | 19:17:12 INFO - TEST-INFO | 1671: exit 0 19:17:12 INFO - TEST-OK | kraken | took 60987ms 19:17:12 INFO - SUITE-END | took 1176s 19:17:12 INFO - Completed test suite (00:19:37) 19:17:12 INFO - javascript benchmark 19:17:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2839.205022593673, 3376.848041838117, 3378.6452422495636, 3424.0926154569042, 3460.6712362325584, 5488.780448782049, 5497.615166938306, 5526.50261476467, 5552.194931734896, 5597.9999999999945, 5535.972320138404, 5618.438156184379, 5621.690807005623, 5631.099024156136, 5631.2960879890015, 5064.290999260115, 5112.463191364909, 5128.1282182029145, 5189.429162792108, 5193.844184674465, 5102.515261050213, 5187.87030324243, 5196.454372290912, 5203.193505006711, 5215.713135777531, 5042.672226305297, 5091.592672586185, 5110.233464980245, 5115.309433226523, 5198.45416231296, 5160.7419629018395, 5188.432249667131, 5200.141976573861, 5203.791848326063, 5284.524392804666, 4953.405591329064, 4978.9544195718945, 5012.523810238022, 5096.898062038776, 5133.743312834381, 5380.757865896044, 5426.565874730012, 5438.83683489496, 5441.945580544183, 5447.618666693333, 6043.576949613529, 6083.148359229712, 6099.913058250938, 6110.229009153409, 6111.052786818006, 6091.664958427285, 6125.8360911427135, 6166.075088736726, 6172.598781079216, 6200.503959683215, 5619.915701264485, 5635.0702134147405, 5674.97162514193, 5707.514861236758, 5757.568182386338, 5521.647196436818, 5880.794172203952, 6231.470325022333, 6262.029385445263, 6263.686815659199, 5880.94119058804, 6058.4111801797135, 6076.655052264809, 6077.957056285658, 6104.969475152596, 6054.969725151346, 6061.7575296987625, 6078.057901025348, 6099.09793686549, 6110.725017374229, 6064.302605200371, 6077.209962704908, 6087.878242435127, 6101.4290607115445, 6127.050307202391, 6055.757769689163, 6093.482054025458, 6096.542759292982, 6118.357572454917, 6133.539984501181, 4665.486796452387, 4747.976260118747, 4770.308305295713, 4778.5699287063635, 4836.685615434985, 4337.392765012901, 4346.261135606891, 4354.869353919387, 4356.716813407118, 4377.868663940087, 5566.332040155207, 5569.053260945568, 5597.076482380362, 5599.1041433370465, 5600.467955544217, 6020.488258497989, 6082.574219804571, 6090.725917333731, 6103.23709536308, 6168.290646575611, 5466.590005749585, 5489.176623506506, 5496.422875598085, 5497.0, 5502.889942201134, 5153.716545589954, 5168.405633352138, 5174.327337446108, 5191.5846732261325, 5201.219817027476, 5128.3845938486875, 6177.84555386119, 6220.782272620437, 6223.3465486123305, 6260.655663938527, 6174.25908890936, 6197.287311959182, 6203.875922481435, 6205.906911396242, 6206.4724498416335, 6200.976838821544, 6258.12386265914, 6267.655278959701, 6275.403836635513, 6322.178116844781, 5867.0, 5951.672658003851, 5957.4042595740075, 6049.576529642883, 6051.576389652682, 6109.252844142575, 6115.388461153849, 6132.325444201224, 6135.386461353829, 6163.013917773134, 5924.911126333022, 5930.7627694893445, 5939.851503712442, 5945.048792193228, 5957.582969192288, 5736.598438109292, 5778.56660750437, 5840.240768700041, 5853.1805547222575, 5894.263217097863, 4825.758712064453, 5749.8275051748515, 5862.618929769551, 5863.325717542367, 5882.205902203148, 5796.797112101056, 5946.702664866826, 5987.982043052779, 6038.5772995889865, 6051.818445446644, 5677.290338707661, 5735.541156707537, 5796.594238403271, 5808.767649293981, 5832.270966129234], "value": 5564.898799396254, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.782936593216, 508.96437249392534, 540.1816248383699, 543.2720154992311, 573.810642487979, 28696.282592935186, 43965.560344396545, 44897.10205795886, 45013.77493112538, 45106.872328191814, 42358.36462453066, 42651.57348426515, 42720.57279427205, 42839.78580107095, 43014.13971720564, 8516.659333626647, 9612.375195612298, 9796.510174491283, 9798.90201097987, 9807.215422766161, 8694.39139260252, 11560.826587601192, 11779.17545771792, 11796.410179491035, 11890.286582805016, 11750.236234644763, 11784.292942423439, 11822.467988940476, 11825.994790442823, 11826.994705450046, 8633.877595912554, 8835.690750823755, 8843.071477494836, 11614.767704645901, 11701.7659646807, 8634.179752923463, 11413.0, 11729.354885481294, 11765.882341176564, 11854.762904741858, 35253.647463525296, 41211.793941030104, 41758.37362439566, 41933.0, 41933.0, 2962.4371369439955, 3492.4062909305226, 3536.6109727929907, 3709.4992176056157, 4056.1190427915026, 28859.0, 29290.267743306376, 30244.54633180504, 30249.395012099856, 30370.240743981358, 2665.200439868032, 2696.2046196372116, 2750.587411888233, 2822.957655635167, 2842.6588809342807, 449853.0, 452359.0, 453075.0, 453872.9999999967, 453923.0, 62794.54629995303, 63116.68441657762, 63392.0, 63422.0, 63494.68252658707, 66781.33218667848, 66942.0, 66950.0, 66964.33035669678, 67027.66486167538, 16971.06659133735, 17255.74116388255, 17262.741058884127, 17262.913685431493, 17290.91354543219, 12187.964023058139, 12390.0, 13139.408726607135, 13170.0, 13413.463461461628, 15407.922960385351, 18454.98497582619, 18553.907230463763, 18589.0, 18755.0, 5427.592930530226, 6445.375764310764, 6445.6454894981225, 6469.5794773339585, 6480.51396145291, 941.3081385181886, 990.6186118344489, 1017.3234798858824, 1047.4867315015588, 1058.1534772182224, 24257.878710606336, 29324.0, 29534.409311813644, 29974.0, 30178.69821301803, 1113.9944300278446, 1116.7208197950513, 1129.88701129888, 1206.2641788509002, 1234.0929416878587, 14019.0, 15219.619509512351, 15258.542243732705, 15579.814706087242, 15657.0, 597626.0, 599201.0, 603110.0, 605851.0, 606027.0, 59983.0, 60526.0, 60544.0, 60565.69717151386, 60687.78624427487, 1305.3342795174528, 1355.8305211848717, 1431.2485944879022, 1454.992725036368, 1467.6404280951235, 26002.34994125162, 29725.256868578457, 29927.401451970403, 30695.539566906962, 30761.077167685005, 53532.46467535275, 53575.73212133914, 54065.18902216391, 54229.45770542244, 54356.0, 436038.0, 437231.0, 437619.0, 437659.0, 438315.0, 27104.1868743938, 27427.451450971268, 27754.86122569374, 28608.427831442837, 41021.58978410178, 46018.769906151596, 46581.76709116433, 46693.066138677714, 47564.0, 48073.03853922972, 37293.4405983905, 38657.613423865405, 38760.0, 39288.6071139285, 39992.0, 28547.857260713565, 30480.0, 35254.0, 36536.45195322019, 37085.0, 36048.81975590105, 36442.27115457623, 37142.0, 37168.44247336346, 37396.43905341368, 27280.590791138548, 31395.05814825559, 31801.68198317987, 31941.0, 31963.520547191347, 23616.645750314103, 23714.525709486057, 23994.52010959736, 24058.27825165248, 24134.51730965406], "value": 19983.231178718615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.66512631699896, 392.42509723255426, 397.0767964482579, 401.06151605243724, 415.80872798512684, 20320.983950802474, 20370.287039953604, 21329.82685952272, 21702.674459883114, 21723.67414488784, 20280.29018984336, 20484.795152048475, 21450.14199432025, 21774.782252177472, 21835.235766748166, 5643.971780141103, 5950.821475355725, 5964.359801054723, 5966.582339236255, 5973.193618861464, 5680.204771332017, 5897.380775018625, 5937.524998000171, 5995.6702381369005, 6031.396860313956, 6013.6993150342305, 6019.909701354483, 6027.276726792791, 6043.335233124375, 6053.606515576474, 5887.676177810219, 5943.762249510014, 6015.9398406016035, 6036.3360030396625, 6036.909446358308, 5596.916046259309, 5800.129980502916, 5807.941920580824, 5823.272090988627, 5823.883522329572, 13684.93157534216, 14038.0, 18034.27862885483, 24497.652629105392, 26268.605970910452, 1879.577095153593, 1925.2010415677478, 2282.577723121228, 2530.417479284433, 2779.2357101796965, 9811.607535698564, 9899.25755568328, 10144.79710405795, 10283.640072597424, 10396.064354208082, 1664.750287456891, 1696.1264948551507, 1735.8871673341193, 1744.1976690722283, 1783.3134243316288, 81637.59181204055, 100083.9991600082, 105442.94557054485, 119661.40169299184, 128416.3579182098, 10860.294080884718, 11447.484863181176, 11492.597759078391, 11709.414529273503, 11715.941420292844, 14071.648208794863, 14119.294035298193, 14941.775873361908, 14952.850471495145, 15250.694986100216, 7396.704131834774, 8328.45865018784, 8661.30709543235, 8717.607707653171, 8870.689525866565, 6833.555818871857, 8686.826263474695, 8747.825043499095, 8762.299016078698, 8837.204651581389, 4619.607333376701, 4685.484596694361, 4701.636525407594, 4744.857654270378, 4757.381540399726, 1889.969966368305, 1973.082516629774, 1984.6427643024106, 1986.1558837494297, 2002.9699554506694, 857.7898414888267, 935.8502639577634, 952.8189643967625, 953.1183655118987, 1038.1487180512058, 20753.962301884847, 21296.467588310417, 21557.137714491244, 21623.13507459684, 21715.457113572287, 890.7594949363765, 940.2947789158131, 969.0406497567358, 986.7335819328741, 998.2962011781676, 11024.559017639365, 11377.0, 11385.601503947488, 11393.259438136494, 11404.315741055563, 598282.0, 602402.0, 606842.5894446347, 611429.8857011372, 615006.0, 73563.63218183875, 77034.0, 112896.0, 133447.3327633375, 135585.0, 995.9651412200683, 996.2030375699411, 1044.691815914292, 1046.706922061824, 1050.5850189175233, 15816.920915395349, 16045.518634440985, 18810.811891880905, 19032.714509282094, 19140.425787226406, 39244.60755392409, 53087.0, 54746.0, 54918.0, 55183.44816551783, 80228.59885700535, 80865.0, 82045.0, 82061.0, 82781.58609206915, 8070.596470176585, 8212.384071194565, 8232.835343292982, 8459.027211870467, 8570.271526920282, 9274.887013558417, 12016.158868879094, 12556.37218139071, 12877.935610322264, 13043.217406955613, 6393.104965304954, 7957.801054973672, 10930.01629853317, 11176.385298808644, 11219.158563107963, 10944.124470042218, 11171.66485005451, 11187.384693841917, 11236.831447528457, 11329.943350283196, 9260.629574816932, 11276.492557835081, 11646.359450230319, 11744.29534227949, 11811.409429528661, 8082.757517274491, 8193.057798353266, 8361.414700971118, 8386.287165590993, 8387.203215694499, 10278.794424111626, 10863.293885897392, 11030.834537482106, 11284.43577821122, 11435.828462572901], "value": 9461.022974282867, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.7984302354653, 1586.3099551695013, 1630.0138416258164, 1855.1744473709196, 1985.3150663021252, 7844.568548729818, 8646.524441155734, 8878.890138732666, 8889.111088891108, 8934.419262747933, 8548.658053677846, 8761.51811650359, 8780.253678437324, 8783.429077109984, 8787.692430764924, 2156.3423155937435, 2220.2340192633596, 2223.9110435582556, 2437.159180082873, 2472.443700167466, 1987.33424302858, 2236.3514580771557, 2267.4218074391006, 2485.689288838895, 2490.5392502387026, 2422.1159276863905, 2457.9016839326405, 2461.8030557555444, 2462.507498500298, 2465.3220364399754, 2408.2654790288957, 2411.8191135664806, 2412.7949124324455, 2414.5412371649504, 2415.2029830156007, 2143.7963393477594, 2405.9398515037087, 2481.565725997943, 2483.3543278747657, 2483.807772269303, 8779.429337093068, 8859.29125669945, 8868.157525035176, 8875.911240887574, 8884.911150888473, 5331.920021199685, 5454.836354909359, 5475.6440831345835, 5480.7533660985355, 5489.0, 3531.770434921722, 3604.333198358312, 3637.6907962823193, 3667.0832291927018, 3668.174660701321, 994.6767300627253, 1057.7038429239826, 1059.7244716373798, 1061.3897009219652, 1067.0556557446716, 11946.880531194576, 11947.342896140708, 11966.760664786656, 11975.341356225405, 11989.280643161439, 4818.927716084261, 4848.369711937426, 4858.5384388483035, 4963.230699241623, 4994.500549944976, 8876.51179185151, 10140.9859014098, 10148.0, 10163.695089147337, 10167.28828981979, 5524.613277070647, 5526.447355264522, 5530.529904958124, 5534.778608855681, 5538.196961440649, 4096.877093687194, 4560.407147070926, 4817.084753896818, 5012.097822391932, 5018.422881368616, 3575.66031227033, 3855.9421608675893, 3932.842686292516, 3950.861719839792, 3965.5836137205756, 1032.390889375272, 1221.6884694403047, 1230.4094034863137, 1231.359692959656, 1236.1285293867802, 771.7568965775763, 820.7045463633207, 915.6932427636683, 962.5812771444444, 974.3861367338673, 5781.103928891029, 5796.681182534917, 5982.641041537521, 6047.818565443044, 6058.515318774487, 870.9303255739526, 969.1665167955553, 976.4531862157214, 987.3039507147444, 994.6568433890296, 3886.358750806142, 3970.7021973352116, 3984.7011474139554, 4022.416749571296, 4043.454133691973, 8824.073472285447, 10446.164306855433, 11105.722356941142, 11108.389038602954, 11111.72220694489, 9041.954790226138, 11031.944840275748, 11037.392943388191, 11044.950729680668, 11087.334759914269, 8118.228768267005, 8249.381296402793, 8324.334053275845, 8408.83182336362, 8435.662573497115, 4828.734419606955, 5912.556558258062, 5969.313528944053, 6137.202163718866, 6151.3541078186145, 9702.805943881223, 9804.705858824247, 10213.285070045025, 10215.336003159771, 10280.485975701335, 10019.499025048864, 10266.384016959006, 10327.535260913126, 10388.792224155626, 10418.114460270961, 7021.613811240431, 7157.252030249034, 7603.543787372775, 7782.766517004499, 7787.727429539939, 7560.092788865371, 8055.879161812461, 8555.973283205805, 8592.140785921358, 8611.009733880433, 7472.327490525661, 7486.026816513819, 7502.624868756649, 7530.4352173588295, 7634.503757255983, 7414.888776668462, 7431.108267007994, 7461.179270280373, 7568.4323675723435, 7583.924160758322, 5883.352831188651, 7197.424206063607, 7197.532160409557, 7199.316064973819, 7204.135503739585, 4708.858734237979, 4792.616590672807, 4847.636427268039, 4904.166291730486, 5012.122878496176, 4722.5513576210205, 4947.1342515059005, 4977.303177555217, 5025.673331233458, 5183.740812959412], "value": 4611.560540381346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.77071082043875, 316.91918560767016, 321.9790713603616, 323.13400087764796, 327.22937482229366, 4632.837850675227, 5306.522412982836, 5309.548688361489, 5328.280682107914, 5337.492938170868, 4193.846692159657, 4409.228385032622, 4514.819407223716, 5195.0, 5273.499017593323, 3196.6643502432203, 3931.19410520844, 3958.990457433365, 4077.429159917614, 4113.56807535209, 3399.456087026076, 4002.4784385836883, 4063.0, 4070.5115386153702, 4122.587741225883, 3412.8293585320757, 4050.9594904050878, 4058.7767672777986, 4061.8375264989363, 4090.1206240658253, 3574.535310409656, 3810.7141964352786, 3865.4781604483455, 3955.46601208836, 3956.7625942443547, 3510.350585141757, 3520.8591656333715, 3520.9471857922154, 3532.8410221539834, 3540.5220295260324, 4740.312654665088, 5033.924491132636, 5038.697678139323, 5077.746112694387, 5152.407473140599, 3912.366192682528, 4046.7167298289132, 4118.608732170439, 4471.6646251531265, 4648.094940662087, 4864.635152363551, 5505.614606977513, 5517.834464966058, 5528.612997090206, 5632.7746890124345, 3740.9625903740884, 3789.621037896216, 3895.0, 3908.8436462541463, 3935.409688546741, 5490.929268792587, 5806.535477161817, 5816.76054718932, 5843.883122337572, 5873.383294754075, 3353.782004169721, 3386.4243078676686, 3393.372226138172, 3958.3072962231495, 3969.285528604879, 3313.552670389515, 3382.3595556155246, 3997.720159588801, 4026.3356546169553, 4076.5515793262716, 4556.691651998521, 4635.844332385457, 4655.743934083658, 4680.15757163707, 4695.671303008757, 4471.664625153061, 4483.256480838471, 5558.44415558449, 5558.471945165284, 5566.721663916869, 1336.3919416665394, 1375.7549417776927, 1376.1880490510646, 1377.3251106957518, 1378.9931050344685, 815.2458975447687, 844.9028361738356, 859.5229647545547, 866.644675682967, 874.54960695242, 4765.451973023147, 4916.3362946001835, 5070.340855688737, 5119.59043276537, 5158.948410515922, 4879.5120487951635, 5200.985807767524, 5586.664800111925, 5607.831765047055, 5623.662580245198, 4763.261694437368, 4940.542539950723, 5199.896002079937, 5207.661502002357, 5251.159814429673, 2533.987330063375, 2680.5979103134296, 2987.8954236601617, 3012.638483382008, 3013.653429855595, 5337.866553336197, 5969.0, 5973.84941421088, 5998.561783388567, 6003.609765365237, 5199.454057324002, 5393.164059570694, 5552.305961754781, 5814.651120932757, 5934.1692163097205, 5794.362620111784, 6022.427869352405, 6033.818985430356, 6055.909936211524, 6064.605140817548, 4986.426560945392, 5315.627906046694, 5609.3722678789345, 5812.505936995402, 5816.621919575383, 4693.671442999094, 5617.719114044363, 5723.399043100415, 5724.0, 5743.741531631003, 5588.636738611977, 6007.339192688888, 6037.245344331958, 6056.697165141637, 6089.939100608937, 3248.5289633003554, 3816.7709937403843, 4045.453863728359, 4060.7563546187325, 4067.328890732996, 5242.370915490165, 5552.167174923794, 5616.971915140398, 5737.225474560881, 5742.856428589319, 4887.584555312696, 5473.096939005179, 5603.831885043455, 5624.971875140598, 5627.662340259598, 5028.396592408934, 5213.504717051874, 5217.973575326378, 5548.223248745098, 5557.888842223214, 4793.832215872428, 5443.584667986432, 5452.164874265281, 5456.099743542271, 5488.890222195454, 4690.319903614025, 5499.395066542597, 5575.020867591947, 5594.664320140804, 5666.631668941671, 4808.148862687821, 5464.644798088111, 5492.231087647812, 5495.230667706604, 5517.44825517445], "value": 4058.9702827572405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.9317571654979, 651.511366475144, 666.8132922781622, 742.0427648333648, 744.3263846219173, 14947.850521494782, 15126.0, 15382.0, 15413.229338533056, 16199.595010124753, 15686.686266274668, 16081.0, 16086.919565402157, 16114.355425782955, 16137.91931040346, 727.3453891497642, 753.107567532473, 918.3112665500857, 934.2899396458706, 935.6351023100996, 908.4594666173616, 939.0656296984475, 941.3034354577597, 942.3074040580202, 942.4251206763869, 951.4338968313876, 955.775392782699, 957.6696039866235, 958.5255298627189, 963.720521048895, 763.0652450747845, 942.524025367192, 949.7245798718363, 958.0, 971.868797712314, 722.443718336883, 725.4776560876161, 726.3753172271842, 726.6548389514991, 726.7855982485127, 14682.632934176625, 14750.70498590033, 14781.482648107372, 14781.778273325908, 14809.185494797783, 11877.524899004029, 11979.341136237503, 12059.236601542429, 12191.268523888595, 12204.755904881942, 10629.574817007311, 10634.0, 11148.721281967935, 11266.493007814668, 11405.030572401356, 4023.6177563131455, 4028.2144981728575, 4060.898477538085, 4155.729877557949, 4265.402843601898, 77470.22529774743, 79281.60359198166, 79632.0, 79802.00494987614, 80133.19866801315, 8874.201321880933, 9086.227670647937, 9147.0, 9439.528023598792, 9442.95278523603, 18717.344892928682, 18761.530961726065, 18763.624727505372, 18775.624487510173, 20085.196592136443, 5859.560532959959, 7545.245475452411, 7594.0, 7667.961660191663, 7792.376609871197, 4049.7367671101283, 4074.930964997376, 4077.041895154636, 4162.646175075092, 4164.0, 3558.768680035841, 3652.7990960497427, 3658.140337020798, 3665.5601327840295, 3669.9633003670156, 647.5758378262245, 668.8327918020495, 669.2771806449024, 669.6350488983516, 694.2744831650937, 569.4647031790104, 585.9326177489557, 593.890130325891, 602.0577795749638, 621.8507558186093, 7153.6780844862105, 7329.973803667491, 7403.888941665879, 7711.53730776144, 7715.57564333967, 555.9054960656698, 575.114323941126, 601.9879602408015, 609.8201030695956, 622.5175488996065, 3671.430928206132, 3698.223373091627, 3740.345439548068, 3802.4774618413594, 3849.210911763138, 75583.62208188923, 80800.59599701964, 82656.0, 82974.0, 83057.0, 65105.34894651087, 66647.0, 71341.0, 72390.2760972394, 73304.0, 63071.68464157742, 63319.73360532764, 63830.0, 64680.0, 64703.67648161729, 8083.7170699025255, 8202.179782021989, 8284.668613255644, 8317.95841020791, 8440.198181172778, 29375.265618359714, 37181.81409092937, 39251.41122883102, 40178.799106004284, 40910.1817963645, 52798.47201527935, 56078.0, 56976.71511642581, 57317.42682573288, 57320.42679573151, 10226.6420675276, 10535.209859260432, 10621.203409744145, 10724.678259652223, 10818.67543973682, 7634.847303054019, 9299.67451139207, 9534.046595340411, 11700.765984680089, 12162.391880406121, 10949.452229552151, 11182.105431565293, 11307.095432365226, 11525.366104863977, 11691.0, 11124.332540047622, 11620.0, 11624.360660163773, 11625.418729063682, 11731.941340293586, 10917.56329746833, 11089.445527723421, 11141.665750027189, 11266.850376107908, 11393.886061139283, 7741.264579864904, 9257.21313688344, 9364.859527107235, 9457.716268511957, 9529.285303602119, 8805.779855503664, 9280.118388753057, 9345.672901448417, 9395.248380129437, 9449.527523623929], "value": 6709.596926134817, "unit": "score"}], "name": "dromaeo_css", "value": 7136.927613567089, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 111.0, 112.0, 112.0, 113.0, 112.0, 112.0, 113.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 140.0, 147.0, 196.0, 120.0, 143.0, 139.0, 117.0, 171.0, 118.0], "value": 146.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 186.0, 187.0, 187.0, 185.0, 185.0, 184.0, 186.0, 180.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 74.0, 164.0, 82.0, 62.0, 57.0, 49.0, 113.0, 65.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 132.0, 79.0, 81.0, 78.0, 102.0, 80.0, 79.0, 102.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 84.0, 87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 91.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 92.0, 92.0, 94.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 84.0, 85.0, 84.0, 85.0, 83.0, 86.0, 88.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 60.0, 58.0, 58.0, 58.0, 121.0, 57.0, 56.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 64.0, 74.0, 70.0, 67.0, 67.0, 68.0, 68.0, 68.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 84.0, 85.0, 84.0, 82.0, 97.0, 104.0, 106.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 140.0, 136.0, 138.0, 139.0, 138.0, 129.0, 228.0, 151.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 169.0, 167.0, 171.0, 166.0, 172.0, 168.0, 165.0, 167.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 65.0, 66.0, 65.0, 67.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1487.8999999999999, "alertThreshold": 2.0}]} 19:17:13 INFO - Return code: 0 19:17:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:17:13 ERROR - # TBPL SUCCESS # 19:17:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:13 INFO - 2016-05-10 19:17:13.140 screenresolution[1675:10021] starting screenresolution argv=screenresolution get 19:17:13 INFO - 2016-05-10 19:17:13.143 screenresolution[1675:10021] Display 0: 1600x1200x32@60 19:17:13 INFO - 2016-05-10 19:17:13.149 screenresolution[1676:10025] starting screenresolution argv=screenresolution list 19:17:13 INFO - Available Modes on Display 0 19:17:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:13 INFO - Intel Iris: 19:17:13 INFO - Chipset Model: Intel Iris 19:17:13 INFO - Type: GPU 19:17:13 INFO - Bus: Built-In 19:17:13 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:13 INFO - Vendor: Intel (0x8086) 19:17:13 INFO - Device ID: 0x0a2e 19:17:13 INFO - Revision ID: 0x0009 19:17:13 INFO - Displays: 19:17:13 INFO - Display: 19:17:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:13 INFO - Display Serial Number: 1600x1200 60 19:17:13 INFO - Main Display: Yes 19:17:13 INFO - Mirror: Off 19:17:13 INFO - Online: Yes 19:17:13 INFO - Rotation: Supported 19:17:13 INFO - Return code: 0 19:17:13 INFO - Running post-action listener: _resource_record_post_action 19:17:13 INFO - ##### 19:17:13 INFO - ##### Finished run-tests step (success) 19:17:13 INFO - ##### 19:17:13 INFO - Running post-run listener: _resource_record_post_run 19:17:14 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37889536; Write bytes: 692803072; Read time: 397; Write time: 1968 19:17:14 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383941120; Write bytes: 377677824; Read time: 28536; Write time: 696 19:17:14 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36099584; Write bytes: 295730688; Read time: 377; Write time: 1227 19:17:14 INFO - Running post-run listener: _upload_blobber_files 19:17:14 INFO - Blob upload gear active. 19:17:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:14 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 19:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:17:14 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:17:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:17:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:16 INFO - (blobuploader) - INFO - Done attempting. 19:17:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:17:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:17 INFO - (blobuploader) - INFO - Done attempting. 19:17:17 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:17 INFO - Return code: 0 19:17:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/050afddf55a8e8842ff4ecf935b25bffb4c082fe64f73b55010e34ad0e29223d9858916c201980d23bfca8fd95ead211fb55b41e07c677d71557f696b384781c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df970a07b22bc3f63c011162c02faee22e048582d12c1949f8102f2070c0a26281b7ef68702d5f3a4dd963544df0bfb333ef31ae7247177a08bdf1dbffdde989"} 19:17:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:17 INFO - Contents: 19:17:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/050afddf55a8e8842ff4ecf935b25bffb4c082fe64f73b55010e34ad0e29223d9858916c201980d23bfca8fd95ead211fb55b41e07c677d71557f696b384781c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df970a07b22bc3f63c011162c02faee22e048582d12c1949f8102f2070c0a26281b7ef68702d5f3a4dd963544df0bfb333ef31ae7247177a08bdf1dbffdde989"} 19:17:17 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:17 INFO - Copying logs to upload dir... 19:17:17 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.895942 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 19:17:17.891554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 19:17:17.895509) ========= 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.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/050afddf55a8e8842ff4ecf935b25bffb4c082fe64f73b55010e34ad0e29223d9858916c201980d23bfca8fd95ead211fb55b41e07c677d71557f696b384781c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df970a07b22bc3f63c011162c02faee22e048582d12c1949f8102f2070c0a26281b7ef68702d5f3a4dd963544df0bfb333ef31ae7247177a08bdf1dbffdde989"} build_url:https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/AQFvd2kXQwqcBIkWQEV1fQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/050afddf55a8e8842ff4ecf935b25bffb4c082fe64f73b55010e34ad0e29223d9858916c201980d23bfca8fd95ead211fb55b41e07c677d71557f696b384781c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df970a07b22bc3f63c011162c02faee22e048582d12c1949f8102f2070c0a26281b7ef68702d5f3a4dd963544df0bfb333ef31ae7247177a08bdf1dbffdde989"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 19:17:17.923447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:17:17.923806) ========= 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.io6dnvUmCR/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.q22dgpw32N/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.005155 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:17:17.973592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 19:17:17.973974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 19:17:17.974342) ========= ========= Total master_lag: 0.16 =========