builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1462968238.41 results: success (0) buildid: 20160511030221 builduid: b506c7b4dc59436f95acca86e65699f1 revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.412163) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.412553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.412817) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.429838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.430107) ========= 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.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:03:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 133M=0s 2016-05-11 05:03:58 (133 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.581683) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.581986) ========= 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.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.019997 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.630846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.631196) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 674a552743785c28c75866969aad513bd8eaf6ae --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 674a552743785c28c75866969aad513bd8eaf6ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:03:58,707 truncating revision to first 12 chars 2016-05-11 05:03:58,708 Setting DEBUG logging. 2016-05-11 05:03:58,708 attempt 1/10 2016-05-11 05:03:58,708 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/674a55274378?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 05:03:58,964 unpacking tar archive at: mozilla-central-674a55274378/testing/mozharness/ program finished with exit code 0 elapsedTime=0.505970 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:59.153799) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:59.154109) ========= script_repo_revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:59.154490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:59.154750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:59.167817) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-11 05:03:59.168146) ========= /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.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:03:59 INFO - MultiFileLogger online at 20160511 05:03:59 in /builds/slave/test 05:03:59 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 05:03:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:59 INFO - {'append_to_log': False, 05:03:59 INFO - 'base_work_dir': '/builds/slave/test', 05:03:59 INFO - 'blob_upload_branch': 'Firefox', 05:03:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:59 INFO - 'branch': 'Firefox', 05:03:59 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:59 INFO - 'config_files': ('talos/mac_config.py',), 05:03:59 INFO - 'default_actions': ('clobber', 05:03:59 INFO - 'read-buildbot-config', 05:03:59 INFO - 'download-and-extract', 05:03:59 INFO - 'populate-webroot', 05:03:59 INFO - 'create-virtualenv', 05:03:59 INFO - 'install', 05:03:59 INFO - 'run-tests'), 05:03:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:59 INFO - 'download_minidump_stackwalk': True, 05:03:59 INFO - 'download_symbols': 'ondemand', 05:03:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:59 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:59 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:59 INFO - 'installer_path': 'installer.exe', 05:03:59 INFO - 'log_level': 'info', 05:03:59 INFO - 'log_name': 'talos', 05:03:59 INFO - 'log_to_console': True, 05:03:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:03:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:03:59 INFO - 'opt_config_files': (), 05:03:59 INFO - 'pip_index': False, 05:03:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:59 INFO - 'cmd': ('bash', 05:03:59 INFO - '-c', 05:03:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:59 INFO - 'enabled': True, 05:03:59 INFO - 'halt_on_failure': False, 05:03:59 INFO - 'name': 'check_screen_resolution'},), 05:03:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:59 INFO - 'cmd': ('bash', 05:03:59 INFO - '-c', 05:03:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:59 INFO - 'enabled': True, 05:03:59 INFO - 'halt_on_failure': False, 05:03:59 INFO - 'name': 'check_screen_resolution'},), 05:03:59 INFO - 'run_cmd_checks_enabled': True, 05:03:59 INFO - 'sps_profile': False, 05:03:59 INFO - 'sps_profile_interval': 0, 05:03:59 INFO - 'suite': 'dromaeojs', 05:03:59 INFO - 'system_bits': '32', 05:03:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:59 INFO - 'title': 't-yosemite-r7-0016', 05:03:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:59 INFO - 'use_talos_json': True, 05:03:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:59 INFO - 'work_dir': 'build'} 05:03:59 INFO - ##### 05:03:59 INFO - ##### Running clobber step. 05:03:59 INFO - ##### 05:03:59 INFO - Running pre-action listener: _resource_record_pre_action 05:03:59 INFO - Running main action method: clobber 05:03:59 INFO - rmtree: /builds/slave/test/build 05:03:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:00 INFO - Running post-action listener: _resource_record_post_action 05:04:00 INFO - ##### 05:04:00 INFO - ##### Finished clobber step (success) 05:04:00 INFO - ##### 05:04:00 INFO - ##### 05:04:00 INFO - ##### Running read-buildbot-config step. 05:04:00 INFO - ##### 05:04:00 INFO - Running pre-action listener: _resource_record_pre_action 05:04:00 INFO - Running main action method: read_buildbot_config 05:04:00 INFO - Using buildbot properties: 05:04:00 INFO - { 05:04:00 INFO - "project": "", 05:04:00 INFO - "product": "firefox", 05:04:00 INFO - "script_repo_revision": "production", 05:04:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:04:00 INFO - "repository": "", 05:04:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:04:00 INFO - "buildid": "20160511030221", 05:04:00 INFO - "stage_platform": "macosx64", 05:04:00 INFO - "basedir": "/builds/slave/test", 05:04:00 INFO - "nightly_build": "True", 05:04:00 INFO - "buildnumber": 5, 05:04:00 INFO - "slavename": "t-yosemite-r7-0016", 05:04:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:04:00 INFO - "platform": "yosemite_r7", 05:04:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:04:00 INFO - "branch": "mozilla-central", 05:04:00 INFO - "repo_path": "mozilla-central", 05:04:00 INFO - "revision": "674a552743785c28c75866969aad513bd8eaf6ae", 05:04:00 INFO - "pgo_build": "False", 05:04:00 INFO - "builduid": "b506c7b4dc59436f95acca86e65699f1", 05:04:00 INFO - "slavebuilddir": "test" 05:04:00 INFO - } 05:04:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:04:00 INFO - Running post-action listener: _resource_record_post_action 05:04:00 INFO - ##### 05:04:00 INFO - ##### Finished read-buildbot-config step (success) 05:04:00 INFO - ##### 05:04:00 INFO - ##### 05:04:00 INFO - ##### Running download-and-extract step. 05:04:00 INFO - ##### 05:04:00 INFO - Running pre-action listener: _resource_record_pre_action 05:04:00 INFO - Running main action method: download_and_extract 05:04:00 INFO - mkdir: /builds/slave/test/build/tests 05:04: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')]} 05:04:00 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:04:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:02 INFO - Downloaded 1199 bytes. 05:04:02 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:02 INFO - Using the following test package requirements: 05:04:02 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:02 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:04:02 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:04:02 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'jsshell-mac.zip'], 05:04:02 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:04:02 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:02 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:04:02 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:04:02 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:04:02 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:02 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:04:02 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:04: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')]} 05:04:02 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:03 INFO - Downloaded 18150554 bytes. 05:04:03 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'] 05:04:03 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 05:04:07 INFO - Return code: 0 05:04:07 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 05:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:07 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:08 INFO - Downloaded 18150554 bytes. 05:04:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:04:11 INFO - Return code: 0 05:04: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')]} 05:04:11 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:04:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:13 INFO - Downloaded 10840152 bytes. 05:04:13 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'] 05:04:13 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 05:04:13 INFO - Return code: 0 05:04:13 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')]} 05:04:13 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:04:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:04:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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 05:04:16 INFO - Downloaded 109983254 bytes. 05:04:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:16 INFO - mkdir: /builds/slave/test/properties 05:04:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:16 INFO - Contents: 05:04:16 INFO - build_url:https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:18 INFO - Running post-action listener: _resource_record_post_action 05:04:18 INFO - Running post-action listener: set_extra_try_arguments 05:04:18 INFO - ##### 05:04:18 INFO - ##### Finished download-and-extract step (success) 05:04:18 INFO - ##### 05:04:18 INFO - ##### 05:04:18 INFO - ##### Running populate-webroot step. 05:04:18 INFO - ##### 05:04:18 INFO - Running pre-action listener: _resource_record_pre_action 05:04:18 INFO - Running main action method: populate_webroot 05:04:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:18 INFO - '--tppagecycles', 05:04:18 INFO - '7'], 05:04:18 INFO - 'tests': ['tsvgm']}}, 05:04:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['damp', 'tps']}, 05:04:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'tests': ['damp', 'tps']}, 05:04:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['dromaeo_dom']}, 05:04:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['a11yr', 05:04:18 INFO - 'ts_paint', 05:04:18 INFO - 'tpaint', 05:04:18 INFO - 'sessionrestore', 05:04:18 INFO - 'sessionrestore_no_auto_restore', 05:04:18 INFO - 'tabpaint']}, 05:04:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tsvgx', 05:04:18 INFO - 'tsvgr_opacity', 05:04:18 INFO - 'tart', 05:04:18 INFO - 'tscrollx', 05:04:18 INFO - 'cart']}, 05:04:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:18 INFO - 'tsvgr_opacity', 05:04:18 INFO - 'tart', 05:04:18 INFO - 'tscrollx', 05:04:18 INFO - 'cart']}, 05:04:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s'], 05:04:18 INFO - 'tests': ['tp5o']}, 05:04:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'tests': ['tp5o']}, 05:04:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'talos_options': ['--disable-e10s', 05:04:18 INFO - '--xperf_path', 05:04:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:18 INFO - 'tests': ['tp5n']}, 05:04:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:18 INFO - 'talos_options': ['--xperf_path', 05:04:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:18 INFO - 'tests': ['tp5n']}}, 05:04:18 INFO - 'talos.zip': {'path': '', 05:04:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:18 INFO - Running post-action listener: _resource_record_post_action 05:04:18 INFO - ##### 05:04:18 INFO - ##### Finished populate-webroot step (success) 05:04:18 INFO - ##### 05:04:18 INFO - ##### 05:04:18 INFO - ##### Running create-virtualenv step. 05:04:18 INFO - ##### 05:04:18 INFO - Running pre-action listener: _resource_record_pre_action 05:04:18 INFO - Running main action method: create_virtualenv 05:04:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:18 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 05:04:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:18 INFO - Using real prefix '/tools/python27' 05:04:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:18 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:21 INFO - Installing pip.................done. 05:04:21 INFO - Return code: 0 05:04:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:21 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:21 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 05:04:21 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 05:04:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:21 INFO - 'HOME': '/Users/cltbld', 05:04:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:21 INFO - 'LOGNAME': 'cltbld', 05:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:21 INFO - 'MOZ_NO_REMOTE': '1', 05:04:21 INFO - 'NO_EM_RESTART': '1', 05:04:21 INFO - 'PAGER': '/bin/cat', 05:04:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:21 INFO - 'PWD': '/builds/slave/test', 05:04:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:21 INFO - 'SHELL': '/bin/bash', 05:04:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:21 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:21 INFO - 'USER': 'cltbld', 05:04:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:21 INFO - 'XPC_FLAGS': '0x0', 05:04:21 INFO - 'XPC_SERVICE_NAME': '0', 05:04:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:22 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 05:04:22 INFO - Cleaning up... 05:04:22 INFO - Return code: 0 05:04:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:22 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 05:04:22 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 05:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:22 INFO - 'HOME': '/Users/cltbld', 05:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:22 INFO - 'LOGNAME': 'cltbld', 05:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:22 INFO - 'MOZ_NO_REMOTE': '1', 05:04:22 INFO - 'NO_EM_RESTART': '1', 05:04:22 INFO - 'PAGER': '/bin/cat', 05:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:22 INFO - 'PWD': '/builds/slave/test', 05:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:22 INFO - 'SHELL': '/bin/bash', 05:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:22 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:22 INFO - 'USER': 'cltbld', 05:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:22 INFO - 'XPC_FLAGS': '0x0', 05:04:22 INFO - 'XPC_SERVICE_NAME': '0', 05:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:22 INFO - Downloading/unpacking psutil>=0.7.1 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:25 INFO - Installing collected packages: psutil 05:04:25 INFO - Running setup.py install for psutil 05:04:25 INFO - building 'psutil._psutil_osx' extension 05:04:25 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 05:04:25 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 05:04:25 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 05:04:25 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 05:04:25 INFO - building 'psutil._psutil_posix' extension 05:04:25 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 05:04:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:25 INFO - ^ 05:04:25 INFO - 1 warning generated. 05:04:25 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 05:04:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:26 INFO - Successfully installed psutil 05:04:26 INFO - Cleaning up... 05:04:26 INFO - Return code: 0 05:04:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:04:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:26 INFO - 'HOME': '/Users/cltbld', 05:04:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:26 INFO - 'LOGNAME': 'cltbld', 05:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:26 INFO - 'MOZ_NO_REMOTE': '1', 05:04:26 INFO - 'NO_EM_RESTART': '1', 05:04:26 INFO - 'PAGER': '/bin/cat', 05:04:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:26 INFO - 'PWD': '/builds/slave/test', 05:04:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:26 INFO - 'SHELL': '/bin/bash', 05:04:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:26 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:26 INFO - 'USER': 'cltbld', 05:04:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:26 INFO - 'XPC_FLAGS': '0x0', 05:04:26 INFO - 'XPC_SERVICE_NAME': '0', 05:04:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:04:28 INFO - Installing collected packages: mozsystemmonitor 05:04:28 INFO - Running setup.py install for mozsystemmonitor 05:04:29 INFO - Successfully installed mozsystemmonitor 05:04:29 INFO - Cleaning up... 05:04:29 INFO - Return code: 0 05:04:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:29 INFO - 'HOME': '/Users/cltbld', 05:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:29 INFO - 'LOGNAME': 'cltbld', 05:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:29 INFO - 'MOZ_NO_REMOTE': '1', 05:04:29 INFO - 'NO_EM_RESTART': '1', 05:04:29 INFO - 'PAGER': '/bin/cat', 05:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:29 INFO - 'PWD': '/builds/slave/test', 05:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:29 INFO - 'SHELL': '/bin/bash', 05:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:29 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:29 INFO - 'USER': 'cltbld', 05:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:29 INFO - 'XPC_FLAGS': '0x0', 05:04:29 INFO - 'XPC_SERVICE_NAME': '0', 05:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:31 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:31 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 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - Downloading docopt-0.6.1.tar.gz 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:33 INFO - Running setup.py install for blobuploader 05:04:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:33 INFO - Running setup.py install for requests 05:04:33 INFO - Running setup.py install for docopt 05:04:33 INFO - Successfully installed blobuploader requests docopt 05:04:33 INFO - Cleaning up... 05:04:34 INFO - Return code: 0 05:04:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:34 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:34 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 05:04:34 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 05:04:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:34 INFO - 'HOME': '/Users/cltbld', 05:04:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:34 INFO - 'LOGNAME': 'cltbld', 05:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:34 INFO - 'MOZ_NO_REMOTE': '1', 05:04:34 INFO - 'NO_EM_RESTART': '1', 05:04:34 INFO - 'PAGER': '/bin/cat', 05:04:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:34 INFO - 'PWD': '/builds/slave/test', 05:04:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:34 INFO - 'SHELL': '/bin/bash', 05:04:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:34 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:34 INFO - 'USER': 'cltbld', 05:04:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:34 INFO - 'XPC_FLAGS': '0x0', 05:04:34 INFO - 'XPC_SERVICE_NAME': '0', 05:04:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-gxIGOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YLMkM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-lApZLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-IyQ4N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YSmwWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-n0NfPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:34 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-XHkN6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kXCQfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-heKsB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-1ZEPEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-yiJ0ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-VwEfw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-00G277-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-8vOO1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-_f9SwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-4piOwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:35 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-06Mv78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:36 INFO - Running setup.py install for manifestparser 05:04:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozcrash 05:04:36 INFO - Running setup.py install for mozdebug 05:04:36 INFO - Running setup.py install for mozdevice 05:04:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozfile 05:04:36 INFO - Running setup.py install for mozhttpd 05:04:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozinfo 05:04:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozInstall 05:04:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozleak 05:04:37 INFO - Running setup.py install for mozlog 05:04:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for moznetwork 05:04:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozprocess 05:04:37 INFO - Running setup.py install for mozprofile 05:04:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozrunner 05:04:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozscreenshot 05:04:38 INFO - Running setup.py install for moztest 05:04:38 INFO - Running setup.py install for mozversion 05:04:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:38 INFO - Cleaning up... 05:04:38 INFO - Return code: 0 05:04:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:38 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')]} 05:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:38 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')]} 05:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:38 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:38 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 05:04:38 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 05:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:38 INFO - 'HOME': '/Users/cltbld', 05:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:38 INFO - 'LOGNAME': 'cltbld', 05:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:38 INFO - 'MOZ_NO_REMOTE': '1', 05:04:38 INFO - 'NO_EM_RESTART': '1', 05:04:38 INFO - 'PAGER': '/bin/cat', 05:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:38 INFO - 'PWD': '/builds/slave/test', 05:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:38 INFO - 'SHELL': '/bin/bash', 05:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:38 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:38 INFO - 'USER': 'cltbld', 05:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:38 INFO - 'XPC_FLAGS': '0x0', 05:04:38 INFO - 'XPC_SERVICE_NAME': '0', 05:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-WHh0md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-hcW_vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kPsodS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-RpDnNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ogYb89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-qN9w3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-usl178-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-O97K67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-lKKnFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-OJMJoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Ur5pVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QdPHDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-G0055t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-xowBZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Z1D8re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-oZWz_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:40 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-dyWCvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 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)) 05:04:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:43 INFO - Downloading blessings-1.6.tar.gz 05:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:43 INFO - Installing collected packages: blessings 05:04:43 INFO - Running setup.py install for blessings 05:04:43 INFO - Successfully installed blessings 05:04:43 INFO - Cleaning up... 05:04:43 INFO - Return code: 0 05:04:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:43 INFO - Reading from file tmpfile_stdout 05:04:43 INFO - Current package versions: 05:04:43 INFO - blessings == 1.6 05:04:43 INFO - blobuploader == 1.2.4 05:04:43 INFO - docopt == 0.6.1 05:04:43 INFO - manifestparser == 1.1 05:04:43 INFO - mozInstall == 1.12 05:04:43 INFO - mozcrash == 0.17 05:04:43 INFO - mozdebug == 0.1 05:04:43 INFO - mozdevice == 0.48 05:04:43 INFO - mozfile == 1.2 05:04:43 INFO - mozhttpd == 0.7 05:04:43 INFO - mozinfo == 0.9 05:04:43 INFO - mozleak == 0.1 05:04:43 INFO - mozlog == 3.1 05:04:43 INFO - moznetwork == 0.27 05:04:43 INFO - mozprocess == 0.22 05:04:43 INFO - mozprofile == 0.28 05:04:43 INFO - mozrunner == 6.11 05:04:43 INFO - mozscreenshot == 0.1 05:04:43 INFO - mozsystemmonitor == 0.0 05:04:43 INFO - moztest == 0.7 05:04:43 INFO - mozversion == 1.4 05:04:43 INFO - psutil == 3.1.1 05:04:43 INFO - requests == 1.2.3 05:04:43 INFO - wsgiref == 0.1.2 05:04:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:43 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:43 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 05:04:43 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 05:04:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:43 INFO - 'HOME': '/Users/cltbld', 05:04:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:43 INFO - 'LOGNAME': 'cltbld', 05:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:43 INFO - 'MOZ_NO_REMOTE': '1', 05:04:43 INFO - 'NO_EM_RESTART': '1', 05:04:43 INFO - 'PAGER': '/bin/cat', 05:04:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:43 INFO - 'PWD': '/builds/slave/test', 05:04:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:43 INFO - 'SHELL': '/bin/bash', 05:04:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:43 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:43 INFO - 'USER': 'cltbld', 05:04:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:43 INFO - 'XPC_FLAGS': '0x0', 05:04:43 INFO - 'XPC_SERVICE_NAME': '0', 05:04:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:44 INFO - 'HOME': '/Users/cltbld', 05:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PAGER': '/bin/cat', 05:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:44 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - 'XPC_FLAGS': '0x0', 05:04:44 INFO - 'XPC_SERVICE_NAME': '0', 05:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:44 INFO - Downloading/unpacking jsonschema 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:46 INFO - Downloading/unpacking functools32 (from jsonschema) 05:04:46 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 05:04:46 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 05:04:46 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 05:04:46 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 05:04:46 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 05:04:46 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 05:04:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:47 INFO - warning: no files found matching '*.txt' 05:04:47 INFO - no previously-included directories found matching 'build' 05:04:47 INFO - no previously-included directories found matching 'dist' 05:04:47 INFO - no previously-included directories found matching '.git*' 05:04:47 INFO - Installing collected packages: jsonschema, functools32 05:04:47 INFO - Running setup.py install for functools32 05:04:47 INFO - warning: no files found matching '*.txt' 05:04:47 INFO - no previously-included directories found matching 'build' 05:04:47 INFO - no previously-included directories found matching 'dist' 05:04:47 INFO - no previously-included directories found matching '.git*' 05:04:47 INFO - Successfully installed jsonschema functools32 05:04:47 INFO - Cleaning up... 05:04:47 INFO - Return code: 0 05:04:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e85635e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, '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.N38tyiZEiP/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.dqhgU6QJC6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 05:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:47 INFO - 'HOME': '/Users/cltbld', 05:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:47 INFO - 'LOGNAME': 'cltbld', 05:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:47 INFO - 'MOZ_NO_REMOTE': '1', 05:04:47 INFO - 'NO_EM_RESTART': '1', 05:04:47 INFO - 'PAGER': '/bin/cat', 05:04:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:47 INFO - 'PWD': '/builds/slave/test', 05:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:47 INFO - 'SHELL': '/bin/bash', 05:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:04:47 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:47 INFO - 'USER': 'cltbld', 05:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:47 INFO - 'XPC_FLAGS': '0x0', 05:04:47 INFO - 'XPC_SERVICE_NAME': '0', 05:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:47 INFO - Downloading/unpacking flake8 05:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:04:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:04:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:04:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:04:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:04:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:04:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:04:51 INFO - no previously-included directories found matching 'docs/_build' 05:04:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:04:51 INFO - Running setup.py install for pep8 05:04:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:04:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:04:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:04:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:04:51 INFO - no previously-included directories found matching 'docs/_build' 05:04:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:04:51 INFO - Cleaning up... 05:04:51 INFO - Return code: 0 05:04:51 INFO - Running post-action listener: _resource_record_post_action 05:04:51 INFO - Running post-action listener: _start_resource_monitoring 05:04:51 INFO - Starting resource monitoring. 05:04:51 INFO - ##### 05:04:51 INFO - ##### Finished create-virtualenv step (success) 05:04:51 INFO - ##### 05:04:51 INFO - ##### 05:04:51 INFO - ##### Running install step. 05:04:51 INFO - ##### 05:04:51 INFO - Running pre-action listener: _resource_record_pre_action 05:04:51 INFO - Running main action method: install 05:04:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:52 INFO - Reading from file tmpfile_stdout 05:04:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:52 INFO - Reading from file tmpfile_stdout 05:04:52 INFO - Output received: 05:04:52 INFO - Usage: mozinstall [options] installer 05:04:52 INFO - Options: 05:04:52 INFO - -h, --help show this help message and exit 05:04:52 INFO - -d DEST, --destination=DEST 05:04:52 INFO - Directory to install application into. [default: 05:04:52 INFO - "/builds/slave/test"] 05:04:52 INFO - --app=APP Application being installed. [default: firefox] 05:04:52 INFO - mkdir: /builds/slave/test/build/application 05:04:52 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'] 05:04:52 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 05:05:25 INFO - Reading from file tmpfile_stdout 05:05:25 INFO - Output received: 05:05:25 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:05:25 INFO - Running post-action listener: _resource_record_post_action 05:05:25 INFO - ##### 05:05:25 INFO - ##### Finished install step (success) 05:05:25 INFO - ##### 05:05:25 INFO - ##### 05:05:25 INFO - ##### Running run-tests step. 05:05:25 INFO - ##### 05:05:25 INFO - Running pre-action listener: _resource_record_pre_action 05:05:25 INFO - Running main action method: run_tests 05:05:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:25 INFO - 2016-05-11 05:05:25.569 screenresolution[2236:17253] starting screenresolution argv=screenresolution get 05:05:25 INFO - 2016-05-11 05:05:25.572 screenresolution[2236:17253] Display 0: 1600x1200x32@60 05:05:25 INFO - 2016-05-11 05:05:25.578 screenresolution[2237:17255] starting screenresolution argv=screenresolution list 05:05:25 INFO - Available Modes on Display 0 05:05:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:25 INFO - Intel Iris: 05:05:25 INFO - Chipset Model: Intel Iris 05:05:25 INFO - Type: GPU 05:05:25 INFO - Bus: Built-In 05:05:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:25 INFO - Vendor: Intel (0x8086) 05:05:25 INFO - Device ID: 0x0a2e 05:05:25 INFO - Revision ID: 0x0009 05:05:25 INFO - Displays: 05:05:25 INFO - Display: 05:05:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:25 INFO - Display Serial Number: 1600x1200 60 05:05:25 INFO - Main Display: Yes 05:05:25 INFO - Mirror: Off 05:05:25 INFO - Online: Yes 05:05:25 INFO - Rotation: Supported 05:05:25 INFO - Return code: 0 05:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:25 INFO - Python 2.7.3 05:05:25 INFO - Return code: 0 05:05:25 INFO - grabbing minidump binary from tooltool 05:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:25 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 0x1092cbd30>, '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 0x1090efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092c2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:25 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 05:05:25 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 05:05:25 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 05:05:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:25 INFO - Return code: 0 05:05:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:05:27 INFO - Return code: 0 05:05:27 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 05:05:27 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --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 05:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N38tyiZEiP/Render', 05:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:27 INFO - 'HOME': '/Users/cltbld', 05:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:27 INFO - 'LOGNAME': 'cltbld', 05:05:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:27 INFO - 'MOZ_NO_REMOTE': '1', 05:05:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:27 INFO - 'NO_EM_RESTART': '1', 05:05:27 INFO - 'PAGER': '/bin/cat', 05:05:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:27 INFO - 'PWD': '/builds/slave/test', 05:05:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:27 INFO - 'SHELL': '/bin/bash', 05:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dqhgU6QJC6/Listeners', 05:05:27 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 05:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:27 INFO - 'USER': 'cltbld', 05:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:27 INFO - 'XPC_FLAGS': '0x0', 05:05:27 INFO - 'XPC_SERVICE_NAME': '0', 05:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:27 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 05:05:27 INFO - mozversion application_buildid: 20160511030221 05:05:27 INFO - mozversion application_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 05:05:27 INFO - mozversion application_display_name: Nightly 05:05:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:27 INFO - mozversion application_name: Firefox 05:05:27 INFO - mozversion application_remotingname: firefox 05:05:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:27 INFO - mozversion application_vendor: Mozilla 05:05:27 INFO - mozversion application_version: 49.0a1 05:05:27 INFO - mozversion platform_buildid: 20160511030221 05:05:27 INFO - mozversion platform_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 05:05:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:27 INFO - mozversion platform_version: 49.0a1 05:05:27 INFO - using testdate: 1462968327 05:05:27 INFO - actual date: 1462968327 05:05:27 INFO - starting webserver on 'localhost:52444' 05:05:27 INFO - SUITE-START | Running 2 tests 05:05:27 INFO - TEST-START | dromaeo_css 05:05:27 INFO - operating with platform_type : mac_ 05:05:27 INFO - Initialising browser for dromaeo_css test... 05:05:27 INFO - TEST-INFO | started process 2255 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_QKWkp/profile http://localhost:52444/getInfo.html) 05:05:29 INFO - PROCESS | 2255 | __metrics Screen width/height:1600/1200 05:05:29 INFO - PROCESS | 2255 | colorDepth:24 05:05:29 INFO - PROCESS | 2255 | Browser inner width/height: 1024/689 05:05:29 INFO - PROCESS | 2255 | __metrics 05:05:29 INFO - PROCESS | 2255 | 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" 05:05:29 ERROR - PROCESS | 2255 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:05:30 INFO - TEST-INFO | 2255: exit 0 05:05:30 INFO - Browser initialized. 05:05:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:30 INFO - TEST-INFO | started process 2258 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_QKWkp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:42 INFO - PROCESS | 2258 | RSS: Main: 239157248 05:05:42 INFO - PROCESS | 2258 | 05:06:33 INFO - PROCESS | 2258 | 1462968393584 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] 05:08:44 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52444/tests/dromaeo/cssquery-ext.html) 05:08:46 INFO - PROCESS | 2258 | RSS: Main: 377094144 05:08:46 INFO - PROCESS | 2258 | 05:11:49 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-ext.html (next: http://localhost:52444/tests/dromaeo/cssquery-jquery.html) 05:11:50 INFO - PROCESS | 2258 | RSS: Main: 377061376 05:11:50 INFO - PROCESS | 2258 | 05:14:52 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52444/tests/dromaeo/cssquery-mootools.html) 05:14:52 INFO - PROCESS | 2258 | RSS: Main: 365621248 05:14:52 INFO - PROCESS | 2258 | 05:17:55 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52444/tests/dromaeo/cssquery-prototype.html) 05:17:55 INFO - PROCESS | 2258 | RSS: Main: 368373760 05:17:55 INFO - PROCESS | 2258 | 05:20:59 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52444/tests/dromaeo/cssquery-yui.html) 05:20:59 INFO - PROCESS | 2258 | RSS: Main: 338001920 05:20:59 INFO - PROCESS | 2258 | 05:24:02 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:52444/tests/dromaeo/cssquery-yui.html (next: http://localhost:52444/tests/dromaeo/cssquery-dojo.html) 05:24:02 INFO - PROCESS | 2258 | RSS: Main: 375324672 05:24:02 INFO - PROCESS | 2258 | 05:24:02 INFO - PROCESS | 2258 | __start_tp_report 05:24:02 INFO - PROCESS | 2258 | _x_x_mozilla_page_load 05:24:02 INFO - PROCESS | 2258 | _x_x_mozilla_page_load_details 05:24:02 INFO - PROCESS | 2258 | |i|pagename|runs| 05:24:02 INFO - PROCESS | 2258 | |0;dojo.html;2840.247334456369;3401.897943061709;3419.9829000854993;3454.740894432915;3481.6344283850194;5645.407232240607;5665.8583535411635;5680.943190568093;5727.312722473309;5763.106718458635;5639.351474580424;5672.971635141829;5762.1068734346245;5782.566307526942;5815.883682326351;5063.392392912856;5090.745462726849;5151.484851514856;5165;5247.134222853225;5252.290940722992;5277.498637629419;5277.736113194344;5297.920531192016;5331.333583302087;5133.307003554528;5178.896422071575;5193.68837869729;5201.0898092833795;5242.8689282767855;5222.4255331913455;5226.92159617606;5232.953409318152;5239.476052394768;5239.5546378557865;4265.9786701066605;5178.197379406198;5195.19474481456;5224.582033437354;5240.266362709223;5536.778528858841;5559.166125081229;5572.247746554204;5612.915806262909;5678.26182596264;6201.751929922798;6221.671405797876;6256.249250090018;6260.561760676755;6280.560360774748;6254.374562543755;6272.968635156841;6331.525135614801;6331.620102793801;6342.619442833445;5671.63134396267;5708.857278568069;5741.081426971665;5848;5861.3552509223955;6242.843928901813;6248.656323902138;6280.2777680566405;6297.4017468340435;6299.527535434769;6152.815415537541;6155.953487907066;6160.260768707694;6160.568760186834;6161.476274516716;6136.764169924236;6172.69136543171;6236.407541283571;6253.6560489172625;6284.183056202664;6230.439260466489;6230.750769969241;6236.095766113979;6238.719257633326;6241.001439769706;6202.906956395657;6265.8746825063245;6287.811365659038;6299.590526615755;6306.621602703853;4798.688085274516;4836.177849765478;4839.008003359303;4886.706797592155;4888.095702295087;4380.8904777380185;4455.844045458393;4461.397711308997;4491.505934347219;4492.011757413364;5225.764840582183;5665.886682266414;5682.460166284196;5690.402507736628;5692.886142277214;6112.351504965227;6164.352742962014;6181.660008699565;6188.319284878659;6266.373362663789;5644.8871022579315;5648.096304591246;5653.491185793298;5667.914981275284;5680.005998950167;5297.443768404339;5300.1254792958725;5333.333333333333;5334.759935802898;5337.599680024014;5600.691961942132;6235.657038862906;6277.623342599459;6280;6367.363263673596;6270.9372906270355;6292.465140463112;6296.370362963667;6305.148804911279;6343.270523889812;6228.377162283736;6337;6349.936500635119;6360.96819515918;6368.5860419072615;6096.768593606556;6142.508599312134;6150.7847225346895;6205.782797602063;6211;6212.378762123752;6245.375462453719;6251.562390632612;6259.906101408574;6289.371062893674;5942.910856337245;5977.7310021050025;6107.84730381744;6111.572189946661;6117.418845209698;5941.524678025834;6002.429769171922;6065.5754097212775;6069.423404776539;6106.3588323227195;5858.677772722541;5904.409559044061;5906.940930590811;5976.193213916065;5983.34183239852;5908.615939963888;6004.789832355847;6044.516438684806;6049.066640152976;6109.7861574844665;5916.290045194604;5924.674142922181;5942.31663358702;5945.435183657545;5990.1314309424015 05:24:03 INFO - PROCESS | 2258 | |1;ext.html;489.5593965431112;494.9851504454865;506.8418663354236;522.1879976636332;540.6999115490903;30178.54732179019;30543.541846872286;31549.36901261979;31608.841955790216;31675.68324316756;42444.363334550006;43764.781176094075;43890.7805460973;46316.76841615787;46324;9600.855987160181;9761.804763904718;9769.413835169913;9772.90227097727;9773.120419162275;8624.396292259513;8843.646254149859;9591.992840751725;9697.175740062103;9713.805723885518;8750;8819.29445644347;8820.911790882074;11656.82514762275;11961.162718609701;11914;11997.800219977933;12007.159498835128;12010.03919686423;12025.819612705816;11478.655640330804;11561.595344162914;11578.421078946103;11578.652640420716;11579.710507237301;31641.683583164104;39530.60469395327;40172.99567510806;40222.99442513931;42139.7893010533;3307.1236122427576;3349.866005359783;3360.781549199294;3560.572731272238;3653.3606618841864;25866.224013279632;27063.052793152343;29595.112146635634;30218.09345719632;30524.54213186804;2734.398432344901;2739.0960982875804;2754.820936639132;2770;2829.5755636654667;458866;462705;462729;463315.6834215841;463887;58177.12734308989;59267;59399.40600593939;59428.40571594229;59468;62152.37847621556;62207;62283.57440340734;62288.688556556925;62291.688541557;16339.918300408423;16572.58568535796;16613.584660383345;16617.3353065876;16619.252133654016;12893.742125157632;12940.029497787704;12950.352482375843;12975.026872984376;12987.896028837466;14061.859381406055;15108.471203507825;15843.603909902346;15900.92049539745;15935.760963585555;6043.516518678495;6396.968015159894;6419.743210271537;7121.82195445118;7135.643217839087;985.3201291109111;1055.3826011783192;1063.1016790811752;1101.686019484443;1111.4220605285368;29001.41997160045;29332;29336.559951600742;29519.612427006447;29903;1086.32104934416;1136.3863513702508;1156.8431568431567;1156.9826452603218;1234.179270784918;14343.282835858165;14834.777478337834;14918.627034324012;15301;15380.461683841235;617336;624114;631140;632128;632577;50296.49703502991;56966;57268;57378;57933.4206657928;1481.9333130009175;1485.814273215848;1492.910425374481;1496.5360738171419;1510.4713350327518;29340;29486.705132948395;29716;30348;30684.84657576698;55505.44494555003;55935.16097258626;56568.71715641396;56689;56700.432995669515;593550;684625;700035.499822518;702070;856257;25204.49591008133;25436.364090897874;25540.489190216464;25553.616695749206;25613.23160305194;36454.4531832028;42593.36109958291;43579.34630980601;43933.68198954037;44889;29240.70759292465;36202.8189859049;36473.27053458969;36672;37059.88820335394;32991;33449.49825752664;35568.46647300241;36465.63534364622;36500.45249321315;34949.47575786416;35170.47244291286;35275.82362088173;35301.82349088238;35798.74704385334;30554.541681875235;31422.214444638154;31720.36559268848;31853;31890;23852.16517421882;23891.402714932265;23914.40213994664;24262.15082472105;24512.264632061066 05:24:03 INFO - PROCESS | 2258 | |2;jquery.html;361.2323811899713;399.3410872061099;401.8894803928921;406.04174149008355;424.1220673206462;20561.177552897865;21854.89072554637;21924.23265185719;21960.341189764313;22415.887920560413;21274.787252127473;21401.67897481539;21598.35204943854;22225.666615000788;22286;5917.822465326047;6093.817185484442;6107.694615269241;6137.6624285664275;6155.507559395238;5995.700214989233;6051.455369016788;6059.303180134275;6064.272287325527;6129.356417576158;5877.764889404419;5999;6007.729652165641;6027.879442411171;6034;5857.824265272048;6010.39896010402;6011.368806275365;6014.067819487943;6022.307434645006;5093.388793344785;5231.503007214308;5262.447443018466;5262.92105618416;5267.683938963636;26610.201693949213;27225.591616125774;27735.306617334525;27770.722292777016;27854.3036424089;2143.7963393477594;2190.3210004898533;2226.1763147635315;2238.373255488466;2783.73554512321;15071.547853564301;15257.38970441181;15415.460458883885;15757.369705211779;16156.676866462605;1722.6210233748554;1743.808181100078;1753.32496988659;1812.3475548802421;1858.6840237159715;77039;97477.02522974821;111014.99999999919;120544.39727801304;123965;11250.774984500264;11505.13711471643;11574.594889179007;11583.420828958519;11693.532258709556;11349.546018159346;13150.539731109557;13727.176369417668;14818.851811481747;14884.181370024537;8713.3464990126;8924.55377231136;8931.374803763674;8981.865272020925;9093.863592046124;8863.335249856287;8950;8965.551722413984;9070.8683459387;9082.637604359266;5828.88342233153;6055.455009049207;7044.583377062121;7055.259197784261;7067.151941767021;1847.9815201848078;1886.3025873001739;1889.1782074797507;1898.8196121368449;1909.4080834941212;884.2528063786091;963.9228861691048;989.4162444157981;1064.1486810551683;1095.2169199022737;20869.68695469569;21866.12535498594;21983.780162198495;22067.558648826933;22099.651588815173;807.4549678966674;902.8600566912139;979.8040391921502;1013.4020927652721;1076.5263284154946;11054.336739795637;11168.832467512993;11269.647642282978;11400;11880.524779008743;608818;612006;617941;623170;625463;121369;124812;126794;126936.36531817282;126949;1046.3355769086684;1048.2400259811727;1048.8007278617038;1090.8418279349607;1094.0536435982817;15581.065136091871;19100.94944777995;19487.805121949164;19492.847141597063;19790.536656085416;44353;46928.29607555958;47034;49300.097986282664;50692.746536268554;53981.730091349295;54896.45103548913;63576.15234948525;70114.64942675458;70551.64724176346;8149.307308878811;8289.751307460785;8304.83390332202;8330.875036874331;8608.48349099056;12660.810087848506;12688.87311126877;12952.611421657366;13170.473181073034;13213;11122.666320010412;11294.830577541179;11321.207515473838;11335.943320283346;11465.36940468249;11021.189596652803;11067.446627668423;11148;11434.542618295174;11576.073914087021;11341.886581134084;11513.251638643462;11518.827217591575;11705.180637355303;11967.726132034886;8155.266026057683;8277.668893244203;8290.212429819157;8290.958545207235;8326.209010144026;11329.943350283196;11569.595064172714;11570.710732231762;11671.12466564994;11696.649100526658 05:24:03 INFO - PROCESS | 2258 | |3;mootools.html;1426.26547328126;1441.4810668159466;1624.8869524375802;1839.7700287464068;2001.8698784578992;7996.720114795983;8673.826523469535;9007.72976810695;9033.774155646104;9148.17666410023;8861.335399845006;8920;9018.549072546362;9023.909760902388;9026.458412495254;2430.064425196303;2452.1540068676277;2453.7791598756107;2458.0782206672498;2458.2010846474877;2380.0296137296386;2448.926532204037;2449.0448724997177;2449.534588428193;2449.742777008415;2409.144753612469;2423.9394015149674;2441.353041444019;2443.6701045358914;2443.767842055011;2074.948126296847;2379.750126236755;2399.5800734871414;2418.504206637635;2426.0902161689364;2375.572396968545;2403.2790162951046;2403.459221675109;2410.2528216253017;2410.5660981023407;8785.297176225886;8902.86645700315;9004.59479323432;9005.864912026325;9020.233280171193;5435.619506634537;5580.776768929238;5601.859953501195;5604.551635869121;5626.634268772516;3489.284696637183;3579.8031108289033;3630.6732394084397;3678.4850120983083;3699.519062521882;901.8692289618034;1020.1787561013381;1033.5400746667735;1037.7509397744564;1043.4886905416367;12160.756784864341;12274.81587776193;12275.140740148194;12276.570320038845;12300.569480068245;4617.515160908057;4646.233371493733;4782.473927867932;4790.161721698689;4808.3027960945465;9519.714408567754;9530.809383812284;9532.666356677622;9545.236381089497;9551.283653726;5358.866028349323;5420.83737487876;5436.728163591804;5438.184272359178;5438.673679579238;4169.374593810892;4480.775961201992;4678.485366609738;4991.850244492671;4999.500049994971;3854.383298672199;3885.1646895916947;3897.2595206910596;3908.355121404993;3983.103801644607;927.8958039932459;1207.335965219126;1215.2465471407784;1217.9147459677915;1220.816877468369;898.4564338575068;908.7682640926654;916.4592890194817;957.3179209120378;975.8048390321964;5152.742362881841;5738.368779434259;5986.670733109634;6123.693815309217;6134.846628834315;427.26510402108323;431.36804581287936;439.97580133092345;444.65539207114745;456.88349470884714;3959.6339262954234;3959.7822119783114;3994.3409337458993;4032.4556184915245;4037.273290807624;9094.408863423856;11153.888461115448;11242.49408776607;11339.092872570174;11429.142814288962;11188.944055279671;11298.774024519464;11318.60384886525;11319.660410187707;11325.150613704005;8184.017917849777;8239.794005149919;8418.789530261793;8458.957705211558;8532.616032278562;4985.252212168059;5847.473727364558;5850.648961062179;5889.263842019747;6192.256929168529;9784.315097943076;10204.183665306906;10315.432651204255;10346.172306215334;10445.791084178425;10041.447720375449;10219.897801022193;10449.895501044892;10464.895351046393;10580.629677961235;7661.770146895602;7678.308952194329;7768.572728499761;7775.377969762519;7779.105402878741;8104.797380065545;8424.957875210584;8481.83036339282;8525;8529.360297977553;7679.846403072019;7732.188120247293;7750.7674769756995;7756;7895.921040789519;7564.130125035471;7567.659455324413;7572.734954276574;7672.769816905278;7755.146933837386;6916.23921368639;7218.169910460154;7272.636368181676;7274.01800756891;7321.963390183015;4862.659613826998;4871.415430148405;4894.14352488306;4935.8025678972435;4981.775820088033;4939.333190019302;4996.675216110941;5053.11570475158;5072.5796776902525;5160.741962901915 05:24:03 INFO - PROCESS | 2258 | |4;prototype.html;267.2863454576281;320.77545717997396;324.4597744754983;329.2525966057049;330.7106282003247;5235.921461178081;5308.150695888654;5358.410574836765;5377.462253774621;5383.850447488215;4300.3764454154125;4829.396325459317;5200.01199772043;5259.342582177228;5270.761371077793;3469.3755124077657;3746.700263978889;3772.3209822232056;3905.453236546886;3964.3547927610007;3372.7470439717117;3562.358775420423;3768.962310376902;3953.3279342511696;3976.940345894814;3352.2625022495013;3937.4684417603544;3979.4826672532677;3990.6009399060154;3998.2203470323298;3677.595464498903;3755.9812200939095;3756.7084078711964;3884.892805550433;3907.7264591478606;3008.6540047894337;3451.4132597458242;3475.9131021724406;3514.648535146465;3515.8221995631493;4233.724807887477;4952.826651067196;5027.296178535008;5042.672226305279;5065.935109258919;3827.674647654953;4303.397524346594;4521.440103164436;4525.366448697185;4526.0495295987885;4652.139354219446;4732.763361831895;5109.718965456936;5342.1452567589;5437.042664640726;3064.5709600655928;3471.930561388758;3513.8931236660624;3854.1713531590563;3879.603342796591;4871.24495703163;5645.520130788929;5686.066737309293;5782;5833.387494313079;3454.101933497309;3655.7623754455876;3800.764751455788;3833.252515759456;3836.501254836853;3121.2508997840355;3433.9141521461665;3902.6389034634203;3909.3580696107133;3911.726179167431;3856.8457261709777;4318.10003598422;4359.498657654411;4456.977715111404;4473.418455600751;4872.3665923430435;4906.70559766408;5313.707746073926;5448.972755136199;5523.25436066134;1286.7389957841367;1296.824928634641;1311.8556958734532;1321.3657444426537;1323.2788130468919;686.7699320727512;704.8942658601251;726.1721637333444;778.780702884271;816.6488409983764;4354.912901741947;4379.802908869108;4943.752812359367;5061.974690126526;5097.46476619957;4948.540180646718;5495;5504.4495550444635;5609.1305847593685;5631.621027383619;4506.909861802811;4957.851264462072;5016.724080175552;5111.7699703513435;5153.3558305211845;2949.1273041618297;2970.5098658721067;2988.8505574721175;2997.1607949774098;3009.9849500752357;4892.461829198785;5713.942860571424;5831.679257640871;5868.65253898444;5885.588008839341;5039.77321020555;5246.396664383568;5402.756875940592;5772.538196944234;5803.593748437569;4877.414710234723;5226.9546090782;5816.651000939957;5830.329512106077;5854.331515518057;4891.9755401222765;5084.720340381315;5220.843374698765;5322.935412917354;5382.946170538401;4807.903841923212;5254.710990895537;5264.236480778829;5517.558595312285;5543.029969755357;5297.72854514906;5880.529557635294;5897.705114744332;5908.202392677105;5932.555058370725;3776.320262352693;3782.9432558511094;3791.312865774704;3795.487609172837;3803.6386543278345;5204.765785539736;5251.52736253739;5597.748101335368;5613.88772224545;5661.745221464962;4404.911901761882;5399.546458077291;5411.972940135274;5422.891542169214;5457.235986961908;5002.399712034579;5055.292259083805;5107.208382700687;5385.219143224289;5457.863553411196;4961.355023846877;5397.8380648580605;5432.728363581726;5442.210879422382;5458.61789674719;5203.869903252449;5360.43715409892;5385.246065550904;5481.945180548303;5650.576206784425;5131.769070391767;5368.2484452177505;5388.7036213007095;5416.377116631637;5421.373587923686 05:24:03 INFO - PROCESS | 2258 | |5;yui.html;654.6497623771281;679.8028571714203;730.6529398535696;732.7872371225624;758.4957496366785;12972.675683107915;15418.768718469231;15419.537413877602;15442.150681712501;15463.922680386611;14074;14109.929450352734;14127.223002734845;14134.717305653907;14940.775888361683;913.5158366065996;919.9448033118001;928.4290161550665;929.8186853563558;931.2596485793811;725.2819708488585;921.8801555797737;924.7364501117183;926.2127191886909;926.4441335198894;783.6591082878966;947.9431234125974;979.750163708257;983.2871168402922;985.241364149608;970.7476056225397;973.3478569358476;973.7760315127525;973.9707808765678;974.2985050763439;562.1118632560544;582.3303201318517;722.0757430488931;722.5881247688819;722.7578761115033;12534.122611417204;12584.307863067528;12584.37078146089;14772.48296309624;15246.771298430533;10308.639197628047;12381.328520649688;12391.318477483735;12411.689707757288;12449.502019919193;7856.685732570691;9928.45393503357;9976.850347244797;10049.849252261221;10187;3386.932261354784;3463.2034632034774;3537.451694987281;3664.560252769658;3687.649673281034;82603.7609435865;83541.16458835395;84286.57856710677;84335;84367.5781621088;9201.21789647874;9223.216026637676;9239.491827949392;9508.381955172892;9631.9036809631;21006.2647807326;21017;21284.255051073447;21311.36065918025;21322.573548529253;7322.231165727629;7336.596487193149;7388.371988381048;7438.256174382518;7558.13081495624;3986.461827653288;4058.964963934178;4059.5534491205945;4069.91860162801;4140.875773726793;3473.8263086846064;3543.645635436436;3567.23304489536;3569.39320315547;3572.964270357315;633.7496688807987;638.5498223752244;647.436730044864;653.3401264722664;663.5898715230136;495.4253066442909;507.3657927590512;535.4538370861699;558.5338486473008;583.4020129367348;7845.843083138419;8010.279074883289;8132.308753755997;8156.469829461066;8284.751457456287;508.6261597725669;529.888723368097;552.5441510753644;571.2003195526213;595.9808727076747;3795.6394142556414;3890.8638197662945;3896.7661940283674;3984.00399900025;4088.7751173685137;84767;84895.57552212322;85447.71828422579;87784;92082;78667.21332786599;79109;79227.20772792313;80843;81303.18696812956;66563.66718166281;67744;67845;68559;69497;8046.87929681067;8098.51408915467;8137.884150120893;8159.143289954469;8543.88148325869;40234.79882600568;40760.59239407568;41055.973600660225;41255;42614;55731.44268557263;58714.4128558709;62714.686426567576;62832.05751913634;63361.732765345354;9208.861867071866;10563.100627432304;10734.19493537972;10934.835977460185;10972.451377430936;12009.279443233434;12055.040831106771;12323.137380383645;12327;12402.75194496123;11268.605598804003;11306.152038597302;11338.886611134112;11487.712807179887;12032.939835300767;10980.151036579118;11384.943075284571;11497.770044599229;11587.88412115868;11816.881831181578;8405.957970210355;10889.965453282242;11248.775024499628;11471.254368466023;11636.94181529087;7577.583232922242;9255.907440925774;9409.321458524724;9447.763805904908;9717.27120465982;7587.051618547682;9054.456732596065;9440.33917625787;9458.621655133718;9599.568019439002 05:24:03 INFO - PROCESS | 2258 | __end_tp_report 05:24:03 INFO - PROCESS | 2258 | __start_cc_report 05:24:03 INFO - PROCESS | 2258 | _x_x_mozilla_cycle_collect,1396 05:24:03 INFO - PROCESS | 2258 | __end_cc_report 05:24:03 INFO - PROCESS | 2258 | __startTimestamp1462969442994__endTimestamp 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | ------- Summary: start ------- 05:24:03 INFO - PROCESS | 2258 | Number of tests: 6 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#0] dojo.html Cycles:165 Average:5725.08 Median:5941.52 stddev:656.27 (11.0%) stddev-sans-first:618.03 05:24:03 INFO - PROCESS | 2258 | Values: 2840.2 3401.9 3420.0 3454.7 3481.6 5645.4 5665.9 5680.9 5727.3 5763.1 5639.4 5673.0 5762.1 5782.6 5815.9 5063.4 5090.7 5151.5 5165.0 5247.1 5252.3 5277.5 5277.7 5297.9 5331.3 5133.3 5178.9 5193.7 5201.1 5242.9 5222.4 5226.9 5233.0 5239.5 5239.6 4266.0 5178.2 5195.2 5224.6 5240.3 5536.8 5559.2 5572.2 5612.9 5678.3 6201.8 6221.7 6256.2 6260.6 6280.6 6254.4 6273.0 6331.5 6331.6 6342.6 5671.6 5708.9 5741.1 5848.0 5861.4 6242.8 6248.7 6280.3 6297.4 6299.5 6152.8 6156.0 6160.3 6160.6 6161.5 6136.8 6172.7 6236.4 6253.7 6284.2 6230.4 6230.8 6236.1 6238.7 6241.0 6202.9 6265.9 6287.8 6299.6 6306.6 4798.7 4836.2 4839.0 4886.7 4888.1 4380.9 4455.8 4461.4 4491.5 4492.0 5225.8 5665.9 5682.5 5690.4 5692.9 6112.4 6164.4 6181.7 6188.3 6266.4 5644.9 5648.1 5653.5 5667.9 5680.0 5297.4 5300.1 5333.3 5334.8 5337.6 5600.7 6235.7 6277.6 6280.0 6367.4 6270.9 6292.5 6296.4 6305.1 6343.3 6228.4 6337.0 6349.9 6361.0 6368.6 6096.8 6142.5 6150.8 6205.8 6211.0 6212.4 6245.4 6251.6 6259.9 6289.4 5942.9 5977.7 6107.8 6111.6 6117.4 5941.5 6002.4 6065.6 6069.4 6106.4 5858.7 5904.4 5906.9 5976.2 5983.3 5908.6 6004.8 6044.5 6049.1 6109.8 5916.3 5924.7 5942.3 5945.4 5990.1 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#1] ext.html Cycles:180 Average:71981.02 Median:18399.68 stddev:163615.67 (889.2%) stddev-sans-first:163986.60 05:24:03 INFO - PROCESS | 2258 | Values: 489.6 495.0 506.8 522.2 540.7 30178.5 30543.5 31549.4 31608.8 31675.7 42444.4 43764.8 43890.8 46316.8 46324.0 9600.9 9761.8 9769.4 9772.9 9773.1 8624.4 8843.6 9592.0 9697.2 9713.8 8750.0 8819.3 8820.9 11656.8 11961.2 11914.0 11997.8 12007.2 12010.0 12025.8 11478.7 11561.6 11578.4 11578.7 11579.7 31641.7 39530.6 40173.0 40223.0 42139.8 3307.1 3349.9 3360.8 3560.6 3653.4 25866.2 27063.1 29595.1 30218.1 30524.5 2734.4 2739.1 2754.8 2770.0 2829.6 458866.0 462705.0 462729.0 463315.7 463887.0 58177.1 59267.0 59399.4 59428.4 59468.0 62152.4 62207.0 62283.6 62288.7 62291.7 16339.9 16572.6 16613.6 16617.3 16619.3 12893.7 12940.0 12950.4 12975.0 12987.9 14061.9 15108.5 15843.6 15900.9 15935.8 6043.5 6397.0 6419.7 7121.8 7135.6 985.3 1055.4 1063.1 1101.7 1111.4 29001.4 29332.0 29336.6 29519.6 29903.0 1086.3 1136.4 1156.8 1157.0 1234.2 14343.3 14834.8 14918.6 15301.0 15380.5 617336.0 624114.0 631140.0 632128.0 632577.0 50296.5 56966.0 57268.0 57378.0 57933.4 1481.9 1485.8 1492.9 1496.5 1510.5 29340.0 29486.7 29716.0 30348.0 30684.8 55505.4 55935.2 56568.7 56689.0 56700.4 593550.0 684625.0 700035.5 702070.0 856257.0 25204.5 25436.4 25540.5 25553.6 25613.2 36454.5 42593.4 43579.3 43933.7 44889.0 29240.7 36202.8 36473.3 36672.0 37059.9 32991.0 33449.5 35568.5 36465.6 36500.5 34949.5 35170.5 35275.8 35301.8 35798.7 30554.5 31422.2 31720.4 31853.0 31890.0 23852.2 23891.4 23914.4 24262.2 24512.3 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#2] jquery.html Cycles:180 Average:35030.23 Median:22162.66 stddev:102358.41 (461.9%) stddev-sans-first:102612.45 05:24:03 INFO - PROCESS | 2258 | Values: 361.2 399.3 401.9 406.0 424.1 20561.2 21854.9 21924.2 21960.3 22415.9 21274.8 21401.7 21598.4 22225.7 22286.0 5917.8 6093.8 6107.7 6137.7 6155.5 5995.7 6051.5 6059.3 6064.3 6129.4 5877.8 5999.0 6007.7 6027.9 6034.0 5857.8 6010.4 6011.4 6014.1 6022.3 5093.4 5231.5 5262.4 5262.9 5267.7 26610.2 27225.6 27735.3 27770.7 27854.3 2143.8 2190.3 2226.2 2238.4 2783.7 15071.5 15257.4 15415.5 15757.4 16156.7 1722.6 1743.8 1753.3 1812.3 1858.7 77039.0 97477.0 111015.0 120544.4 123965.0 11250.8 11505.1 11574.6 11583.4 11693.5 11349.5 13150.5 13727.2 14818.9 14884.2 8713.3 8924.6 8931.4 8981.9 9093.9 8863.3 8950.0 8965.6 9070.9 9082.6 5828.9 6055.5 7044.6 7055.3 7067.2 1848.0 1886.3 1889.2 1898.8 1909.4 884.3 963.9 989.4 1064.1 1095.2 20869.7 21866.1 21983.8 22067.6 22099.7 807.5 902.9 979.8 1013.4 1076.5 11054.3 11168.8 11269.6 11400.0 11880.5 608818.0 612006.0 617941.0 623170.0 625463.0 121369.0 124812.0 126794.0 126936.4 126949.0 1046.3 1048.2 1048.8 1090.8 1094.1 15581.1 19100.9 19487.8 19492.8 19790.5 44353.0 46928.3 47034.0 49300.1 50692.7 53981.7 54896.5 63576.2 70114.6 70551.6 8149.3 8289.8 8304.8 8330.9 8608.5 12660.8 12688.9 12952.6 13170.5 13213.0 11122.7 11294.8 11321.2 11335.9 11465.4 11021.2 11067.4 11148.0 11434.5 11576.1 11341.9 11513.3 11518.8 11705.2 11967.7 8155.3 8277.7 8290.2 8291.0 8326.2 11329.9 11569.6 11570.7 11671.1 11696.6 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#3] mootools.html Cycles:180 Average:5805.28 Median:4882.78 stddev:3323.13 (68.1%) stddev-sans-first:3316.16 05:24:03 INFO - PROCESS | 2258 | Values: 1426.3 1441.5 1624.9 1839.8 2001.9 7996.7 8673.8 9007.7 9033.8 9148.2 8861.3 8920.0 9018.5 9023.9 9026.5 2430.1 2452.2 2453.8 2458.1 2458.2 2380.0 2448.9 2449.0 2449.5 2449.7 2409.1 2423.9 2441.4 2443.7 2443.8 2074.9 2379.8 2399.6 2418.5 2426.1 2375.6 2403.3 2403.5 2410.3 2410.6 8785.3 8902.9 9004.6 9005.9 9020.2 5435.6 5580.8 5601.9 5604.6 5626.6 3489.3 3579.8 3630.7 3678.5 3699.5 901.9 1020.2 1033.5 1037.8 1043.5 12160.8 12274.8 12275.1 12276.6 12300.6 4617.5 4646.2 4782.5 4790.2 4808.3 9519.7 9530.8 9532.7 9545.2 9551.3 5358.9 5420.8 5436.7 5438.2 5438.7 4169.4 4480.8 4678.5 4991.9 4999.5 3854.4 3885.2 3897.3 3908.4 3983.1 927.9 1207.3 1215.2 1217.9 1220.8 898.5 908.8 916.5 957.3 975.8 5152.7 5738.4 5986.7 6123.7 6134.8 427.3 431.4 440.0 444.7 456.9 3959.6 3959.8 3994.3 4032.5 4037.3 9094.4 11153.9 11242.5 11339.1 11429.1 11188.9 11298.8 11318.6 11319.7 11325.2 8184.0 8239.8 8418.8 8459.0 8532.6 4985.3 5847.5 5850.6 5889.3 6192.3 9784.3 10204.2 10315.4 10346.2 10445.8 10041.4 10219.9 10449.9 10464.9 10580.6 7661.8 7678.3 7768.6 7775.4 7779.1 8104.8 8425.0 8481.8 8525.0 8529.4 7679.8 7732.2 7750.8 7756.0 7895.9 7564.1 7567.7 7572.7 7672.8 7755.1 6916.2 7218.2 7272.6 7274.0 7322.0 4862.7 4871.4 4894.1 4935.8 4981.8 4939.3 4996.7 5053.1 5072.6 5160.7 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#4] prototype.html Cycles:180 Average:4395.48 Median:4981.88 stddev:1360.46 (27.3%) stddev-sans-first:1328.52 05:24:03 INFO - PROCESS | 2258 | Values: 267.3 320.8 324.5 329.3 330.7 5235.9 5308.2 5358.4 5377.5 5383.9 4300.4 4829.4 5200.0 5259.3 5270.8 3469.4 3746.7 3772.3 3905.5 3964.4 3372.7 3562.4 3769.0 3953.3 3976.9 3352.3 3937.5 3979.5 3990.6 3998.2 3677.6 3756.0 3756.7 3884.9 3907.7 3008.7 3451.4 3475.9 3514.6 3515.8 4233.7 4952.8 5027.3 5042.7 5065.9 3827.7 4303.4 4521.4 4525.4 4526.0 4652.1 4732.8 5109.7 5342.1 5437.0 3064.6 3471.9 3513.9 3854.2 3879.6 4871.2 5645.5 5686.1 5782.0 5833.4 3454.1 3655.8 3800.8 3833.3 3836.5 3121.3 3433.9 3902.6 3909.4 3911.7 3856.8 4318.1 4359.5 4457.0 4473.4 4872.4 4906.7 5313.7 5449.0 5523.3 1286.7 1296.8 1311.9 1321.4 1323.3 686.8 704.9 726.2 778.8 816.6 4354.9 4379.8 4943.8 5062.0 5097.5 4948.5 5495.0 5504.4 5609.1 5631.6 4506.9 4957.9 5016.7 5111.8 5153.4 2949.1 2970.5 2988.9 2997.2 3010.0 4892.5 5713.9 5831.7 5868.7 5885.6 5039.8 5246.4 5402.8 5772.5 5803.6 4877.4 5227.0 5816.7 5830.3 5854.3 4892.0 5084.7 5220.8 5322.9 5382.9 4807.9 5254.7 5264.2 5517.6 5543.0 5297.7 5880.5 5897.7 5908.2 5932.6 3776.3 3782.9 3791.3 3795.5 3803.6 5204.8 5251.5 5597.7 5613.9 5661.7 4404.9 5399.5 5412.0 5422.9 5457.2 5002.4 5055.3 5107.2 5385.2 5457.9 4961.4 5397.8 5432.7 5442.2 5458.6 5203.9 5360.4 5385.2 5481.9 5650.6 5131.8 5368.2 5388.7 5416.4 5421.4 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - PROCESS | 2258 | [#5] yui.html Cycles:180 Average:17654.03 Median:31732.90 stddev:24911.20 (78.5%) stddev-sans-first:24948.38 05:24:03 INFO - PROCESS | 2258 | Values: 654.6 679.8 730.7 732.8 758.5 12972.7 15418.8 15419.5 15442.2 15463.9 14074.0 14109.9 14127.2 14134.7 14940.8 913.5 919.9 928.4 929.8 931.3 725.3 921.9 924.7 926.2 926.4 783.7 947.9 979.8 983.3 985.2 970.7 973.3 973.8 974.0 974.3 562.1 582.3 722.1 722.6 722.8 12534.1 12584.3 12584.4 14772.5 15246.8 10308.6 12381.3 12391.3 12411.7 12449.5 7856.7 9928.5 9976.9 10049.8 10187.0 3386.9 3463.2 3537.5 3664.6 3687.6 82603.8 83541.2 84286.6 84335.0 84367.6 9201.2 9223.2 9239.5 9508.4 9631.9 21006.3 21017.0 21284.3 21311.4 21322.6 7322.2 7336.6 7388.4 7438.3 7558.1 3986.5 4059.0 4059.6 4069.9 4140.9 3473.8 3543.6 3567.2 3569.4 3573.0 633.7 638.5 647.4 653.3 663.6 495.4 507.4 535.5 558.5 583.4 7845.8 8010.3 8132.3 8156.5 8284.8 508.6 529.9 552.5 571.2 596.0 3795.6 3890.9 3896.8 3984.0 4088.8 84767.0 84895.6 85447.7 87784.0 92082.0 78667.2 79109.0 79227.2 80843.0 81303.2 66563.7 67744.0 67845.0 68559.0 69497.0 8046.9 8098.5 8137.9 8159.1 8543.9 40234.8 40760.6 41056.0 41255.0 42614.0 55731.4 58714.4 62714.7 62832.1 63361.7 9208.9 10563.1 10734.2 10934.8 10972.5 12009.3 12055.0 12323.1 12327.0 12402.8 11268.6 11306.2 11338.9 11487.7 12032.9 10980.2 11384.9 11497.8 11587.9 11816.9 8406.0 10890.0 11248.8 11471.3 11636.9 7577.6 9255.9 9409.3 9447.8 9717.3 7587.1 9054.5 9440.3 9458.6 9599.6 05:24:03 INFO - PROCESS | 2258 | -------- Summary: end -------- 05:24:03 INFO - PROCESS | 2258 | 05:24:03 INFO - TEST-INFO | 2258: exit 0 05:24:03 INFO - TEST-OK | dromaeo_css | took 1116296ms 05:24:03 INFO - TEST-START | kraken 05:24:03 INFO - operating with platform_type : mac_ 05:24:03 INFO - Initialising browser for kraken test... 05:24:03 INFO - TEST-INFO | started process 2261 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpPRDPDo/profile http://localhost:52444/getInfo.html) 05:24:06 INFO - PROCESS | 2261 | __metrics Screen width/height:1600/1200 05:24:06 INFO - PROCESS | 2261 | colorDepth:24 05:24:06 INFO - PROCESS | 2261 | Browser inner width/height: 1024/689 05:24:06 INFO - PROCESS | 2261 | __metrics 05:24:06 INFO - PROCESS | 2261 | 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" 05:24:06 ERROR - PROCESS | 2261 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:24:06 INFO - TEST-INFO | 2261: exit 0 05:24:06 INFO - Browser initialized. 05:24:06 INFO - Running cycle 1/1 for kraken test... 05:24:06 INFO - TEST-INFO | started process 2264 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpPRDPDo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:19 INFO - PROCESS | 2264 | RSS: Main: 238641152 05:24:19 INFO - PROCESS | 2264 | 05:24:21 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:22 INFO - PROCESS | 2264 | RSS: Main: 287035392 05:24:22 INFO - PROCESS | 2264 | 05:24:25 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-dft) 05:24:26 INFO - PROCESS | 2264 | RSS: Main: 296325120 05:24:26 INFO - PROCESS | 2264 | 05:24:29 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-fft) 05:24:30 INFO - PROCESS | 2264 | RSS: Main: 384249856 05:24:30 INFO - PROCESS | 2264 | 05:24:32 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52444/tests/kraken/driver.html?testName=audio-oscillator) 05:24:33 INFO - PROCESS | 2264 | RSS: Main: 283766784 05:24:33 INFO - PROCESS | 2264 | 05:24:35 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:35 INFO - PROCESS | 2264 | RSS: Main: 287318016 05:24:35 INFO - PROCESS | 2264 | 05:24:38 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:39 INFO - PROCESS | 2264 | RSS: Main: 326393856 05:24:39 INFO - PROCESS | 2264 | 05:24:42 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52444/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:43 INFO - PROCESS | 2264 | RSS: Main: 329068544 05:24:43 INFO - PROCESS | 2264 | 05:24:46 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52444/tests/kraken/driver.html?testName=json-parse-financial) 05:24:47 INFO - PROCESS | 2264 | RSS: Main: 327786496 05:24:47 INFO - PROCESS | 2264 | 05:24:48 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52444/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:49 INFO - PROCESS | 2264 | RSS: Main: 316608512 05:24:49 INFO - PROCESS | 2264 | 05:24:51 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:52 INFO - PROCESS | 2264 | RSS: Main: 323878912 05:24:52 INFO - PROCESS | 2264 | 05:24:54 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:55 INFO - PROCESS | 2264 | RSS: Main: 325423104 05:24:55 INFO - PROCESS | 2264 | 05:24:58 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:58 INFO - PROCESS | 2264 | RSS: Main: 326148096 05:24:58 INFO - PROCESS | 2264 | 05:25:01 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:02 INFO - PROCESS | 2264 | RSS: Main: 321933312 05:25:02 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | Cycle 1(1): loaded http://localhost:52444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52444/tests/kraken/driver.html?testName=ai-astar) 05:25:04 INFO - PROCESS | 2264 | RSS: Main: 369037312 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | __start_tp_report 05:25:04 INFO - PROCESS | 2264 | _x_x_mozilla_page_load 05:25:04 INFO - PROCESS | 2264 | _x_x_mozilla_page_load_details 05:25:04 INFO - PROCESS | 2264 | |i|pagename|runs| 05:25:04 INFO - PROCESS | 2264 | |0;ai-astar;118;152;164;114;112;113;112;111;113;112 05:25:04 INFO - PROCESS | 2264 | |1;audio-beat-detection;160;104;168;206;137;169;163;115;168;116 05:25:04 INFO - PROCESS | 2264 | |2;audio-dft;189;185;186;185;187;183;184;184;182;185 05:25:04 INFO - PROCESS | 2264 | |3;audio-fft;79;74;59;158;82;87;56;58;112;55 05:25:04 INFO - PROCESS | 2264 | |4;audio-oscillator;93;99;133;80;82;76;101;80;77;100 05:25:04 INFO - PROCESS | 2264 | |5;imaging-gaussian-blur;87;84;86;85;86;82;87;84;87;85 05:25:04 INFO - PROCESS | 2264 | |6;imaging-darkroom;95;93;93;94;93;92;93;93;92;92 05:25:04 INFO - PROCESS | 2264 | |7;imaging-desaturate;85;101;139;86;83;84;84;87;84;83 05:25:04 INFO - PROCESS | 2264 | |8;json-parse-financial;59;59;58;57;56;78;83;165;70;56 05:25:04 INFO - PROCESS | 2264 | |9;json-stringify-tinderbox;63;77;74;73;71;68;67;69;68;71 05:25:04 INFO - PROCESS | 2264 | |10;stanford-crypto-aes;85;98;105;105;79;84;86;83;84;86 05:25:04 INFO - PROCESS | 2264 | |11;stanford-crypto-ccm;139;139;136;133;142;132;149;136;267;148 05:25:04 INFO - PROCESS | 2264 | |12;stanford-crypto-pbkdf2;168;170;171;166;224;225;168;166;168;168 05:25:04 INFO - PROCESS | 2264 | |13;stanford-crypto-sha256-iterative;67;67;69;67;66;66;67;70;82;83 05:25:04 INFO - PROCESS | 2264 | __end_tp_report 05:25:04 INFO - PROCESS | 2264 | __start_cc_report 05:25:04 INFO - PROCESS | 2264 | _x_x_mozilla_cycle_collect,2239 05:25:04 INFO - PROCESS | 2264 | __end_cc_report 05:25:04 INFO - PROCESS | 2264 | __startTimestamp1462969504523__endTimestamp 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | ------- Summary: start ------- 05:25:04 INFO - PROCESS | 2264 | Number of tests: 14 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#0] ai-astar Cycles:10 Average:122.10 Median:113.50 stddev:19.23 (16.9%) stddev-sans-first:20.34 05:25:04 INFO - PROCESS | 2264 | Values: 118.0 152.0 164.0 114.0 112.0 113.0 112.0 111.0 113.0 112.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:165.50 stddev:31.76 (19.2%) stddev-sans-first:33.50 05:25:04 INFO - PROCESS | 2264 | Values: 160.0 104.0 168.0 206.0 137.0 169.0 163.0 115.0 168.0 116.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:2.00 (1.1%) stddev-sans-first:1.51 05:25:04 INFO - PROCESS | 2264 | Values: 189.0 185.0 186.0 185.0 187.0 183.0 184.0 184.0 182.0 185.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#3] audio-fft Cycles:10 Average:82.00 Median:66.50 stddev:32.12 (48.3%) stddev-sans-first:34.05 05:25:04 INFO - PROCESS | 2264 | Values: 79.0 74.0 59.0 158.0 82.0 87.0 56.0 58.0 112.0 55.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:80.00 stddev:17.46 (21.8%) stddev-sans-first:18.52 05:25:04 INFO - PROCESS | 2264 | Values: 93.0 99.0 133.0 80.0 82.0 76.0 101.0 80.0 77.0 100.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.62 05:25:04 INFO - PROCESS | 2264 | Values: 87.0 84.0 86.0 85.0 86.0 82.0 87.0 84.0 87.0 85.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.94 (1.0%) stddev-sans-first:0.67 05:25:04 INFO - PROCESS | 2264 | Values: 95.0 93.0 93.0 94.0 93.0 92.0 93.0 93.0 92.0 92.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:84.00 stddev:17.49 (20.8%) stddev-sans-first:18.38 05:25:04 INFO - PROCESS | 2264 | Values: 85.0 101.0 139.0 86.0 83.0 84.0 84.0 87.0 84.0 83.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:59.00 stddev:33.39 (56.6%) stddev-sans-first:34.97 05:25:04 INFO - PROCESS | 2264 | Values: 59.0 59.0 58.0 57.0 56.0 78.0 83.0 165.0 70.0 56.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.98 (5.6%) stddev-sans-first:3.30 05:25:04 INFO - PROCESS | 2264 | Values: 63.0 77.0 74.0 73.0 71.0 68.0 67.0 69.0 68.0 71.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:84.50 stddev:9.49 (11.2%) stddev-sans-first:9.92 05:25:04 INFO - PROCESS | 2264 | Values: 85.0 98.0 105.0 105.0 79.0 84.0 86.0 83.0 84.0 86.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:140.50 stddev:40.77 (29.0%) stddev-sans-first:42.97 05:25:04 INFO - PROCESS | 2264 | Values: 139.0 139.0 136.0 133.0 142.0 132.0 149.0 136.0 267.0 148.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:169.00 stddev:23.82 (14.1%) stddev-sans-first:24.90 05:25:04 INFO - PROCESS | 2264 | Values: 168.0 170.0 171.0 166.0 224.0 225.0 168.0 166.0 168.0 168.0 05:25:04 INFO - PROCESS | 2264 | 05:25:04 INFO - PROCESS | 2264 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:68.00 stddev:6.50 (9.6%) stddev-sans-first:6.78 05:25:04 INFO - PROCESS | 2264 | Values: 67.0 67.0 69.0 67.0 66.0 66.0 67.0 70.0 82.0 83.0 05:25:04 INFO - PROCESS | 2264 | -------- Summary: end -------- 05:25:04 INFO - PROCESS | 2264 | 05:25:05 INFO - TEST-INFO | 2264: exit 0 05:25:05 INFO - TEST-OK | kraken | took 61800ms 05:25:05 INFO - SUITE-END | took 1178s 05:25:05 INFO - Completed test suite (00:19:38) 05:25:05 INFO - javascript benchmark 05:25:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2840.247334456369, 3401.897943061709, 3419.9829000854993, 3454.740894432915, 3481.6344283850194, 5645.407232240607, 5665.8583535411635, 5680.943190568093, 5727.312722473309, 5763.106718458635, 5639.351474580424, 5672.971635141829, 5762.1068734346245, 5782.566307526942, 5815.883682326351, 5063.392392912856, 5090.745462726849, 5151.484851514856, 5165.0, 5247.134222853225, 5252.290940722992, 5277.498637629419, 5277.736113194344, 5297.920531192016, 5331.333583302087, 5133.307003554528, 5178.896422071575, 5193.68837869729, 5201.0898092833795, 5242.8689282767855, 5222.4255331913455, 5226.92159617606, 5232.953409318152, 5239.476052394768, 5239.5546378557865, 4265.9786701066605, 5178.197379406198, 5195.19474481456, 5224.582033437354, 5240.266362709223, 5536.778528858841, 5559.166125081229, 5572.247746554204, 5612.915806262909, 5678.26182596264, 6201.751929922798, 6221.671405797876, 6256.249250090018, 6260.561760676755, 6280.560360774748, 6254.374562543755, 6272.968635156841, 6331.525135614801, 6331.620102793801, 6342.619442833445, 5671.63134396267, 5708.857278568069, 5741.081426971665, 5848.0, 5861.3552509223955, 6242.843928901813, 6248.656323902138, 6280.2777680566405, 6297.4017468340435, 6299.527535434769, 6152.815415537541, 6155.953487907066, 6160.260768707694, 6160.568760186834, 6161.476274516716, 6136.764169924236, 6172.69136543171, 6236.407541283571, 6253.6560489172625, 6284.183056202664, 6230.439260466489, 6230.750769969241, 6236.095766113979, 6238.719257633326, 6241.001439769706, 6202.906956395657, 6265.8746825063245, 6287.811365659038, 6299.590526615755, 6306.621602703853, 4798.688085274516, 4836.177849765478, 4839.008003359303, 4886.706797592155, 4888.095702295087, 4380.8904777380185, 4455.844045458393, 4461.397711308997, 4491.505934347219, 4492.011757413364, 5225.764840582183, 5665.886682266414, 5682.460166284196, 5690.402507736628, 5692.886142277214, 6112.351504965227, 6164.352742962014, 6181.660008699565, 6188.319284878659, 6266.373362663789, 5644.8871022579315, 5648.096304591246, 5653.491185793298, 5667.914981275284, 5680.005998950167, 5297.443768404339, 5300.1254792958725, 5333.333333333333, 5334.759935802898, 5337.599680024014, 5600.691961942132, 6235.657038862906, 6277.623342599459, 6280.0, 6367.363263673596, 6270.9372906270355, 6292.465140463112, 6296.370362963667, 6305.148804911279, 6343.270523889812, 6228.377162283736, 6337.0, 6349.936500635119, 6360.96819515918, 6368.5860419072615, 6096.768593606556, 6142.508599312134, 6150.7847225346895, 6205.782797602063, 6211.0, 6212.378762123752, 6245.375462453719, 6251.562390632612, 6259.906101408574, 6289.371062893674, 5942.910856337245, 5977.7310021050025, 6107.84730381744, 6111.572189946661, 6117.418845209698, 5941.524678025834, 6002.429769171922, 6065.5754097212775, 6069.423404776539, 6106.3588323227195, 5858.677772722541, 5904.409559044061, 5906.940930590811, 5976.193213916065, 5983.34183239852, 5908.615939963888, 6004.789832355847, 6044.516438684806, 6049.066640152976, 6109.7861574844665, 5916.290045194604, 5924.674142922181, 5942.31663358702, 5945.435183657545, 5990.1314309424015], "value": 5681.733813108698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.5593965431112, 494.9851504454865, 506.8418663354236, 522.1879976636332, 540.6999115490903, 30178.54732179019, 30543.541846872286, 31549.36901261979, 31608.841955790216, 31675.68324316756, 42444.363334550006, 43764.781176094075, 43890.7805460973, 46316.76841615787, 46324.0, 9600.855987160181, 9761.804763904718, 9769.413835169913, 9772.90227097727, 9773.120419162275, 8624.396292259513, 8843.646254149859, 9591.992840751725, 9697.175740062103, 9713.805723885518, 8750.0, 8819.29445644347, 8820.911790882074, 11656.82514762275, 11961.162718609701, 11914.0, 11997.800219977933, 12007.159498835128, 12010.03919686423, 12025.819612705816, 11478.655640330804, 11561.595344162914, 11578.421078946103, 11578.652640420716, 11579.710507237301, 31641.683583164104, 39530.60469395327, 40172.99567510806, 40222.99442513931, 42139.7893010533, 3307.1236122427576, 3349.866005359783, 3360.781549199294, 3560.572731272238, 3653.3606618841864, 25866.224013279632, 27063.052793152343, 29595.112146635634, 30218.09345719632, 30524.54213186804, 2734.398432344901, 2739.0960982875804, 2754.820936639132, 2770.0, 2829.5755636654667, 458866.0, 462705.0, 462729.0, 463315.6834215841, 463887.0, 58177.12734308989, 59267.0, 59399.40600593939, 59428.40571594229, 59468.0, 62152.37847621556, 62207.0, 62283.57440340734, 62288.688556556925, 62291.688541557, 16339.918300408423, 16572.58568535796, 16613.584660383345, 16617.3353065876, 16619.252133654016, 12893.742125157632, 12940.029497787704, 12950.352482375843, 12975.026872984376, 12987.896028837466, 14061.859381406055, 15108.471203507825, 15843.603909902346, 15900.92049539745, 15935.760963585555, 6043.516518678495, 6396.968015159894, 6419.743210271537, 7121.82195445118, 7135.643217839087, 985.3201291109111, 1055.3826011783192, 1063.1016790811752, 1101.686019484443, 1111.4220605285368, 29001.41997160045, 29332.0, 29336.559951600742, 29519.612427006447, 29903.0, 1086.32104934416, 1136.3863513702508, 1156.8431568431567, 1156.9826452603218, 1234.179270784918, 14343.282835858165, 14834.777478337834, 14918.627034324012, 15301.0, 15380.461683841235, 617336.0, 624114.0, 631140.0, 632128.0, 632577.0, 50296.49703502991, 56966.0, 57268.0, 57378.0, 57933.4206657928, 1481.9333130009175, 1485.814273215848, 1492.910425374481, 1496.5360738171419, 1510.4713350327518, 29340.0, 29486.705132948395, 29716.0, 30348.0, 30684.84657576698, 55505.44494555003, 55935.16097258626, 56568.71715641396, 56689.0, 56700.432995669515, 593550.0, 684625.0, 700035.499822518, 702070.0, 856257.0, 25204.49591008133, 25436.364090897874, 25540.489190216464, 25553.616695749206, 25613.23160305194, 36454.4531832028, 42593.36109958291, 43579.34630980601, 43933.68198954037, 44889.0, 29240.70759292465, 36202.8189859049, 36473.27053458969, 36672.0, 37059.88820335394, 32991.0, 33449.49825752664, 35568.46647300241, 36465.63534364622, 36500.45249321315, 34949.47575786416, 35170.47244291286, 35275.82362088173, 35301.82349088238, 35798.74704385334, 30554.541681875235, 31422.214444638154, 31720.36559268848, 31853.0, 31890.0, 23852.16517421882, 23891.402714932265, 23914.40213994664, 24262.15082472105, 24512.264632061066], "value": 19727.109338551778, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.2323811899713, 399.3410872061099, 401.8894803928921, 406.04174149008355, 424.1220673206462, 20561.177552897865, 21854.89072554637, 21924.23265185719, 21960.341189764313, 22415.887920560413, 21274.787252127473, 21401.67897481539, 21598.35204943854, 22225.666615000788, 22286.0, 5917.822465326047, 6093.817185484442, 6107.694615269241, 6137.6624285664275, 6155.507559395238, 5995.700214989233, 6051.455369016788, 6059.303180134275, 6064.272287325527, 6129.356417576158, 5877.764889404419, 5999.0, 6007.729652165641, 6027.879442411171, 6034.0, 5857.824265272048, 6010.39896010402, 6011.368806275365, 6014.067819487943, 6022.307434645006, 5093.388793344785, 5231.503007214308, 5262.447443018466, 5262.92105618416, 5267.683938963636, 26610.201693949213, 27225.591616125774, 27735.306617334525, 27770.722292777016, 27854.3036424089, 2143.7963393477594, 2190.3210004898533, 2226.1763147635315, 2238.373255488466, 2783.73554512321, 15071.547853564301, 15257.38970441181, 15415.460458883885, 15757.369705211779, 16156.676866462605, 1722.6210233748554, 1743.808181100078, 1753.32496988659, 1812.3475548802421, 1858.6840237159715, 77039.0, 97477.02522974821, 111014.99999999919, 120544.39727801304, 123965.0, 11250.774984500264, 11505.13711471643, 11574.594889179007, 11583.420828958519, 11693.532258709556, 11349.546018159346, 13150.539731109557, 13727.176369417668, 14818.851811481747, 14884.181370024537, 8713.3464990126, 8924.55377231136, 8931.374803763674, 8981.865272020925, 9093.863592046124, 8863.335249856287, 8950.0, 8965.551722413984, 9070.8683459387, 9082.637604359266, 5828.88342233153, 6055.455009049207, 7044.583377062121, 7055.259197784261, 7067.151941767021, 1847.9815201848078, 1886.3025873001739, 1889.1782074797507, 1898.8196121368449, 1909.4080834941212, 884.2528063786091, 963.9228861691048, 989.4162444157981, 1064.1486810551683, 1095.2169199022737, 20869.68695469569, 21866.12535498594, 21983.780162198495, 22067.558648826933, 22099.651588815173, 807.4549678966674, 902.8600566912139, 979.8040391921502, 1013.4020927652721, 1076.5263284154946, 11054.336739795637, 11168.832467512993, 11269.647642282978, 11400.0, 11880.524779008743, 608818.0, 612006.0, 617941.0, 623170.0, 625463.0, 121369.0, 124812.0, 126794.0, 126936.36531817282, 126949.0, 1046.3355769086684, 1048.2400259811727, 1048.8007278617038, 1090.8418279349607, 1094.0536435982817, 15581.065136091871, 19100.94944777995, 19487.805121949164, 19492.847141597063, 19790.536656085416, 44353.0, 46928.29607555958, 47034.0, 49300.097986282664, 50692.746536268554, 53981.730091349295, 54896.45103548913, 63576.15234948525, 70114.64942675458, 70551.64724176346, 8149.307308878811, 8289.751307460785, 8304.83390332202, 8330.875036874331, 8608.48349099056, 12660.810087848506, 12688.87311126877, 12952.611421657366, 13170.473181073034, 13213.0, 11122.666320010412, 11294.830577541179, 11321.207515473838, 11335.943320283346, 11465.36940468249, 11021.189596652803, 11067.446627668423, 11148.0, 11434.542618295174, 11576.073914087021, 11341.886581134084, 11513.251638643462, 11518.827217591575, 11705.180637355303, 11967.726132034886, 8155.266026057683, 8277.668893244203, 8290.212429819157, 8290.958545207235, 8326.209010144026, 11329.943350283196, 11569.595064172714, 11570.710732231762, 11671.12466564994, 11696.649100526658], "value": 9873.197387987317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.26547328126, 1441.4810668159466, 1624.8869524375802, 1839.7700287464068, 2001.8698784578992, 7996.720114795983, 8673.826523469535, 9007.72976810695, 9033.774155646104, 9148.17666410023, 8861.335399845006, 8920.0, 9018.549072546362, 9023.909760902388, 9026.458412495254, 2430.064425196303, 2452.1540068676277, 2453.7791598756107, 2458.0782206672498, 2458.2010846474877, 2380.0296137296386, 2448.926532204037, 2449.0448724997177, 2449.534588428193, 2449.742777008415, 2409.144753612469, 2423.9394015149674, 2441.353041444019, 2443.6701045358914, 2443.767842055011, 2074.948126296847, 2379.750126236755, 2399.5800734871414, 2418.504206637635, 2426.0902161689364, 2375.572396968545, 2403.2790162951046, 2403.459221675109, 2410.2528216253017, 2410.5660981023407, 8785.297176225886, 8902.86645700315, 9004.59479323432, 9005.864912026325, 9020.233280171193, 5435.619506634537, 5580.776768929238, 5601.859953501195, 5604.551635869121, 5626.634268772516, 3489.284696637183, 3579.8031108289033, 3630.6732394084397, 3678.4850120983083, 3699.519062521882, 901.8692289618034, 1020.1787561013381, 1033.5400746667735, 1037.7509397744564, 1043.4886905416367, 12160.756784864341, 12274.81587776193, 12275.140740148194, 12276.570320038845, 12300.569480068245, 4617.515160908057, 4646.233371493733, 4782.473927867932, 4790.161721698689, 4808.3027960945465, 9519.714408567754, 9530.809383812284, 9532.666356677622, 9545.236381089497, 9551.283653726, 5358.866028349323, 5420.83737487876, 5436.728163591804, 5438.184272359178, 5438.673679579238, 4169.374593810892, 4480.775961201992, 4678.485366609738, 4991.850244492671, 4999.500049994971, 3854.383298672199, 3885.1646895916947, 3897.2595206910596, 3908.355121404993, 3983.103801644607, 927.8958039932459, 1207.335965219126, 1215.2465471407784, 1217.9147459677915, 1220.816877468369, 898.4564338575068, 908.7682640926654, 916.4592890194817, 957.3179209120378, 975.8048390321964, 5152.742362881841, 5738.368779434259, 5986.670733109634, 6123.693815309217, 6134.846628834315, 427.26510402108323, 431.36804581287936, 439.97580133092345, 444.65539207114745, 456.88349470884714, 3959.6339262954234, 3959.7822119783114, 3994.3409337458993, 4032.4556184915245, 4037.273290807624, 9094.408863423856, 11153.888461115448, 11242.49408776607, 11339.092872570174, 11429.142814288962, 11188.944055279671, 11298.774024519464, 11318.60384886525, 11319.660410187707, 11325.150613704005, 8184.017917849777, 8239.794005149919, 8418.789530261793, 8458.957705211558, 8532.616032278562, 4985.252212168059, 5847.473727364558, 5850.648961062179, 5889.263842019747, 6192.256929168529, 9784.315097943076, 10204.183665306906, 10315.432651204255, 10346.172306215334, 10445.791084178425, 10041.447720375449, 10219.897801022193, 10449.895501044892, 10464.895351046393, 10580.629677961235, 7661.770146895602, 7678.308952194329, 7768.572728499761, 7775.377969762519, 7779.105402878741, 8104.797380065545, 8424.957875210584, 8481.83036339282, 8525.0, 8529.360297977553, 7679.846403072019, 7732.188120247293, 7750.7674769756995, 7756.0, 7895.921040789519, 7564.130125035471, 7567.659455324413, 7572.734954276574, 7672.769816905278, 7755.146933837386, 6916.23921368639, 7218.169910460154, 7272.636368181676, 7274.01800756891, 7321.963390183015, 4862.659613826998, 4871.415430148405, 4894.14352488306, 4935.8025678972435, 4981.775820088033, 4939.333190019302, 4996.675216110941, 5053.11570475158, 5072.5796776902525, 5160.741962901915], "value": 4553.895355538946, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.2863454576281, 320.77545717997396, 324.4597744754983, 329.2525966057049, 330.7106282003247, 5235.921461178081, 5308.150695888654, 5358.410574836765, 5377.462253774621, 5383.850447488215, 4300.3764454154125, 4829.396325459317, 5200.01199772043, 5259.342582177228, 5270.761371077793, 3469.3755124077657, 3746.700263978889, 3772.3209822232056, 3905.453236546886, 3964.3547927610007, 3372.7470439717117, 3562.358775420423, 3768.962310376902, 3953.3279342511696, 3976.940345894814, 3352.2625022495013, 3937.4684417603544, 3979.4826672532677, 3990.6009399060154, 3998.2203470323298, 3677.595464498903, 3755.9812200939095, 3756.7084078711964, 3884.892805550433, 3907.7264591478606, 3008.6540047894337, 3451.4132597458242, 3475.9131021724406, 3514.648535146465, 3515.8221995631493, 4233.724807887477, 4952.826651067196, 5027.296178535008, 5042.672226305279, 5065.935109258919, 3827.674647654953, 4303.397524346594, 4521.440103164436, 4525.366448697185, 4526.0495295987885, 4652.139354219446, 4732.763361831895, 5109.718965456936, 5342.1452567589, 5437.042664640726, 3064.5709600655928, 3471.930561388758, 3513.8931236660624, 3854.1713531590563, 3879.603342796591, 4871.24495703163, 5645.520130788929, 5686.066737309293, 5782.0, 5833.387494313079, 3454.101933497309, 3655.7623754455876, 3800.764751455788, 3833.252515759456, 3836.501254836853, 3121.2508997840355, 3433.9141521461665, 3902.6389034634203, 3909.3580696107133, 3911.726179167431, 3856.8457261709777, 4318.10003598422, 4359.498657654411, 4456.977715111404, 4473.418455600751, 4872.3665923430435, 4906.70559766408, 5313.707746073926, 5448.972755136199, 5523.25436066134, 1286.7389957841367, 1296.824928634641, 1311.8556958734532, 1321.3657444426537, 1323.2788130468919, 686.7699320727512, 704.8942658601251, 726.1721637333444, 778.780702884271, 816.6488409983764, 4354.912901741947, 4379.802908869108, 4943.752812359367, 5061.974690126526, 5097.46476619957, 4948.540180646718, 5495.0, 5504.4495550444635, 5609.1305847593685, 5631.621027383619, 4506.909861802811, 4957.851264462072, 5016.724080175552, 5111.7699703513435, 5153.3558305211845, 2949.1273041618297, 2970.5098658721067, 2988.8505574721175, 2997.1607949774098, 3009.9849500752357, 4892.461829198785, 5713.942860571424, 5831.679257640871, 5868.65253898444, 5885.588008839341, 5039.77321020555, 5246.396664383568, 5402.756875940592, 5772.538196944234, 5803.593748437569, 4877.414710234723, 5226.9546090782, 5816.651000939957, 5830.329512106077, 5854.331515518057, 4891.9755401222765, 5084.720340381315, 5220.843374698765, 5322.935412917354, 5382.946170538401, 4807.903841923212, 5254.710990895537, 5264.236480778829, 5517.558595312285, 5543.029969755357, 5297.72854514906, 5880.529557635294, 5897.705114744332, 5908.202392677105, 5932.555058370725, 3776.320262352693, 3782.9432558511094, 3791.312865774704, 3795.487609172837, 3803.6386543278345, 5204.765785539736, 5251.52736253739, 5597.748101335368, 5613.88772224545, 5661.745221464962, 4404.911901761882, 5399.546458077291, 5411.972940135274, 5422.891542169214, 5457.235986961908, 5002.399712034579, 5055.292259083805, 5107.208382700687, 5385.219143224289, 5457.863553411196, 4961.355023846877, 5397.8380648580605, 5432.728363581726, 5442.210879422382, 5458.61789674719, 5203.869903252449, 5360.43715409892, 5385.246065550904, 5481.945180548303, 5650.576206784425, 5131.769070391767, 5368.2484452177505, 5388.7036213007095, 5416.377116631637, 5421.373587923686], "value": 3958.7405880611004, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.6497623771281, 679.8028571714203, 730.6529398535696, 732.7872371225624, 758.4957496366785, 12972.675683107915, 15418.768718469231, 15419.537413877602, 15442.150681712501, 15463.922680386611, 14074.0, 14109.929450352734, 14127.223002734845, 14134.717305653907, 14940.775888361683, 913.5158366065996, 919.9448033118001, 928.4290161550665, 929.8186853563558, 931.2596485793811, 725.2819708488585, 921.8801555797737, 924.7364501117183, 926.2127191886909, 926.4441335198894, 783.6591082878966, 947.9431234125974, 979.750163708257, 983.2871168402922, 985.241364149608, 970.7476056225397, 973.3478569358476, 973.7760315127525, 973.9707808765678, 974.2985050763439, 562.1118632560544, 582.3303201318517, 722.0757430488931, 722.5881247688819, 722.7578761115033, 12534.122611417204, 12584.307863067528, 12584.37078146089, 14772.48296309624, 15246.771298430533, 10308.639197628047, 12381.328520649688, 12391.318477483735, 12411.689707757288, 12449.502019919193, 7856.685732570691, 9928.45393503357, 9976.850347244797, 10049.849252261221, 10187.0, 3386.932261354784, 3463.2034632034774, 3537.451694987281, 3664.560252769658, 3687.649673281034, 82603.7609435865, 83541.16458835395, 84286.57856710677, 84335.0, 84367.5781621088, 9201.21789647874, 9223.216026637676, 9239.491827949392, 9508.381955172892, 9631.9036809631, 21006.2647807326, 21017.0, 21284.255051073447, 21311.36065918025, 21322.573548529253, 7322.231165727629, 7336.596487193149, 7388.371988381048, 7438.256174382518, 7558.13081495624, 3986.461827653288, 4058.964963934178, 4059.5534491205945, 4069.91860162801, 4140.875773726793, 3473.8263086846064, 3543.645635436436, 3567.23304489536, 3569.39320315547, 3572.964270357315, 633.7496688807987, 638.5498223752244, 647.436730044864, 653.3401264722664, 663.5898715230136, 495.4253066442909, 507.3657927590512, 535.4538370861699, 558.5338486473008, 583.4020129367348, 7845.843083138419, 8010.279074883289, 8132.308753755997, 8156.469829461066, 8284.751457456287, 508.6261597725669, 529.888723368097, 552.5441510753644, 571.2003195526213, 595.9808727076747, 3795.6394142556414, 3890.8638197662945, 3896.7661940283674, 3984.00399900025, 4088.7751173685137, 84767.0, 84895.57552212322, 85447.71828422579, 87784.0, 92082.0, 78667.21332786599, 79109.0, 79227.20772792313, 80843.0, 81303.18696812956, 66563.66718166281, 67744.0, 67845.0, 68559.0, 69497.0, 8046.87929681067, 8098.51408915467, 8137.884150120893, 8159.143289954469, 8543.88148325869, 40234.79882600568, 40760.59239407568, 41055.973600660225, 41255.0, 42614.0, 55731.44268557263, 58714.4128558709, 62714.686426567576, 62832.05751913634, 63361.732765345354, 9208.861867071866, 10563.100627432304, 10734.19493537972, 10934.835977460185, 10972.451377430936, 12009.279443233434, 12055.040831106771, 12323.137380383645, 12327.0, 12402.75194496123, 11268.605598804003, 11306.152038597302, 11338.886611134112, 11487.712807179887, 12032.939835300767, 10980.151036579118, 11384.943075284571, 11497.770044599229, 11587.88412115868, 11816.881831181578, 8405.957970210355, 10889.965453282242, 11248.775024499628, 11471.254368466023, 11636.94181529087, 7577.583232922242, 9255.907440925774, 9409.321458524724, 9447.763805904908, 9717.27120465982, 7587.051618547682, 9054.456732596065, 9440.33917625787, 9458.621655133718, 9599.568019439002], "value": 6731.450906962203, "unit": "score"}], "name": "dromaeo_css", "value": 7156.210984613704, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 152.0, 164.0, 114.0, 112.0, 113.0, 112.0, 111.0, 113.0, 112.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 104.0, 168.0, 206.0, 137.0, 169.0, 163.0, 115.0, 168.0, 116.0], "value": 150.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 186.0, 185.0, 187.0, 183.0, 184.0, 184.0, 182.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 59.0, 158.0, 82.0, 87.0, 56.0, 58.0, 112.0, 55.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 133.0, 80.0, 82.0, 76.0, 101.0, 80.0, 77.0, 100.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 85.0, 86.0, 82.0, 87.0, 84.0, 87.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 101.0, 139.0, 86.0, 83.0, 84.0, 84.0, 87.0, 84.0, 83.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 57.0, 56.0, 78.0, 83.0, 165.0, 70.0, 56.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 77.0, 74.0, 73.0, 71.0, 68.0, 67.0, 69.0, 68.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 98.0, 105.0, 105.0, 79.0, 84.0, 86.0, 83.0, 84.0, 86.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 136.0, 133.0, 142.0, 132.0, 149.0, 136.0, 267.0, 148.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 171.0, 166.0, 224.0, 225.0, 168.0, 166.0, 168.0, 168.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 66.0, 66.0, 67.0, 70.0, 82.0, 83.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1537.3000000000002, "alertThreshold": 2.0}]} 05:25:05 INFO - Return code: 0 05:25:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:25:05 ERROR - # TBPL SUCCESS # 05:25:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:05 INFO - 2016-05-11 05:25:05.798 screenresolution[2268:19364] starting screenresolution argv=screenresolution get 05:25:05 INFO - 2016-05-11 05:25:05.801 screenresolution[2268:19364] Display 0: 1600x1200x32@60 05:25:05 INFO - 2016-05-11 05:25:05.807 screenresolution[2269:19367] starting screenresolution argv=screenresolution list 05:25:05 INFO - Available Modes on Display 0 05:25:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:06 INFO - Intel Iris: 05:25:06 INFO - Chipset Model: Intel Iris 05:25:06 INFO - Type: GPU 05:25:06 INFO - Bus: Built-In 05:25:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:06 INFO - Vendor: Intel (0x8086) 05:25:06 INFO - Device ID: 0x0a2e 05:25:06 INFO - Revision ID: 0x0009 05:25:06 INFO - Displays: 05:25:06 INFO - Display: 05:25:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:06 INFO - Display Serial Number: 1600x1200 60 05:25:06 INFO - Main Display: Yes 05:25:06 INFO - Mirror: Off 05:25:06 INFO - Online: Yes 05:25:06 INFO - Rotation: Supported 05:25:06 INFO - Return code: 0 05:25:06 INFO - Running post-action listener: _resource_record_post_action 05:25:06 INFO - ##### 05:25:06 INFO - ##### Finished run-tests step (success) 05:25:06 INFO - ##### 05:25:06 INFO - Running post-run listener: _resource_record_post_run 05:25:07 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 237568; Write bytes: 693055488; Read time: 32; Write time: 1962 05:25:07 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 381563392; Write bytes: 376076288; Read time: 28332; Write time: 680 05:25:07 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 237568; Write bytes: 295491584; Read time: 32; Write time: 1232 05:25:07 INFO - Running post-run listener: _upload_blobber_files 05:25:07 INFO - Blob upload gear active. 05:25:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:07 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 05:25:07 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'] 05:25:07 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 05:25:07 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:08 INFO - (blobuploader) - INFO - Done attempting. 05:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:09 INFO - (blobuploader) - INFO - Done attempting. 05:25:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:09 INFO - Return code: 0 05:25:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d395dc852ad18452c20508167d0e32f525a2c37bf3c0a8eb4bcf04a9f573e2bfafefb7e561c97f48323ee69bd4aeb60ca528f58e92742acec6515807eeb64769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7055fa98ffb34b3d90c179bbb6de9deb16ceee1a4cd508d6eb33f89c851a5515649ecc17eb2d59913c9ef6ce39ad459a97774cf9dc44425831c9737fbabe6af4"} 05:25:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:09 INFO - Contents: 05:25:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d395dc852ad18452c20508167d0e32f525a2c37bf3c0a8eb4bcf04a9f573e2bfafefb7e561c97f48323ee69bd4aeb60ca528f58e92742acec6515807eeb64769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7055fa98ffb34b3d90c179bbb6de9deb16ceee1a4cd508d6eb33f89c851a5515649ecc17eb2d59913c9ef6ce39ad459a97774cf9dc44425831c9737fbabe6af4"} 05:25:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:09 INFO - Copying logs to upload dir... 05:25:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.339593 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-11 05:25:09.541195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:09.544534) ========= 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.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/d395dc852ad18452c20508167d0e32f525a2c37bf3c0a8eb4bcf04a9f573e2bfafefb7e561c97f48323ee69bd4aeb60ca528f58e92742acec6515807eeb64769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7055fa98ffb34b3d90c179bbb6de9deb16ceee1a4cd508d6eb33f89c851a5515649ecc17eb2d59913c9ef6ce39ad459a97774cf9dc44425831c9737fbabe6af4"} build_url:https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/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/d395dc852ad18452c20508167d0e32f525a2c37bf3c0a8eb4bcf04a9f573e2bfafefb7e561c97f48323ee69bd4aeb60ca528f58e92742acec6515807eeb64769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7055fa98ffb34b3d90c179bbb6de9deb16ceee1a4cd508d6eb33f89c851a5515649ecc17eb2d59913c9ef6ce39ad459a97774cf9dc44425831c9737fbabe6af4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:09.572627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:09.572980) ========= 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.N38tyiZEiP/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.dqhgU6QJC6/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005333 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:09.621712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 05:25:09.622031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 05:25:09.622361) ========= ========= Total master_lag: 0.16 =========