builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0053 starttime: 1462881858.17 results: success (0) buildid: 20160510030710 builduid: 2365e4bf703b425484e5d911e00d7883 revision: 1579b9e2e50f3a27ad02d58cc9170c91e0973fec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.168262) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.168800) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.169074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.187465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.187824) ========= 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.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 05:04:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 4.53M=0.003s 2016-05-10 05:04:18 (4.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.592549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.797749) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.798060) ========= 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.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.019631 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.846429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:18.846783) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1579b9e2e50f3a27ad02d58cc9170c91e0973fec --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 1579b9e2e50f3a27ad02d58cc9170c91e0973fec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 05:04:18,917 truncating revision to first 12 chars 2016-05-10 05:04:18,917 Setting DEBUG logging. 2016-05-10 05:04:18,917 attempt 1/10 2016-05-10 05:04:18,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1579b9e2e50f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 05:04:19,189 unpacking tar archive at: mozilla-central-1579b9e2e50f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.522290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:19.386008) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:19.386334) ========= script_repo_revision: 1579b9e2e50f3a27ad02d58cc9170c91e0973fec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:19.386719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:19.386987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 05:04:19.400461) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-10 05:04:19.400775) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:04:19 INFO - MultiFileLogger online at 20160510 05:04:19 in /builds/slave/test 05:04:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:04:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:19 INFO - {'append_to_log': False, 05:04:19 INFO - 'base_work_dir': '/builds/slave/test', 05:04:19 INFO - 'blob_upload_branch': 'Firefox', 05:04:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:19 INFO - 'branch': 'Firefox', 05:04:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:19 INFO - 'config_files': ('talos/mac_config.py',), 05:04:19 INFO - 'default_actions': ('clobber', 05:04:19 INFO - 'read-buildbot-config', 05:04:19 INFO - 'download-and-extract', 05:04:19 INFO - 'populate-webroot', 05:04:19 INFO - 'create-virtualenv', 05:04:19 INFO - 'install', 05:04:19 INFO - 'run-tests'), 05:04:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:19 INFO - 'download_minidump_stackwalk': True, 05:04:19 INFO - 'download_symbols': 'ondemand', 05:04:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:19 INFO - 'installer_path': 'installer.exe', 05:04:19 INFO - 'log_level': 'info', 05:04:19 INFO - 'log_name': 'talos', 05:04:19 INFO - 'log_to_console': True, 05:04:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:04:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:04:19 INFO - 'opt_config_files': (), 05:04:19 INFO - 'pip_index': False, 05:04:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:19 INFO - 'cmd': ('bash', 05:04:19 INFO - '-c', 05:04:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:19 INFO - 'enabled': True, 05:04:19 INFO - 'halt_on_failure': False, 05:04:19 INFO - 'name': 'check_screen_resolution'},), 05:04:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:19 INFO - 'cmd': ('bash', 05:04:19 INFO - '-c', 05:04:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:19 INFO - 'enabled': True, 05:04:19 INFO - 'halt_on_failure': False, 05:04:19 INFO - 'name': 'check_screen_resolution'},), 05:04:19 INFO - 'run_cmd_checks_enabled': True, 05:04:19 INFO - 'sps_profile': False, 05:04:19 INFO - 'sps_profile_interval': 0, 05:04:19 INFO - 'suite': 'dromaeojs-e10s', 05:04:19 INFO - 'system_bits': '32', 05:04:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:19 INFO - 'title': 't-yosemite-r7-0053', 05:04:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:19 INFO - 'use_talos_json': True, 05:04:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:19 INFO - 'work_dir': 'build'} 05:04:19 INFO - ##### 05:04:19 INFO - ##### Running clobber step. 05:04:19 INFO - ##### 05:04:19 INFO - Running pre-action listener: _resource_record_pre_action 05:04:19 INFO - Running main action method: clobber 05:04:19 INFO - rmtree: /builds/slave/test/build 05:04:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:20 INFO - Running post-action listener: _resource_record_post_action 05:04:20 INFO - ##### 05:04:20 INFO - ##### Finished clobber step (success) 05:04:20 INFO - ##### 05:04:20 INFO - ##### 05:04:20 INFO - ##### Running read-buildbot-config step. 05:04:20 INFO - ##### 05:04:20 INFO - Running pre-action listener: _resource_record_pre_action 05:04:20 INFO - Running main action method: read_buildbot_config 05:04:20 INFO - Using buildbot properties: 05:04:20 INFO - { 05:04:20 INFO - "project": "", 05:04:20 INFO - "product": "firefox", 05:04:20 INFO - "script_repo_revision": "production", 05:04:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:04:20 INFO - "repository": "", 05:04:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:04:20 INFO - "buildid": "20160510030710", 05:04:20 INFO - "pgo_build": "False", 05:04:20 INFO - "basedir": "/builds/slave/test", 05:04:20 INFO - "buildnumber": 4, 05:04:20 INFO - "slavename": "t-yosemite-r7-0053", 05:04:20 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:04:20 INFO - "platform": "yosemite_r7", 05:04:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:04:20 INFO - "branch": "mozilla-central", 05:04:20 INFO - "repo_path": "mozilla-central", 05:04:20 INFO - "revision": "1579b9e2e50f3a27ad02d58cc9170c91e0973fec", 05:04:20 INFO - "stage_platform": "macosx64", 05:04:20 INFO - "builduid": "2365e4bf703b425484e5d911e00d7883", 05:04:20 INFO - "slavebuilddir": "test" 05:04:20 INFO - } 05:04:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:04:20 INFO - Running post-action listener: _resource_record_post_action 05:04:20 INFO - ##### 05:04:20 INFO - ##### Finished read-buildbot-config step (success) 05:04:20 INFO - ##### 05:04:20 INFO - ##### 05:04:20 INFO - ##### Running download-and-extract step. 05:04:20 INFO - ##### 05:04:20 INFO - Running pre-action listener: _resource_record_pre_action 05:04:20 INFO - Running main action method: download_and_extract 05:04:20 INFO - mkdir: /builds/slave/test/build/tests 05:04:20 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:20 INFO - https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:04:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:22 INFO - Downloaded 1199 bytes. 05:04:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:22 INFO - Using the following test package requirements: 05:04:22 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:04:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:04:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'jsshell-mac.zip'], 05:04:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:04:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:04:22 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:04:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:04:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:22 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:04:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 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 - https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:24 INFO - Downloaded 18149743 bytes. 05:04:24 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:24 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:27 INFO - Return code: 0 05:04:27 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:27 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:27 INFO - https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:28 INFO - Downloaded 18149743 bytes. 05:04:28 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:28 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:32 INFO - Return code: 0 05:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:32 INFO - https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:04:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:33 INFO - Downloaded 10840152 bytes. 05:04:33 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:33 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:33 INFO - Return code: 0 05:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:33 INFO - https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:04:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:04:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a_gQ64TlQHywRrhDGL_f6g/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:36 INFO - Downloaded 109803161 bytes. 05:04:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:36 INFO - mkdir: /builds/slave/test/properties 05:04:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:36 INFO - Contents: 05:04:36 INFO - build_url:https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:37 INFO - Running post-action listener: _resource_record_post_action 05:04:37 INFO - Running post-action listener: set_extra_try_arguments 05:04:37 INFO - ##### 05:04:37 INFO - ##### Finished download-and-extract step (success) 05:04:37 INFO - ##### 05:04:37 INFO - ##### 05:04:37 INFO - ##### Running populate-webroot step. 05:04:37 INFO - ##### 05:04:37 INFO - Running pre-action listener: _resource_record_pre_action 05:04:37 INFO - Running main action method: populate_webroot 05:04:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:37 INFO - '--tppagecycles', 05:04:37 INFO - '7'], 05:04:37 INFO - 'tests': ['tsvgm']}}, 05:04:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['damp', 'tps']}, 05:04:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'tests': ['damp', 'tps']}, 05:04:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['dromaeo_dom']}, 05:04:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['a11yr', 05:04:37 INFO - 'ts_paint', 05:04:37 INFO - 'tpaint', 05:04:37 INFO - 'sessionrestore', 05:04:37 INFO - 'sessionrestore_no_auto_restore', 05:04:37 INFO - 'tabpaint']}, 05:04:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['tsvgx', 05:04:37 INFO - 'tsvgr_opacity', 05:04:37 INFO - 'tart', 05:04:37 INFO - 'tscrollx', 05:04:37 INFO - 'cart']}, 05:04:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:37 INFO - 'tsvgr_opacity', 05:04:37 INFO - 'tart', 05:04:37 INFO - 'tscrollx', 05:04:37 INFO - 'cart']}, 05:04:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'talos_options': ['--disable-e10s'], 05:04:37 INFO - 'tests': ['tp5o']}, 05:04:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'tests': ['tp5o']}, 05:04:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'talos_options': ['--disable-e10s', 05:04:37 INFO - '--xperf_path', 05:04:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:37 INFO - 'tests': ['tp5n']}, 05:04:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:37 INFO - 'talos_options': ['--xperf_path', 05:04:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:37 INFO - 'tests': ['tp5n']}}, 05:04:37 INFO - 'talos.zip': {'path': '', 05:04:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:37 INFO - Running post-action listener: _resource_record_post_action 05:04:37 INFO - ##### 05:04:37 INFO - ##### Finished populate-webroot step (success) 05:04:37 INFO - ##### 05:04:37 INFO - ##### 05:04:37 INFO - ##### Running create-virtualenv step. 05:04:37 INFO - ##### 05:04:37 INFO - Running pre-action listener: _resource_record_pre_action 05:04:37 INFO - Running main action method: create_virtualenv 05:04:37 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:37 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:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:37 INFO - Using real prefix '/tools/python27' 05:04:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:41 INFO - Installing pip.................done. 05:04:41 INFO - Return code: 0 05:04:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:41 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:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:41 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:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:41 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:41 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:41 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:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:41 INFO - 'HOME': '/Users/cltbld', 05:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:41 INFO - 'LOGNAME': 'cltbld', 05:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:41 INFO - 'MOZ_NO_REMOTE': '1', 05:04:41 INFO - 'NO_EM_RESTART': '1', 05:04:41 INFO - 'PAGER': '/bin/cat', 05:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:41 INFO - 'PWD': '/builds/slave/test', 05:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:41 INFO - 'SHELL': '/bin/bash', 05:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:41 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:41 INFO - 'USER': 'cltbld', 05:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:41 INFO - 'XPC_FLAGS': '0x0', 05:04:41 INFO - 'XPC_SERVICE_NAME': '0', 05:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:41 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:41 INFO - Cleaning up... 05:04:41 INFO - Return code: 0 05:04:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:04:41 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:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:41 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:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:41 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:41 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:41 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:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:41 INFO - 'HOME': '/Users/cltbld', 05:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:41 INFO - 'LOGNAME': 'cltbld', 05:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:41 INFO - 'MOZ_NO_REMOTE': '1', 05:04:41 INFO - 'NO_EM_RESTART': '1', 05:04:41 INFO - 'PAGER': '/bin/cat', 05:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:41 INFO - 'PWD': '/builds/slave/test', 05:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:41 INFO - 'SHELL': '/bin/bash', 05:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:41 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:41 INFO - 'USER': 'cltbld', 05:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:41 INFO - 'XPC_FLAGS': '0x0', 05:04:41 INFO - 'XPC_SERVICE_NAME': '0', 05:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:41 INFO - Downloading/unpacking psutil>=0.7.1 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:44 INFO - Installing collected packages: psutil 05:04:44 INFO - Running setup.py install for psutil 05:04:44 INFO - building 'psutil._psutil_osx' extension 05:04:44 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:45 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:45 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:45 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:45 INFO - building 'psutil._psutil_posix' extension 05:04:45 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:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:45 INFO - ^ 05:04:45 INFO - 1 warning generated. 05:04:45 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:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:45 INFO - Successfully installed psutil 05:04:45 INFO - Cleaning up... 05:04:45 INFO - Return code: 0 05:04:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:04:45 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:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:45 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:45 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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:45 INFO - 'HOME': '/Users/cltbld', 05:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:45 INFO - 'LOGNAME': 'cltbld', 05:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:45 INFO - 'MOZ_NO_REMOTE': '1', 05:04:45 INFO - 'NO_EM_RESTART': '1', 05:04:45 INFO - 'PAGER': '/bin/cat', 05:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:45 INFO - 'PWD': '/builds/slave/test', 05:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:45 INFO - 'SHELL': '/bin/bash', 05:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:45 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:45 INFO - 'USER': 'cltbld', 05:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:45 INFO - 'XPC_FLAGS': '0x0', 05:04:45 INFO - 'XPC_SERVICE_NAME': '0', 05:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:04:45 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:45 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:45 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:45 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:45 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:45 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:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:48 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:48 INFO - Installing collected packages: mozsystemmonitor 05:04:48 INFO - Running setup.py install for mozsystemmonitor 05:04:48 INFO - Successfully installed mozsystemmonitor 05:04:48 INFO - Cleaning up... 05:04:48 INFO - Return code: 0 05:04:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:48 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:48 INFO - 'HOME': '/Users/cltbld', 05:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:48 INFO - 'LOGNAME': 'cltbld', 05:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:48 INFO - 'MOZ_NO_REMOTE': '1', 05:04:48 INFO - 'NO_EM_RESTART': '1', 05:04:48 INFO - 'PAGER': '/bin/cat', 05:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:48 INFO - 'PWD': '/builds/slave/test', 05:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:48 INFO - 'SHELL': '/bin/bash', 05:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:48 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:48 INFO - 'USER': 'cltbld', 05:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:48 INFO - 'XPC_FLAGS': '0x0', 05:04:48 INFO - 'XPC_SERVICE_NAME': '0', 05:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:48 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:48 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:48 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:48 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:48 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:48 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 blobuploader-1.2.4.tar.gz 05:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 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/requests/setup.py) egg_info for package requests 05:04:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - Downloading docopt-0.6.1.tar.gz 05:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:52 INFO - Running setup.py install for blobuploader 05:04:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:52 INFO - Running setup.py install for requests 05:04:53 INFO - Running setup.py install for docopt 05:04:53 INFO - Successfully installed blobuploader requests docopt 05:04:53 INFO - Cleaning up... 05:04:53 INFO - Return code: 0 05:04:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:53 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:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:53 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:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:53 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:53 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:53 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:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:53 INFO - 'HOME': '/Users/cltbld', 05:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:53 INFO - 'LOGNAME': 'cltbld', 05:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:53 INFO - 'MOZ_NO_REMOTE': '1', 05:04:53 INFO - 'NO_EM_RESTART': '1', 05:04:53 INFO - 'PAGER': '/bin/cat', 05:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:53 INFO - 'PWD': '/builds/slave/test', 05:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:53 INFO - 'SHELL': '/bin/bash', 05:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:53 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:53 INFO - 'USER': 'cltbld', 05:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:53 INFO - 'XPC_FLAGS': '0x0', 05:04:53 INFO - 'XPC_SERVICE_NAME': '0', 05:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:53 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-6f01R7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:53 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-fzgaf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:53 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-UWbFHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:53 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-RffK5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-UnsBvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-4nLpTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Jaed6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-K8Ypap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-e5gCWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-XnLI6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-0UDcUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-B7biNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:54 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-a5BSz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:55 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-XjuRbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:55 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-kVKcDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:55 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-eX0NXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:55 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-iCt0YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:55 INFO - Running setup.py install for manifestparser 05:04:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:55 INFO - Running setup.py install for mozcrash 05:04:55 INFO - Running setup.py install for mozdebug 05:04:55 INFO - Running setup.py install for mozdevice 05:04:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Running setup.py install for mozfile 05:04:56 INFO - Running setup.py install for mozhttpd 05:04:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Running setup.py install for mozinfo 05:04:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Running setup.py install for mozInstall 05:04:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Running setup.py install for mozleak 05:04:56 INFO - Running setup.py install for mozlog 05:04:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:56 INFO - Running setup.py install for moznetwork 05:04:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:57 INFO - Running setup.py install for mozprocess 05:04:57 INFO - Running setup.py install for mozprofile 05:04:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:57 INFO - Running setup.py install for mozrunner 05:04:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:57 INFO - Running setup.py install for mozscreenshot 05:04:57 INFO - Running setup.py install for moztest 05:04:57 INFO - Running setup.py install for mozversion 05:04:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:58 INFO - Cleaning up... 05:04:58 INFO - Return code: 0 05:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:58 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:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:58 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:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:58 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:58 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:58 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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:58 INFO - 'HOME': '/Users/cltbld', 05:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:58 INFO - 'LOGNAME': 'cltbld', 05:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:58 INFO - 'MOZ_NO_REMOTE': '1', 05:04:58 INFO - 'NO_EM_RESTART': '1', 05:04:58 INFO - 'PAGER': '/bin/cat', 05:04:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:58 INFO - 'PWD': '/builds/slave/test', 05:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:58 INFO - 'SHELL': '/bin/bash', 05:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:04:58 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:58 INFO - 'USER': 'cltbld', 05:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:58 INFO - 'XPC_FLAGS': '0x0', 05:04:58 INFO - 'XPC_SERVICE_NAME': '0', 05:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-BL20s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-mKCkF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-nq3Kcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-XDHaVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-L96tuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:58 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ll0Phz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:58 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:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-TSQOA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-eOQPFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-qngiZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Fu0dGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-zwU7DY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-stcy5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-npx6Q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-y2psV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-4FzCMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:59 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-kpMMaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:00 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:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:00 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-En20j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:00 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:05:00 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:05:00 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:05:00 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:05:00 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:05:00 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:05:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:00 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:05:00 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:05:00 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:05:00 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:05:00 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:05:00 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:05:02 INFO - Downloading blessings-1.6.tar.gz 05:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:02 INFO - Installing collected packages: blessings 05:05:02 INFO - Running setup.py install for blessings 05:05:02 INFO - Successfully installed blessings 05:05:02 INFO - Cleaning up... 05:05:02 INFO - Return code: 0 05:05:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:03 INFO - Reading from file tmpfile_stdout 05:05:03 INFO - Current package versions: 05:05:03 INFO - blessings == 1.6 05:05:03 INFO - blobuploader == 1.2.4 05:05:03 INFO - docopt == 0.6.1 05:05:03 INFO - manifestparser == 1.1 05:05:03 INFO - mozInstall == 1.12 05:05:03 INFO - mozcrash == 0.17 05:05:03 INFO - mozdebug == 0.1 05:05:03 INFO - mozdevice == 0.48 05:05:03 INFO - mozfile == 1.2 05:05:03 INFO - mozhttpd == 0.7 05:05:03 INFO - mozinfo == 0.9 05:05:03 INFO - mozleak == 0.1 05:05:03 INFO - mozlog == 3.1 05:05:03 INFO - moznetwork == 0.27 05:05:03 INFO - mozprocess == 0.22 05:05:03 INFO - mozprofile == 0.28 05:05:03 INFO - mozrunner == 6.11 05:05:03 INFO - mozscreenshot == 0.1 05:05:03 INFO - mozsystemmonitor == 0.0 05:05:03 INFO - moztest == 0.7 05:05:03 INFO - mozversion == 1.4 05:05:03 INFO - psutil == 3.1.1 05:05:03 INFO - requests == 1.2.3 05:05:03 INFO - wsgiref == 0.1.2 05:05:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:03 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:05:03 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:05:03 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:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:03 INFO - 'HOME': '/Users/cltbld', 05:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:03 INFO - 'LOGNAME': 'cltbld', 05:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:03 INFO - 'MOZ_NO_REMOTE': '1', 05:05:03 INFO - 'NO_EM_RESTART': '1', 05:05:03 INFO - 'PAGER': '/bin/cat', 05:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:03 INFO - 'PWD': '/builds/slave/test', 05:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:03 INFO - 'SHELL': '/bin/bash', 05:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:05:03 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:03 INFO - 'USER': 'cltbld', 05:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:03 INFO - 'XPC_FLAGS': '0x0', 05:05:03 INFO - 'XPC_SERVICE_NAME': '0', 05:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 INFO - Cleaning up... 05:05:03 INFO - Return code: 0 05:05:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:03 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:05:03 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:05:03 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:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:03 INFO - 'HOME': '/Users/cltbld', 05:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:03 INFO - 'LOGNAME': 'cltbld', 05:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:03 INFO - 'MOZ_NO_REMOTE': '1', 05:05:03 INFO - 'NO_EM_RESTART': '1', 05:05:03 INFO - 'PAGER': '/bin/cat', 05:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:03 INFO - 'PWD': '/builds/slave/test', 05:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:03 INFO - 'SHELL': '/bin/bash', 05:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:05:03 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:03 INFO - 'USER': 'cltbld', 05:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:03 INFO - 'XPC_FLAGS': '0x0', 05:05:03 INFO - 'XPC_SERVICE_NAME': '0', 05:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:03 INFO - Downloading/unpacking jsonschema 05:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:03 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:05:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:06 INFO - Downloading/unpacking functools32 (from jsonschema) 05:05:06 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:05:06 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:05:06 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:05:06 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:05:06 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:05:06 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:05:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:06 INFO - warning: no files found matching '*.txt' 05:05:06 INFO - no previously-included directories found matching 'build' 05:05:06 INFO - no previously-included directories found matching 'dist' 05:05:06 INFO - no previously-included directories found matching '.git*' 05:05:06 INFO - Installing collected packages: jsonschema, functools32 05:05:06 INFO - Running setup.py install for functools32 05:05:06 INFO - warning: no files found matching '*.txt' 05:05:06 INFO - no previously-included directories found matching 'build' 05:05:06 INFO - no previously-included directories found matching 'dist' 05:05:06 INFO - no previously-included directories found matching '.git*' 05:05:06 INFO - Successfully installed jsonschema functools32 05:05:06 INFO - Cleaning up... 05:05:06 INFO - Return code: 0 05:05:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:06 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 0x10a204a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6624c78d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, '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.TH6QxWwHJM/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.veL809AtTb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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:05:06 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:05:06 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:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:06 INFO - 'HOME': '/Users/cltbld', 05:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:06 INFO - 'LOGNAME': 'cltbld', 05:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:06 INFO - 'MOZ_NO_REMOTE': '1', 05:05:06 INFO - 'NO_EM_RESTART': '1', 05:05:06 INFO - 'PAGER': '/bin/cat', 05:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:06 INFO - 'PWD': '/builds/slave/test', 05:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:06 INFO - 'SHELL': '/bin/bash', 05:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:05:06 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:06 INFO - 'USER': 'cltbld', 05:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:06 INFO - 'XPC_FLAGS': '0x0', 05:05:06 INFO - 'XPC_SERVICE_NAME': '0', 05:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:07 INFO - Downloading/unpacking flake8 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:05:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:05:09 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:05:09 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:05:09 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:05:09 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:05:09 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:05:09 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:05:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:05:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:05:09 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:05:09 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:05:09 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:05:09 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:05:09 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:05:09 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:05:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:05:10 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:05:10 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:05:10 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:05:10 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:05:10 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:05:10 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:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:05:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:05:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:05:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:05:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:05:10 INFO - no previously-included directories found matching 'docs/_build' 05:05:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:05:10 INFO - Running setup.py install for pep8 05:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:05:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:05:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:05:11 INFO - no previously-included directories found matching 'docs/_build' 05:05:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:05:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:05:11 INFO - Cleaning up... 05:05:11 INFO - Return code: 0 05:05:11 INFO - Running post-action listener: _resource_record_post_action 05:05:11 INFO - Running post-action listener: _start_resource_monitoring 05:05:11 INFO - Starting resource monitoring. 05:05:11 INFO - ##### 05:05:11 INFO - ##### Finished create-virtualenv step (success) 05:05:11 INFO - ##### 05:05:11 INFO - ##### 05:05:11 INFO - ##### Running install step. 05:05:11 INFO - ##### 05:05:11 INFO - Running pre-action listener: _resource_record_pre_action 05:05:11 INFO - Running main action method: install 05:05:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:11 INFO - Reading from file tmpfile_stdout 05:05:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:11 INFO - Reading from file tmpfile_stdout 05:05:11 INFO - Output received: 05:05:11 INFO - Usage: mozinstall [options] installer 05:05:11 INFO - Options: 05:05:11 INFO - -h, --help show this help message and exit 05:05:11 INFO - -d DEST, --destination=DEST 05:05:11 INFO - Directory to install application into. [default: 05:05:11 INFO - "/builds/slave/test"] 05:05:11 INFO - --app=APP Application being installed. [default: firefox] 05:05:11 INFO - mkdir: /builds/slave/test/build/application 05:05:11 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:05:11 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:44 INFO - Reading from file tmpfile_stdout 05:05:44 INFO - Output received: 05:05:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:05:44 INFO - Running post-action listener: _resource_record_post_action 05:05:44 INFO - ##### 05:05:44 INFO - ##### Finished install step (success) 05:05:44 INFO - ##### 05:05:44 INFO - ##### 05:05:44 INFO - ##### Running run-tests step. 05:05:44 INFO - ##### 05:05:44 INFO - Running pre-action listener: _resource_record_pre_action 05:05:44 INFO - Running main action method: run_tests 05:05:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:44 INFO - 2016-05-10 05:05:44.821 screenresolution[1925:13506] starting screenresolution argv=screenresolution get 05:05:44 INFO - 2016-05-10 05:05:44.824 screenresolution[1925:13506] Display 0: 1600x1200x32@60 05:05:44 INFO - 2016-05-10 05:05:44.829 screenresolution[1926:13508] starting screenresolution argv=screenresolution list 05:05:44 INFO - Available Modes on Display 0 05:05:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:45 INFO - Intel Iris: 05:05:45 INFO - Chipset Model: Intel Iris 05:05:45 INFO - Type: GPU 05:05:45 INFO - Bus: Built-In 05:05:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:45 INFO - Vendor: Intel (0x8086) 05:05:45 INFO - Device ID: 0x0a2e 05:05:45 INFO - Revision ID: 0x0009 05:05:45 INFO - Displays: 05:05:45 INFO - Display: 05:05:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:45 INFO - Display Serial Number: 1600x1200 60 05:05:45 INFO - Main Display: Yes 05:05:45 INFO - Mirror: Off 05:05:45 INFO - Online: Yes 05:05:45 INFO - Rotation: Supported 05:05:45 INFO - Return code: 0 05:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:45 INFO - Python 2.7.3 05:05:45 INFO - Return code: 0 05:05:45 INFO - grabbing minidump binary from tooltool 05:05:45 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:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a0a8d30>, '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 0x109eccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a09fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:45 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:45 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:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:45 INFO - Return code: 0 05:05:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:05:46 INFO - Return code: 0 05:05:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:05:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:05:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TH6QxWwHJM/Render', 05:05:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:46 INFO - 'HOME': '/Users/cltbld', 05:05:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:46 INFO - 'LOGNAME': 'cltbld', 05:05:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:46 INFO - 'MOZ_NO_REMOTE': '1', 05:05:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:46 INFO - 'NO_EM_RESTART': '1', 05:05:46 INFO - 'PAGER': '/bin/cat', 05:05:46 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:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:46 INFO - 'PWD': '/builds/slave/test', 05:05:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:46 INFO - 'SHELL': '/bin/bash', 05:05:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.veL809AtTb/Listeners', 05:05:46 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:05:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:46 INFO - 'USER': 'cltbld', 05:05:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:46 INFO - 'XPC_FLAGS': '0x0', 05:05:46 INFO - 'XPC_SERVICE_NAME': '0', 05:05:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:05:46 INFO - mozversion application_buildid: 20160510030710 05:05:46 INFO - mozversion application_changeset: 1579b9e2e50f3a27ad02d58cc9170c91e0973fec 05:05:46 INFO - mozversion application_display_name: Nightly 05:05:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:46 INFO - mozversion application_name: Firefox 05:05:46 INFO - mozversion application_remotingname: firefox 05:05:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:46 INFO - mozversion application_vendor: Mozilla 05:05:46 INFO - mozversion application_version: 49.0a1 05:05:46 INFO - mozversion platform_buildid: 20160510030710 05:05:46 INFO - mozversion platform_changeset: 1579b9e2e50f3a27ad02d58cc9170c91e0973fec 05:05:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:46 INFO - mozversion platform_version: 49.0a1 05:05:46 INFO - using testdate: 1462881946 05:05:46 INFO - actual date: 1462881946 05:05:46 INFO - starting webserver on 'localhost:51213' 05:05:46 INFO - SUITE-START | Running 2 tests 05:05:46 INFO - TEST-START | dromaeo_css 05:05:46 INFO - operating with platform_type : mac_ 05:05:46 INFO - Initialising browser for dromaeo_css test... 05:05:46 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpxNxPSg/profile http://localhost:51213/getInfo.html) 05:05:49 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 05:05:49 INFO - PROCESS | 1944 | colorDepth:24 05:05:49 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 05:05:49 INFO - PROCESS | 1944 | __metrics 05:05:49 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 05:05:50 INFO - TEST-INFO | 1944: exit 0 05:05:50 INFO - Browser initialized. 05:05:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:50 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpxNxPSg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:02 INFO - PROCESS | 1947 | RSS: Main: 249860096 05:06:02 INFO - PROCESS | 1947 | 05:06:52 INFO - PROCESS | 1947 | 1462882012008 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:09:02 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51213/tests/dromaeo/cssquery-ext.html) 05:09:03 INFO - PROCESS | 1947 | RSS: Main: 264568832 05:09:03 INFO - PROCESS | 1947 | 05:12:05 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-ext.html (next: http://localhost:51213/tests/dromaeo/cssquery-jquery.html) 05:12:06 INFO - PROCESS | 1947 | RSS: Main: 275247104 05:12:06 INFO - PROCESS | 1947 | 05:15:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51213/tests/dromaeo/cssquery-mootools.html) 05:15:09 INFO - PROCESS | 1947 | RSS: Main: 277250048 05:15:09 INFO - PROCESS | 1947 | 05:18:10 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51213/tests/dromaeo/cssquery-prototype.html) 05:18:11 INFO - PROCESS | 1947 | RSS: Main: 278663168 05:18:11 INFO - PROCESS | 1947 | 05:21:14 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51213/tests/dromaeo/cssquery-yui.html) 05:21:15 INFO - PROCESS | 1947 | RSS: Main: 281858048 05:21:15 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51213/tests/dromaeo/cssquery-yui.html (next: http://localhost:51213/tests/dromaeo/cssquery-dojo.html) 05:24:17 INFO - PROCESS | 1947 | RSS: Main: 282824704 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | __start_tp_report 05:24:17 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 05:24:17 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 05:24:17 INFO - PROCESS | 1947 | |i|pagename|runs| 05:24:17 INFO - PROCESS | 1947 | |0;dojo.html;2959.2158078109305;3424.691777740006;3495.947560786589;3523.982380088099;3529.4529347951075;5751.396103409144;5772.451617096374;5898.616589921652;5929.080992446167;5932.37710040446;5640.464155905182;5775.480206781389;5841.97765391057;5967.910481342783;5998.640081595107;5248.737563121838;5268.789248430058;5368.275282836806;5381.67709937403;5419.783208671648;360.6731302070259;5338.332708411449;5342.2253773202865;5406.432324605918;5435.864103397407;5319.281896943901;5332.546733527654;5336.119540275849;5361.410244873061;5374.328208973879;5250.002499525116;5351.170568561879;5383.138697808332;5395.271638328814;5396.83809485716;5234.136367499358;5246.973765131188;5284.762185701614;5294.338207724035;5328.520433161034;5634.605577609569;5787.218725472091;5797.159411885253;5842.73707683151;5885.85285367865;6190.473809726179;6239.720857224258;6309.4636955858805;6342.429181373653;6345.904811427832;4904.926426103611;6317.968410157966;6335.588186767845;6341.556091073627;6341.714622841983;6038.698065096728;6063.120847477091;6063.939360606426;6067.817965461043;6078;6255.280642726054;6300.3699630037545;6325.5255855811;6401.135846660734;6401.647909364937;6252.187215661935;6300.180976473029;6301.306856245809;6310.810675679737;6328.557001009977;6270;6284.905726414107;6324.051392291193;6331.841703957346;6334.619922804646;4503.795249624985;6217.751289948352;6252.937470625327;6256.5307601930035;6319.304876463585;6209.96895015531;6256.186695729617;6277.52918531112;6279.780207692709;6283.748650054038;4958.157113290749;5056.443791182967;5074.08666440037;5075.492450754895;5099.617528685363;4156.9792151039655;4178.1643671265865;4315.28797748368;4512.616427603691;4517.909641807211;5908.556858235564;6026.578139530191;6030.18592490017;6056.878862422727;6065.878682426415;6345.524085693592;6346.619202847844;6355.777547785898;6361.745530178833;6369.458596019297;5222.981518603911;5831.475167234969;5939.3763654816485;5975.19334889793;6011.489023433057;5420.91868621971;5573.303337082864;5588.524975377057;5594.24477695514;5596.27248457706;6194.256689197326;6282.780102696383;6561.606303621798;6581.769638062645;6599.868002639921;6222.875542489034;6227.875442491216;6264.279607844974;6349.904751428825;6354.078658594571;6504.60972341661;6526.412622863965;6545.836354091186;6566.310537393505;6567.310432404529;6147.924113280247;6244.219472565929;6249.000159974382;6304.779332723333;6308.71610777507;5202;6324.272708638566;6325.240971083499;6341.841453963688;6346;6286.905696414466;6313.526485513513;6328.303886572381;6329.746810127727;6332.335104814112;6130.092410195535;6195.690215489298;6200.906986395117;6291.433770960635;6310.1796766420075;5962.8968640801595;6025.758969641341;6102.664353460602;6125.050617154348;6206.379362063938;6101.217066730222;6200.224971878515;6255.710270248586;6276.623402595859;6277.717502712298;6048.123022161674;6121.418465245794;6176.474999625082;6191.84520386994;6218.036204388146 05:24:17 INFO - PROCESS | 1947 | |1;ext.html;505.335483838752;513.463430714903;526.1739069660633;530.4509832323544;559.7900787204799;32471.83764081179;42123;42228.73313800587;42278.57721422785;42319;42009.789951050276;42591.36112958308;42673.786631066876;42891.57108428915;42897;9550.33147679661;9637.662681806138;9725.027497250288;9730.65942692006;9781.804363912717;9634.181094606967;9670.951645241765;9677.612895484172;9715.951420242889;9752.268579856503;9777.902220977805;9789.608415663364;9792.461414622228;9793.167580755644;9809.362391444569;9582.760430989212;9673;9684.75788105296;9730.562124704406;9747.512624368788;9492.715218543455;9512.571934262905;9527.190188833958;9593.47235902025;9612.807743845155;39427.40858887119;39432.80283598564;39479;39740.60259397398;39905;3081.8613162407746;3108.7202151806446;3311.9668803311897;3539.2036791721657;3939.9015024624614;28650.283742906595;29132.854335728185;29359.972400965864;29582;30078.097657070324;2230.5984922713906;2719.945601087967;2749.8900043998215;2759.1446651537885;2767.6678798544103;507045;515561;515561.68876622256;516142;518696;34679.653203468144;45307;62281.688591556755;62301.68849155725;62505.68747156326;35796.642033579854;36816.63183368186;37193.25613487715;37356.43965340522;37429.62570374316;14829.258537073103;14966.80265578773;15058.247087645794;15086.321115549827;15088;12599.118061735773;12874.098813083181;12953.093283470067;12975.480980760853;12993.220406775436;7645.006149200568;7691.346235569928;8293.502389856629;8302.626381812834;8385.371097167737;3411.7441191910707;3444.293919746446;3453.9481907771406;3458.4985177149674;3495.807730574843;934.3272843552631;961.8845738511424;978.090375950373;1062.628080171949;1141.058626633031;27901.860490697418;29622.407551848843;29731;29989.400211996075;30102.397952041276;1049.1134990932649;1056.2183983852049;1080.897314755097;1106.8893110689028;1146.529922731676;11432.199746017865;12616.87383126157;14962.401503939938;15054.611135782017;15827.546730256829;890635;1009038;1029672;1030417;1030975;60140.69929650324;60177.097343539885;61520.07719884205;61693.38306616966;61764.382356176764;1428.9142651440948;1429;1444.9422023119168;1452.2811208451883;1452.527928423288;28071;29765.702342976292;29788.55317170201;30621.23446913845;30985;52694.209586856996;53148.73425633002;53229.201561975824;53277.73361133169;53283.7335813334;637667;642019.7899010475;642960;643432;643660;27134.864325678245;27411.31471713223;27909.860450697615;27998.580021300106;28006.85996570004;44288.77855610701;45192.77403612961;45300;45625.8593535151;45968.54031459642;36619.81690091533;37142.25715485621;37265.6273437273;37972;38198.8090059559;29383.502402296046;36433.27133457369;36535.634643653226;36770.448443273905;37177.4423383644;29217.41565168642;32978.34043319168;36510.4523432154;36682.8165859169;37444.43833342556;30596.388072238875;33118.66881331156;33137.66862331346;33285;33385.83307083449;19586.902065489583;24589.13938012161;24659.136930207358;24674.13640522573;24737.886795093906 05:24:17 INFO - PROCESS | 1947 | |2;jquery.html;311.3182131132819;399.41884557968154;402.5732723313287;412.5214750888966;421.56368158955485;11774.058075353973;18544.721829172573;18695.3456629018;21716.782832171673;21731.891340543316;22233.2218372357;22695.886520567416;22893.542129157406;23417.531649367003;23767.524649507;5871.941280587203;6818.488613353993;7578.924210757877;7590.962045189766;7700;6655.234648015492;7487.217310701568;7838.686452541891;7899;7903.802404939865;7661.195574464685;7781.130237251399;7871.724489642864;7896.921030789705;7924.326432253265;7077.858442831166;7875.409344299172;7918.485298455611;7918.80202994924;7929.444938854283;7141.285871412869;7290.307420795069;7352.264773522658;7398.889016664754;7406.333429991327;28462.146135615963;28618;29507.262318441997;30024.399512009637;30268;2133.264023911748;2192.4299682082615;2349.659299401594;2382.9046838126455;2402.5074859653905;15707.528774136794;15713.685726285525;16685.66628667432;16803.6375213913;16976.915115424345;1816.6366726654721;1816.8819026763217;1834.9357772478033;1858.4053103007043;1912.7513423255027;97180;122997.38501307525;127290.36354818166;148665.7966971151;155665.22167389203;21711.782882171294;22417.88791056067;22521.43696407603;22631;22656.773432265796;37200.81399592985;37550;37607.05982350408;37629.87110386693;37631.9302438363;11150.665480035612;11545.711357215969;11609.941950290195;11626.76746465066;11712;9355.345125841126;9389.90610093904;9428.198603118812;9486.667966621273;9576.329656924088;9631.71104866855;9899.604015839286;10031.799364012679;10059.04439078301;10171.949140254399;3154.9904030709954;3332.6334103248623;3522.8238588070494;3904.54013971202;3937.625925537069;814.739283429297;876.3164731509432;881.725149115281;921.5991043895926;938.7559234599054;20289.28987485431;21073.016855418642;21120.78879211219;21243.78756212449;21593.568128637653;865.9781457879636;883.9204471597459;912.8224590278467;926.1618235496886;929.0802105915095;8112.959435202904;8441.451305665112;11199.60801371948;11247.044001259821;11272.323660580392;426179;533165;536155;561008;644353;106709.4664526688;132956;138442;140777.5922240785;141293;1013.7313611892854;1054.3779170289565;1070.4968664727567;1075.9246852720235;1077.1113831089383;18121;18773.90613046926;18805.90597047006;18972;19155.90422047881;56044.159337609155;57612.71193644005;57624.423755761905;58577;59165;60650.69674651598;83577;84617;84717.57641211755;85975;5327.3074500314715;5914.763409463573;8127.065387480516;8130.308923741311;8194.22154895284;12608.558700445441;12629.431675574804;12841.678958026125;12847.35763211817;12983.156094853804;10705.9464702676;10713.196510261918;11061.502232399722;11122;11418.942905285421;10932.781344372908;10981.83527247076;11017.669469915916;11110.44447777624;11224.7755044897;11089.390083545482;11168.66494005181;11281.830772538582;11314.717132071764;11323.603673871376;8098.473599216032;8192.426530142833;8217;8334.791630209293;8558.486490810572;10822.350658960488;10977.3962432067;11170.162237832032;11241.831372529583;11372.260803047775 05:24:17 INFO - PROCESS | 1947 | |3;mootools.html;1640.9589760255992;1661.9916900415496;1942.766867975843;1972.8224459798614;1984.2955750708506;8046.316063134641;8705;8743.300535957123;9260.953695231523;9266.305027122968;8847.867281990775;8918.197362237397;9002.32482563807;9050;9071.41035832672;2378.2746262390006;2409.4337830609793;2412.384841865322;2414.396400899775;2424.9272521824373;2445.7065152181763;2446.987765061172;2462.729099799021;2480.4790993891306;2494.8128890333205;2429.9392515187174;2453.901843926241;2470.2712699753615;2470.567650661136;2473.6165894286323;2394.449276666359;2445.8410203336816;2451.3994071452607;2456.8403053801535;2460.2619214235747;2357.116081469449;2419.346776370388;2426.2478631624076;2445.3519817248443;2452.840565363246;8939.195472407515;8993.955030224872;8996.370254082216;9089.227415669677;9099.408538444979;4668.953310466886;5357.491038351351;5433.157860531624;5519.116941289374;5538.169274608801;3577.2130131371055;3577.5706915170344;3628.6915612172997;3667.1382225177063;3669.743117981742;1012.6759436980169;1047.2721458586286;1049.790041991597;1054.151408116466;1067.4822710985165;11672.708182295426;11736.706582335424;11898.167128301022;11899.167058305922;11906.761864762742;4254.829806807693;4832.130216561053;4869;4960.751962401865;5006.324146240211;9100.544972751335;10087.49562521871;10101.848472272923;10109.595616175418;10152.796944061078;4457.955420445754;4622.168009758276;5507;5528.364238112588;5544.63959842609;4474.977625111918;4921.360223170965;5054.545090941833;5055.317532133188;5106.974465127651;3510.648935106469;4072.6741860651077;4138.370224431208;4175.770332631734;4198.391233271159;1360.5170164591625;1379.2896658221025;1382.0740104130057;1382.7718426459521;1388.7569675306984;929.8326301265837;946.2524605561667;948.3977674176947;957.593022965237;979.6277414582413;5996.730147143389;6067.666278354733;6085.117657939574;6143.784967526115;6191.380861913773;455.16250099816494;460.4658596028591;465.41357889060026;466.10042617747393;471.79948521878066;3972.781497017634;4024;4093.63157315843;4113.814878330482;4115.506139263308;10655.84016239757;10815.67552973412;10961.945190274157;11177.83233251502;11192.328460292409;9564.18704410119;10549.367037977747;10989.175811814146;11120.332780033224;11168.883111688767;7578.848423031509;8213.95893020543;8330.125336839666;8486.787830304169;8526.957365213135;5987.491063259672;5995.550333724901;6010.609310394811;6039.6678182698615;6064.3026052002815;10067.194624430174;10188.541515631667;10266.640667576898;10353.9482302588;10374.636887708893;8367.832643347221;10116.494175291058;10324.019218174262;10460.63387781424;10506.106980906708;6935.098437203132;7404.814879628052;7644.350230230492;7689.538627682357;7727.995360603086;8381.832363352576;8387.496750195009;8402.453840500348;8542.530160841214;8547.658093676184;7496.8500629986;7693.115291741521;7710;7745.302922736981;7768.184340644377;7407.740729074457;7412.481126321321;7514.39884809203;7590.8102297443;7602;6384.265809431789;6657.667116644246;7022.052022976833;7085.078939737801;7173.210946795953;4873.902521949471;5007.824726134568;5075.11185542536;5099.235114732672;5118.053160165381;5024.773885175103;5065.088284108896;5076.593872490118;5088.7201203932655;5286.101362768415 05:24:17 INFO - PROCESS | 1947 | |4;prototype.html;260.2011823701238;304.84605274336457;310.6753442652428;315.3865731152908;321.14415083801674;5576.386597474275;5604.467575580314;5612.961602103609;5616.427400327915;5666.546676265899;4874.8781280468;5054.393472783262;5541.722913854312;5551.77792888285;5600;3302.6201986771466;3944.7435916665468;3976.7017473689516;4093.4269202311552;4161.979190104061;3513.578370595519;4151.83392664293;4200.411942328076;4224.408582798395;4236.2798324284795;3909.2572411241918;4103.733257338278;4162.458880345551;4178.498580170369;4207.284761590522;3514.420120680085;3844.557875844258;4113;4167.833006758127;4208.852690155845;3160.715535601807;3556.413191823346;3561.928761424757;3595.586507551639;3614.34941710492;4808.422989241279;5266.762995665166;5292.417834038252;5306.575473962073;5326;4664.017792438234;4729.810807567693;4744.7627618618935;4767.284907263938;4770.642201834877;5113.488651134857;5307.336582927134;5775.624584402;5822.97088514559;5861.5603829712945;3944.2111577684577;3962.841486340543;3964.9008774780573;3979.98010009951;3993.520777506689;5782.7108644568025;6048.60684055535;6082.661814400824;6144.78493252734;6174.826782911277;3935;3961.742486738353;3985.7608543487486;3987.401889716508;3989.4813674222746;4018.095928416083;4029.1135950090934;4037.0916543778003;4039.8788036358956;4044.139695739934;4562.24722920721;4749.050189962021;4775.403074615673;4776.617870570346;4837.201861692852;5322.37667511405;5365.946340536545;5394.460553944574;5440.972795135999;5728.713564321851;1213.3447938113497;1279.9440461619201;1300.0119908869137;1357.2670757790681;1358.2054498118514;740.8666440040737;830.7507747675673;852.2798235491;860.337540094124;880.8546589812737;4249.70384032873;4386.881345257003;4508.346289788028;5335.9466405336225;5356.107701840962;5539.944600554023;5887.264091988502;5894;5898.8820223595285;5916.497097746654;5156.458571849902;5235.633505654568;5358.571314294847;5387.7102231551435;5440;2740.4108116754596;2803.8738256778493;2866.022444513279;2973.583698282199;3051.7030262138946;5660.745266463019;5667.85434084079;5903.704814759245;6108.236470441195;6141.385861413913;5205.958808238292;5711.914321285184;5814.244148260784;6068.939310606837;6130.478909292671;5829.854253643693;6129.478994285536;6188.628682279078;6198.72105755233;6202.906956395657;4920.48334924842;5353.732313384237;5510.614257001971;5669.177969194526;5837.387074357302;4904.6321525886415;5552.194931734956;5859.326177489473;5892.234009578728;6017.338092809699;5834.883302334015;5879.470847623735;6309.621422714652;6359.554831161774;6363.713632886439;3567.090391950036;3798.2213646203013;3961.603839616015;3981.70137239714;4013.618706222904;5268.128869766063;6046.3046749624355;6100.481459075851;6176.752929882754;6180.381961803963;5189.289657894624;5554.416786237549;5598.384177740527;5882.735276912635;5971.790987315597;5864.592497800582;5896.616719913192;5914.319853216935;5930.584859059824;5939.138824870456;5533.612647114664;5557.860223813942;5792.971035144966;5801.82594522165;5822.679752613647;5111.386633603991;5703;5842.503387212135;5880.676562789087;5896.818177274436;4366.607005369532;4934.481879402611;5641.097424412074;5726.942730572641;5766.94233057664 05:24:17 INFO - PROCESS | 1947 | |5;yui.html;549.5850632772258;718.2027948976635;725.3689290317423;736.2416710787884;740.9110906691201;11947.701307467318;13316.334183290834;15478.922605386986;15605.985610935295;15621.84378156218;14321.427142914297;14331.92834035831;14345.92827035866;14356.425742970268;14410.567682969528;722.2163952111965;888.471359541072;902.5757893789925;920.1120918313826;920.1580553793272;863.5423225690395;882.5984177199359;884.1997991817416;887.6183241206279;892.5314210039717;968.4237878462338;969.7769513012012;970.4710932541747;971.0629242780715;972.5088830140769;927.4759760735163;959.52023988006;960.0255740423476;961.1589858873485;965.2760429677742;696.7282759723712;708.1290013283639;712.2165617820408;714.1465948191913;714.5641158893071;14694.11835289886;14750.33623486935;14818.703625927421;14864.479743208936;14885.255737213096;12681.746365072739;12768.680782980407;12799.48802047917;12829.615111546575;12998;9918.652847150315;10148.238882083799;10171.338862973884;10283.845742313872;10438.112760415373;2860.1705505403406;3457.619661837196;3473.94789078164;3495.7028652564563;3593.694535964446;78538.6073069631;79561.20438795596;80683.5965820173;80915.5954220231;81677.59161204156;8760.956195218983;8876.689315873913;9490.810183796286;9531.523423828781;9836.950815245878;22242.666360004612;22309.888450557642;22395.44011399728;22467.52857185708;22799.974001169987;7362.079740032496;7417.554946703215;7444.73943411978;7522.736704215436;7645.235476452422;3924.8822535323984;3984.641382275609;3999.0402303447545;4002.959970400317;4021.356582946773;3482.425399809003;3580.462930560385;3591.964080359163;3592.7844329340323;3595.694365978869;623.8471574464195;639.1435476461564;639.5810743962712;646.4149944300322;648.4131860666105;500.93237412949514;568.2612603615283;594.6669864875684;595.4224402329734;602.5691630484225;7946.5066369923225;7967.3626109911065;8048.0342358917305;8154.9806274215725;8159.184081591912;503.05174745604796;533.4585395823243;540.9486098820606;561.943805619443;572.7422659803105;3727.440883867387;3874.050857539921;3874.380099184117;3975;4051.5340735815757;69388;88193.67709484363;89595;90369.19261614862;90857;61676.38323616796;74354;76255.61872190604;76767.61616191884;78849.21150788534;68586.65706671534;69215.96176057363;69220.65389673121;69475.26311842244;69679.909602712;7663.846723065396;7748.108967468813;7791.104023037423;7793.571353575608;7935.603219838869;35905.82047089748;40731.796341018104;43287.783561082;43925.78037109794;44685.329720053574;58236.70881645565;59456.70271648614;60067.699661501414;62963.68518157563;63201.36798631955;11111.277766945122;11266.774664506827;11414.600488982845;11443.771124577295;11600;12008.939955300168;12159.635210943687;12259.080568957186;12456.128071035304;12480.001599871806;11704.473298701409;11712.414379280832;11797.941010294893;11955.820662690241;12169.513219471122;11520.711982200177;11527.538898443969;11675.474603642686;11782.764344713229;12042.494688163979;11130;11218.102551795673;11273;11541.711457213636;11746.70633234176;8242.573397704606;8331.250187482914;8521.573921304034;8637;10150.086492215736;8739.082396348545;8785.51679657625;8881.067487913677;8917.464952102895;9003.414778039407 05:24:17 INFO - PROCESS | 1947 | __end_tp_report 05:24:17 INFO - PROCESS | 1947 | __start_cc_report 05:24:17 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,415 05:24:17 INFO - PROCESS | 1947 | __end_cc_report 05:24:17 INFO - PROCESS | 1947 | __startTimestamp1462883057824__endTimestamp 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | ------- Summary: start ------- 05:24:17 INFO - PROCESS | 1947 | Number of tests: 6 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5812.90 Median:6102.66 stddev:798.44 (13.1%) stddev-sans-first:768.86 05:24:17 INFO - PROCESS | 1947 | Values: 2959.2 3424.7 3495.9 3524.0 3529.5 5751.4 5772.5 5898.6 5929.1 5932.4 5640.5 5775.5 5842.0 5967.9 5998.6 5248.7 5268.8 5368.3 5381.7 5419.8 360.7 5338.3 5342.2 5406.4 5435.9 5319.3 5332.5 5336.1 5361.4 5374.3 5250.0 5351.2 5383.1 5395.3 5396.8 5234.1 5247.0 5284.8 5294.3 5328.5 5634.6 5787.2 5797.2 5842.7 5885.9 6190.5 6239.7 6309.5 6342.4 6345.9 4904.9 6318.0 6335.6 6341.6 6341.7 6038.7 6063.1 6063.9 6067.8 6078.0 6255.3 6300.4 6325.5 6401.1 6401.6 6252.2 6300.2 6301.3 6310.8 6328.6 6270.0 6284.9 6324.1 6331.8 6334.6 4503.8 6217.8 6252.9 6256.5 6319.3 6210.0 6256.2 6277.5 6279.8 6283.7 4958.2 5056.4 5074.1 5075.5 5099.6 4157.0 4178.2 4315.3 4512.6 4517.9 5908.6 6026.6 6030.2 6056.9 6065.9 6345.5 6346.6 6355.8 6361.7 6369.5 5223.0 5831.5 5939.4 5975.2 6011.5 5420.9 5573.3 5588.5 5594.2 5596.3 6194.3 6282.8 6561.6 6581.8 6599.9 6222.9 6227.9 6264.3 6349.9 6354.1 6504.6 6526.4 6545.8 6566.3 6567.3 6147.9 6244.2 6249.0 6304.8 6308.7 5202.0 6324.3 6325.2 6341.8 6346.0 6286.9 6313.5 6328.3 6329.7 6332.3 6130.1 6195.7 6200.9 6291.4 6310.2 5962.9 6025.8 6102.7 6125.1 6206.4 6101.2 6200.2 6255.7 6276.6 6277.7 6048.1 6121.4 6176.5 6191.8 6218.0 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:81079.64 Median:37229.44 stddev:202466.60 (543.8%) stddev-sans-first:202944.19 05:24:17 INFO - PROCESS | 1947 | Values: 505.3 513.5 526.2 530.5 559.8 32471.8 42123.0 42228.7 42278.6 42319.0 42009.8 42591.4 42673.8 42891.6 42897.0 9550.3 9637.7 9725.0 9730.7 9781.8 9634.2 9671.0 9677.6 9716.0 9752.3 9777.9 9789.6 9792.5 9793.2 9809.4 9582.8 9673.0 9684.8 9730.6 9747.5 9492.7 9512.6 9527.2 9593.5 9612.8 39427.4 39432.8 39479.0 39740.6 39905.0 3081.9 3108.7 3312.0 3539.2 3939.9 28650.3 29132.9 29360.0 29582.0 30078.1 2230.6 2719.9 2749.9 2759.1 2767.7 507045.0 515561.0 515561.7 516142.0 518696.0 34679.7 45307.0 62281.7 62301.7 62505.7 35796.6 36816.6 37193.3 37356.4 37429.6 14829.3 14966.8 15058.2 15086.3 15088.0 12599.1 12874.1 12953.1 12975.5 12993.2 7645.0 7691.3 8293.5 8302.6 8385.4 3411.7 3444.3 3453.9 3458.5 3495.8 934.3 961.9 978.1 1062.6 1141.1 27901.9 29622.4 29731.0 29989.4 30102.4 1049.1 1056.2 1080.9 1106.9 1146.5 11432.2 12616.9 14962.4 15054.6 15827.5 890635.0 1009038.0 1029672.0 1030417.0 1030975.0 60140.7 60177.1 61520.1 61693.4 61764.4 1428.9 1429.0 1444.9 1452.3 1452.5 28071.0 29765.7 29788.6 30621.2 30985.0 52694.2 53148.7 53229.2 53277.7 53283.7 637667.0 642019.8 642960.0 643432.0 643660.0 27134.9 27411.3 27909.9 27998.6 28006.9 44288.8 45192.8 45300.0 45625.9 45968.5 36619.8 37142.3 37265.6 37972.0 38198.8 29383.5 36433.3 36535.6 36770.4 37177.4 29217.4 32978.3 36510.5 36682.8 37444.4 30596.4 33118.7 33137.7 33285.0 33385.8 19586.9 24589.1 24659.1 24674.1 24737.9 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:35816.00 Median:28540.07 stddev:91778.27 (321.6%) stddev-sans-first:91997.01 05:24:17 INFO - PROCESS | 1947 | Values: 311.3 399.4 402.6 412.5 421.6 11774.1 18544.7 18695.3 21716.8 21731.9 22233.2 22695.9 22893.5 23417.5 23767.5 5871.9 6818.5 7578.9 7591.0 7700.0 6655.2 7487.2 7838.7 7899.0 7903.8 7661.2 7781.1 7871.7 7896.9 7924.3 7077.9 7875.4 7918.5 7918.8 7929.4 7141.3 7290.3 7352.3 7398.9 7406.3 28462.1 28618.0 29507.3 30024.4 30268.0 2133.3 2192.4 2349.7 2382.9 2402.5 15707.5 15713.7 16685.7 16803.6 16976.9 1816.6 1816.9 1834.9 1858.4 1912.8 97180.0 122997.4 127290.4 148665.8 155665.2 21711.8 22417.9 22521.4 22631.0 22656.8 37200.8 37550.0 37607.1 37629.9 37631.9 11150.7 11545.7 11609.9 11626.8 11712.0 9355.3 9389.9 9428.2 9486.7 9576.3 9631.7 9899.6 10031.8 10059.0 10171.9 3155.0 3332.6 3522.8 3904.5 3937.6 814.7 876.3 881.7 921.6 938.8 20289.3 21073.0 21120.8 21243.8 21593.6 866.0 883.9 912.8 926.2 929.1 8113.0 8441.5 11199.6 11247.0 11272.3 426179.0 533165.0 536155.0 561008.0 644353.0 106709.5 132956.0 138442.0 140777.6 141293.0 1013.7 1054.4 1070.5 1075.9 1077.1 18121.0 18773.9 18805.9 18972.0 19155.9 56044.2 57612.7 57624.4 58577.0 59165.0 60650.7 83577.0 84617.0 84717.6 85975.0 5327.3 5914.8 8127.1 8130.3 8194.2 12608.6 12629.4 12841.7 12847.4 12983.2 10705.9 10713.2 11061.5 11122.0 11418.9 10932.8 10981.8 11017.7 11110.4 11224.8 11089.4 11168.7 11281.8 11314.7 11323.6 8098.5 8192.4 8217.0 8334.8 8558.5 10822.4 10977.4 11170.2 11241.8 11372.3 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5793.97 Median:2570.38 stddev:3244.11 (126.2%) stddev-sans-first:3238.20 05:24:17 INFO - PROCESS | 1947 | Values: 1641.0 1662.0 1942.8 1972.8 1984.3 8046.3 8705.0 8743.3 9261.0 9266.3 8847.9 8918.2 9002.3 9050.0 9071.4 2378.3 2409.4 2412.4 2414.4 2424.9 2445.7 2447.0 2462.7 2480.5 2494.8 2429.9 2453.9 2470.3 2470.6 2473.6 2394.4 2445.8 2451.4 2456.8 2460.3 2357.1 2419.3 2426.2 2445.4 2452.8 8939.2 8994.0 8996.4 9089.2 9099.4 4669.0 5357.5 5433.2 5519.1 5538.2 3577.2 3577.6 3628.7 3667.1 3669.7 1012.7 1047.3 1049.8 1054.2 1067.5 11672.7 11736.7 11898.2 11899.2 11906.8 4254.8 4832.1 4869.0 4960.8 5006.3 9100.5 10087.5 10101.8 10109.6 10152.8 4458.0 4622.2 5507.0 5528.4 5544.6 4475.0 4921.4 5054.5 5055.3 5107.0 3510.6 4072.7 4138.4 4175.8 4198.4 1360.5 1379.3 1382.1 1382.8 1388.8 929.8 946.3 948.4 957.6 979.6 5996.7 6067.7 6085.1 6143.8 6191.4 455.2 460.5 465.4 466.1 471.8 3972.8 4024.0 4093.6 4113.8 4115.5 10655.8 10815.7 10961.9 11177.8 11192.3 9564.2 10549.4 10989.2 11120.3 11168.9 7578.8 8214.0 8330.1 8486.8 8527.0 5987.5 5995.6 6010.6 6039.7 6064.3 10067.2 10188.5 10266.6 10353.9 10374.6 8367.8 10116.5 10324.0 10460.6 10506.1 6935.1 7404.8 7644.4 7689.5 7728.0 8381.8 8387.5 8402.5 8542.5 8547.7 7496.9 7693.1 7710.0 7745.3 7768.2 7407.7 7412.5 7514.4 7590.8 7602.0 6384.3 6657.7 7022.1 7085.1 7173.2 4873.9 5007.8 5075.1 5099.2 5118.1 5024.8 5065.1 5076.6 5088.7 5286.1 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:4681.32 Median:5314.86 stddev:1469.77 (27.7%) stddev-sans-first:1435.95 05:24:17 INFO - PROCESS | 1947 | Values: 260.2 304.8 310.7 315.4 321.1 5576.4 5604.5 5613.0 5616.4 5666.5 4874.9 5054.4 5541.7 5551.8 5600.0 3302.6 3944.7 3976.7 4093.4 4162.0 3513.6 4151.8 4200.4 4224.4 4236.3 3909.3 4103.7 4162.5 4178.5 4207.3 3514.4 3844.6 4113.0 4167.8 4208.9 3160.7 3556.4 3561.9 3595.6 3614.3 4808.4 5266.8 5292.4 5306.6 5326.0 4664.0 4729.8 4744.8 4767.3 4770.6 5113.5 5307.3 5775.6 5823.0 5861.6 3944.2 3962.8 3964.9 3980.0 3993.5 5782.7 6048.6 6082.7 6144.8 6174.8 3935.0 3961.7 3985.8 3987.4 3989.5 4018.1 4029.1 4037.1 4039.9 4044.1 4562.2 4749.1 4775.4 4776.6 4837.2 5322.4 5365.9 5394.5 5441.0 5728.7 1213.3 1279.9 1300.0 1357.3 1358.2 740.9 830.8 852.3 860.3 880.9 4249.7 4386.9 4508.3 5335.9 5356.1 5539.9 5887.3 5894.0 5898.9 5916.5 5156.5 5235.6 5358.6 5387.7 5440.0 2740.4 2803.9 2866.0 2973.6 3051.7 5660.7 5667.9 5903.7 6108.2 6141.4 5206.0 5711.9 5814.2 6068.9 6130.5 5829.9 6129.5 6188.6 6198.7 6202.9 4920.5 5353.7 5510.6 5669.2 5837.4 4904.6 5552.2 5859.3 5892.2 6017.3 5834.9 5879.5 6309.6 6359.6 6363.7 3567.1 3798.2 3961.6 3981.7 4013.6 5268.1 6046.3 6100.5 6176.8 6180.4 5189.3 5554.4 5598.4 5882.7 5971.8 5864.6 5896.6 5914.3 5930.6 5939.1 5533.6 5557.9 5793.0 5801.8 5822.7 5111.4 5703.0 5842.5 5880.7 5896.8 4366.6 4934.5 5641.1 5726.9 5766.9 05:24:17 INFO - PROCESS | 1947 | 05:24:17 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:17522.05 Median:30026.06 stddev:24262.04 (80.8%) stddev-sans-first:24296.63 05:24:17 INFO - PROCESS | 1947 | Values: 549.6 718.2 725.4 736.2 740.9 11947.7 13316.3 15478.9 15606.0 15621.8 14321.4 14331.9 14345.9 14356.4 14410.6 722.2 888.5 902.6 920.1 920.2 863.5 882.6 884.2 887.6 892.5 968.4 969.8 970.5 971.1 972.5 927.5 959.5 960.0 961.2 965.3 696.7 708.1 712.2 714.1 714.6 14694.1 14750.3 14818.7 14864.5 14885.3 12681.7 12768.7 12799.5 12829.6 12998.0 9918.7 10148.2 10171.3 10283.8 10438.1 2860.2 3457.6 3473.9 3495.7 3593.7 78538.6 79561.2 80683.6 80915.6 81677.6 8761.0 8876.7 9490.8 9531.5 9837.0 22242.7 22309.9 22395.4 22467.5 22800.0 7362.1 7417.6 7444.7 7522.7 7645.2 3924.9 3984.6 3999.0 4003.0 4021.4 3482.4 3580.5 3592.0 3592.8 3595.7 623.8 639.1 639.6 646.4 648.4 500.9 568.3 594.7 595.4 602.6 7946.5 7967.4 8048.0 8155.0 8159.2 503.1 533.5 540.9 561.9 572.7 3727.4 3874.1 3874.4 3975.0 4051.5 69388.0 88193.7 89595.0 90369.2 90857.0 61676.4 74354.0 76255.6 76767.6 78849.2 68586.7 69216.0 69220.7 69475.3 69679.9 7663.8 7748.1 7791.1 7793.6 7935.6 35905.8 40731.8 43287.8 43925.8 44685.3 58236.7 59456.7 60067.7 62963.7 63201.4 11111.3 11266.8 11414.6 11443.8 11600.0 12008.9 12159.6 12259.1 12456.1 12480.0 11704.5 11712.4 11797.9 11955.8 12169.5 11520.7 11527.5 11675.5 11782.8 12042.5 11130.0 11218.1 11273.0 11541.7 11746.7 8242.6 8331.3 8521.6 8637.0 10150.1 8739.1 8785.5 8881.1 8917.5 9003.4 05:24:17 INFO - PROCESS | 1947 | -------- Summary: end -------- 05:24:17 INFO - PROCESS | 1947 | 05:24:18 INFO - TEST-INFO | 1947: exit 0 05:24:18 INFO - TEST-OK | dromaeo_css | took 1111922ms 05:24:18 INFO - TEST-START | kraken 05:24:18 INFO - operating with platform_type : mac_ 05:24:18 INFO - Initialising browser for kraken test... 05:24:18 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpwep0Th/profile http://localhost:51213/getInfo.html) 05:24:21 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 05:24:21 INFO - PROCESS | 1952 | colorDepth:24 05:24:21 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 05:24:21 INFO - PROCESS | 1952 | __metrics 05:24:21 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 05:24:22 INFO - TEST-INFO | 1952: exit 0 05:24:22 INFO - Browser initialized. 05:24:22 INFO - Running cycle 1/1 for kraken test... 05:24:22 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpwep0Th/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:34 INFO - PROCESS | 1955 | RSS: Main: 249491456 05:24:34 INFO - PROCESS | 1955 | 05:24:36 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51213/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:37 INFO - PROCESS | 1955 | RSS: Main: 261279744 05:24:37 INFO - PROCESS | 1955 | 05:24:40 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51213/tests/kraken/driver.html?testName=audio-dft) 05:24:41 INFO - PROCESS | 1955 | RSS: Main: 266084352 05:24:41 INFO - PROCESS | 1955 | 05:24:44 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51213/tests/kraken/driver.html?testName=audio-fft) 05:24:45 INFO - PROCESS | 1955 | RSS: Main: 266477568 05:24:45 INFO - PROCESS | 1955 | 05:24:47 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51213/tests/kraken/driver.html?testName=audio-oscillator) 05:24:48 INFO - PROCESS | 1955 | RSS: Main: 267051008 05:24:48 INFO - PROCESS | 1955 | 05:24:50 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51213/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:50 INFO - PROCESS | 1955 | RSS: Main: 267452416 05:24:50 INFO - PROCESS | 1955 | 05:24:53 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51213/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:54 INFO - PROCESS | 1955 | RSS: Main: 267919360 05:24:54 INFO - PROCESS | 1955 | 05:24:57 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51213/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:58 INFO - PROCESS | 1955 | RSS: Main: 268718080 05:24:58 INFO - PROCESS | 1955 | 05:25:01 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51213/tests/kraken/driver.html?testName=json-parse-financial) 05:25:02 INFO - PROCESS | 1955 | RSS: Main: 269451264 05:25:02 INFO - PROCESS | 1955 | 05:25:03 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51213/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:04 INFO - PROCESS | 1955 | RSS: Main: 269504512 05:25:04 INFO - PROCESS | 1955 | 05:25:06 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:06 INFO - PROCESS | 1955 | RSS: Main: 269590528 05:25:06 INFO - PROCESS | 1955 | 05:25:09 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:09 INFO - PROCESS | 1955 | RSS: Main: 270372864 05:25:09 INFO - PROCESS | 1955 | 05:25:12 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:13 INFO - PROCESS | 1955 | RSS: Main: 269893632 05:25:13 INFO - PROCESS | 1955 | 05:25:16 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:17 INFO - PROCESS | 1955 | RSS: Main: 269819904 05:25:17 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51213/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51213/tests/kraken/driver.html?testName=ai-astar) 05:25:19 INFO - PROCESS | 1955 | RSS: Main: 271839232 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | __start_tp_report 05:25:19 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 05:25:19 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 05:25:19 INFO - PROCESS | 1955 | |i|pagename|runs| 05:25:19 INFO - PROCESS | 1955 | |0;ai-astar;116;170;109;115;117;115;109;116;115;115 05:25:19 INFO - PROCESS | 1955 | |1;audio-beat-detection;155;124;144;197;118;136;159;147;161;119 05:25:19 INFO - PROCESS | 1955 | |2;audio-dft;198;198;209;249;234;195;275;285;193;280 05:25:19 INFO - PROCESS | 1955 | |3;audio-fft;74;78;77;177;59;58;62;84;161;51 05:25:19 INFO - PROCESS | 1955 | |4;audio-oscillator;78;87;162;79;82;85;157;87;82;125 05:25:19 INFO - PROCESS | 1955 | |5;imaging-gaussian-blur;88;89;88;80;134;108;89;85;88;86 05:25:19 INFO - PROCESS | 1955 | |6;imaging-darkroom;93;97;119;152;94;95;96;95;96;93 05:25:19 INFO - PROCESS | 1955 | |7;imaging-desaturate;92;91;92;94;89;89;90;89;91;104 05:25:19 INFO - PROCESS | 1955 | |8;json-parse-financial;61;64;63;60;62;62;62;123;62;62 05:25:19 INFO - PROCESS | 1955 | |9;json-stringify-tinderbox;64;65;74;72;78;73;70;67;68;70 05:25:19 INFO - PROCESS | 1955 | |10;stanford-crypto-aes;86;86;88;86;94;107;87;87;87;87 05:25:19 INFO - PROCESS | 1955 | |11;stanford-crypto-ccm;141;162;128;126;153;152;149;176;250;147 05:25:19 INFO - PROCESS | 1955 | |12;stanford-crypto-pbkdf2;174;174;174;170;204;186;203;160;206;227 05:25:19 INFO - PROCESS | 1955 | |13;stanford-crypto-sha256-iterative;65;70;90;87;87;82;88;58;77;86 05:25:19 INFO - PROCESS | 1955 | __end_tp_report 05:25:19 INFO - PROCESS | 1955 | __start_cc_report 05:25:19 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1077 05:25:19 INFO - PROCESS | 1955 | __end_cc_report 05:25:19 INFO - PROCESS | 1955 | __startTimestamp1462883119489__endTimestamp 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | ------- Summary: start ------- 05:25:19 INFO - PROCESS | 1955 | Number of tests: 14 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#0] ai-astar Cycles:10 Average:119.70 Median:115.50 stddev:17.90 (15.5%) stddev-sans-first:18.93 05:25:19 INFO - PROCESS | 1955 | Values: 116.0 170.0 109.0 115.0 117.0 115.0 109.0 116.0 115.0 115.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:151.00 stddev:23.99 (15.9%) stddev-sans-first:25.22 05:25:19 INFO - PROCESS | 1955 | Values: 155.0 124.0 144.0 197.0 118.0 136.0 159.0 147.0 161.0 119.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#2] audio-dft Cycles:10 Average:231.60 Median:241.50 stddev:37.98 (15.7%) stddev-sans-first:38.29 05:25:19 INFO - PROCESS | 1955 | Values: 198.0 198.0 209.0 249.0 234.0 195.0 275.0 285.0 193.0 280.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#3] audio-fft Cycles:10 Average:88.10 Median:68.00 stddev:44.06 (64.8%) stddev-sans-first:46.43 05:25:19 INFO - PROCESS | 1955 | Values: 74.0 78.0 77.0 177.0 59.0 58.0 62.0 84.0 161.0 51.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:82.00 stddev:32.99 (40.2%) stddev-sans-first:33.79 05:25:19 INFO - PROCESS | 1955 | Values: 78.0 87.0 162.0 79.0 82.0 85.0 157.0 87.0 82.0 125.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:88.00 stddev:15.96 (18.1%) stddev-sans-first:16.80 05:25:19 INFO - PROCESS | 1955 | Values: 88.0 89.0 88.0 80.0 134.0 108.0 89.0 85.0 88.0 86.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:95.00 stddev:18.86 (19.8%) stddev-sans-first:19.65 05:25:19 INFO - PROCESS | 1955 | Values: 93.0 97.0 119.0 152.0 94.0 95.0 96.0 95.0 96.0 93.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:91.00 stddev:4.48 (4.9%) stddev-sans-first:4.76 05:25:19 INFO - PROCESS | 1955 | Values: 92.0 91.0 92.0 94.0 89.0 89.0 90.0 89.0 91.0 104.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:62.00 stddev:19.32 (31.2%) stddev-sans-first:20.32 05:25:19 INFO - PROCESS | 1955 | Values: 61.0 64.0 63.0 60.0 62.0 62.0 62.0 123.0 62.0 62.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:4.31 (6.1%) stddev-sans-first:3.96 05:25:19 INFO - PROCESS | 1955 | Values: 64.0 65.0 74.0 72.0 78.0 73.0 70.0 67.0 68.0 70.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:87.00 stddev:6.59 (7.6%) stddev-sans-first:6.86 05:25:19 INFO - PROCESS | 1955 | Values: 86.0 86.0 88.0 86.0 94.0 107.0 87.0 87.0 87.0 87.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:158.40 Median:152.50 stddev:35.40 (23.2%) stddev-sans-first:36.98 05:25:19 INFO - PROCESS | 1955 | Values: 141.0 162.0 128.0 126.0 153.0 152.0 149.0 176.0 250.0 147.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:194.50 stddev:21.15 (10.9%) stddev-sans-first:21.83 05:25:19 INFO - PROCESS | 1955 | Values: 174.0 174.0 174.0 170.0 204.0 186.0 203.0 160.0 206.0 227.0 05:25:19 INFO - PROCESS | 1955 | 05:25:19 INFO - PROCESS | 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.00 Median:86.50 stddev:11.11 (12.8%) stddev-sans-first:10.56 05:25:19 INFO - PROCESS | 1955 | Values: 65.0 70.0 90.0 87.0 87.0 82.0 88.0 58.0 77.0 86.0 05:25:19 INFO - PROCESS | 1955 | -------- Summary: end -------- 05:25:19 INFO - PROCESS | 1955 | 05:25:20 INFO - TEST-INFO | 1955: exit 0 05:25:20 INFO - TEST-OK | kraken | took 61908ms 05:25:20 INFO - SUITE-END | took 1173s 05:25:20 INFO - Completed test suite (00:19:34) 05:25:20 INFO - javascript benchmark 05:25:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.2158078109305, 3424.691777740006, 3495.947560786589, 3523.982380088099, 3529.4529347951075, 5751.396103409144, 5772.451617096374, 5898.616589921652, 5929.080992446167, 5932.37710040446, 5640.464155905182, 5775.480206781389, 5841.97765391057, 5967.910481342783, 5998.640081595107, 5248.737563121838, 5268.789248430058, 5368.275282836806, 5381.67709937403, 5419.783208671648, 360.6731302070259, 5338.332708411449, 5342.2253773202865, 5406.432324605918, 5435.864103397407, 5319.281896943901, 5332.546733527654, 5336.119540275849, 5361.410244873061, 5374.328208973879, 5250.002499525116, 5351.170568561879, 5383.138697808332, 5395.271638328814, 5396.83809485716, 5234.136367499358, 5246.973765131188, 5284.762185701614, 5294.338207724035, 5328.520433161034, 5634.605577609569, 5787.218725472091, 5797.159411885253, 5842.73707683151, 5885.85285367865, 6190.473809726179, 6239.720857224258, 6309.4636955858805, 6342.429181373653, 6345.904811427832, 4904.926426103611, 6317.968410157966, 6335.588186767845, 6341.556091073627, 6341.714622841983, 6038.698065096728, 6063.120847477091, 6063.939360606426, 6067.817965461043, 6078.0, 6255.280642726054, 6300.3699630037545, 6325.5255855811, 6401.135846660734, 6401.647909364937, 6252.187215661935, 6300.180976473029, 6301.306856245809, 6310.810675679737, 6328.557001009977, 6270.0, 6284.905726414107, 6324.051392291193, 6331.841703957346, 6334.619922804646, 4503.795249624985, 6217.751289948352, 6252.937470625327, 6256.5307601930035, 6319.304876463585, 6209.96895015531, 6256.186695729617, 6277.52918531112, 6279.780207692709, 6283.748650054038, 4958.157113290749, 5056.443791182967, 5074.08666440037, 5075.492450754895, 5099.617528685363, 4156.9792151039655, 4178.1643671265865, 4315.28797748368, 4512.616427603691, 4517.909641807211, 5908.556858235564, 6026.578139530191, 6030.18592490017, 6056.878862422727, 6065.878682426415, 6345.524085693592, 6346.619202847844, 6355.777547785898, 6361.745530178833, 6369.458596019297, 5222.981518603911, 5831.475167234969, 5939.3763654816485, 5975.19334889793, 6011.489023433057, 5420.91868621971, 5573.303337082864, 5588.524975377057, 5594.24477695514, 5596.27248457706, 6194.256689197326, 6282.780102696383, 6561.606303621798, 6581.769638062645, 6599.868002639921, 6222.875542489034, 6227.875442491216, 6264.279607844974, 6349.904751428825, 6354.078658594571, 6504.60972341661, 6526.412622863965, 6545.836354091186, 6566.310537393505, 6567.310432404529, 6147.924113280247, 6244.219472565929, 6249.000159974382, 6304.779332723333, 6308.71610777507, 5202.0, 6324.272708638566, 6325.240971083499, 6341.841453963688, 6346.0, 6286.905696414466, 6313.526485513513, 6328.303886572381, 6329.746810127727, 6332.335104814112, 6130.092410195535, 6195.690215489298, 6200.906986395117, 6291.433770960635, 6310.1796766420075, 5962.8968640801595, 6025.758969641341, 6102.664353460602, 6125.050617154348, 6206.379362063938, 6101.217066730222, 6200.224971878515, 6255.710270248586, 6276.623402595859, 6277.717502712298, 6048.123022161674, 6121.418465245794, 6176.474999625082, 6191.84520386994, 6218.036204388146], "value": 5764.187251532453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.335483838752, 513.463430714903, 526.1739069660633, 530.4509832323544, 559.7900787204799, 32471.83764081179, 42123.0, 42228.73313800587, 42278.57721422785, 42319.0, 42009.789951050276, 42591.36112958308, 42673.786631066876, 42891.57108428915, 42897.0, 9550.33147679661, 9637.662681806138, 9725.027497250288, 9730.65942692006, 9781.804363912717, 9634.181094606967, 9670.951645241765, 9677.612895484172, 9715.951420242889, 9752.268579856503, 9777.902220977805, 9789.608415663364, 9792.461414622228, 9793.167580755644, 9809.362391444569, 9582.760430989212, 9673.0, 9684.75788105296, 9730.562124704406, 9747.512624368788, 9492.715218543455, 9512.571934262905, 9527.190188833958, 9593.47235902025, 9612.807743845155, 39427.40858887119, 39432.80283598564, 39479.0, 39740.60259397398, 39905.0, 3081.8613162407746, 3108.7202151806446, 3311.9668803311897, 3539.2036791721657, 3939.9015024624614, 28650.283742906595, 29132.854335728185, 29359.972400965864, 29582.0, 30078.097657070324, 2230.5984922713906, 2719.945601087967, 2749.8900043998215, 2759.1446651537885, 2767.6678798544103, 507045.0, 515561.0, 515561.68876622256, 516142.0, 518696.0, 34679.653203468144, 45307.0, 62281.688591556755, 62301.68849155725, 62505.68747156326, 35796.642033579854, 36816.63183368186, 37193.25613487715, 37356.43965340522, 37429.62570374316, 14829.258537073103, 14966.80265578773, 15058.247087645794, 15086.321115549827, 15088.0, 12599.118061735773, 12874.098813083181, 12953.093283470067, 12975.480980760853, 12993.220406775436, 7645.006149200568, 7691.346235569928, 8293.502389856629, 8302.626381812834, 8385.371097167737, 3411.7441191910707, 3444.293919746446, 3453.9481907771406, 3458.4985177149674, 3495.807730574843, 934.3272843552631, 961.8845738511424, 978.090375950373, 1062.628080171949, 1141.058626633031, 27901.860490697418, 29622.407551848843, 29731.0, 29989.400211996075, 30102.397952041276, 1049.1134990932649, 1056.2183983852049, 1080.897314755097, 1106.8893110689028, 1146.529922731676, 11432.199746017865, 12616.87383126157, 14962.401503939938, 15054.611135782017, 15827.546730256829, 890635.0, 1009038.0, 1029672.0, 1030417.0, 1030975.0, 60140.69929650324, 60177.097343539885, 61520.07719884205, 61693.38306616966, 61764.382356176764, 1428.9142651440948, 1429.0, 1444.9422023119168, 1452.2811208451883, 1452.527928423288, 28071.0, 29765.702342976292, 29788.55317170201, 30621.23446913845, 30985.0, 52694.209586856996, 53148.73425633002, 53229.201561975824, 53277.73361133169, 53283.7335813334, 637667.0, 642019.7899010475, 642960.0, 643432.0, 643660.0, 27134.864325678245, 27411.31471713223, 27909.860450697615, 27998.580021300106, 28006.85996570004, 44288.77855610701, 45192.77403612961, 45300.0, 45625.8593535151, 45968.54031459642, 36619.81690091533, 37142.25715485621, 37265.6273437273, 37972.0, 38198.8090059559, 29383.502402296046, 36433.27133457369, 36535.634643653226, 36770.448443273905, 37177.4423383644, 29217.41565168642, 32978.34043319168, 36510.4523432154, 36682.8165859169, 37444.43833342556, 30596.388072238875, 33118.66881331156, 33137.66862331346, 33285.0, 33385.83307083449, 19586.902065489583, 24589.13938012161, 24659.136930207358, 24674.13640522573, 24737.886795093906], "value": 18836.04562085197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.3182131132819, 399.41884557968154, 402.5732723313287, 412.5214750888966, 421.56368158955485, 11774.058075353973, 18544.721829172573, 18695.3456629018, 21716.782832171673, 21731.891340543316, 22233.2218372357, 22695.886520567416, 22893.542129157406, 23417.531649367003, 23767.524649507, 5871.941280587203, 6818.488613353993, 7578.924210757877, 7590.962045189766, 7700.0, 6655.234648015492, 7487.217310701568, 7838.686452541891, 7899.0, 7903.802404939865, 7661.195574464685, 7781.130237251399, 7871.724489642864, 7896.921030789705, 7924.326432253265, 7077.858442831166, 7875.409344299172, 7918.485298455611, 7918.80202994924, 7929.444938854283, 7141.285871412869, 7290.307420795069, 7352.264773522658, 7398.889016664754, 7406.333429991327, 28462.146135615963, 28618.0, 29507.262318441997, 30024.399512009637, 30268.0, 2133.264023911748, 2192.4299682082615, 2349.659299401594, 2382.9046838126455, 2402.5074859653905, 15707.528774136794, 15713.685726285525, 16685.66628667432, 16803.6375213913, 16976.915115424345, 1816.6366726654721, 1816.8819026763217, 1834.9357772478033, 1858.4053103007043, 1912.7513423255027, 97180.0, 122997.38501307525, 127290.36354818166, 148665.7966971151, 155665.22167389203, 21711.782882171294, 22417.88791056067, 22521.43696407603, 22631.0, 22656.773432265796, 37200.81399592985, 37550.0, 37607.05982350408, 37629.87110386693, 37631.9302438363, 11150.665480035612, 11545.711357215969, 11609.941950290195, 11626.76746465066, 11712.0, 9355.345125841126, 9389.90610093904, 9428.198603118812, 9486.667966621273, 9576.329656924088, 9631.71104866855, 9899.604015839286, 10031.799364012679, 10059.04439078301, 10171.949140254399, 3154.9904030709954, 3332.6334103248623, 3522.8238588070494, 3904.54013971202, 3937.625925537069, 814.739283429297, 876.3164731509432, 881.725149115281, 921.5991043895926, 938.7559234599054, 20289.28987485431, 21073.016855418642, 21120.78879211219, 21243.78756212449, 21593.568128637653, 865.9781457879636, 883.9204471597459, 912.8224590278467, 926.1618235496886, 929.0802105915095, 8112.959435202904, 8441.451305665112, 11199.60801371948, 11247.044001259821, 11272.323660580392, 426179.0, 533165.0, 536155.0, 561008.0, 644353.0, 106709.4664526688, 132956.0, 138442.0, 140777.5922240785, 141293.0, 1013.7313611892854, 1054.3779170289565, 1070.4968664727567, 1075.9246852720235, 1077.1113831089383, 18121.0, 18773.90613046926, 18805.90597047006, 18972.0, 19155.90422047881, 56044.159337609155, 57612.71193644005, 57624.423755761905, 58577.0, 59165.0, 60650.69674651598, 83577.0, 84617.0, 84717.57641211755, 85975.0, 5327.3074500314715, 5914.763409463573, 8127.065387480516, 8130.308923741311, 8194.22154895284, 12608.558700445441, 12629.431675574804, 12841.678958026125, 12847.35763211817, 12983.156094853804, 10705.9464702676, 10713.196510261918, 11061.502232399722, 11122.0, 11418.942905285421, 10932.781344372908, 10981.83527247076, 11017.669469915916, 11110.44447777624, 11224.7755044897, 11089.390083545482, 11168.66494005181, 11281.830772538582, 11314.717132071764, 11323.603673871376, 8098.473599216032, 8192.426530142833, 8217.0, 8334.791630209293, 8558.486490810572, 10822.350658960488, 10977.3962432067, 11170.162237832032, 11241.831372529583, 11372.260803047775], "value": 11106.690672835864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1640.9589760255992, 1661.9916900415496, 1942.766867975843, 1972.8224459798614, 1984.2955750708506, 8046.316063134641, 8705.0, 8743.300535957123, 9260.953695231523, 9266.305027122968, 8847.867281990775, 8918.197362237397, 9002.32482563807, 9050.0, 9071.41035832672, 2378.2746262390006, 2409.4337830609793, 2412.384841865322, 2414.396400899775, 2424.9272521824373, 2445.7065152181763, 2446.987765061172, 2462.729099799021, 2480.4790993891306, 2494.8128890333205, 2429.9392515187174, 2453.901843926241, 2470.2712699753615, 2470.567650661136, 2473.6165894286323, 2394.449276666359, 2445.8410203336816, 2451.3994071452607, 2456.8403053801535, 2460.2619214235747, 2357.116081469449, 2419.346776370388, 2426.2478631624076, 2445.3519817248443, 2452.840565363246, 8939.195472407515, 8993.955030224872, 8996.370254082216, 9089.227415669677, 9099.408538444979, 4668.953310466886, 5357.491038351351, 5433.157860531624, 5519.116941289374, 5538.169274608801, 3577.2130131371055, 3577.5706915170344, 3628.6915612172997, 3667.1382225177063, 3669.743117981742, 1012.6759436980169, 1047.2721458586286, 1049.790041991597, 1054.151408116466, 1067.4822710985165, 11672.708182295426, 11736.706582335424, 11898.167128301022, 11899.167058305922, 11906.761864762742, 4254.829806807693, 4832.130216561053, 4869.0, 4960.751962401865, 5006.324146240211, 9100.544972751335, 10087.49562521871, 10101.848472272923, 10109.595616175418, 10152.796944061078, 4457.955420445754, 4622.168009758276, 5507.0, 5528.364238112588, 5544.63959842609, 4474.977625111918, 4921.360223170965, 5054.545090941833, 5055.317532133188, 5106.974465127651, 3510.648935106469, 4072.6741860651077, 4138.370224431208, 4175.770332631734, 4198.391233271159, 1360.5170164591625, 1379.2896658221025, 1382.0740104130057, 1382.7718426459521, 1388.7569675306984, 929.8326301265837, 946.2524605561667, 948.3977674176947, 957.593022965237, 979.6277414582413, 5996.730147143389, 6067.666278354733, 6085.117657939574, 6143.784967526115, 6191.380861913773, 455.16250099816494, 460.4658596028591, 465.41357889060026, 466.10042617747393, 471.79948521878066, 3972.781497017634, 4024.0, 4093.63157315843, 4113.814878330482, 4115.506139263308, 10655.84016239757, 10815.67552973412, 10961.945190274157, 11177.83233251502, 11192.328460292409, 9564.18704410119, 10549.367037977747, 10989.175811814146, 11120.332780033224, 11168.883111688767, 7578.848423031509, 8213.95893020543, 8330.125336839666, 8486.787830304169, 8526.957365213135, 5987.491063259672, 5995.550333724901, 6010.609310394811, 6039.6678182698615, 6064.3026052002815, 10067.194624430174, 10188.541515631667, 10266.640667576898, 10353.9482302588, 10374.636887708893, 8367.832643347221, 10116.494175291058, 10324.019218174262, 10460.63387781424, 10506.106980906708, 6935.098437203132, 7404.814879628052, 7644.350230230492, 7689.538627682357, 7727.995360603086, 8381.832363352576, 8387.496750195009, 8402.453840500348, 8542.530160841214, 8547.658093676184, 7496.8500629986, 7693.115291741521, 7710.0, 7745.302922736981, 7768.184340644377, 7407.740729074457, 7412.481126321321, 7514.39884809203, 7590.8102297443, 7602.0, 6384.265809431789, 6657.667116644246, 7022.052022976833, 7085.078939737801, 7173.210946795953, 4873.902521949471, 5007.824726134568, 5075.11185542536, 5099.235114732672, 5118.053160165381, 5024.773885175103, 5065.088284108896, 5076.593872490118, 5088.7201203932655, 5286.101362768415], "value": 4604.266323035464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.2011823701238, 304.84605274336457, 310.6753442652428, 315.3865731152908, 321.14415083801674, 5576.386597474275, 5604.467575580314, 5612.961602103609, 5616.427400327915, 5666.546676265899, 4874.8781280468, 5054.393472783262, 5541.722913854312, 5551.77792888285, 5600.0, 3302.6201986771466, 3944.7435916665468, 3976.7017473689516, 4093.4269202311552, 4161.979190104061, 3513.578370595519, 4151.83392664293, 4200.411942328076, 4224.408582798395, 4236.2798324284795, 3909.2572411241918, 4103.733257338278, 4162.458880345551, 4178.498580170369, 4207.284761590522, 3514.420120680085, 3844.557875844258, 4113.0, 4167.833006758127, 4208.852690155845, 3160.715535601807, 3556.413191823346, 3561.928761424757, 3595.586507551639, 3614.34941710492, 4808.422989241279, 5266.762995665166, 5292.417834038252, 5306.575473962073, 5326.0, 4664.017792438234, 4729.810807567693, 4744.7627618618935, 4767.284907263938, 4770.642201834877, 5113.488651134857, 5307.336582927134, 5775.624584402, 5822.97088514559, 5861.5603829712945, 3944.2111577684577, 3962.841486340543, 3964.9008774780573, 3979.98010009951, 3993.520777506689, 5782.7108644568025, 6048.60684055535, 6082.661814400824, 6144.78493252734, 6174.826782911277, 3935.0, 3961.742486738353, 3985.7608543487486, 3987.401889716508, 3989.4813674222746, 4018.095928416083, 4029.1135950090934, 4037.0916543778003, 4039.8788036358956, 4044.139695739934, 4562.24722920721, 4749.050189962021, 4775.403074615673, 4776.617870570346, 4837.201861692852, 5322.37667511405, 5365.946340536545, 5394.460553944574, 5440.972795135999, 5728.713564321851, 1213.3447938113497, 1279.9440461619201, 1300.0119908869137, 1357.2670757790681, 1358.2054498118514, 740.8666440040737, 830.7507747675673, 852.2798235491, 860.337540094124, 880.8546589812737, 4249.70384032873, 4386.881345257003, 4508.346289788028, 5335.9466405336225, 5356.107701840962, 5539.944600554023, 5887.264091988502, 5894.0, 5898.8820223595285, 5916.497097746654, 5156.458571849902, 5235.633505654568, 5358.571314294847, 5387.7102231551435, 5440.0, 2740.4108116754596, 2803.8738256778493, 2866.022444513279, 2973.583698282199, 3051.7030262138946, 5660.745266463019, 5667.85434084079, 5903.704814759245, 6108.236470441195, 6141.385861413913, 5205.958808238292, 5711.914321285184, 5814.244148260784, 6068.939310606837, 6130.478909292671, 5829.854253643693, 6129.478994285536, 6188.628682279078, 6198.72105755233, 6202.906956395657, 4920.48334924842, 5353.732313384237, 5510.614257001971, 5669.177969194526, 5837.387074357302, 4904.6321525886415, 5552.194931734956, 5859.326177489473, 5892.234009578728, 6017.338092809699, 5834.883302334015, 5879.470847623735, 6309.621422714652, 6359.554831161774, 6363.713632886439, 3567.090391950036, 3798.2213646203013, 3961.603839616015, 3981.70137239714, 4013.618706222904, 5268.128869766063, 6046.3046749624355, 6100.481459075851, 6176.752929882754, 6180.381961803963, 5189.289657894624, 5554.416786237549, 5598.384177740527, 5882.735276912635, 5971.790987315597, 5864.592497800582, 5896.616719913192, 5914.319853216935, 5930.584859059824, 5939.138824870456, 5533.612647114664, 5557.860223813942, 5792.971035144966, 5801.82594522165, 5822.679752613647, 5111.386633603991, 5703.0, 5842.503387212135, 5880.676562789087, 5896.818177274436, 4366.607005369532, 4934.481879402611, 5641.097424412074, 5726.942730572641, 5766.94233057664], "value": 4197.944229568685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.5850632772258, 718.2027948976635, 725.3689290317423, 736.2416710787884, 740.9110906691201, 11947.701307467318, 13316.334183290834, 15478.922605386986, 15605.985610935295, 15621.84378156218, 14321.427142914297, 14331.92834035831, 14345.92827035866, 14356.425742970268, 14410.567682969528, 722.2163952111965, 888.471359541072, 902.5757893789925, 920.1120918313826, 920.1580553793272, 863.5423225690395, 882.5984177199359, 884.1997991817416, 887.6183241206279, 892.5314210039717, 968.4237878462338, 969.7769513012012, 970.4710932541747, 971.0629242780715, 972.5088830140769, 927.4759760735163, 959.52023988006, 960.0255740423476, 961.1589858873485, 965.2760429677742, 696.7282759723712, 708.1290013283639, 712.2165617820408, 714.1465948191913, 714.5641158893071, 14694.11835289886, 14750.33623486935, 14818.703625927421, 14864.479743208936, 14885.255737213096, 12681.746365072739, 12768.680782980407, 12799.48802047917, 12829.615111546575, 12998.0, 9918.652847150315, 10148.238882083799, 10171.338862973884, 10283.845742313872, 10438.112760415373, 2860.1705505403406, 3457.619661837196, 3473.94789078164, 3495.7028652564563, 3593.694535964446, 78538.6073069631, 79561.20438795596, 80683.5965820173, 80915.5954220231, 81677.59161204156, 8760.956195218983, 8876.689315873913, 9490.810183796286, 9531.523423828781, 9836.950815245878, 22242.666360004612, 22309.888450557642, 22395.44011399728, 22467.52857185708, 22799.974001169987, 7362.079740032496, 7417.554946703215, 7444.73943411978, 7522.736704215436, 7645.235476452422, 3924.8822535323984, 3984.641382275609, 3999.0402303447545, 4002.959970400317, 4021.356582946773, 3482.425399809003, 3580.462930560385, 3591.964080359163, 3592.7844329340323, 3595.694365978869, 623.8471574464195, 639.1435476461564, 639.5810743962712, 646.4149944300322, 648.4131860666105, 500.93237412949514, 568.2612603615283, 594.6669864875684, 595.4224402329734, 602.5691630484225, 7946.5066369923225, 7967.3626109911065, 8048.0342358917305, 8154.9806274215725, 8159.184081591912, 503.05174745604796, 533.4585395823243, 540.9486098820606, 561.943805619443, 572.7422659803105, 3727.440883867387, 3874.050857539921, 3874.380099184117, 3975.0, 4051.5340735815757, 69388.0, 88193.67709484363, 89595.0, 90369.19261614862, 90857.0, 61676.38323616796, 74354.0, 76255.61872190604, 76767.61616191884, 78849.21150788534, 68586.65706671534, 69215.96176057363, 69220.65389673121, 69475.26311842244, 69679.909602712, 7663.846723065396, 7748.108967468813, 7791.104023037423, 7793.571353575608, 7935.603219838869, 35905.82047089748, 40731.796341018104, 43287.783561082, 43925.78037109794, 44685.329720053574, 58236.70881645565, 59456.70271648614, 60067.699661501414, 62963.68518157563, 63201.36798631955, 11111.277766945122, 11266.774664506827, 11414.600488982845, 11443.771124577295, 11600.0, 12008.939955300168, 12159.635210943687, 12259.080568957186, 12456.128071035304, 12480.001599871806, 11704.473298701409, 11712.414379280832, 11797.941010294893, 11955.820662690241, 12169.513219471122, 11520.711982200177, 11527.538898443969, 11675.474603642686, 11782.764344713229, 12042.494688163979, 11130.0, 11218.102551795673, 11273.0, 11541.711457213636, 11746.70633234176, 8242.573397704606, 8331.250187482914, 8521.573921304034, 8637.0, 10150.086492215736, 8739.082396348545, 8785.51679657625, 8881.067487913677, 8917.464952102895, 9003.414778039407], "value": 6767.180971394758, "unit": "score"}], "name": "dromaeo_css", "value": 7350.672194606895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 170.0, 109.0, 115.0, 117.0, 115.0, 109.0, 116.0, 115.0, 115.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 144.0, 197.0, 118.0, 136.0, 159.0, 147.0, 161.0, 119.0], "value": 146.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 209.0, 249.0, 234.0, 195.0, 275.0, 285.0, 193.0, 280.0], "value": 231.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 77.0, 177.0, 59.0, 58.0, 62.0, 84.0, 161.0, 51.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 87.0, 162.0, 79.0, 82.0, 85.0, 157.0, 87.0, 82.0, 125.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 80.0, 134.0, 108.0, 89.0, 85.0, 88.0, 86.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 119.0, 152.0, 94.0, 95.0, 96.0, 95.0, 96.0, 93.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 94.0, 89.0, 89.0, 90.0, 89.0, 91.0, 104.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 63.0, 60.0, 62.0, 62.0, 62.0, 123.0, 62.0, 62.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 74.0, 72.0, 78.0, 73.0, 70.0, 67.0, 68.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 86.0, 94.0, 107.0, 87.0, 87.0, 87.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 162.0, 128.0, 126.0, 153.0, 152.0, 149.0, 176.0, 250.0, 147.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 174.0, 170.0, 204.0, 186.0, 203.0, 160.0, 206.0, 227.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 90.0, 87.0, 87.0, 82.0, 88.0, 58.0, 77.0, 86.0], "value": 79.0, "unit": "score"}], "name": "kraken", "value": 1629.3, "alertThreshold": 2.0}]} 05:25:20 INFO - Return code: 0 05:25:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:25:20 ERROR - # TBPL SUCCESS # 05:25:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:20 INFO - 2016-05-10 05:25:20.795 screenresolution[1960:16271] starting screenresolution argv=screenresolution get 05:25:20 INFO - 2016-05-10 05:25:20.797 screenresolution[1960:16271] Display 0: 1600x1200x32@60 05:25:20 INFO - 2016-05-10 05:25:20.803 screenresolution[1961:16274] starting screenresolution argv=screenresolution list 05:25:20 INFO - Available Modes on Display 0 05:25:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:21 INFO - Intel Iris: 05:25:21 INFO - Chipset Model: Intel Iris 05:25:21 INFO - Type: GPU 05:25:21 INFO - Bus: Built-In 05:25:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:21 INFO - Vendor: Intel (0x8086) 05:25:21 INFO - Device ID: 0x0a2e 05:25:21 INFO - Revision ID: 0x0009 05:25:21 INFO - Displays: 05:25:21 INFO - Display: 05:25:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:21 INFO - Display Serial Number: 1600x1200 60 05:25:21 INFO - Main Display: Yes 05:25:21 INFO - Mirror: Off 05:25:21 INFO - Online: Yes 05:25:21 INFO - Rotation: Supported 05:25:21 INFO - Return code: 0 05:25:21 INFO - Running post-action listener: _resource_record_post_action 05:25:21 INFO - ##### 05:25:21 INFO - ##### Finished run-tests step (success) 05:25:21 INFO - ##### 05:25:21 INFO - Running post-run listener: _resource_record_post_run 05:25:22 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 675840; Write bytes: 696074240; Read time: 12; Write time: 1858 05:25:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 383855104; Write bytes: 378112000; Read time: 28507; Write time: 695 05:25:22 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 581632; Write bytes: 298579968; Read time: 9; Write time: 1120 05:25:22 INFO - Running post-run listener: _upload_blobber_files 05:25:22 INFO - Blob upload gear active. 05:25:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:22 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:22 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:22 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:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:23 INFO - (blobuploader) - INFO - Done attempting. 05:25:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:24 INFO - (blobuploader) - INFO - Done attempting. 05:25:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:24 INFO - Return code: 0 05:25:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62b19fd744cf5a89d0725033a3413cf425e39f989726e04155ea231fb0d79bbd2c24475b2acc1babb82f7ce102c56acbc47e2edf9f9b56b1b8411d62a89ff22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99cf890a6319cda3f56dfab2ca1ff91e78472995d099f8a617b82383e2fb2aae3349fc3aaa0f65b736e4481b607a90837108f6451784ee2a2321c8b41bb182c8"} 05:25:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:24 INFO - Contents: 05:25:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62b19fd744cf5a89d0725033a3413cf425e39f989726e04155ea231fb0d79bbd2c24475b2acc1babb82f7ce102c56acbc47e2edf9f9b56b1b8411d62a89ff22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99cf890a6319cda3f56dfab2ca1ff91e78472995d099f8a617b82383e2fb2aae3349fc3aaa0f65b736e4481b607a90837108f6451784ee2a2321c8b41bb182c8"} 05:25:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:24 INFO - Copying logs to upload dir... 05:25:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.535280 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-10 05:25:24.970440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:25:24.974177) ========= 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.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62b19fd744cf5a89d0725033a3413cf425e39f989726e04155ea231fb0d79bbd2c24475b2acc1babb82f7ce102c56acbc47e2edf9f9b56b1b8411d62a89ff22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99cf890a6319cda3f56dfab2ca1ff91e78472995d099f8a617b82383e2fb2aae3349fc3aaa0f65b736e4481b607a90837108f6451784ee2a2321c8b41bb182c8"} build_url:https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/a_gQ64TlQHywRrhDGL_f6g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62b19fd744cf5a89d0725033a3413cf425e39f989726e04155ea231fb0d79bbd2c24475b2acc1babb82f7ce102c56acbc47e2edf9f9b56b1b8411d62a89ff22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99cf890a6319cda3f56dfab2ca1ff91e78472995d099f8a617b82383e2fb2aae3349fc3aaa0f65b736e4481b607a90837108f6451784ee2a2321c8b41bb182c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 05:25:25.003984) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:25:25.004455) ========= 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.TH6QxWwHJM/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.veL809AtTb/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.005009 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 05:25:25.053602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:25:25.053922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 05:25:25.054241) ========= ========= Total master_lag: 0.16 =========