builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0065 starttime: 1462977767.16 results: success (0) buildid: 20160511055410 builduid: 23130035363849e1b7a059821a626cb0 revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.161880) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.162295) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.162573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.180344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.180600) ========= 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.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-11 07:42:47-- 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% 8.41M=0.001s 2016-05-11 07:42:47 (8.41 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081359 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.278043) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.278325) ========= 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.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.020365 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:47.329151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 07:42:47.329464) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-11 07:42:47,399 truncating revision to first 12 chars 2016-05-11 07:42:47,399 Setting DEBUG logging. 2016-05-11 07:42:47,399 attempt 1/10 2016-05-11 07:42:47,399 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3461f3cae784?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 07:42:48,451 unpacking tar archive at: mozilla-central-3461f3cae784/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293152 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 07:42:48.645254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:48.645580) ========= script_repo_revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:48.645987) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:48.646252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:42:48.660045) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-05-11 07:42:48.660308) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:42:48 INFO - MultiFileLogger online at 20160511 07:42:48 in /builds/slave/test 07:42:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:42:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:42:48 INFO - {'append_to_log': False, 07:42:48 INFO - 'base_work_dir': '/builds/slave/test', 07:42:48 INFO - 'blob_upload_branch': 'Firefox', 07:42:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:42:48 INFO - 'branch': 'Firefox', 07:42:48 INFO - 'buildbot_json_path': 'buildprops.json', 07:42:48 INFO - 'config_files': ('talos/mac_config.py',), 07:42:48 INFO - 'default_actions': ('clobber', 07:42:48 INFO - 'read-buildbot-config', 07:42:48 INFO - 'download-and-extract', 07:42:48 INFO - 'populate-webroot', 07:42:48 INFO - 'create-virtualenv', 07:42:48 INFO - 'install', 07:42:48 INFO - 'run-tests'), 07:42:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:42:48 INFO - 'download_minidump_stackwalk': True, 07:42:48 INFO - 'download_symbols': 'ondemand', 07:42:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:42:48 INFO - 'tooltool.py': '/tools/tooltool.py', 07:42:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:42:48 INFO - '/tools/misc-python/virtualenv.py')}, 07:42:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:42:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:42:48 INFO - 'installer_path': 'installer.exe', 07:42:48 INFO - 'log_level': 'info', 07:42:48 INFO - 'log_name': 'talos', 07:42:48 INFO - 'log_to_console': True, 07:42:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:42:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:42:48 INFO - 'opt_config_files': (), 07:42:48 INFO - 'pip_index': False, 07:42:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:42:48 INFO - 'cmd': ('bash', 07:42:48 INFO - '-c', 07:42:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:42:48 INFO - 'enabled': True, 07:42:48 INFO - 'halt_on_failure': False, 07:42:48 INFO - 'name': 'check_screen_resolution'},), 07:42:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:42:48 INFO - 'cmd': ('bash', 07:42:48 INFO - '-c', 07:42:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:42:48 INFO - 'enabled': True, 07:42:48 INFO - 'halt_on_failure': False, 07:42:48 INFO - 'name': 'check_screen_resolution'},), 07:42:48 INFO - 'run_cmd_checks_enabled': True, 07:42:48 INFO - 'sps_profile': False, 07:42:48 INFO - 'sps_profile_interval': 0, 07:42:48 INFO - 'suite': 'dromaeojs', 07:42:48 INFO - 'system_bits': '32', 07:42:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:42:48 INFO - 'title': 't-yosemite-r7-0065', 07:42:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:42:48 INFO - 'use_talos_json': True, 07:42:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:42:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:42:48 INFO - 'work_dir': 'build'} 07:42:48 INFO - ##### 07:42:48 INFO - ##### Running clobber step. 07:42:48 INFO - ##### 07:42:48 INFO - Running pre-action listener: _resource_record_pre_action 07:42:48 INFO - Running main action method: clobber 07:42:48 INFO - rmtree: /builds/slave/test/build 07:42:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:42:50 INFO - Running post-action listener: _resource_record_post_action 07:42:50 INFO - ##### 07:42:50 INFO - ##### Finished clobber step (success) 07:42:50 INFO - ##### 07:42:50 INFO - ##### 07:42:50 INFO - ##### Running read-buildbot-config step. 07:42:50 INFO - ##### 07:42:50 INFO - Running pre-action listener: _resource_record_pre_action 07:42:50 INFO - Running main action method: read_buildbot_config 07:42:50 INFO - Using buildbot properties: 07:42:50 INFO - { 07:42:50 INFO - "project": "", 07:42:50 INFO - "product": "firefox", 07:42:50 INFO - "script_repo_revision": "production", 07:42:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:42:50 INFO - "repository": "", 07:42:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:42:50 INFO - "buildid": "20160511055410", 07:42:50 INFO - "pgo_build": "False", 07:42:50 INFO - "basedir": "/builds/slave/test", 07:42:50 INFO - "buildnumber": 246, 07:42:50 INFO - "slavename": "t-yosemite-r7-0065", 07:42:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:42:50 INFO - "platform": "yosemite_r7", 07:42:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:42:50 INFO - "branch": "mozilla-central", 07:42:50 INFO - "repo_path": "mozilla-central", 07:42:50 INFO - "revision": "3461f3cae78495f100a0f7d3d2e0b89292d3ec02", 07:42:50 INFO - "stage_platform": "macosx64", 07:42:50 INFO - "builduid": "23130035363849e1b7a059821a626cb0", 07:42:50 INFO - "slavebuilddir": "test" 07:42:50 INFO - } 07:42:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:42:50 INFO - Running post-action listener: _resource_record_post_action 07:42:50 INFO - ##### 07:42:50 INFO - ##### Finished read-buildbot-config step (success) 07:42:50 INFO - ##### 07:42:50 INFO - ##### 07:42:50 INFO - ##### Running download-and-extract step. 07:42:50 INFO - ##### 07:42:50 INFO - Running pre-action listener: _resource_record_pre_action 07:42:50 INFO - Running main action method: download_and_extract 07:42:50 INFO - mkdir: /builds/slave/test/build/tests 07:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:42:50 INFO - https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:42:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:42:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:42:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:51 INFO - Downloaded 1199 bytes. 07:42:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:42:51 INFO - Using the following test package requirements: 07:42:51 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:42:51 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:42:51 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:42:51 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'jsshell-mac.zip'], 07:42:51 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:42:51 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:42:51 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:42:51 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:42:51 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:42:51 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:42:51 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:42:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:42:51 INFO - https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:42:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:42:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:42:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:52 INFO - Downloaded 18150493 bytes. 07:42:52 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'] 07:42:52 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 07:42:55 INFO - Return code: 0 07:42:55 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 07:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:42:55 INFO - https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:42:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:42:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:42:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:42:57 INFO - Downloaded 18150493 bytes. 07:42:57 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'] 07:42:57 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 07:43:00 INFO - Return code: 0 07:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:00 INFO - https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:43:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:43:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:43:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:43:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:43:02 INFO - Downloaded 10840152 bytes. 07:43:02 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'] 07:43:02 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 07:43:02 INFO - Return code: 0 07:43:02 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')]} 07:43:02 INFO - https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:43:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:43:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/edZfUCkJTTS5M_mLIC4Ddw/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 07:43:06 INFO - Downloaded 109808567 bytes. 07:43:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:06 INFO - mkdir: /builds/slave/test/properties 07:43:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:06 INFO - Contents: 07:43:06 INFO - build_url:https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:43:07 INFO - Running post-action listener: _resource_record_post_action 07:43:07 INFO - Running post-action listener: set_extra_try_arguments 07:43:07 INFO - ##### 07:43:07 INFO - ##### Finished download-and-extract step (success) 07:43:07 INFO - ##### 07:43:07 INFO - ##### 07:43:07 INFO - ##### Running populate-webroot step. 07:43:07 INFO - ##### 07:43:07 INFO - Running pre-action listener: _resource_record_pre_action 07:43:07 INFO - Running main action method: populate_webroot 07:43:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:43:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:43:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:43:07 INFO - '--tppagecycles', 07:43:07 INFO - '7'], 07:43:07 INFO - 'tests': ['tsvgm']}}, 07:43:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:43:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:43:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:43:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:43:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['damp', 'tps']}, 07:43:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'tests': ['damp', 'tps']}, 07:43:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['dromaeo_dom']}, 07:43:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:43:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['a11yr', 07:43:07 INFO - 'ts_paint', 07:43:07 INFO - 'tpaint', 07:43:07 INFO - 'sessionrestore', 07:43:07 INFO - 'sessionrestore_no_auto_restore', 07:43:07 INFO - 'tabpaint']}, 07:43:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['tsvgx', 07:43:07 INFO - 'tsvgr_opacity', 07:43:07 INFO - 'tart', 07:43:07 INFO - 'tscrollx', 07:43:07 INFO - 'cart']}, 07:43:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:43:07 INFO - 'tsvgr_opacity', 07:43:07 INFO - 'tart', 07:43:07 INFO - 'tscrollx', 07:43:07 INFO - 'cart']}, 07:43:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'talos_options': ['--disable-e10s'], 07:43:07 INFO - 'tests': ['tp5o']}, 07:43:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'tests': ['tp5o']}, 07:43:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'talos_options': ['--disable-e10s', 07:43:07 INFO - '--xperf_path', 07:43:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:07 INFO - 'tests': ['tp5n']}, 07:43:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:07 INFO - 'talos_options': ['--xperf_path', 07:43:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:07 INFO - 'tests': ['tp5n']}}, 07:43:07 INFO - 'talos.zip': {'path': '', 07:43:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:43:07 INFO - Running post-action listener: _resource_record_post_action 07:43:07 INFO - ##### 07:43:07 INFO - ##### Finished populate-webroot step (success) 07:43:07 INFO - ##### 07:43:07 INFO - ##### 07:43:07 INFO - ##### Running create-virtualenv step. 07:43:07 INFO - ##### 07:43:07 INFO - Running pre-action listener: _resource_record_pre_action 07:43:07 INFO - Running main action method: create_virtualenv 07:43:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:43:07 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 07:43:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:43:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:43:07 INFO - Using real prefix '/tools/python27' 07:43:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:43:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:43:11 INFO - Installing pip.................done. 07:43:11 INFO - Return code: 0 07:43:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:43: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')]} 07:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43: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')]} 07:43:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:43:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:11 INFO - 'HOME': '/Users/cltbld', 07:43:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:11 INFO - 'LOGNAME': 'cltbld', 07:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:11 INFO - 'MOZ_NO_REMOTE': '1', 07:43:11 INFO - 'NO_EM_RESTART': '1', 07:43:11 INFO - 'PAGER': '/bin/cat', 07:43:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:11 INFO - 'PWD': '/builds/slave/test', 07:43:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:11 INFO - 'SHELL': '/bin/bash', 07:43:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:11 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:11 INFO - 'USER': 'cltbld', 07:43:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:11 INFO - 'XPC_FLAGS': '0x0', 07:43:11 INFO - 'XPC_SERVICE_NAME': '0', 07:43:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:11 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 07:43:11 INFO - Cleaning up... 07:43:11 INFO - Return code: 0 07:43:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:43: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')]} 07:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43: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')]} 07:43:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 07:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 07:43:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:11 INFO - 'HOME': '/Users/cltbld', 07:43:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:11 INFO - 'LOGNAME': 'cltbld', 07:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:11 INFO - 'MOZ_NO_REMOTE': '1', 07:43:11 INFO - 'NO_EM_RESTART': '1', 07:43:11 INFO - 'PAGER': '/bin/cat', 07:43:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:11 INFO - 'PWD': '/builds/slave/test', 07:43:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:11 INFO - 'SHELL': '/bin/bash', 07:43:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:11 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:11 INFO - 'USER': 'cltbld', 07:43:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:11 INFO - 'XPC_FLAGS': '0x0', 07:43:11 INFO - 'XPC_SERVICE_NAME': '0', 07:43:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:11 INFO - Downloading/unpacking psutil>=0.7.1 07:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:43:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:14 INFO - Installing collected packages: psutil 07:43:14 INFO - Running setup.py install for psutil 07:43:15 INFO - building 'psutil._psutil_osx' extension 07:43:15 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 07:43:15 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 07:43:15 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 07:43:15 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 07:43:15 INFO - building 'psutil._psutil_posix' extension 07:43:15 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 07:43:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:43:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:43:15 INFO - ^ 07:43:15 INFO - 1 warning generated. 07:43:15 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 07:43:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:15 INFO - Successfully installed psutil 07:43:15 INFO - Cleaning up... 07:43:15 INFO - Return code: 0 07:43:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:15 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:15 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 07:43:15 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 07:43:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:15 INFO - 'HOME': '/Users/cltbld', 07:43:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:15 INFO - 'LOGNAME': 'cltbld', 07:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:15 INFO - 'MOZ_NO_REMOTE': '1', 07:43:15 INFO - 'NO_EM_RESTART': '1', 07:43:15 INFO - 'PAGER': '/bin/cat', 07:43:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:15 INFO - 'PWD': '/builds/slave/test', 07:43:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:15 INFO - 'SHELL': '/bin/bash', 07:43:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:15 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:15 INFO - 'USER': 'cltbld', 07:43:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:15 INFO - 'XPC_FLAGS': '0x0', 07:43:15 INFO - 'XPC_SERVICE_NAME': '0', 07:43:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:43:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:43:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:43:18 INFO - Installing collected packages: mozsystemmonitor 07:43:18 INFO - Running setup.py install for mozsystemmonitor 07:43:18 INFO - Successfully installed mozsystemmonitor 07:43:18 INFO - Cleaning up... 07:43:18 INFO - Return code: 0 07:43:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:43:18 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')]} 07:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:18 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')]} 07:43:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:18 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:18 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 07:43:18 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 07:43:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:18 INFO - 'HOME': '/Users/cltbld', 07:43:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:18 INFO - 'LOGNAME': 'cltbld', 07:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:18 INFO - 'MOZ_NO_REMOTE': '1', 07:43:18 INFO - 'NO_EM_RESTART': '1', 07:43:18 INFO - 'PAGER': '/bin/cat', 07:43:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:18 INFO - 'PWD': '/builds/slave/test', 07:43:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:18 INFO - 'SHELL': '/bin/bash', 07:43:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:18 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:18 INFO - 'USER': 'cltbld', 07:43:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:18 INFO - 'XPC_FLAGS': '0x0', 07:43:18 INFO - 'XPC_SERVICE_NAME': '0', 07:43:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:43:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:43:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:43:21 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 07:43:21 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 07:43:21 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 07:43:21 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 07:43:21 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 07:43:21 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 07:43:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:43:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:22 INFO - Downloading docopt-0.6.1.tar.gz 07:43:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:43:22 INFO - Installing collected packages: blobuploader, requests, docopt 07:43:22 INFO - Running setup.py install for blobuploader 07:43:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:43:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:43:22 INFO - Running setup.py install for requests 07:43:23 INFO - Running setup.py install for docopt 07:43:23 INFO - Successfully installed blobuploader requests docopt 07:43:23 INFO - Cleaning up... 07:43:23 INFO - Return code: 0 07:43:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:23 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:23 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 07:43:23 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 07:43:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:23 INFO - 'HOME': '/Users/cltbld', 07:43:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:23 INFO - 'LOGNAME': 'cltbld', 07:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:23 INFO - 'MOZ_NO_REMOTE': '1', 07:43:23 INFO - 'NO_EM_RESTART': '1', 07:43:23 INFO - 'PAGER': '/bin/cat', 07:43:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:23 INFO - 'PWD': '/builds/slave/test', 07:43:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:23 INFO - 'SHELL': '/bin/bash', 07:43:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:23 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:23 INFO - 'USER': 'cltbld', 07:43:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:23 INFO - 'XPC_FLAGS': '0x0', 07:43:23 INFO - 'XPC_SERVICE_NAME': '0', 07:43:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-K6X8NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:23 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-xY7zlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-yPYqc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-6QLb5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-SK1DD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-gnE1cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-voTKkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-MKMuyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-WvQHoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Wt7mMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:24 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ueN2bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-K98I8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Dv0bcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-1EdCaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-G9XtQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-OaTcSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:25 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-yBBor9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:43:25 INFO - Running setup.py install for manifestparser 07:43:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:43:25 INFO - Running setup.py install for mozcrash 07:43:25 INFO - Running setup.py install for mozdebug 07:43:26 INFO - Running setup.py install for mozdevice 07:43:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Running setup.py install for mozfile 07:43:26 INFO - Running setup.py install for mozhttpd 07:43:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Running setup.py install for mozinfo 07:43:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Running setup.py install for mozInstall 07:43:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:43:26 INFO - Running setup.py install for mozleak 07:43:26 INFO - Running setup.py install for mozlog 07:43:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Running setup.py install for moznetwork 07:43:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Running setup.py install for mozprocess 07:43:27 INFO - Running setup.py install for mozprofile 07:43:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Running setup.py install for mozrunner 07:43:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:43:27 INFO - Running setup.py install for mozscreenshot 07:43:27 INFO - Running setup.py install for moztest 07:43:28 INFO - Running setup.py install for mozversion 07:43:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:43:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:43:28 INFO - Cleaning up... 07:43:28 INFO - Return code: 0 07:43:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:28 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')]} 07:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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')]} 07:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:28 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 07:43:28 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 07:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:28 INFO - 'HOME': '/Users/cltbld', 07:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:28 INFO - 'LOGNAME': 'cltbld', 07:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:28 INFO - 'MOZ_NO_REMOTE': '1', 07:43:28 INFO - 'NO_EM_RESTART': '1', 07:43:28 INFO - 'PAGER': '/bin/cat', 07:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:28 INFO - 'PWD': '/builds/slave/test', 07:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:28 INFO - 'SHELL': '/bin/bash', 07:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:28 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:28 INFO - 'USER': 'cltbld', 07:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:28 INFO - 'XPC_FLAGS': '0x0', 07:43:28 INFO - 'XPC_SERVICE_NAME': '0', 07:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:28 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-eLEAS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:28 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)) 07:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:28 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-pSzPqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:28 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)) 07:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:28 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-QATpBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:28 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)) 07:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:28 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-RzRM22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:28 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)) 07:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:28 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-oxR5tA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-_q9Hcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-EDl3vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-MGQHF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-frNzBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-DqnY8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-SBC_nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ZT74Fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-mW1lOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-a7W7Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:29 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)) 07:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:29 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-0N3B7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:30 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)) 07:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:30 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-e8LxF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:30 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)) 07:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:30 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-xSrZal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:30 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)) 07:43:30 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)) 07:43:30 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)) 07:43:30 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)) 07:43:30 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)) 07:43:30 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)) 07:43:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43:32 INFO - Downloading blessings-1.6.tar.gz 07:43:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:43:32 INFO - Installing collected packages: blessings 07:43:32 INFO - Running setup.py install for blessings 07:43:32 INFO - Successfully installed blessings 07:43:32 INFO - Cleaning up... 07:43:33 INFO - Return code: 0 07:43:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:33 INFO - Reading from file tmpfile_stdout 07:43:33 INFO - Current package versions: 07:43:33 INFO - blessings == 1.6 07:43:33 INFO - blobuploader == 1.2.4 07:43:33 INFO - docopt == 0.6.1 07:43:33 INFO - manifestparser == 1.1 07:43:33 INFO - mozInstall == 1.12 07:43:33 INFO - mozcrash == 0.17 07:43:33 INFO - mozdebug == 0.1 07:43:33 INFO - mozdevice == 0.48 07:43:33 INFO - mozfile == 1.2 07:43:33 INFO - mozhttpd == 0.7 07:43:33 INFO - mozinfo == 0.9 07:43:33 INFO - mozleak == 0.1 07:43:33 INFO - mozlog == 3.1 07:43:33 INFO - moznetwork == 0.27 07:43:33 INFO - mozprocess == 0.22 07:43:33 INFO - mozprofile == 0.28 07:43:33 INFO - mozrunner == 6.11 07:43:33 INFO - mozscreenshot == 0.1 07:43:33 INFO - mozsystemmonitor == 0.0 07:43:33 INFO - moztest == 0.7 07:43:33 INFO - mozversion == 1.4 07:43:33 INFO - psutil == 3.1.1 07:43:33 INFO - requests == 1.2.3 07:43:33 INFO - wsgiref == 0.1.2 07:43:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:33 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')]} 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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')]} 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:33 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 07:43:33 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 07:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:33 INFO - 'HOME': '/Users/cltbld', 07:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:33 INFO - 'LOGNAME': 'cltbld', 07:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:33 INFO - 'MOZ_NO_REMOTE': '1', 07:43:33 INFO - 'NO_EM_RESTART': '1', 07:43:33 INFO - 'PAGER': '/bin/cat', 07:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:33 INFO - 'PWD': '/builds/slave/test', 07:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:33 INFO - 'SHELL': '/bin/bash', 07:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:33 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:33 INFO - 'USER': 'cltbld', 07:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:33 INFO - 'XPC_FLAGS': '0x0', 07:43:33 INFO - 'XPC_SERVICE_NAME': '0', 07:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 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)) 07:43:33 INFO - Cleaning up... 07:43:33 INFO - Return code: 0 07:43:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:43:33 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')]} 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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')]} 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43:33 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 07:43:33 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 07:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:33 INFO - 'HOME': '/Users/cltbld', 07:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:33 INFO - 'LOGNAME': 'cltbld', 07:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:33 INFO - 'MOZ_NO_REMOTE': '1', 07:43:33 INFO - 'NO_EM_RESTART': '1', 07:43:33 INFO - 'PAGER': '/bin/cat', 07:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:33 INFO - 'PWD': '/builds/slave/test', 07:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:33 INFO - 'SHELL': '/bin/bash', 07:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:33 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:33 INFO - 'USER': 'cltbld', 07:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:33 INFO - 'XPC_FLAGS': '0x0', 07:43:33 INFO - 'XPC_SERVICE_NAME': '0', 07:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:33 INFO - Downloading/unpacking jsonschema 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:43:36 INFO - Downloading/unpacking functools32 (from jsonschema) 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - Downloading functools32-3.2.3-2.tar.gz 07:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:43:36 INFO - warning: no files found matching '*.txt' 07:43:36 INFO - no previously-included directories found matching 'build' 07:43:36 INFO - no previously-included directories found matching 'dist' 07:43:36 INFO - no previously-included directories found matching '.git*' 07:43:36 INFO - Installing collected packages: jsonschema, functools32 07:43:36 INFO - Running setup.py install for functools32 07:43:36 INFO - warning: no files found matching '*.txt' 07:43:36 INFO - no previously-included directories found matching 'build' 07:43:36 INFO - no previously-included directories found matching 'dist' 07:43:36 INFO - no previously-included directories found matching '.git*' 07:43:36 INFO - Successfully installed jsonschema functools32 07:43:36 INFO - Cleaning up... 07:43:37 INFO - Return code: 0 07:43:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:43: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')]} 07:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43: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')]} 07:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb20c6d510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, '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.XLoSIqiAim/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.UMcrNudsiF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 07:43: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', 'flake8'] in /builds/slave/test/build 07:43: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 flake8 07:43:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:43:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:37 INFO - 'HOME': '/Users/cltbld', 07:43:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:37 INFO - 'LOGNAME': 'cltbld', 07:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:37 INFO - 'MOZ_NO_REMOTE': '1', 07:43:37 INFO - 'NO_EM_RESTART': '1', 07:43:37 INFO - 'PAGER': '/bin/cat', 07:43:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:37 INFO - 'PWD': '/builds/slave/test', 07:43:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:37 INFO - 'SHELL': '/bin/bash', 07:43:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:43:37 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:43:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:37 INFO - 'USER': 'cltbld', 07:43:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:37 INFO - 'XPC_FLAGS': '0x0', 07:43:37 INFO - 'XPC_SERVICE_NAME': '0', 07:43:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:37 INFO - Downloading/unpacking flake8 07:43:37 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 07:43:37 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 07:43:37 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 07:43:37 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 07:43:37 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 07:43:37 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 07:43:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:43:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:43:39 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 07:43:39 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 07:43:39 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 07:43:39 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 07:43:39 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 07:43:39 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 07:43:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:43:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43: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 07:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:43:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:41 INFO - no previously-included directories found matching 'docs/_build' 07:43:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:43:41 INFO - Running setup.py install for pep8 07:43:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:41 INFO - no previously-included directories found matching 'docs/_build' 07:43:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:43:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:43:41 INFO - Cleaning up... 07:43:41 INFO - Return code: 0 07:43:41 INFO - Running post-action listener: _resource_record_post_action 07:43:41 INFO - Running post-action listener: _start_resource_monitoring 07:43:41 INFO - Starting resource monitoring. 07:43:41 INFO - ##### 07:43:41 INFO - ##### Finished create-virtualenv step (success) 07:43:41 INFO - ##### 07:43:41 INFO - ##### 07:43:41 INFO - ##### Running install step. 07:43:41 INFO - ##### 07:43:41 INFO - Running pre-action listener: _resource_record_pre_action 07:43:41 INFO - Running main action method: install 07:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:41 INFO - Reading from file tmpfile_stdout 07:43:41 INFO - Detecting whether we're running mozinstall >=1.0... 07:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:43:41 INFO - Reading from file tmpfile_stdout 07:43:41 INFO - Output received: 07:43:41 INFO - Usage: mozinstall [options] installer 07:43:41 INFO - Options: 07:43:41 INFO - -h, --help show this help message and exit 07:43:41 INFO - -d DEST, --destination=DEST 07:43:41 INFO - Directory to install application into. [default: 07:43:41 INFO - "/builds/slave/test"] 07:43:41 INFO - --app=APP Application being installed. [default: firefox] 07:43:41 INFO - mkdir: /builds/slave/test/build/application 07:43:41 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'] 07:43:41 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 07:44:15 INFO - Reading from file tmpfile_stdout 07:44:15 INFO - Output received: 07:44:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:44:15 INFO - Running post-action listener: _resource_record_post_action 07:44:15 INFO - ##### 07:44:15 INFO - ##### Finished install step (success) 07:44:15 INFO - ##### 07:44:15 INFO - ##### 07:44:15 INFO - ##### Running run-tests step. 07:44:15 INFO - ##### 07:44:15 INFO - Running pre-action listener: _resource_record_pre_action 07:44:15 INFO - Running main action method: run_tests 07:44:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:44:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:44:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:44:15 INFO - 2016-05-11 07:44:15.289 screenresolution[1918:18461] starting screenresolution argv=screenresolution get 07:44:15 INFO - 2016-05-11 07:44:15.292 screenresolution[1918:18461] Display 0: 1600x1200x32@60 07:44:15 INFO - 2016-05-11 07:44:15.298 screenresolution[1919:18463] starting screenresolution argv=screenresolution list 07:44:15 INFO - Available Modes on Display 0 07:44:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:44:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:44:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:44:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:44:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:44:15 INFO - Intel Iris: 07:44:15 INFO - Chipset Model: Intel Iris 07:44:15 INFO - Type: GPU 07:44:15 INFO - Bus: Built-In 07:44:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:44:15 INFO - Vendor: Intel (0x8086) 07:44:15 INFO - Device ID: 0x0a2e 07:44:15 INFO - Revision ID: 0x0009 07:44:15 INFO - Displays: 07:44:15 INFO - Display: 07:44:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:44:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:44:15 INFO - Display Serial Number: 1600x1200 60 07:44:15 INFO - Main Display: Yes 07:44:15 INFO - Mirror: Off 07:44:15 INFO - Online: Yes 07:44:15 INFO - Rotation: Supported 07:44:15 INFO - Return code: 0 07:44:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:44:15 INFO - Python 2.7.3 07:44:15 INFO - Return code: 0 07:44:15 INFO - grabbing minidump binary from tooltool 07:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:15 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 0x10a25dd30>, '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 0x10a081db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a254b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:44:15 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 07:44:15 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 07:44:15 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 07:44:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:44:15 INFO - Return code: 0 07:44:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:44:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:44:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:44:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:44:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:44:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:44:16 INFO - Return code: 0 07:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 07:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0065 --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 07:44:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XLoSIqiAim/Render', 07:44:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:44:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:44:16 INFO - 'HOME': '/Users/cltbld', 07:44:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:44:16 INFO - 'LOGNAME': 'cltbld', 07:44:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:44:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:16 INFO - 'MOZ_NO_REMOTE': '1', 07:44:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:44:16 INFO - 'NO_EM_RESTART': '1', 07:44:16 INFO - 'PAGER': '/bin/cat', 07:44:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:16 INFO - 'PWD': '/builds/slave/test', 07:44:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:44:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:44:16 INFO - 'SHELL': '/bin/bash', 07:44:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UMcrNudsiF/Listeners', 07:44:16 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 07:44:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:44:16 INFO - 'USER': 'cltbld', 07:44:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:44:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:16 INFO - 'XPC_FLAGS': '0x0', 07:44:16 INFO - 'XPC_SERVICE_NAME': '0', 07:44:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:44:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 07:44:16 INFO - mozversion application_buildid: 20160511055410 07:44:16 INFO - mozversion application_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 07:44:16 INFO - mozversion application_display_name: Nightly 07:44:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:44:16 INFO - mozversion application_name: Firefox 07:44:16 INFO - mozversion application_remotingname: firefox 07:44:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:44:16 INFO - mozversion application_vendor: Mozilla 07:44:16 INFO - mozversion application_version: 49.0a1 07:44:16 INFO - mozversion platform_buildid: 20160511055410 07:44:16 INFO - mozversion platform_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 07:44:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:44:16 INFO - mozversion platform_version: 49.0a1 07:44:16 INFO - using testdate: 1462977856 07:44:16 INFO - actual date: 1462977856 07:44:16 INFO - starting webserver on 'localhost:55564' 07:44:16 INFO - SUITE-START | Running 2 tests 07:44:16 INFO - TEST-START | dromaeo_css 07:44:16 INFO - operating with platform_type : mac_ 07:44:16 INFO - Initialising browser for dromaeo_css test... 07:44:16 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmponUse2/profile http://localhost:55564/getInfo.html) 07:44:19 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 07:44:19 INFO - PROCESS | 1937 | colorDepth:24 07:44:19 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 07:44:19 INFO - PROCESS | 1937 | __metrics 07:44:19 INFO - PROCESS | 1937 | 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" 07:44:19 ERROR - PROCESS | 1937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:44:19 INFO - TEST-INFO | 1937: exit 0 07:44:19 INFO - Browser initialized. 07:44:19 INFO - Running cycle 1/1 for dromaeo_css test... 07:44:19 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmponUse2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:31 INFO - PROCESS | 1939 | RSS: Main: 239161344 07:44:31 INFO - PROCESS | 1939 | 07:45:23 INFO - PROCESS | 1939 | 1462977923372 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] 07:47:34 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55564/tests/dromaeo/cssquery-ext.html) 07:47:35 INFO - PROCESS | 1939 | RSS: Main: 351395840 07:47:35 INFO - PROCESS | 1939 | 07:50:38 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-ext.html (next: http://localhost:55564/tests/dromaeo/cssquery-jquery.html) 07:50:39 INFO - PROCESS | 1939 | RSS: Main: 369987584 07:50:39 INFO - PROCESS | 1939 | 07:53:41 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55564/tests/dromaeo/cssquery-mootools.html) 07:53:42 INFO - PROCESS | 1939 | RSS: Main: 370704384 07:53:42 INFO - PROCESS | 1939 | 07:56:44 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55564/tests/dromaeo/cssquery-prototype.html) 07:56:44 INFO - PROCESS | 1939 | RSS: Main: 368533504 07:56:44 INFO - PROCESS | 1939 | 07:59:48 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55564/tests/dromaeo/cssquery-yui.html) 07:59:48 INFO - PROCESS | 1939 | RSS: Main: 337743872 07:59:48 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:55564/tests/dromaeo/cssquery-yui.html (next: http://localhost:55564/tests/dromaeo/cssquery-dojo.html) 08:02:51 INFO - PROCESS | 1939 | RSS: Main: 457904128 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | __start_tp_report 08:02:51 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 08:02:51 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 08:02:51 INFO - PROCESS | 1939 | |i|pagename|runs| 08:02:51 INFO - PROCESS | 1939 | |0;dojo.html;3342.331533693261;3356.3287342531494;3391.3217356528685;3392.0841372829323;3435.6220815710267;5523.143912693529;5538.695371754552;5549.417311182328;5679.290088738908;5713.657180569164;5580.637258578189;5656.095024796034;5665.320161580606;5670.064439367499;5687.459691329317;3935.5080614923204;5080.949190508103;5096.337476128098;5161.509656582619;5163.7418129093585;5007.649464537484;5224.947750522484;5225.477452254782;5242.895142097156;5292.55013323866;5211.1922651989;5225.477452254782;5236.371635403757;5242.3184985951775;5283.339582552181;5162.354705661793;5164.431912489623;5193.065248255312;5195.870103247411;5211.983663185681;4905.583025442842;5017.122003649347;5036.294918711384;5038.622103342264;5096.490350964911;5444.292242008513;5444.646098003617;5466.945330546683;5560.582956278255;5562.916556251659;6145.170401995718;6148.87702245957;6153.384661533811;6158.230221222348;6163.1063495793305;6000.789972350991;6099.664518451484;6100.938990610126;6219.471344935686;6254.843628909313;5683.801066962677;5729.1119876419225;5764.279465066867;5769;5782.884342313214;6262.718177682015;6266.905996410058;6269.153664255358;6271.027990661455;6274.937250627527;5924.437178468039;5995.100734889714;6037.78867739627;6045.063015232646;6072.7278141455345;6140.3552626974415;6217.533684973645;6222.502199824004;6227.937720622826;6229.0967809667345;6115.816525504242;6152;6152;6158.846028849314;6173.413525715139;6010.549208809357;6064.878702426015;6066.939330606726;6149.3235744068115;6185.165002724664;4899.4365647950945;4914.8034078637165;4927.162382395001;4932.21084626458;4933.827316043993;4309.052008558112;4328.8268469260875;4347.478302603645;4351.499577548622;4367.71609845359;5612.410696876851;5626.634268772516;5634.830955071355;5639.7180140993605;5647.971760141255;5975.372585878507;6074.149619053336;6227.937720622826;6235.906461403082;6240.594361366497;5516.696581687966;5523.088690366125;5538.335399751975;5546.889062218733;5666.631668941588;5361.892762144736;5368.731563421813;5369.812056578079;5380.461953804666;5386.111291636915;6277.529185311211;6286.559940804192;6290.654014029273;6303.621782693053;6326.177596912372;4726.220173671443;6034.698265086816;6104.481119104926;6119.350229749377;6138.970878344182;6264.342244064308;6271.43557079865;6276.560640755103;6278.183836101278;6284.905726414199;6064.484518815773;6075.36208698098;6085;6127.877442451215;6144.078388241621;6042.3655516170165;6045.667488288186;6065.6663883486835;6113.633182009094;6167.6916154193;5944.405559444021;5954.612950158225;5961.761529538943;5963.970180149072;5968.6717230551;5865.824025279248;5968.104784282393;5971.373005834324;5984.102384642339;6002.399760023963;5618.2415373924;5756.050251708589;5762;5911.290645122613;5912.674802885883;5940.821775346746;5985.221921150222;6012.917674818575;6040.395960403925;6044.7279872406725;5696.430356964271;5698;5893.616914900689;5894.882102358015;5919.526437884872 08:02:51 INFO - PROCESS | 1939 | |1;ext.html;503.59712230215825;519.890822927185;535.0422743289512;553.3498139685868;566.06881679637;29546.261343466424;41416.79291603542;42408.575914240886;42447;44245.336319955146;42929.57070429295;43179.78410107953;43257.35113973285;43308.350374744325;43372.349414758726;7434.219406962272;9662.565184566694;9702.272329575275;9858.112769850712;9859.654912078078;8565.700200492984;11438.48526816291;11657.183997120204;11666.41667916605;11741.647750567497;11647.6505704829;11689.59086431975;11728.296302221894;11745;11747.882521174764;8567.871481927714;8746.125387461203;8757.781055473664;11656.358900260482;11729.413529323585;11358.545658173663;11684.240524365889;11699.473523691455;11737.58918437859;11741.589044383405;39615.405768913486;39915.80042099771;40060.19879602421;40077.79961100205;40107.59892401068;3079.49474396413;3369.157710572357;3388.3731509670542;3396.015155604872;3746.4942232798494;27359.316017099532;29414.558781618292;29899.10302690901;29946;29965;2673.8796754145915;2771.9722802771917;2820.1680504251294;2825.731555502224;2923.035398318571;497751;500047;500386;500490;501045;63762.68118659377;64383.356166437734;64438.67780661066;64439.355606444275;64480.67759661265;67391;67397;67519.66240168767;67527.98708019384;67595;15331.156786376856;16990.575235618962;17262.482125536255;17279.827201728072;17351.4794556161;11423.857614238676;11662.533498660128;12993.285369304589;13249.73500529984;13256.536021239403;16072.9552579082;16887.831121688625;19351.12919918607;19419.70811962652;19465.610687786448;6692.631905245258;7796.337311328601;7858.882116768253;8064.983754061484;8109.635066421907;932.5477143585356;990.4305024610877;1000.6947880896397;1016.5019140621042;1041.2763129624839;29518;29838.701612983594;29909.551356729666;30099.548506772415;30401.69598304033;1058.9364638121583;1111.4831603304501;1121.8709848367541;1133.070881876853;1218.2124251690939;15206.315715792816;15323.463678771412;15372.077675339291;15625;15854.682906341808;642395.3640695394;647662;648564;649159;649600.751996237;58776;59296;59362.40637593655;59446.811063778485;59498.810023799284;1318.064174436161;1441.4954765831874;1465.1868213141809;1471.4849802569015;1487.375302373006;28754.424911501235;30043.69956300409;30226.070610820454;30338.241543961576;30974;41395;50592.74703626458;50653.24020139627;50707.98584028373;50834.49165508297;506999;507606;509960;510276;510619;25561;26941.191764247105;26957.595636065867;26977.0558030468;26986.865065674545;44507.55492445164;44572.77713611411;45556.77221613871;46206.76896615495;46861.76569117133;36287.455688165224;37189.81405092957;37224;37985;38133.61866381301;30397.848010759804;36364.90905272846;36695.81652091722;37394;37666.43500347442;36273.455898161024;36641.45037824381;37028.07429814276;37928;38101.42847857229;32500.674993250712;32603.02190934276;33084.834575826964;33156.50265245975;33383.33233335271;24268.75731242665;24725.258242252763;24955.251342459756;25080;25163.370915727253 08:02:51 INFO - PROCESS | 1939 | |2;jquery.html;389.55979742890537;395.13071243264824;397.35429926369665;400.6434273496587;423.2107120220787;20130.194792208313;20264.797352026475;21265.681014784772;21281.82949937755;21406.14375424981;19835.305764298253;20196.158724768542;20332.59334813303;20829.68755468669;20833;5639.323281206251;5852.853678658046;5895;5896.437444077323;5910.202122713443;5361.571074314065;5674.18426103647;5835.5915085944;5910.556708246877;5937.22816033915;5734.684592347419;5780.161876527901;5787.21872547207;5792.855178620547;5794.768209271645;5082.872928176806;5516.503514683698;5773.653580785166;5793.304803423574;5803.303603567557;5054.393472783253;5091.134507133796;5092.363454568179;5095.87260318495;5102.183650615883;23922.760772392226;24707;25325.366865828317;25333.24000279976;25415.745842541524;2158.32012915931;2178.2049551913437;2395.449046719247;2399.796017338528;2609.6216048673027;14361.784110853037;14501.938042427735;14563;14582.343794529272;14618.853811461855;1689.780328557292;1746.8515176210037;1767.8408943195175;1788.937387191442;1796.5778042160082;115539.4223028888;118638;118764.40617796942;119426;121182.18226726606;10464.162866970624;10645.467726613793;10903.01872831449;10959.890401096047;11076.778464430667;13757.931210343884;13834.93082534581;13851.861481385258;13856.307184640727;13862;8365.372597055246;8571.871421928676;8590.484570925764;8697.652093916173;8700.651973921098;8529;8544.974603047674;8547.95726021366;8656.047834738174;8685.826283474295;6383.680815959184;6402.679866006774;6514.641694706742;6556.082148499214;6557.540972131905;1632.5428879913406;1878.1360574136052;1880.2948894164688;1887.3582981786258;1933.2267093162566;978.6183388478499;985.5318723606373;991.5389343954952;1006.33079002464;1048.7745134796055;14925;18365.5408614783;19956.70064949027;20363.898180509004;20816.95915204234;940.2243149401771;966.4153187321709;971.7570607348163;1049.9527521261562;1085.310827624464;8795.252403545643;10800.792864222001;10973.451327433597;11084.833727494093;11095.667129986114;592572.0371398116;594224;609659;610583;613696;103788;114288;116914.41542792402;117690.41154794171;120066;1062.8405739139346;1097.3635291530893;1126.0541145437871;1128.3173679923693;1129.949152288149;14692.41230350774;17643.47069587863;18264.995425251207;18477.445676629723;19117.808821911603;51464.22803657873;52073.698157546365;52783;52963;53371.13201037945;71979;72356;72356;72547.18632034241;73722;7632.38940884739;7840.431913617185;7959.761207163794;8030.5181689098845;8171.305439037748;8743.519106449206;10474.476276186313;11378;11584.826227606423;12068;10309.175265978554;10617.577434401614;10700.83948740754;10755.193360497837;10829.67510974672;10218.795624087328;10314.329568578018;10406.406706084754;10557.9472102639;10875.347479151276;10502.579896804042;10691.625793097515;10742.086922611665;10836.620718274822;10861.945690271497;7709.421793365632;7839.608019599112;7948.523088614702;8002.2797948182615;8050.5169689818795;10437.277849155109;10480.63317783874;10658.294672852617;10733.248672593156;10742.624008159677 08:02:51 INFO - PROCESS | 1939 | |3;mootools.html;1410.2173293821938;1575.3068649794086;1614.991925040375;1810.900400477974;1932.2560814086573;7516.887246691297;7543.207963163857;7845.450818442711;8660.566971651417;8934.86597701035;8617.353698472609;8859.512726800023;8859.734207973772;8866.556672166382;8880.378373513857;2407.458321877581;2432.7080750309988;2440.9023639054417;2442.1086303499183;2444.0590372706456;2370.7984821290124;2421.2494126820743;2422.515496900618;2422.685050943375;2423.309356833303;2363.3382652857226;2376.6197408414655;2398.0887262840097;2404.1825779235055;2404.4469771952463;2357.0571771291447;2383.3088404362716;2388.235764555343;2391.015457170553;2393.401649587603;2301.97698023021;2375.0381095656285;2380.571497130516;2382.8808559571953;2385.8687772172525;7838.176991415876;8086.716964906259;8848.336374771918;8893.977192622866;8904.28765698749;5234.293370395024;5425.782968681248;5437.102878025121;5447.782088716446;5477.315335583052;3429.639887811769;3539.4159963605975;3569.089882080079;3585.94621080684;3587.5336206293277;1026.2559644257929;1041.463646222196;1042.99478502607;1044.7231483656838;1048.1405247696891;11729.70675733105;11880.40597970098;11880.584179553674;11897.345645989553;11908.523659053628;4015.7791321477016;4802.495737947465;4940.382452193476;4941.6293777966075;4942.950570494249;8554.828903422022;10312.896871031344;10323.48382580868;10338.689839304832;10342.37945723259;5511.696856672841;5511.751971161308;5512.972435137799;5514.751836167382;5534.916976245359;4798.1123492154065;4916.06594746997;4922.827701030446;4957.553820156203;4987.152184128707;3831.0653120174134;3845.596212397658;3906.374980003186;3947.50656167979;3959.3269144245546;1291.044626976031;1292.1730092740654;1294.5922034559085;1298.1626850681257;1314.8553659097493;813.2111851504031;903.823754367905;921.5392303848076;941.9011003844635;965.034965034965;5912.438318359749;5937;6085.8478538036015;6106.480949119375;6177.289611694623;437.7482947305312;438.52201100800283;451.77185521311526;463.58045968398653;463.7797046402932;3906.7851268179957;3952.486176796998;4002.539707933625;4027.7180597357906;4033.4353190553347;10262.281640285257;10440.42577658236;10680.946595267129;10732.570697172181;10905.509252083675;10768.623098191525;10780.029797318279;10801.999999999844;10883.619073332553;10913;7941.920580794118;8306.6677332906;8307.460015098999;8308.5430301334;8418.116097809765;4569.748663823521;5793.797217097382;5895;5909.734061967308;5948.5538584605465;8011.599420029092;10087.193024557871;10125.493725313558;10265.589376425158;10268.229882758673;7719.382449403922;10257.230707696803;10373.118284945864;10374.066334029974;10511.526981285708;7315.341619254293;7481.102267727908;7578.772636820904;7584.241575842371;7603.2776886195725;8387.706430274911;8423.747287581382;8491.95754021226;8492.915070849212;8502.277306428776;7380.630968451448;7560.395168386406;7570.621468926422;7681.692732290646;7702.306792388712;7404.592747398729;7485.251474852471;7535.133459651991;7668.424868135024;7699.538027718355;7024.5434046786795;7091.858162836817;7101.431885449255;7115.288471152843;7127.7148914042855;4687.250039993584;4957.157283261782;4999.675021123615;5006.674566153189;5059.089363914383;4910.484399138039;5002.074616196015;5032.421271553818;5066.417362003417;5141.331626888481 08:02:51 INFO - PROCESS | 1939 | |4;prototype.html;305.1471138168818;307.898393530135;319.4393838812884;323.9514072889068;325.1513549635451;4803.006752197843;5148.510891465314;5455.745178608918;5479.369872464667;5519.475649813261;4863.465018847924;5082.923756143651;5097.171070056117;5219.42586315505;5370.731463426832;4031.5766844481345;4041.4746083009168;4082.7958602069925;4087;4113.650339721127;3283.7044665980056;4066.1867626474677;4101.241270364977;4121.278776214165;4172.958270417287;3425.588929328484;3777.924441511168;3824.827882745283;4041.575634558373;4089.8159582818694;4005.9399109013098;4009.578994205625;4063.512378514597;4075.473206611905;4098.90532647852;3239.919002024945;3389.254364039907;3528.6471352864764;3542.220711443478;3550.3254381667402;4900.779464924051;5191.5846732261325;5217.947820521784;5221.25883910946;5225.399079105913;4263.467066616673;4318.935215971763;4551.886202844922;4586.3808385867815;4606.631469482433;5601.971990140064;5653.406392328788;5672.631278966854;5686.15692153923;5687.099579214589;3403.9829800851085;3701.5002974598624;3849.1716434693576;3849.8642900344184;3866.961330386688;5263.973680131575;5925.703714814328;5928.347881733006;5946.345901950782;5948.286205655306;3456.395130852091;3620.855165793339;3894.766314021168;3899.4930659014713;3919.431682406035;3811.5616704079384;3874.5156855393075;3974.330781071899;3992.2414741199077;4019.7588144711412;4479.3057076153245;4743.193657078305;4784.30627559002;4801.95198048022;4812.446568644651;5218.582513398919;5589.413111623221;5673.971630141823;5765.443330300879;5787.858492357855;1190.4880901212389;1307.3985966455537;1410.7883817427469;1410.978835317471;1418.8935829812806;774.8140446292962;799.476342995339;840.2563731098022;909.231699214163;909.8899342801772;4352.824737320906;4584.7936842842155;5145.867909060001;5316.255724198615;5325.295905310341;5230.750777165779;5399.757010934517;5804.506616937523;5829;5860.355360910296;4394.340848872631;4782.9150198122015;5141.485851414828;5201.257578711098;5286.682799031993;2614.281072704983;2667.2662769200174;2727.8772455239564;2858.6426696662916;3038.936372269732;5175.534201921845;5855.795047173328;5957.195778569838;6014.6391216527145;6019.668918209453;4795.688280261772;5542.806001789918;5803.941960580424;6162.907556386658;6167.620989097702;5336.306280183551;5857.619254748428;6195.96902015487;6225.62646241227;6228.159198508325;4871.853844384674;5533.616595851037;5699.5155411790465;5947.375525569752;5985.461308482258;4936.481669424658;5109.8978020438635;5849;5874.177615133799;5881.32364778056;5679.204911312502;6151.3541078186145;6200.100985357008;6209;6217.844553886189;3346.8326583671205;3681.7422780405113;3958.327084395602;3962.7424217425773;3968.5634580196734;4859.602060135583;4917.672228498214;5307.256984022317;5855.7364918579615;5951.970240148771;5273.841784746463;5294.538039811506;5672.745440919311;5684.459976302244;5762.501749545065;5408.13469844823;5468.261784659179;5739.799107031234;5752.252207213036;5778.595498315078;5207.3230480037355;5232.947670523246;5515.365732940763;5565.714399912126;5587.329520457572;4669.762512934008;5501.312335958005;5582.665040097445;5679.971600141973;5705.231378272749;5174.353205849269;5383.0041442333295;5477.972610136924;5657.4908258256955;5671.262735844314 08:02:51 INFO - PROCESS | 1939 | |5;yui.html;658.7891874600128;738.2801768275929;758.5941521286113;763.9159692433831;791.2364568191698;15096.622584435394;15715.685686286268;15722.135282559455;15742.370305187793;15783.131927743969;14325.212113333762;14389.784153237684;14417.567472975827;14428.42286308549;15291.770623440658;775.8176828445311;915.8671992561075;923.7690577355661;925.5372313843078;925.9212966897788;901.2969883490888;910.2217603948628;916.5325683901225;920.2959735802117;920.5259291464868;833.3374966901298;960.5533426956464;969.3311614985672;969.8690676758653;971.028971028971;959.8800149981253;960.2030314838737;962.4850704872895;962.5187406296851;963.0706368354537;714.921358650548;714.974975875847;716.8530451257479;717.2719689515146;717.8241330873917;12651.557195498206;15236.542903712907;15269.694606107816;15273.465428710053;15276.694466110726;10288.588456461732;11301.434928253637;11397.088232941345;12257.080718946025;12286.754264914742;9865.062819032251;9980.451075190862;10182.592696292213;10430.426326552037;10711;3396.473546600256;3444.7416443766565;3528.8412021459094;3543.6102028776813;3647.7993710345922;56330.186395545876;61358.69320653413;64370.356296436905;67609.3239067608;71211.99999999948;9282.220225616478;9438.622455101857;9489.288303377274;9562.235021198287;9772.16936560386;20916.058777355054;20930.37208883736;20961.47596310074;20961.58076838455;20981.482222133443;7496.850062998709;7571;7623.923760762433;7628.46600737943;7691.038620172479;3991.830579399684;4071.1043570414463;4072.0430698786354;4090.877273681794;4103.835846566164;3509.94735078974;3593.94609080864;3596.0829988353325;3598.8200589970397;3601;610.185402487675;614.8339948213957;622.1259130921063;632.6871741137159;641.791417789211;485.27936015017673;554.1099794315105;559.0245022435876;593.4510577715596;602.1359349333633;8092.271695547429;8183.508989460652;8195.918040819515;8223.753287401387;8350.498970061693;501.95231453011905;573.4580821123998;611.8378629663143;612.6293592376549;628.4218518962565;3364.5794275715534;3694.7413681042067;3857.8842634721004;3859.459675645412;3902.8829135125993;76761.46477070427;80667.7899831503;81792.59103704563;83321;83846.74229886435;61527.76944461175;65813.67093164599;77773.44453111019;78559.60720196362;78886;70332.648336758;72799;72858;73481;74066;8303.6678532858;8309.875351869849;8316.767053378066;8458.196471335214;8519.659213631385;43560.91097722582;43664.56335436605;44401.445949391615;45324.32013519734;45870.31123515652;54319;63287.734245315754;63325.050124249094;63638.68180659067;64045.35954640394;10826.837597435888;10827.566897324019;10838.620648277272;11009;11284.887151128713;11853.051755859338;11919.702007449536;12432.378381081091;12911.869541525542;13089.280089594782;11590.88409115898;11860.881391186324;12006;12114.273143611059;12363.876361236273;11049.447527623748;11408.828867566826;11499.655010349703;11681;12381.690457738629;11193.496292667012;11272.041876440595;11667;11748.823767643322;12354.876451235372;8517.449252815844;9497.572609232462;9506.95246523763;9610.231181505602;9627.518624068629;8285.4200205985;8439.691847763606;8767.363271237731;8805.496195402417;9517.619295228113 08:02:51 INFO - PROCESS | 1939 | __end_tp_report 08:02:51 INFO - PROCESS | 1939 | __start_cc_report 08:02:51 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,1122 08:02:51 INFO - PROCESS | 1939 | __end_cc_report 08:02:51 INFO - PROCESS | 1939 | __startTimestamp1462978971932__endTimestamp 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | ------- Summary: start ------- 08:02:51 INFO - PROCESS | 1939 | Number of tests: 6 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#0] dojo.html Cycles:165 Average:5652.40 Median:5893.62 stddev:636.85 (10.8%) stddev-sans-first:612.48 08:02:51 INFO - PROCESS | 1939 | Values: 3342.3 3356.3 3391.3 3392.1 3435.6 5523.1 5538.7 5549.4 5679.3 5713.7 5580.6 5656.1 5665.3 5670.1 5687.5 3935.5 5080.9 5096.3 5161.5 5163.7 5007.6 5224.9 5225.5 5242.9 5292.6 5211.2 5225.5 5236.4 5242.3 5283.3 5162.4 5164.4 5193.1 5195.9 5212.0 4905.6 5017.1 5036.3 5038.6 5096.5 5444.3 5444.6 5466.9 5560.6 5562.9 6145.2 6148.9 6153.4 6158.2 6163.1 6000.8 6099.7 6100.9 6219.5 6254.8 5683.8 5729.1 5764.3 5769.0 5782.9 6262.7 6266.9 6269.2 6271.0 6274.9 5924.4 5995.1 6037.8 6045.1 6072.7 6140.4 6217.5 6222.5 6227.9 6229.1 6115.8 6152.0 6152.0 6158.8 6173.4 6010.5 6064.9 6066.9 6149.3 6185.2 4899.4 4914.8 4927.2 4932.2 4933.8 4309.1 4328.8 4347.5 4351.5 4367.7 5612.4 5626.6 5634.8 5639.7 5648.0 5975.4 6074.1 6227.9 6235.9 6240.6 5516.7 5523.1 5538.3 5546.9 5666.6 5361.9 5368.7 5369.8 5380.5 5386.1 6277.5 6286.6 6290.7 6303.6 6326.2 4726.2 6034.7 6104.5 6119.4 6139.0 6264.3 6271.4 6276.6 6278.2 6284.9 6064.5 6075.4 6085.0 6127.9 6144.1 6042.4 6045.7 6065.7 6113.6 6167.7 5944.4 5954.6 5961.8 5964.0 5968.7 5865.8 5968.1 5971.4 5984.1 6002.4 5618.2 5756.1 5762.0 5911.3 5912.7 5940.8 5985.2 6012.9 6040.4 6044.7 5696.4 5698.0 5893.6 5894.9 5919.5 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#1] ext.html Cycles:180 Average:68995.58 Median:36280.46 stddev:148509.55 (409.3%) stddev-sans-first:148837.12 08:02:51 INFO - PROCESS | 1939 | Values: 503.6 519.9 535.0 553.3 566.1 29546.3 41416.8 42408.6 42447.0 44245.3 42929.6 43179.8 43257.4 43308.4 43372.3 7434.2 9662.6 9702.3 9858.1 9859.7 8565.7 11438.5 11657.2 11666.4 11741.6 11647.7 11689.6 11728.3 11745.0 11747.9 8567.9 8746.1 8757.8 11656.4 11729.4 11358.5 11684.2 11699.5 11737.6 11741.6 39615.4 39915.8 40060.2 40077.8 40107.6 3079.5 3369.2 3388.4 3396.0 3746.5 27359.3 29414.6 29899.1 29946.0 29965.0 2673.9 2772.0 2820.2 2825.7 2923.0 497751.0 500047.0 500386.0 500490.0 501045.0 63762.7 64383.4 64438.7 64439.4 64480.7 67391.0 67397.0 67519.7 67528.0 67595.0 15331.2 16990.6 17262.5 17279.8 17351.5 11423.9 11662.5 12993.3 13249.7 13256.5 16073.0 16887.8 19351.1 19419.7 19465.6 6692.6 7796.3 7858.9 8065.0 8109.6 932.5 990.4 1000.7 1016.5 1041.3 29518.0 29838.7 29909.6 30099.5 30401.7 1058.9 1111.5 1121.9 1133.1 1218.2 15206.3 15323.5 15372.1 15625.0 15854.7 642395.4 647662.0 648564.0 649159.0 649600.8 58776.0 59296.0 59362.4 59446.8 59498.8 1318.1 1441.5 1465.2 1471.5 1487.4 28754.4 30043.7 30226.1 30338.2 30974.0 41395.0 50592.7 50653.2 50708.0 50834.5 506999.0 507606.0 509960.0 510276.0 510619.0 25561.0 26941.2 26957.6 26977.1 26986.9 44507.6 44572.8 45556.8 46206.8 46861.8 36287.5 37189.8 37224.0 37985.0 38133.6 30397.8 36364.9 36695.8 37394.0 37666.4 36273.5 36641.5 37028.1 37928.0 38101.4 32500.7 32603.0 33084.8 33156.5 33383.3 24268.8 24725.3 24955.3 25080.0 25163.4 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#2] jquery.html Cycles:180 Average:34591.54 Median:21343.99 stddev:100433.97 (470.5%) stddev-sans-first:100682.88 08:02:51 INFO - PROCESS | 1939 | Values: 389.6 395.1 397.4 400.6 423.2 20130.2 20264.8 21265.7 21281.8 21406.1 19835.3 20196.2 20332.6 20829.7 20833.0 5639.3 5852.9 5895.0 5896.4 5910.2 5361.6 5674.2 5835.6 5910.6 5937.2 5734.7 5780.2 5787.2 5792.9 5794.8 5082.9 5516.5 5773.7 5793.3 5803.3 5054.4 5091.1 5092.4 5095.9 5102.2 23922.8 24707.0 25325.4 25333.2 25415.7 2158.3 2178.2 2395.4 2399.8 2609.6 14361.8 14501.9 14563.0 14582.3 14618.9 1689.8 1746.9 1767.8 1788.9 1796.6 115539.4 118638.0 118764.4 119426.0 121182.2 10464.2 10645.5 10903.0 10959.9 11076.8 13757.9 13834.9 13851.9 13856.3 13862.0 8365.4 8571.9 8590.5 8697.7 8700.7 8529.0 8545.0 8548.0 8656.0 8685.8 6383.7 6402.7 6514.6 6556.1 6557.5 1632.5 1878.1 1880.3 1887.4 1933.2 978.6 985.5 991.5 1006.3 1048.8 14925.0 18365.5 19956.7 20363.9 20817.0 940.2 966.4 971.8 1050.0 1085.3 8795.3 10800.8 10973.5 11084.8 11095.7 592572.0 594224.0 609659.0 610583.0 613696.0 103788.0 114288.0 116914.4 117690.4 120066.0 1062.8 1097.4 1126.1 1128.3 1129.9 14692.4 17643.5 18265.0 18477.4 19117.8 51464.2 52073.7 52783.0 52963.0 53371.1 71979.0 72356.0 72356.0 72547.2 73722.0 7632.4 7840.4 7959.8 8030.5 8171.3 8743.5 10474.5 11378.0 11584.8 12068.0 10309.2 10617.6 10700.8 10755.2 10829.7 10218.8 10314.3 10406.4 10557.9 10875.3 10502.6 10691.6 10742.1 10836.6 10861.9 7709.4 7839.6 7948.5 8002.3 8050.5 10437.3 10480.6 10658.3 10733.2 10742.6 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#3] mootools.html Cycles:180 Average:5714.94 Median:4800.30 stddev:3213.14 (66.9%) stddev-sans-first:3205.87 08:02:51 INFO - PROCESS | 1939 | Values: 1410.2 1575.3 1615.0 1810.9 1932.3 7516.9 7543.2 7845.5 8660.6 8934.9 8617.4 8859.5 8859.7 8866.6 8880.4 2407.5 2432.7 2440.9 2442.1 2444.1 2370.8 2421.2 2422.5 2422.7 2423.3 2363.3 2376.6 2398.1 2404.2 2404.4 2357.1 2383.3 2388.2 2391.0 2393.4 2302.0 2375.0 2380.6 2382.9 2385.9 7838.2 8086.7 8848.3 8894.0 8904.3 5234.3 5425.8 5437.1 5447.8 5477.3 3429.6 3539.4 3569.1 3585.9 3587.5 1026.3 1041.5 1043.0 1044.7 1048.1 11729.7 11880.4 11880.6 11897.3 11908.5 4015.8 4802.5 4940.4 4941.6 4943.0 8554.8 10312.9 10323.5 10338.7 10342.4 5511.7 5511.8 5513.0 5514.8 5534.9 4798.1 4916.1 4922.8 4957.6 4987.2 3831.1 3845.6 3906.4 3947.5 3959.3 1291.0 1292.2 1294.6 1298.2 1314.9 813.2 903.8 921.5 941.9 965.0 5912.4 5937.0 6085.8 6106.5 6177.3 437.7 438.5 451.8 463.6 463.8 3906.8 3952.5 4002.5 4027.7 4033.4 10262.3 10440.4 10680.9 10732.6 10905.5 10768.6 10780.0 10802.0 10883.6 10913.0 7941.9 8306.7 8307.5 8308.5 8418.1 4569.7 5793.8 5895.0 5909.7 5948.6 8011.6 10087.2 10125.5 10265.6 10268.2 7719.4 10257.2 10373.1 10374.1 10511.5 7315.3 7481.1 7578.8 7584.2 7603.3 8387.7 8423.7 8492.0 8492.9 8502.3 7380.6 7560.4 7570.6 7681.7 7702.3 7404.6 7485.3 7535.1 7668.4 7699.5 7024.5 7091.9 7101.4 7115.3 7127.7 4687.3 4957.2 4999.7 5006.7 5059.1 4910.5 5002.1 5032.4 5066.4 5141.3 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#4] prototype.html Cycles:180 Average:4576.97 Median:5183.56 stddev:1431.41 (27.6%) stddev-sans-first:1399.05 08:02:51 INFO - PROCESS | 1939 | Values: 305.1 307.9 319.4 324.0 325.2 4803.0 5148.5 5455.7 5479.4 5519.5 4863.5 5082.9 5097.2 5219.4 5370.7 4031.6 4041.5 4082.8 4087.0 4113.7 3283.7 4066.2 4101.2 4121.3 4173.0 3425.6 3777.9 3824.8 4041.6 4089.8 4005.9 4009.6 4063.5 4075.5 4098.9 3239.9 3389.3 3528.6 3542.2 3550.3 4900.8 5191.6 5217.9 5221.3 5225.4 4263.5 4318.9 4551.9 4586.4 4606.6 5602.0 5653.4 5672.6 5686.2 5687.1 3404.0 3701.5 3849.2 3849.9 3867.0 5264.0 5925.7 5928.3 5946.3 5948.3 3456.4 3620.9 3894.8 3899.5 3919.4 3811.6 3874.5 3974.3 3992.2 4019.8 4479.3 4743.2 4784.3 4802.0 4812.4 5218.6 5589.4 5674.0 5765.4 5787.9 1190.5 1307.4 1410.8 1411.0 1418.9 774.8 799.5 840.3 909.2 909.9 4352.8 4584.8 5145.9 5316.3 5325.3 5230.8 5399.8 5804.5 5829.0 5860.4 4394.3 4782.9 5141.5 5201.3 5286.7 2614.3 2667.3 2727.9 2858.6 3038.9 5175.5 5855.8 5957.2 6014.6 6019.7 4795.7 5542.8 5803.9 6162.9 6167.6 5336.3 5857.6 6196.0 6225.6 6228.2 4871.9 5533.6 5699.5 5947.4 5985.5 4936.5 5109.9 5849.0 5874.2 5881.3 5679.2 6151.4 6200.1 6209.0 6217.8 3346.8 3681.7 3958.3 3962.7 3968.6 4859.6 4917.7 5307.3 5855.7 5952.0 5273.8 5294.5 5672.7 5684.5 5762.5 5408.1 5468.3 5739.8 5752.3 5778.6 5207.3 5232.9 5515.4 5565.7 5587.3 4669.8 5501.3 5582.7 5680.0 5705.2 5174.4 5383.0 5478.0 5657.5 5671.3 08:02:51 INFO - PROCESS | 1939 | 08:02:51 INFO - PROCESS | 1939 | [#5] yui.html Cycles:180 Average:17135.02 Median:30986.76 stddev:23128.45 (74.6%) stddev-sans-first:23160.24 08:02:51 INFO - PROCESS | 1939 | Values: 658.8 738.3 758.6 763.9 791.2 15096.6 15715.7 15722.1 15742.4 15783.1 14325.2 14389.8 14417.6 14428.4 15291.8 775.8 915.9 923.8 925.5 925.9 901.3 910.2 916.5 920.3 920.5 833.3 960.6 969.3 969.9 971.0 959.9 960.2 962.5 962.5 963.1 714.9 715.0 716.9 717.3 717.8 12651.6 15236.5 15269.7 15273.5 15276.7 10288.6 11301.4 11397.1 12257.1 12286.8 9865.1 9980.5 10182.6 10430.4 10711.0 3396.5 3444.7 3528.8 3543.6 3647.8 56330.2 61358.7 64370.4 67609.3 71212.0 9282.2 9438.6 9489.3 9562.2 9772.2 20916.1 20930.4 20961.5 20961.6 20981.5 7496.9 7571.0 7623.9 7628.5 7691.0 3991.8 4071.1 4072.0 4090.9 4103.8 3509.9 3593.9 3596.1 3598.8 3601.0 610.2 614.8 622.1 632.7 641.8 485.3 554.1 559.0 593.5 602.1 8092.3 8183.5 8195.9 8223.8 8350.5 502.0 573.5 611.8 612.6 628.4 3364.6 3694.7 3857.9 3859.5 3902.9 76761.5 80667.8 81792.6 83321.0 83846.7 61527.8 65813.7 77773.4 78559.6 78886.0 70332.6 72799.0 72858.0 73481.0 74066.0 8303.7 8309.9 8316.8 8458.2 8519.7 43560.9 43664.6 44401.4 45324.3 45870.3 54319.0 63287.7 63325.1 63638.7 64045.4 10826.8 10827.6 10838.6 11009.0 11284.9 11853.1 11919.7 12432.4 12911.9 13089.3 11590.9 11860.9 12006.0 12114.3 12363.9 11049.4 11408.8 11499.7 11681.0 12381.7 11193.5 11272.0 11667.0 11748.8 12354.9 8517.4 9497.6 9507.0 9610.2 9627.5 8285.4 8439.7 8767.4 8805.5 9517.6 08:02:51 INFO - PROCESS | 1939 | -------- Summary: end -------- 08:02:51 INFO - PROCESS | 1939 | 08:02:52 INFO - TEST-INFO | 1939: exit 0 08:02:52 INFO - TEST-OK | dromaeo_css | took 1115595ms 08:02:52 INFO - TEST-START | kraken 08:02:52 INFO - operating with platform_type : mac_ 08:02:52 INFO - Initialising browser for kraken test... 08:02:52 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpae6G2T/profile http://localhost:55564/getInfo.html) 08:02:54 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 08:02:54 INFO - PROCESS | 1943 | colorDepth:24 08:02:54 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 08:02:54 INFO - PROCESS | 1943 | __metrics 08:02:54 INFO - PROCESS | 1943 | 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" 08:02:54 ERROR - PROCESS | 1943 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:02:55 INFO - TEST-INFO | 1943: exit 0 08:02:55 INFO - Browser initialized. 08:02:55 INFO - Running cycle 1/1 for kraken test... 08:02:55 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpae6G2T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:07 INFO - PROCESS | 1945 | RSS: Main: 238698496 08:03:07 INFO - PROCESS | 1945 | 08:03:10 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55564/tests/kraken/driver.html?testName=audio-beat-detection) 08:03:11 INFO - PROCESS | 1945 | RSS: Main: 287432704 08:03:11 INFO - PROCESS | 1945 | 08:03:13 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55564/tests/kraken/driver.html?testName=audio-dft) 08:03:14 INFO - PROCESS | 1945 | RSS: Main: 295690240 08:03:14 INFO - PROCESS | 1945 | 08:03:18 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55564/tests/kraken/driver.html?testName=audio-fft) 08:03:18 INFO - PROCESS | 1945 | RSS: Main: 385454080 08:03:18 INFO - PROCESS | 1945 | 08:03:20 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55564/tests/kraken/driver.html?testName=audio-oscillator) 08:03:21 INFO - PROCESS | 1945 | RSS: Main: 284688384 08:03:21 INFO - PROCESS | 1945 | 08:03:23 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55564/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:03:24 INFO - PROCESS | 1945 | RSS: Main: 288698368 08:03:24 INFO - PROCESS | 1945 | 08:03:27 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55564/tests/kraken/driver.html?testName=imaging-darkroom) 08:03:27 INFO - PROCESS | 1945 | RSS: Main: 326144000 08:03:27 INFO - PROCESS | 1945 | 08:03:30 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55564/tests/kraken/driver.html?testName=imaging-desaturate) 08:03:31 INFO - PROCESS | 1945 | RSS: Main: 328396800 08:03:31 INFO - PROCESS | 1945 | 08:03:34 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55564/tests/kraken/driver.html?testName=json-parse-financial) 08:03:35 INFO - PROCESS | 1945 | RSS: Main: 325439488 08:03:35 INFO - PROCESS | 1945 | 08:03:36 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55564/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:03:37 INFO - PROCESS | 1945 | RSS: Main: 315953152 08:03:37 INFO - PROCESS | 1945 | 08:03:39 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:03:40 INFO - PROCESS | 1945 | RSS: Main: 323686400 08:03:40 INFO - PROCESS | 1945 | 08:03:42 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:03:43 INFO - PROCESS | 1945 | RSS: Main: 324882432 08:03:43 INFO - PROCESS | 1945 | 08:03:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:03:46 INFO - PROCESS | 1945 | RSS: Main: 329129984 08:03:46 INFO - PROCESS | 1945 | 08:03:49 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:03:50 INFO - PROCESS | 1945 | RSS: Main: 326799360 08:03:50 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:55564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55564/tests/kraken/driver.html?testName=ai-astar) 08:03:52 INFO - PROCESS | 1945 | RSS: Main: 373886976 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | __start_tp_report 08:03:52 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 08:03:52 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 08:03:52 INFO - PROCESS | 1945 | |i|pagename|runs| 08:03:52 INFO - PROCESS | 1945 | |0;ai-astar;113;105;110;111;111;111;112;112;111;111 08:03:52 INFO - PROCESS | 1945 | |1;audio-beat-detection;162;120;142;196;116;138;136;117;168;117 08:03:52 INFO - PROCESS | 1945 | |2;audio-dft;188;185;186;186;186;217;244;185;182;187 08:03:52 INFO - PROCESS | 1945 | |3;audio-fft;80;73;57;126;56;62;55;58;110;58 08:03:52 INFO - PROCESS | 1945 | |4;audio-oscillator;90;101;135;79;79;77;103;80;99;128 08:03:52 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;87;84;87;85;86;84;87;85;87;83 08:03:52 INFO - PROCESS | 1945 | |6;imaging-darkroom;93;93;94;94;93;93;94;93;94;93 08:03:52 INFO - PROCESS | 1945 | |7;imaging-desaturate;86;88;86;83;86;86;85;89;85;85 08:03:52 INFO - PROCESS | 1945 | |8;json-parse-financial;60;61;59;57;59;57;57;120;56;56 08:03:52 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;62;68;79;74;70;67;67;69;67;69 08:03:52 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;85;83;84;83;83;85;78;82;83;77 08:03:52 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;138;138;148;139;139;153;144;150;237;140 08:03:52 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;170;168;166;167;169;169;169;166;165;166 08:03:52 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;67;67;67;68;66;66;66;65;68;65 08:03:52 INFO - PROCESS | 1945 | __end_tp_report 08:03:52 INFO - PROCESS | 1945 | __start_cc_report 08:03:52 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,2253 08:03:52 INFO - PROCESS | 1945 | __end_cc_report 08:03:52 INFO - PROCESS | 1945 | __startTimestamp1462979032278__endTimestamp 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | ------- Summary: start ------- 08:03:52 INFO - PROCESS | 1945 | Number of tests: 14 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.16 (1.9%) stddev-sans-first:2.13 08:03:52 INFO - PROCESS | 1945 | Values: 113.0 105.0 110.0 111.0 111.0 111.0 112.0 112.0 111.0 111.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:140.00 stddev:26.77 (19.1%) stddev-sans-first:27.31 08:03:52 INFO - PROCESS | 1945 | Values: 162.0 120.0 142.0 196.0 116.0 138.0 136.0 117.0 168.0 117.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:194.60 Median:186.50 stddev:20.02 (10.7%) stddev-sans-first:21.10 08:03:52 INFO - PROCESS | 1945 | Values: 188.0 185.0 186.0 186.0 186.0 217.0 244.0 185.0 182.0 187.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.00 stddev:25.09 (43.3%) stddev-sans-first:26.50 08:03:52 INFO - PROCESS | 1945 | Values: 80.0 73.0 57.0 126.0 56.0 62.0 55.0 58.0 110.0 58.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:79.00 stddev:20.67 (26.2%) stddev-sans-first:21.77 08:03:52 INFO - PROCESS | 1945 | Values: 90.0 101.0 135.0 79.0 79.0 77.0 103.0 80.0 99.0 128.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.51 (1.7%) stddev-sans-first:1.50 08:03:52 INFO - PROCESS | 1945 | Values: 87.0 84.0 87.0 85.0 86.0 84.0 87.0 85.0 87.0 83.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 08:03:52 INFO - PROCESS | 1945 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 93.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.76 08:03:52 INFO - PROCESS | 1945 | Values: 86.0 88.0 86.0 83.0 86.0 86.0 85.0 89.0 85.0 85.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:58.00 stddev:19.68 (33.9%) stddev-sans-first:20.81 08:03:52 INFO - PROCESS | 1945 | Values: 60.0 61.0 59.0 57.0 59.0 57.0 57.0 120.0 56.0 56.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:4.57 (6.6%) stddev-sans-first:4.03 08:03:52 INFO - PROCESS | 1945 | Values: 62.0 68.0 79.0 74.0 70.0 67.0 67.0 69.0 67.0 69.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:2.71 (3.3%) stddev-sans-first:2.69 08:03:52 INFO - PROCESS | 1945 | Values: 85.0 83.0 84.0 83.0 83.0 85.0 78.0 82.0 83.0 77.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:146.00 stddev:30.15 (20.6%) stddev-sans-first:31.51 08:03:52 INFO - PROCESS | 1945 | Values: 138.0 138.0 148.0 139.0 139.0 153.0 144.0 150.0 237.0 140.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.50 stddev:1.72 (1.0%) stddev-sans-first:1.56 08:03:52 INFO - PROCESS | 1945 | Values: 170.0 168.0 166.0 167.0 169.0 169.0 169.0 166.0 165.0 166.0 08:03:52 INFO - PROCESS | 1945 | 08:03:52 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 08:03:52 INFO - PROCESS | 1945 | Values: 67.0 67.0 67.0 68.0 66.0 66.0 66.0 65.0 68.0 65.0 08:03:52 INFO - PROCESS | 1945 | -------- Summary: end -------- 08:03:52 INFO - PROCESS | 1945 | 08:03:53 INFO - TEST-INFO | 1945: exit 0 08:03:53 INFO - TEST-OK | kraken | took 60650ms 08:03:53 INFO - SUITE-END | took 1176s 08:03:53 INFO - Completed test suite (00:19:36) 08:03:53 INFO - javascript benchmark 08:03:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3342.331533693261, 3356.3287342531494, 3391.3217356528685, 3392.0841372829323, 3435.6220815710267, 5523.143912693529, 5538.695371754552, 5549.417311182328, 5679.290088738908, 5713.657180569164, 5580.637258578189, 5656.095024796034, 5665.320161580606, 5670.064439367499, 5687.459691329317, 3935.5080614923204, 5080.949190508103, 5096.337476128098, 5161.509656582619, 5163.7418129093585, 5007.649464537484, 5224.947750522484, 5225.477452254782, 5242.895142097156, 5292.55013323866, 5211.1922651989, 5225.477452254782, 5236.371635403757, 5242.3184985951775, 5283.339582552181, 5162.354705661793, 5164.431912489623, 5193.065248255312, 5195.870103247411, 5211.983663185681, 4905.583025442842, 5017.122003649347, 5036.294918711384, 5038.622103342264, 5096.490350964911, 5444.292242008513, 5444.646098003617, 5466.945330546683, 5560.582956278255, 5562.916556251659, 6145.170401995718, 6148.87702245957, 6153.384661533811, 6158.230221222348, 6163.1063495793305, 6000.789972350991, 6099.664518451484, 6100.938990610126, 6219.471344935686, 6254.843628909313, 5683.801066962677, 5729.1119876419225, 5764.279465066867, 5769.0, 5782.884342313214, 6262.718177682015, 6266.905996410058, 6269.153664255358, 6271.027990661455, 6274.937250627527, 5924.437178468039, 5995.100734889714, 6037.78867739627, 6045.063015232646, 6072.7278141455345, 6140.3552626974415, 6217.533684973645, 6222.502199824004, 6227.937720622826, 6229.0967809667345, 6115.816525504242, 6152.0, 6152.0, 6158.846028849314, 6173.413525715139, 6010.549208809357, 6064.878702426015, 6066.939330606726, 6149.3235744068115, 6185.165002724664, 4899.4365647950945, 4914.8034078637165, 4927.162382395001, 4932.21084626458, 4933.827316043993, 4309.052008558112, 4328.8268469260875, 4347.478302603645, 4351.499577548622, 4367.71609845359, 5612.410696876851, 5626.634268772516, 5634.830955071355, 5639.7180140993605, 5647.971760141255, 5975.372585878507, 6074.149619053336, 6227.937720622826, 6235.906461403082, 6240.594361366497, 5516.696581687966, 5523.088690366125, 5538.335399751975, 5546.889062218733, 5666.631668941588, 5361.892762144736, 5368.731563421813, 5369.812056578079, 5380.461953804666, 5386.111291636915, 6277.529185311211, 6286.559940804192, 6290.654014029273, 6303.621782693053, 6326.177596912372, 4726.220173671443, 6034.698265086816, 6104.481119104926, 6119.350229749377, 6138.970878344182, 6264.342244064308, 6271.43557079865, 6276.560640755103, 6278.183836101278, 6284.905726414199, 6064.484518815773, 6075.36208698098, 6085.0, 6127.877442451215, 6144.078388241621, 6042.3655516170165, 6045.667488288186, 6065.6663883486835, 6113.633182009094, 6167.6916154193, 5944.405559444021, 5954.612950158225, 5961.761529538943, 5963.970180149072, 5968.6717230551, 5865.824025279248, 5968.104784282393, 5971.373005834324, 5984.102384642339, 6002.399760023963, 5618.2415373924, 5756.050251708589, 5762.0, 5911.290645122613, 5912.674802885883, 5940.821775346746, 5985.221921150222, 6012.917674818575, 6040.395960403925, 6044.7279872406725, 5696.430356964271, 5698.0, 5893.616914900689, 5894.882102358015, 5919.526437884872], "value": 5612.404017627407, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.59712230215825, 519.890822927185, 535.0422743289512, 553.3498139685868, 566.06881679637, 29546.261343466424, 41416.79291603542, 42408.575914240886, 42447.0, 44245.336319955146, 42929.57070429295, 43179.78410107953, 43257.35113973285, 43308.350374744325, 43372.349414758726, 7434.219406962272, 9662.565184566694, 9702.272329575275, 9858.112769850712, 9859.654912078078, 8565.700200492984, 11438.48526816291, 11657.183997120204, 11666.41667916605, 11741.647750567497, 11647.6505704829, 11689.59086431975, 11728.296302221894, 11745.0, 11747.882521174764, 8567.871481927714, 8746.125387461203, 8757.781055473664, 11656.358900260482, 11729.413529323585, 11358.545658173663, 11684.240524365889, 11699.473523691455, 11737.58918437859, 11741.589044383405, 39615.405768913486, 39915.80042099771, 40060.19879602421, 40077.79961100205, 40107.59892401068, 3079.49474396413, 3369.157710572357, 3388.3731509670542, 3396.015155604872, 3746.4942232798494, 27359.316017099532, 29414.558781618292, 29899.10302690901, 29946.0, 29965.0, 2673.8796754145915, 2771.9722802771917, 2820.1680504251294, 2825.731555502224, 2923.035398318571, 497751.0, 500047.0, 500386.0, 500490.0, 501045.0, 63762.68118659377, 64383.356166437734, 64438.67780661066, 64439.355606444275, 64480.67759661265, 67391.0, 67397.0, 67519.66240168767, 67527.98708019384, 67595.0, 15331.156786376856, 16990.575235618962, 17262.482125536255, 17279.827201728072, 17351.4794556161, 11423.857614238676, 11662.533498660128, 12993.285369304589, 13249.73500529984, 13256.536021239403, 16072.9552579082, 16887.831121688625, 19351.12919918607, 19419.70811962652, 19465.610687786448, 6692.631905245258, 7796.337311328601, 7858.882116768253, 8064.983754061484, 8109.635066421907, 932.5477143585356, 990.4305024610877, 1000.6947880896397, 1016.5019140621042, 1041.2763129624839, 29518.0, 29838.701612983594, 29909.551356729666, 30099.548506772415, 30401.69598304033, 1058.9364638121583, 1111.4831603304501, 1121.8709848367541, 1133.070881876853, 1218.2124251690939, 15206.315715792816, 15323.463678771412, 15372.077675339291, 15625.0, 15854.682906341808, 642395.3640695394, 647662.0, 648564.0, 649159.0, 649600.751996237, 58776.0, 59296.0, 59362.40637593655, 59446.811063778485, 59498.810023799284, 1318.064174436161, 1441.4954765831874, 1465.1868213141809, 1471.4849802569015, 1487.375302373006, 28754.424911501235, 30043.69956300409, 30226.070610820454, 30338.241543961576, 30974.0, 41395.0, 50592.74703626458, 50653.24020139627, 50707.98584028373, 50834.49165508297, 506999.0, 507606.0, 509960.0, 510276.0, 510619.0, 25561.0, 26941.191764247105, 26957.595636065867, 26977.0558030468, 26986.865065674545, 44507.55492445164, 44572.77713611411, 45556.77221613871, 46206.76896615495, 46861.76569117133, 36287.455688165224, 37189.81405092957, 37224.0, 37985.0, 38133.61866381301, 30397.848010759804, 36364.90905272846, 36695.81652091722, 37394.0, 37666.43500347442, 36273.455898161024, 36641.45037824381, 37028.07429814276, 37928.0, 38101.42847857229, 32500.674993250712, 32603.02190934276, 33084.834575826964, 33156.50265245975, 33383.33233335271, 24268.75731242665, 24725.258242252763, 24955.251342459756, 25080.0, 25163.370915727253], "value": 20196.493717246223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.55979742890537, 395.13071243264824, 397.35429926369665, 400.6434273496587, 423.2107120220787, 20130.194792208313, 20264.797352026475, 21265.681014784772, 21281.82949937755, 21406.14375424981, 19835.305764298253, 20196.158724768542, 20332.59334813303, 20829.68755468669, 20833.0, 5639.323281206251, 5852.853678658046, 5895.0, 5896.437444077323, 5910.202122713443, 5361.571074314065, 5674.18426103647, 5835.5915085944, 5910.556708246877, 5937.22816033915, 5734.684592347419, 5780.161876527901, 5787.21872547207, 5792.855178620547, 5794.768209271645, 5082.872928176806, 5516.503514683698, 5773.653580785166, 5793.304803423574, 5803.303603567557, 5054.393472783253, 5091.134507133796, 5092.363454568179, 5095.87260318495, 5102.183650615883, 23922.760772392226, 24707.0, 25325.366865828317, 25333.24000279976, 25415.745842541524, 2158.32012915931, 2178.2049551913437, 2395.449046719247, 2399.796017338528, 2609.6216048673027, 14361.784110853037, 14501.938042427735, 14563.0, 14582.343794529272, 14618.853811461855, 1689.780328557292, 1746.8515176210037, 1767.8408943195175, 1788.937387191442, 1796.5778042160082, 115539.4223028888, 118638.0, 118764.40617796942, 119426.0, 121182.18226726606, 10464.162866970624, 10645.467726613793, 10903.01872831449, 10959.890401096047, 11076.778464430667, 13757.931210343884, 13834.93082534581, 13851.861481385258, 13856.307184640727, 13862.0, 8365.372597055246, 8571.871421928676, 8590.484570925764, 8697.652093916173, 8700.651973921098, 8529.0, 8544.974603047674, 8547.95726021366, 8656.047834738174, 8685.826283474295, 6383.680815959184, 6402.679866006774, 6514.641694706742, 6556.082148499214, 6557.540972131905, 1632.5428879913406, 1878.1360574136052, 1880.2948894164688, 1887.3582981786258, 1933.2267093162566, 978.6183388478499, 985.5318723606373, 991.5389343954952, 1006.33079002464, 1048.7745134796055, 14925.0, 18365.5408614783, 19956.70064949027, 20363.898180509004, 20816.95915204234, 940.2243149401771, 966.4153187321709, 971.7570607348163, 1049.9527521261562, 1085.310827624464, 8795.252403545643, 10800.792864222001, 10973.451327433597, 11084.833727494093, 11095.667129986114, 592572.0371398116, 594224.0, 609659.0, 610583.0, 613696.0, 103788.0, 114288.0, 116914.41542792402, 117690.41154794171, 120066.0, 1062.8405739139346, 1097.3635291530893, 1126.0541145437871, 1128.3173679923693, 1129.949152288149, 14692.41230350774, 17643.47069587863, 18264.995425251207, 18477.445676629723, 19117.808821911603, 51464.22803657873, 52073.698157546365, 52783.0, 52963.0, 53371.13201037945, 71979.0, 72356.0, 72356.0, 72547.18632034241, 73722.0, 7632.38940884739, 7840.431913617185, 7959.761207163794, 8030.5181689098845, 8171.305439037748, 8743.519106449206, 10474.476276186313, 11378.0, 11584.826227606423, 12068.0, 10309.175265978554, 10617.577434401614, 10700.83948740754, 10755.193360497837, 10829.67510974672, 10218.795624087328, 10314.329568578018, 10406.406706084754, 10557.9472102639, 10875.347479151276, 10502.579896804042, 10691.625793097515, 10742.086922611665, 10836.620718274822, 10861.945690271497, 7709.421793365632, 7839.608019599112, 7948.523088614702, 8002.2797948182615, 8050.5169689818795, 10437.277849155109, 10480.63317783874, 10658.294672852617, 10733.248672593156, 10742.624008159677], "value": 9559.737326007564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.2173293821938, 1575.3068649794086, 1614.991925040375, 1810.900400477974, 1932.2560814086573, 7516.887246691297, 7543.207963163857, 7845.450818442711, 8660.566971651417, 8934.86597701035, 8617.353698472609, 8859.512726800023, 8859.734207973772, 8866.556672166382, 8880.378373513857, 2407.458321877581, 2432.7080750309988, 2440.9023639054417, 2442.1086303499183, 2444.0590372706456, 2370.7984821290124, 2421.2494126820743, 2422.515496900618, 2422.685050943375, 2423.309356833303, 2363.3382652857226, 2376.6197408414655, 2398.0887262840097, 2404.1825779235055, 2404.4469771952463, 2357.0571771291447, 2383.3088404362716, 2388.235764555343, 2391.015457170553, 2393.401649587603, 2301.97698023021, 2375.0381095656285, 2380.571497130516, 2382.8808559571953, 2385.8687772172525, 7838.176991415876, 8086.716964906259, 8848.336374771918, 8893.977192622866, 8904.28765698749, 5234.293370395024, 5425.782968681248, 5437.102878025121, 5447.782088716446, 5477.315335583052, 3429.639887811769, 3539.4159963605975, 3569.089882080079, 3585.94621080684, 3587.5336206293277, 1026.2559644257929, 1041.463646222196, 1042.99478502607, 1044.7231483656838, 1048.1405247696891, 11729.70675733105, 11880.40597970098, 11880.584179553674, 11897.345645989553, 11908.523659053628, 4015.7791321477016, 4802.495737947465, 4940.382452193476, 4941.6293777966075, 4942.950570494249, 8554.828903422022, 10312.896871031344, 10323.48382580868, 10338.689839304832, 10342.37945723259, 5511.696856672841, 5511.751971161308, 5512.972435137799, 5514.751836167382, 5534.916976245359, 4798.1123492154065, 4916.06594746997, 4922.827701030446, 4957.553820156203, 4987.152184128707, 3831.0653120174134, 3845.596212397658, 3906.374980003186, 3947.50656167979, 3959.3269144245546, 1291.044626976031, 1292.1730092740654, 1294.5922034559085, 1298.1626850681257, 1314.8553659097493, 813.2111851504031, 903.823754367905, 921.5392303848076, 941.9011003844635, 965.034965034965, 5912.438318359749, 5937.0, 6085.8478538036015, 6106.480949119375, 6177.289611694623, 437.7482947305312, 438.52201100800283, 451.77185521311526, 463.58045968398653, 463.7797046402932, 3906.7851268179957, 3952.486176796998, 4002.539707933625, 4027.7180597357906, 4033.4353190553347, 10262.281640285257, 10440.42577658236, 10680.946595267129, 10732.570697172181, 10905.509252083675, 10768.623098191525, 10780.029797318279, 10801.999999999844, 10883.619073332553, 10913.0, 7941.920580794118, 8306.6677332906, 8307.460015098999, 8308.5430301334, 8418.116097809765, 4569.748663823521, 5793.797217097382, 5895.0, 5909.734061967308, 5948.5538584605465, 8011.599420029092, 10087.193024557871, 10125.493725313558, 10265.589376425158, 10268.229882758673, 7719.382449403922, 10257.230707696803, 10373.118284945864, 10374.066334029974, 10511.526981285708, 7315.341619254293, 7481.102267727908, 7578.772636820904, 7584.241575842371, 7603.2776886195725, 8387.706430274911, 8423.747287581382, 8491.95754021226, 8492.915070849212, 8502.277306428776, 7380.630968451448, 7560.395168386406, 7570.621468926422, 7681.692732290646, 7702.306792388712, 7404.592747398729, 7485.251474852471, 7535.133459651991, 7668.424868135024, 7699.538027718355, 7024.5434046786795, 7091.858162836817, 7101.431885449255, 7115.288471152843, 7127.7148914042855, 4687.250039993584, 4957.157283261782, 4999.675021123615, 5006.674566153189, 5059.089363914383, 4910.484399138039, 5002.074616196015, 5032.421271553818, 5066.417362003417, 5141.331626888481], "value": 4524.8446882191865, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.1471138168818, 307.898393530135, 319.4393838812884, 323.9514072889068, 325.1513549635451, 4803.006752197843, 5148.510891465314, 5455.745178608918, 5479.369872464667, 5519.475649813261, 4863.465018847924, 5082.923756143651, 5097.171070056117, 5219.42586315505, 5370.731463426832, 4031.5766844481345, 4041.4746083009168, 4082.7958602069925, 4087.0, 4113.650339721127, 3283.7044665980056, 4066.1867626474677, 4101.241270364977, 4121.278776214165, 4172.958270417287, 3425.588929328484, 3777.924441511168, 3824.827882745283, 4041.575634558373, 4089.8159582818694, 4005.9399109013098, 4009.578994205625, 4063.512378514597, 4075.473206611905, 4098.90532647852, 3239.919002024945, 3389.254364039907, 3528.6471352864764, 3542.220711443478, 3550.3254381667402, 4900.779464924051, 5191.5846732261325, 5217.947820521784, 5221.25883910946, 5225.399079105913, 4263.467066616673, 4318.935215971763, 4551.886202844922, 4586.3808385867815, 4606.631469482433, 5601.971990140064, 5653.406392328788, 5672.631278966854, 5686.15692153923, 5687.099579214589, 3403.9829800851085, 3701.5002974598624, 3849.1716434693576, 3849.8642900344184, 3866.961330386688, 5263.973680131575, 5925.703714814328, 5928.347881733006, 5946.345901950782, 5948.286205655306, 3456.395130852091, 3620.855165793339, 3894.766314021168, 3899.4930659014713, 3919.431682406035, 3811.5616704079384, 3874.5156855393075, 3974.330781071899, 3992.2414741199077, 4019.7588144711412, 4479.3057076153245, 4743.193657078305, 4784.30627559002, 4801.95198048022, 4812.446568644651, 5218.582513398919, 5589.413111623221, 5673.971630141823, 5765.443330300879, 5787.858492357855, 1190.4880901212389, 1307.3985966455537, 1410.7883817427469, 1410.978835317471, 1418.8935829812806, 774.8140446292962, 799.476342995339, 840.2563731098022, 909.231699214163, 909.8899342801772, 4352.824737320906, 4584.7936842842155, 5145.867909060001, 5316.255724198615, 5325.295905310341, 5230.750777165779, 5399.757010934517, 5804.506616937523, 5829.0, 5860.355360910296, 4394.340848872631, 4782.9150198122015, 5141.485851414828, 5201.257578711098, 5286.682799031993, 2614.281072704983, 2667.2662769200174, 2727.8772455239564, 2858.6426696662916, 3038.936372269732, 5175.534201921845, 5855.795047173328, 5957.195778569838, 6014.6391216527145, 6019.668918209453, 4795.688280261772, 5542.806001789918, 5803.941960580424, 6162.907556386658, 6167.620989097702, 5336.306280183551, 5857.619254748428, 6195.96902015487, 6225.62646241227, 6228.159198508325, 4871.853844384674, 5533.616595851037, 5699.5155411790465, 5947.375525569752, 5985.461308482258, 4936.481669424658, 5109.8978020438635, 5849.0, 5874.177615133799, 5881.32364778056, 5679.204911312502, 6151.3541078186145, 6200.100985357008, 6209.0, 6217.844553886189, 3346.8326583671205, 3681.7422780405113, 3958.327084395602, 3962.7424217425773, 3968.5634580196734, 4859.602060135583, 4917.672228498214, 5307.256984022317, 5855.7364918579615, 5951.970240148771, 5273.841784746463, 5294.538039811506, 5672.745440919311, 5684.459976302244, 5762.501749545065, 5408.13469844823, 5468.261784659179, 5739.799107031234, 5752.252207213036, 5778.595498315078, 5207.3230480037355, 5232.947670523246, 5515.365732940763, 5565.714399912126, 5587.329520457572, 4669.762512934008, 5501.312335958005, 5582.665040097445, 5679.971600141973, 5705.231378272749, 5174.353205849269, 5383.0041442333295, 5477.972610136924, 5657.4908258256955, 5671.262735844314], "value": 4119.575140821387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.7891874600128, 738.2801768275929, 758.5941521286113, 763.9159692433831, 791.2364568191698, 15096.622584435394, 15715.685686286268, 15722.135282559455, 15742.370305187793, 15783.131927743969, 14325.212113333762, 14389.784153237684, 14417.567472975827, 14428.42286308549, 15291.770623440658, 775.8176828445311, 915.8671992561075, 923.7690577355661, 925.5372313843078, 925.9212966897788, 901.2969883490888, 910.2217603948628, 916.5325683901225, 920.2959735802117, 920.5259291464868, 833.3374966901298, 960.5533426956464, 969.3311614985672, 969.8690676758653, 971.028971028971, 959.8800149981253, 960.2030314838737, 962.4850704872895, 962.5187406296851, 963.0706368354537, 714.921358650548, 714.974975875847, 716.8530451257479, 717.2719689515146, 717.8241330873917, 12651.557195498206, 15236.542903712907, 15269.694606107816, 15273.465428710053, 15276.694466110726, 10288.588456461732, 11301.434928253637, 11397.088232941345, 12257.080718946025, 12286.754264914742, 9865.062819032251, 9980.451075190862, 10182.592696292213, 10430.426326552037, 10711.0, 3396.473546600256, 3444.7416443766565, 3528.8412021459094, 3543.6102028776813, 3647.7993710345922, 56330.186395545876, 61358.69320653413, 64370.356296436905, 67609.3239067608, 71211.99999999948, 9282.220225616478, 9438.622455101857, 9489.288303377274, 9562.235021198287, 9772.16936560386, 20916.058777355054, 20930.37208883736, 20961.47596310074, 20961.58076838455, 20981.482222133443, 7496.850062998709, 7571.0, 7623.923760762433, 7628.46600737943, 7691.038620172479, 3991.830579399684, 4071.1043570414463, 4072.0430698786354, 4090.877273681794, 4103.835846566164, 3509.94735078974, 3593.94609080864, 3596.0829988353325, 3598.8200589970397, 3601.0, 610.185402487675, 614.8339948213957, 622.1259130921063, 632.6871741137159, 641.791417789211, 485.27936015017673, 554.1099794315105, 559.0245022435876, 593.4510577715596, 602.1359349333633, 8092.271695547429, 8183.508989460652, 8195.918040819515, 8223.753287401387, 8350.498970061693, 501.95231453011905, 573.4580821123998, 611.8378629663143, 612.6293592376549, 628.4218518962565, 3364.5794275715534, 3694.7413681042067, 3857.8842634721004, 3859.459675645412, 3902.8829135125993, 76761.46477070427, 80667.7899831503, 81792.59103704563, 83321.0, 83846.74229886435, 61527.76944461175, 65813.67093164599, 77773.44453111019, 78559.60720196362, 78886.0, 70332.648336758, 72799.0, 72858.0, 73481.0, 74066.0, 8303.6678532858, 8309.875351869849, 8316.767053378066, 8458.196471335214, 8519.659213631385, 43560.91097722582, 43664.56335436605, 44401.445949391615, 45324.32013519734, 45870.31123515652, 54319.0, 63287.734245315754, 63325.050124249094, 63638.68180659067, 64045.35954640394, 10826.837597435888, 10827.566897324019, 10838.620648277272, 11009.0, 11284.887151128713, 11853.051755859338, 11919.702007449536, 12432.378381081091, 12911.869541525542, 13089.280089594782, 11590.88409115898, 11860.881391186324, 12006.0, 12114.273143611059, 12363.876361236273, 11049.447527623748, 11408.828867566826, 11499.655010349703, 11681.0, 12381.690457738629, 11193.496292667012, 11272.041876440595, 11667.0, 11748.823767643322, 12354.876451235372, 8517.449252815844, 9497.572609232462, 9506.95246523763, 9610.231181505602, 9627.518624068629, 8285.4200205985, 8439.691847763606, 8767.363271237731, 8805.496195402417, 9517.619295228113], "value": 6786.965716505828, "unit": "score"}], "name": "dromaeo_css", "value": 7180.834324932465, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 110.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 120.0, 142.0, 196.0, 116.0, 138.0, 136.0, 117.0, 168.0, 117.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 185.0, 186.0, 186.0, 186.0, 217.0, 244.0, 185.0, 182.0, 187.0], "value": 194.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 57.0, 126.0, 56.0, 62.0, 55.0, 58.0, 110.0, 58.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 135.0, 79.0, 79.0, 77.0, 103.0, 80.0, 99.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 86.0, 84.0, 87.0, 85.0, 87.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 83.0, 86.0, 86.0, 85.0, 89.0, 85.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 57.0, 59.0, 57.0, 57.0, 120.0, 56.0, 56.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 79.0, 74.0, 70.0, 67.0, 67.0, 69.0, 67.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 83.0, 83.0, 85.0, 78.0, 82.0, 83.0, 77.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 148.0, 139.0, 139.0, 153.0, 144.0, 150.0, 237.0, 140.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 166.0, 167.0, 169.0, 169.0, 169.0, 166.0, 165.0, 166.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 66.0, 66.0, 66.0, 65.0, 68.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1484.2, "alertThreshold": 2.0}]} 08:03:53 INFO - Return code: 0 08:03:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:03:53 ERROR - # TBPL SUCCESS # 08:03:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:53 INFO - 2016-05-11 08:03:53.611 screenresolution[1949:20596] starting screenresolution argv=screenresolution get 08:03:53 INFO - 2016-05-11 08:03:53.614 screenresolution[1949:20596] Display 0: 1600x1200x32@60 08:03:53 INFO - 2016-05-11 08:03:53.620 screenresolution[1950:20599] starting screenresolution argv=screenresolution list 08:03:53 INFO - Available Modes on Display 0 08:03:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:53 INFO - Intel Iris: 08:03:53 INFO - Chipset Model: Intel Iris 08:03:53 INFO - Type: GPU 08:03:53 INFO - Bus: Built-In 08:03:53 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:53 INFO - Vendor: Intel (0x8086) 08:03:53 INFO - Device ID: 0x0a2e 08:03:53 INFO - Revision ID: 0x0009 08:03:53 INFO - Displays: 08:03:53 INFO - Display: 08:03:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:53 INFO - Display Serial Number: 1600x1200 60 08:03:53 INFO - Main Display: Yes 08:03:53 INFO - Mirror: Off 08:03:53 INFO - Online: Yes 08:03:53 INFO - Rotation: Supported 08:03:53 INFO - Return code: 0 08:03:53 INFO - Running post-action listener: _resource_record_post_action 08:03:53 INFO - ##### 08:03:53 INFO - ##### Finished run-tests step (success) 08:03:53 INFO - ##### 08:03:53 INFO - Running post-run listener: _resource_record_post_run 08:03:54 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 438272; Write bytes: 691235328; Read time: 8; Write time: 2005 08:03:54 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 377448960; Write bytes: 370874368; Read time: 28015; Write time: 735 08:03:54 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 438272; Write bytes: 294466048; Read time: 8; Write time: 1220 08:03:54 INFO - Running post-run listener: _upload_blobber_files 08:03:54 INFO - Blob upload gear active. 08:03:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:03:54 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 08:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:03:55 INFO - (blobuploader) - INFO - Open directory for files ... 08:03:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:03:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:03:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:56 INFO - (blobuploader) - INFO - Done attempting. 08:03:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:03:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:57 INFO - (blobuploader) - INFO - Done attempting. 08:03:57 INFO - (blobuploader) - INFO - Iteration through files over. 08:03:57 INFO - Return code: 0 08:03:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:03:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:03:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f435f65700544bda9f46df2f67017267a10351b8d5b86963ac36653355fb06385d03487fcf47ff0d94ef6f4fd2c26ebf670cd868354c71e46b6c6b8a3ac4c23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c701f5e7c01153c979d687801606ffc0f45ec7723a3a7ef33b76ef063b57f3242617c37c090c69de8743deddaf22ff8c5fba333fe35ef636dae85065a471846d"} 08:03:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:03:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:03:57 INFO - Contents: 08:03:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f435f65700544bda9f46df2f67017267a10351b8d5b86963ac36653355fb06385d03487fcf47ff0d94ef6f4fd2c26ebf670cd868354c71e46b6c6b8a3ac4c23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c701f5e7c01153c979d687801606ffc0f45ec7723a3a7ef33b76ef063b57f3242617c37c090c69de8743deddaf22ff8c5fba333fe35ef636dae85065a471846d"} 08:03:57 INFO - Running post-run listener: copy_logs_to_upload_dir 08:03:57 INFO - Copying logs to upload dir... 08:03:57 INFO - mkdir: /builds/slave/test/build/upload/logs 08:03:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.600557 ========= master_lag: 13.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-05-11 08:04:11.098263) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:04:11.106096) ========= 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.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f435f65700544bda9f46df2f67017267a10351b8d5b86963ac36653355fb06385d03487fcf47ff0d94ef6f4fd2c26ebf670cd868354c71e46b6c6b8a3ac4c23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c701f5e7c01153c979d687801606ffc0f45ec7723a3a7ef33b76ef063b57f3242617c37c090c69de8743deddaf22ff8c5fba333fe35ef636dae85065a471846d"} build_url:https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011120 build_url: 'https://queue.taskcluster.net/v1/task/edZfUCkJTTS5M_mLIC4Ddw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f435f65700544bda9f46df2f67017267a10351b8d5b86963ac36653355fb06385d03487fcf47ff0d94ef6f4fd2c26ebf670cd868354c71e46b6c6b8a3ac4c23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c701f5e7c01153c979d687801606ffc0f45ec7723a3a7ef33b76ef063b57f3242617c37c090c69de8743deddaf22ff8c5fba333fe35ef636dae85065a471846d"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:04:11.169311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-05-11 08:04:11.169618) ========= 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.XLoSIqiAim/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.UMcrNudsiF/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.005046 ========= master_lag: 7.44 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-05-11 08:04:18.609949) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:04:18.610249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:04:18.758515) ========= ========= Total master_lag: 21.39 =========