builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1462793315.7 results: success (0) buildid: 20160509021943 builduid: dd03919b53644c46859a963eee0d3790 revision: 748b70bcdbc95a4f3e72ca53a23c8f766049d011 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:35.698751) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:35.699198) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:35.699509) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:35.883486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:35.883916) ========= 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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-09 04:28:35-- 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% 9.58M=0.001s 2016-05-09 04:28:36 (9.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.196577 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:36.121750) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:36.122074) ========= 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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.027070 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:36.245503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-09 04:28:36.245856) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 748b70bcdbc95a4f3e72ca53a23c8f766049d011 --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 748b70bcdbc95a4f3e72ca53a23c8f766049d011 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-09 04:28:36,336 truncating revision to first 12 chars 2016-05-09 04:28:36,336 Setting DEBUG logging. 2016-05-09 04:28:36,336 attempt 1/10 2016-05-09 04:28:36,336 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/748b70bcdbc9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 04:28:38,171 unpacking tar archive at: mozilla-central-748b70bcdbc9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.846768 ========= master_lag: 2.96 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-09 04:28:42.053072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:42.053492) ========= script_repo_revision: 748b70bcdbc95a4f3e72ca53a23c8f766049d011 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:42.054145) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:42.054425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:28:42.271763) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-09 04:28:42.272155) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:28:42 INFO - MultiFileLogger online at 20160509 04:28:42 in /builds/slave/test 04:28:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:28:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:42 INFO - {'append_to_log': False, 04:28:42 INFO - 'base_work_dir': '/builds/slave/test', 04:28:42 INFO - 'blob_upload_branch': 'Firefox', 04:28:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:42 INFO - 'branch': 'Firefox', 04:28:42 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:42 INFO - 'config_files': ('talos/mac_config.py',), 04:28:42 INFO - 'default_actions': ('clobber', 04:28:42 INFO - 'read-buildbot-config', 04:28:42 INFO - 'download-and-extract', 04:28:42 INFO - 'populate-webroot', 04:28:42 INFO - 'create-virtualenv', 04:28:42 INFO - 'install', 04:28:42 INFO - 'run-tests'), 04:28:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:42 INFO - 'download_minidump_stackwalk': True, 04:28:42 INFO - 'download_symbols': 'ondemand', 04:28:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:42 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:42 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:42 INFO - 'installer_path': 'installer.exe', 04:28:42 INFO - 'log_level': 'info', 04:28:42 INFO - 'log_name': 'talos', 04:28:42 INFO - 'log_to_console': True, 04:28:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:28:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:28:42 INFO - 'opt_config_files': (), 04:28:42 INFO - 'pip_index': False, 04:28:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:42 INFO - 'cmd': ('bash', 04:28:42 INFO - '-c', 04:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:42 INFO - 'enabled': True, 04:28:42 INFO - 'halt_on_failure': False, 04:28:42 INFO - 'name': 'check_screen_resolution'},), 04:28:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:42 INFO - 'cmd': ('bash', 04:28:42 INFO - '-c', 04:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:42 INFO - 'enabled': True, 04:28:42 INFO - 'halt_on_failure': False, 04:28:42 INFO - 'name': 'check_screen_resolution'},), 04:28:42 INFO - 'run_cmd_checks_enabled': True, 04:28:42 INFO - 'sps_profile': False, 04:28:42 INFO - 'sps_profile_interval': 0, 04:28:42 INFO - 'suite': 'dromaeojs', 04:28:42 INFO - 'system_bits': '32', 04:28:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:42 INFO - 'title': 't-yosemite-r7-0093', 04:28:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:42 INFO - 'use_talos_json': True, 04:28:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:42 INFO - 'work_dir': 'build'} 04:28:42 INFO - ##### 04:28:42 INFO - ##### Running clobber step. 04:28:42 INFO - ##### 04:28:42 INFO - Running pre-action listener: _resource_record_pre_action 04:28:42 INFO - Running main action method: clobber 04:28:42 INFO - rmtree: /builds/slave/test/build 04:28:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:44 INFO - Running post-action listener: _resource_record_post_action 04:28:44 INFO - ##### 04:28:44 INFO - ##### Finished clobber step (success) 04:28:44 INFO - ##### 04:28:44 INFO - ##### 04:28:44 INFO - ##### Running read-buildbot-config step. 04:28:44 INFO - ##### 04:28:44 INFO - Running pre-action listener: _resource_record_pre_action 04:28:44 INFO - Running main action method: read_buildbot_config 04:28:44 INFO - Using buildbot properties: 04:28:44 INFO - { 04:28:44 INFO - "project": "", 04:28:44 INFO - "product": "firefox", 04:28:44 INFO - "script_repo_revision": "production", 04:28:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:28:44 INFO - "repository": "", 04:28:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:28:44 INFO - "buildid": "20160509021943", 04:28:44 INFO - "pgo_build": "False", 04:28:44 INFO - "basedir": "/builds/slave/test", 04:28:44 INFO - "buildnumber": 0, 04:28:44 INFO - "slavename": "t-yosemite-r7-0093", 04:28:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:28:44 INFO - "platform": "yosemite_r7", 04:28:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:28:44 INFO - "branch": "mozilla-central", 04:28:44 INFO - "repo_path": "mozilla-central", 04:28:44 INFO - "revision": "748b70bcdbc95a4f3e72ca53a23c8f766049d011", 04:28:44 INFO - "stage_platform": "macosx64", 04:28:44 INFO - "builduid": "dd03919b53644c46859a963eee0d3790", 04:28:44 INFO - "slavebuilddir": "test" 04:28:44 INFO - } 04:28:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:28:44 INFO - Running post-action listener: _resource_record_post_action 04:28:44 INFO - ##### 04:28:44 INFO - ##### Finished read-buildbot-config step (success) 04:28:44 INFO - ##### 04:28:44 INFO - ##### 04:28:44 INFO - ##### Running download-and-extract step. 04:28:44 INFO - ##### 04:28:44 INFO - Running pre-action listener: _resource_record_pre_action 04:28:44 INFO - Running main action method: download_and_extract 04:28:44 INFO - mkdir: /builds/slave/test/build/tests 04:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:28:44 INFO - https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:28:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:28:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:28:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:46 INFO - Downloaded 1199 bytes. 04:28:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:28:46 INFO - Using the following test package requirements: 04:28:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:28:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:28:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:28:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'jsshell-mac.zip'], 04:28:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:28:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:28:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:28:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:28:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:28:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:28:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:28:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:28:46 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')]} 04:28:46 INFO - https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:28:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:28:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:28:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:47 INFO - Downloaded 18146574 bytes. 04:28:47 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'] 04:28:47 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 04:28:50 INFO - Return code: 0 04:28:50 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 04:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:28:50 INFO - https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:28:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:28:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:28:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:54 INFO - Downloaded 18146574 bytes. 04:28:54 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'] 04:28:54 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 04:28:58 INFO - Return code: 0 04:28: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')]} 04:28:58 INFO - https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:28:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:28:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:28:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:29:00 INFO - Downloaded 10840152 bytes. 04:29:00 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'] 04:29:00 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 04:29:00 INFO - Return code: 0 04:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:00 INFO - https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:29:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WaBLD2dJTA2bqZaegnxhcQ/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 04:29:06 INFO - Downloaded 110437221 bytes. 04:29:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:06 INFO - mkdir: /builds/slave/test/properties 04:29:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:06 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:06 INFO - Contents: 04:29:06 INFO - build_url:https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:08 INFO - Running post-action listener: _resource_record_post_action 04:29:08 INFO - Running post-action listener: set_extra_try_arguments 04:29:08 INFO - ##### 04:29:08 INFO - ##### Finished download-and-extract step (success) 04:29:08 INFO - ##### 04:29:08 INFO - ##### 04:29:08 INFO - ##### Running populate-webroot step. 04:29:08 INFO - ##### 04:29:08 INFO - Running pre-action listener: _resource_record_pre_action 04:29:08 INFO - Running main action method: populate_webroot 04:29:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:08 INFO - '--tppagecycles', 04:29:08 INFO - '7'], 04:29:08 INFO - 'tests': ['tsvgm']}}, 04:29:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['damp', 'tps']}, 04:29:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'tests': ['damp', 'tps']}, 04:29:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['dromaeo_dom']}, 04:29:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['a11yr', 04:29:08 INFO - 'ts_paint', 04:29:08 INFO - 'tpaint', 04:29:08 INFO - 'sessionrestore', 04:29:08 INFO - 'sessionrestore_no_auto_restore', 04:29:08 INFO - 'tabpaint']}, 04:29:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tsvgx', 04:29:08 INFO - 'tsvgr_opacity', 04:29:08 INFO - 'tart', 04:29:08 INFO - 'tscrollx', 04:29:08 INFO - 'cart']}, 04:29:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:08 INFO - 'tsvgr_opacity', 04:29:08 INFO - 'tart', 04:29:08 INFO - 'tscrollx', 04:29:08 INFO - 'cart']}, 04:29:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s'], 04:29:08 INFO - 'tests': ['tp5o']}, 04:29:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'tests': ['tp5o']}, 04:29:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'talos_options': ['--disable-e10s', 04:29:08 INFO - '--xperf_path', 04:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:08 INFO - 'tests': ['tp5n']}, 04:29:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:08 INFO - 'talos_options': ['--xperf_path', 04:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:08 INFO - 'tests': ['tp5n']}}, 04:29:08 INFO - 'talos.zip': {'path': '', 04:29:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:08 INFO - Running post-action listener: _resource_record_post_action 04:29:08 INFO - ##### 04:29:08 INFO - ##### Finished populate-webroot step (success) 04:29:08 INFO - ##### 04:29:08 INFO - ##### 04:29:08 INFO - ##### Running create-virtualenv step. 04:29:08 INFO - ##### 04:29:08 INFO - Running pre-action listener: _resource_record_pre_action 04:29:08 INFO - Running main action method: create_virtualenv 04:29:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:08 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 04:29:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:08 INFO - Using real prefix '/tools/python27' 04:29:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:11 INFO - Installing pip.................done. 04:29:11 INFO - Return code: 0 04:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:11 INFO - 'HOME': '/Users/cltbld', 04:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:11 INFO - 'LOGNAME': 'cltbld', 04:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:11 INFO - 'MOZ_NO_REMOTE': '1', 04:29:11 INFO - 'NO_EM_RESTART': '1', 04:29:11 INFO - 'PAGER': '/bin/cat', 04:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:11 INFO - 'PWD': '/builds/slave/test', 04:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:11 INFO - 'SHELL': '/bin/bash', 04:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:11 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:11 INFO - 'USER': 'cltbld', 04:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:11 INFO - 'XPC_FLAGS': '0x0', 04:29:11 INFO - 'XPC_SERVICE_NAME': '0', 04:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:12 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 04:29:12 INFO - Cleaning up... 04:29:12 INFO - Return code: 0 04:29:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:12 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:12 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 04:29:12 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 04:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:12 INFO - 'HOME': '/Users/cltbld', 04:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:12 INFO - 'LOGNAME': 'cltbld', 04:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:12 INFO - 'MOZ_NO_REMOTE': '1', 04:29:12 INFO - 'NO_EM_RESTART': '1', 04:29:12 INFO - 'PAGER': '/bin/cat', 04:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:12 INFO - 'PWD': '/builds/slave/test', 04:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:12 INFO - 'SHELL': '/bin/bash', 04:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:12 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:12 INFO - 'USER': 'cltbld', 04:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:12 INFO - 'XPC_FLAGS': '0x0', 04:29:12 INFO - 'XPC_SERVICE_NAME': '0', 04:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:12 INFO - Downloading/unpacking psutil>=0.7.1 04:29:12 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 04:29:12 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 04:29:12 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 04:29:12 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 04:29:12 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 04:29:12 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 04:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:15 INFO - Installing collected packages: psutil 04:29:15 INFO - Running setup.py install for psutil 04:29:15 INFO - building 'psutil._psutil_osx' extension 04:29:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:29:16 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 04:29:16 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 04:29:16 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 04:29:16 INFO - building 'psutil._psutil_posix' extension 04:29:16 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 04:29:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:16 INFO - ^ 04:29:16 INFO - 1 warning generated. 04:29:16 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 04:29:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:16 INFO - Successfully installed psutil 04:29:16 INFO - Cleaning up... 04:29:16 INFO - Return code: 0 04:29:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:16 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:16 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 04:29:16 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 04:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:16 INFO - 'HOME': '/Users/cltbld', 04:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:16 INFO - 'LOGNAME': 'cltbld', 04:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:16 INFO - 'MOZ_NO_REMOTE': '1', 04:29:16 INFO - 'NO_EM_RESTART': '1', 04:29:16 INFO - 'PAGER': '/bin/cat', 04:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:16 INFO - 'PWD': '/builds/slave/test', 04:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:16 INFO - 'SHELL': '/bin/bash', 04:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:16 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:16 INFO - 'USER': 'cltbld', 04:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:16 INFO - 'XPC_FLAGS': '0x0', 04:29:16 INFO - 'XPC_SERVICE_NAME': '0', 04:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:29:17 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 04:29:17 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 04:29:17 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 04:29:17 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 04:29:17 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 04:29:17 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 04:29:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:29:19 INFO - Installing collected packages: mozsystemmonitor 04:29:19 INFO - Running setup.py install for mozsystemmonitor 04:29:19 INFO - Successfully installed mozsystemmonitor 04:29:19 INFO - Cleaning up... 04:29:20 INFO - Return code: 0 04:29:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:20 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:20 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 04:29:20 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 04:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:20 INFO - 'HOME': '/Users/cltbld', 04:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:20 INFO - 'LOGNAME': 'cltbld', 04:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:20 INFO - 'MOZ_NO_REMOTE': '1', 04:29:20 INFO - 'NO_EM_RESTART': '1', 04:29:20 INFO - 'PAGER': '/bin/cat', 04:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:20 INFO - 'PWD': '/builds/slave/test', 04:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:20 INFO - 'SHELL': '/bin/bash', 04:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:20 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:20 INFO - 'USER': 'cltbld', 04:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:20 INFO - 'XPC_FLAGS': '0x0', 04:29:20 INFO - 'XPC_SERVICE_NAME': '0', 04:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:20 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 04:29:20 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 04:29:20 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 04:29:20 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 04:29:20 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 04:29:20 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 04:29:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29:24 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 04:29:24 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 04:29:24 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 04:29:24 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 04:29:24 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 04:29:24 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 04:29:24 INFO - Downloading docopt-0.6.1.tar.gz 04:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:24 INFO - Running setup.py install for blobuploader 04:29:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:24 INFO - Running setup.py install for requests 04:29:25 INFO - Running setup.py install for docopt 04:29:25 INFO - Successfully installed blobuploader requests docopt 04:29:25 INFO - Cleaning up... 04:29:25 INFO - Return code: 0 04:29:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:25 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:25 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 04:29:25 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 04:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:25 INFO - 'HOME': '/Users/cltbld', 04:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:25 INFO - 'LOGNAME': 'cltbld', 04:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:25 INFO - 'MOZ_NO_REMOTE': '1', 04:29:25 INFO - 'NO_EM_RESTART': '1', 04:29:25 INFO - 'PAGER': '/bin/cat', 04:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:25 INFO - 'PWD': '/builds/slave/test', 04:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:25 INFO - 'SHELL': '/bin/bash', 04:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:25 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:25 INFO - 'USER': 'cltbld', 04:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:25 INFO - 'XPC_FLAGS': '0x0', 04:29:25 INFO - 'XPC_SERVICE_NAME': '0', 04:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:25 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-NBqJxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:25 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-U_bqYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:25 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-oHZFlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fKjAGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-kYk0oW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-B_Xjrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fddDcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-uMlCvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-dxmV9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-5_amD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-St8Sc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zMyFf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:26 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-pfm5c7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:27 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ZVOSPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:27 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lQmI34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:27 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-WLfM50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:27 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-1LMrE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:27 INFO - Running setup.py install for manifestparser 04:29:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:27 INFO - Running setup.py install for mozcrash 04:29:27 INFO - Running setup.py install for mozdebug 04:29:27 INFO - Running setup.py install for mozdevice 04:29:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Running setup.py install for mozfile 04:29:28 INFO - Running setup.py install for mozhttpd 04:29:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Running setup.py install for mozinfo 04:29:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Running setup.py install for mozInstall 04:29:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Running setup.py install for mozleak 04:29:28 INFO - Running setup.py install for mozlog 04:29:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:28 INFO - Running setup.py install for moznetwork 04:29:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:29 INFO - Running setup.py install for mozprocess 04:29:29 INFO - Running setup.py install for mozprofile 04:29:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:29 INFO - Running setup.py install for mozrunner 04:29:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:29 INFO - Running setup.py install for mozscreenshot 04:29:29 INFO - Running setup.py install for moztest 04:29:29 INFO - Running setup.py install for mozversion 04:29:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:30 INFO - Cleaning up... 04:29:30 INFO - Return code: 0 04:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:30 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:30 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 04:29:30 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 04:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:30 INFO - 'HOME': '/Users/cltbld', 04:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:30 INFO - 'LOGNAME': 'cltbld', 04:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:30 INFO - 'MOZ_NO_REMOTE': '1', 04:29:30 INFO - 'NO_EM_RESTART': '1', 04:29:30 INFO - 'PAGER': '/bin/cat', 04:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:30 INFO - 'PWD': '/builds/slave/test', 04:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:30 INFO - 'SHELL': '/bin/bash', 04:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:30 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:30 INFO - 'USER': 'cltbld', 04:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:30 INFO - 'XPC_FLAGS': '0x0', 04:29:30 INFO - 'XPC_SERVICE_NAME': '0', 04:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-s_Vr60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-gNjx5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-YnxVVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zMR5I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-hvh_KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-dGyL12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BUZY9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:31 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)) 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:31 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-kdogHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-OrK42m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-LnF0qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qDIxWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7iglYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-L3uQCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zNhepo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-EiVXfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-h8zRbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:32 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)) 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:32 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-A3i4Un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:33 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)) 04:29:33 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)) 04:29:33 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)) 04:29:33 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)) 04:29:33 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)) 04:29:33 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)) 04:29:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:36 INFO - Downloading blessings-1.6.tar.gz 04:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:36 INFO - Installing collected packages: blessings 04:29:36 INFO - Running setup.py install for blessings 04:29:36 INFO - Successfully installed blessings 04:29:36 INFO - Cleaning up... 04:29:37 INFO - Return code: 0 04:29:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:37 INFO - Reading from file tmpfile_stdout 04:29:37 INFO - Current package versions: 04:29:37 INFO - blessings == 1.6 04:29:37 INFO - blobuploader == 1.2.4 04:29:37 INFO - docopt == 0.6.1 04:29:37 INFO - manifestparser == 1.1 04:29:37 INFO - mozInstall == 1.12 04:29:37 INFO - mozcrash == 0.17 04:29:37 INFO - mozdebug == 0.1 04:29:37 INFO - mozdevice == 0.48 04:29:37 INFO - mozfile == 1.2 04:29:37 INFO - mozhttpd == 0.7 04:29:37 INFO - mozinfo == 0.9 04:29:37 INFO - mozleak == 0.1 04:29:37 INFO - mozlog == 3.1 04:29:37 INFO - moznetwork == 0.27 04:29:37 INFO - mozprocess == 0.22 04:29:37 INFO - mozprofile == 0.28 04:29:37 INFO - mozrunner == 6.11 04:29:37 INFO - mozscreenshot == 0.1 04:29:37 INFO - mozsystemmonitor == 0.0 04:29:37 INFO - moztest == 0.7 04:29:37 INFO - mozversion == 1.4 04:29:37 INFO - psutil == 3.1.1 04:29:37 INFO - requests == 1.2.3 04:29:37 INFO - wsgiref == 0.1.2 04:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:37 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 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:37 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 04:29:37 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 04:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:37 INFO - 'HOME': '/Users/cltbld', 04:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:37 INFO - 'LOGNAME': 'cltbld', 04:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:37 INFO - 'MOZ_NO_REMOTE': '1', 04:29:37 INFO - 'NO_EM_RESTART': '1', 04:29:37 INFO - 'PAGER': '/bin/cat', 04:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:37 INFO - 'PWD': '/builds/slave/test', 04:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:37 INFO - 'SHELL': '/bin/bash', 04:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:37 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:37 INFO - 'USER': 'cltbld', 04:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:37 INFO - 'XPC_FLAGS': '0x0', 04:29:37 INFO - 'XPC_SERVICE_NAME': '0', 04:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 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)) 04:29:37 INFO - Cleaning up... 04:29:37 INFO - Return code: 0 04:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:37 INFO - 'HOME': '/Users/cltbld', 04:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:37 INFO - 'LOGNAME': 'cltbld', 04:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:37 INFO - 'MOZ_NO_REMOTE': '1', 04:29:37 INFO - 'NO_EM_RESTART': '1', 04:29:37 INFO - 'PAGER': '/bin/cat', 04:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:37 INFO - 'PWD': '/builds/slave/test', 04:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:37 INFO - 'SHELL': '/bin/bash', 04:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:37 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:37 INFO - 'USER': 'cltbld', 04:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:37 INFO - 'XPC_FLAGS': '0x0', 04:29:37 INFO - 'XPC_SERVICE_NAME': '0', 04:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:37 INFO - Downloading/unpacking jsonschema 04:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:29:40 INFO - Downloading/unpacking functools32 (from jsonschema) 04:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:29:40 INFO - warning: no files found matching '*.txt' 04:29:40 INFO - no previously-included directories found matching 'build' 04:29:40 INFO - no previously-included directories found matching 'dist' 04:29:40 INFO - no previously-included directories found matching '.git*' 04:29:40 INFO - Installing collected packages: jsonschema, functools32 04:29:41 INFO - Running setup.py install for functools32 04:29:41 INFO - warning: no files found matching '*.txt' 04:29:41 INFO - no previously-included directories found matching 'build' 04:29:41 INFO - no previously-included directories found matching 'dist' 04:29:41 INFO - no previously-included directories found matching '.git*' 04:29:41 INFO - Successfully installed jsonschema functools32 04:29:41 INFO - Cleaning up... 04:29:41 INFO - Return code: 0 04:29:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10933ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcf85aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, '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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 04:29: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', 'flake8'] in /builds/slave/test/build 04:29: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 flake8 04:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:41 INFO - 'HOME': '/Users/cltbld', 04:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:41 INFO - 'LOGNAME': 'cltbld', 04:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:41 INFO - 'MOZ_NO_REMOTE': '1', 04:29:41 INFO - 'NO_EM_RESTART': '1', 04:29:41 INFO - 'PAGER': '/bin/cat', 04:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:41 INFO - 'PWD': '/builds/slave/test', 04:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:41 INFO - 'SHELL': '/bin/bash', 04:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:29:41 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:41 INFO - 'USER': 'cltbld', 04:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:41 INFO - 'XPC_FLAGS': '0x0', 04:29:41 INFO - 'XPC_SERVICE_NAME': '0', 04:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:41 INFO - Downloading/unpacking flake8 04:29: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 04:29: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 04:29: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 04:29: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 04:29: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 04:29: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 04:29:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:29:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:29:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:29:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:29:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:29:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:29:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:29:45 INFO - no previously-included directories found matching 'docs/_build' 04:29:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:29:45 INFO - Running setup.py install for pep8 04:29:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:29:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:29:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:29:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:29:45 INFO - no previously-included directories found matching 'docs/_build' 04:29:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:29:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:29:45 INFO - Cleaning up... 04:29:45 INFO - Return code: 0 04:29:45 INFO - Running post-action listener: _resource_record_post_action 04:29:45 INFO - Running post-action listener: _start_resource_monitoring 04:29:45 INFO - Starting resource monitoring. 04:29:45 INFO - ##### 04:29:45 INFO - ##### Finished create-virtualenv step (success) 04:29:45 INFO - ##### 04:29:45 INFO - ##### 04:29:45 INFO - ##### Running install step. 04:29:45 INFO - ##### 04:29:45 INFO - Running pre-action listener: _resource_record_pre_action 04:29:45 INFO - Running main action method: install 04:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:46 INFO - Reading from file tmpfile_stdout 04:29:46 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:46 INFO - Reading from file tmpfile_stdout 04:29:46 INFO - Output received: 04:29:46 INFO - Usage: mozinstall [options] installer 04:29:46 INFO - Options: 04:29:46 INFO - -h, --help show this help message and exit 04:29:46 INFO - -d DEST, --destination=DEST 04:29:46 INFO - Directory to install application into. [default: 04:29:46 INFO - "/builds/slave/test"] 04:29:46 INFO - --app=APP Application being installed. [default: firefox] 04:29:46 INFO - mkdir: /builds/slave/test/build/application 04:29:46 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'] 04:29:46 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 04:30:19 INFO - Reading from file tmpfile_stdout 04:30:19 INFO - Output received: 04:30:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:30:19 INFO - Running post-action listener: _resource_record_post_action 04:30:19 INFO - ##### 04:30:19 INFO - ##### Finished install step (success) 04:30:19 INFO - ##### 04:30:19 INFO - ##### 04:30:19 INFO - ##### Running run-tests step. 04:30:19 INFO - ##### 04:30:19 INFO - Running pre-action listener: _resource_record_pre_action 04:30:19 INFO - Running main action method: run_tests 04:30:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:19 INFO - 2016-05-09 04:30:19.840 screenresolution[1647:8025] starting screenresolution argv=screenresolution get 04:30:19 INFO - 2016-05-09 04:30:19.843 screenresolution[1647:8025] Display 0: 1600x1200x32@60 04:30:19 INFO - 2016-05-09 04:30:19.849 screenresolution[1648:8027] starting screenresolution argv=screenresolution list 04:30:19 INFO - Available Modes on Display 0 04:30:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:20 INFO - Intel Iris: 04:30:20 INFO - Chipset Model: Intel Iris 04:30:20 INFO - Type: GPU 04:30:20 INFO - Bus: Built-In 04:30:20 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:20 INFO - Vendor: Intel (0x8086) 04:30:20 INFO - Device ID: 0x0a2e 04:30:20 INFO - Revision ID: 0x0009 04:30:20 INFO - Displays: 04:30:20 INFO - Display: 04:30:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:20 INFO - Display Serial Number: 1600x1200 60 04:30:20 INFO - Main Display: Yes 04:30:20 INFO - Mirror: Off 04:30:20 INFO - Online: Yes 04:30:20 INFO - Rotation: Supported 04:30:20 INFO - Return code: 0 04:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:20 INFO - Python 2.7.3 04:30:20 INFO - Return code: 0 04:30:20 INFO - grabbing minidump binary from tooltool 04:30: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')]} 04:30:20 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 0x1091e2d30>, '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 0x109006db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091d9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:20 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 04:30:20 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 04:30:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:20 INFO - Return code: 0 04:30:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:30:21 INFO - Return code: 0 04:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7AlMQMUbA/Render', 04:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:21 INFO - 'HOME': '/Users/cltbld', 04:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:21 INFO - 'LOGNAME': 'cltbld', 04:30:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:21 INFO - 'MOZ_NO_REMOTE': '1', 04:30:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:21 INFO - 'NO_EM_RESTART': '1', 04:30:21 INFO - 'PAGER': '/bin/cat', 04:30:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:21 INFO - 'PWD': '/builds/slave/test', 04:30:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:21 INFO - 'SHELL': '/bin/bash', 04:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9j8FFqMQsd/Listeners', 04:30:21 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 04:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:21 INFO - 'USER': 'cltbld', 04:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:21 INFO - 'XPC_FLAGS': '0x0', 04:30:21 INFO - 'XPC_SERVICE_NAME': '0', 04:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:30:21 INFO - mozversion application_buildid: 20160509021943 04:30:21 INFO - mozversion application_changeset: 748b70bcdbc95a4f3e72ca53a23c8f766049d011 04:30:21 INFO - mozversion application_display_name: Nightly 04:30:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:21 INFO - mozversion application_name: Firefox 04:30:21 INFO - mozversion application_remotingname: firefox 04:30:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:30:21 INFO - mozversion application_vendor: Mozilla 04:30:21 INFO - mozversion application_version: 49.0a1 04:30:21 INFO - mozversion platform_buildid: 20160509021943 04:30:21 INFO - mozversion platform_changeset: 748b70bcdbc95a4f3e72ca53a23c8f766049d011 04:30:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:30:21 INFO - mozversion platform_version: 49.0a1 04:30:21 INFO - using testdate: 1462793421 04:30:21 INFO - actual date: 1462793421 04:30:21 INFO - starting webserver on 'localhost:49276' 04:30:21 INFO - SUITE-START | Running 2 tests 04:30:21 INFO - TEST-START | dromaeo_css 04:30:21 INFO - operating with platform_type : mac_ 04:30:21 INFO - Initialising browser for dromaeo_css test... 04:30:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpsjZ8fZ/profile http://localhost:49276/getInfo.html) 04:30:23 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 04:30:23 INFO - PROCESS | 1666 | colorDepth:24 04:30:23 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 04:30:23 INFO - PROCESS | 1666 | __metrics 04:30:23 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:30:23 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:30:24 INFO - TEST-INFO | 1666: exit 0 04:30:24 INFO - Browser initialized. 04:30:24 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:24 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpsjZ8fZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:36 INFO - PROCESS | 1669 | RSS: Main: 238829568 04:30:36 INFO - PROCESS | 1669 | 04:31:27 INFO - PROCESS | 1669 | 1462793487812 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] 04:33:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 04:33:39 INFO - PROCESS | 1669 | RSS: Main: 367005696 04:33:39 INFO - PROCESS | 1669 | 04:36:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 04:36:43 INFO - PROCESS | 1669 | RSS: Main: 372260864 04:36:43 INFO - PROCESS | 1669 | 04:39:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 04:39:45 INFO - PROCESS | 1669 | RSS: Main: 367665152 04:39:45 INFO - PROCESS | 1669 | 04:42:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 04:42:48 INFO - PROCESS | 1669 | RSS: Main: 368934912 04:42:48 INFO - PROCESS | 1669 | 04:45:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 04:45:52 INFO - PROCESS | 1669 | RSS: Main: 338526208 04:45:52 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 04:48:55 INFO - PROCESS | 1669 | RSS: Main: 409276416 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | __start_tp_report 04:48:55 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 04:48:55 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 04:48:55 INFO - PROCESS | 1669 | |i|pagename|runs| 04:48:55 INFO - PROCESS | 1669 | |0;dojo.html;3358.680925312096;3364.4953257011443;3391.067456449477;3406.5571475708152;3439.965600343996;5472.808451704191;5565.777368905239;5584.994700953826;5612.214289999398;5633.7183140843;5510.1459273812625;5541.168824676301;5546.805861794838;5671.6880571568545;5710.800121995731;4967.826126085588;5073.391193056838;5108.948910510884;5138.948610513884;5235.502627250424;5196.636235463519;5233.73831308435;5240.868978275555;5247.842564723045;5255.658382205163;5038.168702164138;5044.899102017939;5104.285400043997;5120.539151476385;5180.481951804827;5098.337216161875;5109.642325037249;5109.8722531937;5110.48895110486;5132.5124113209185;5072.822451214227;5130.3436943042125;5134.332536770234;5172.61205409592;5183.585313174937;4433.445819272591;5450.700211488366;5513.476219759116;5523.558115350762;5558.999380111578;6117.327094019654;6150.600210986271;6169.691515424211;6188.659623720739;6193.628382297076;6221.471174950135;6280.717367718418;6288.245410550718;6295.653739044351;6322.9051564226575;5605.019121653694;5692.231548740909;5746.482816546531;5756.683382413924;5824.417558244227;6337.556371053982;6350.618962862243;6355.682215889187;6357.237131544151;6361.5546911716665;5853.239078919713;6067.588707074236;6067.898410930598;6069.061737007342;6095.238095238095;6072.210612620331;6142;6146.385361463907;6155.138280640714;6200.441960223511;6112.663803490851;6120.990036643993;6129.509639228851;6130.1724267224245;6137.84655383619;5951.375105613848;6040.788572399945;6108.8778224436155;6128.050152226412;6155.230596175478;4224.957750422518;4818.6626936114135;4888.413390393175;4899.877503062452;4906.754662266872;4347.195768782722;4369.344598310279;4371.0820727646915;4388.253996820548;4429.512753597101;5463.726813659301;5588.050031494575;5610.803621873297;5616.5787565931905;5676.574256930747;6057.18228039209;6097.5731698780655;6098.765064574895;6144.893919094606;6267.310595834455;5584.357798853184;5591.692456914828;5606.6636001840025;5612.410696876851;5636.013697602903;5222.033923724123;5330.360356757214;5336.306280183629;5345.118055520796;5348.358197016305;6258.780942666985;6279.309275979638;6311.779087731999;6340.397662222082;6340.556161068681;6149.754009839557;6226.844328891632;6235.625862448086;6238.189035425366;6260.217472815898;6196.318404975359;6271.278802937721;6272.09054687077;6279.874402512016;6283.088952101828;6029.60807547508;6085.148079268817;6105.114758359924;6135.294441139326;6145.047517634774;5076.213186956028;5621.5221706155435;6175.876482470236;6206.131141640264;6248.812535623939;5120.462351453044;5797.101449275369;5853.03424934881;5971.193888825127;6029.849253768691;5894.498967587803;5923.141144534105;5955.225820643289;6028.758849645965;6043.3050199227655;5038.319826823332;5894.469497745052;5901.704914754159;5908.970455147697;5922.289325280994;5767.884642307215;5858.912116318344;5999.730012149551;6002.579819412599;6055.364186760326;5784.363719990882;5832.912506312324;5892.793752218823;5892.823215303548;5910.704464776658 04:48:55 INFO - PROCESS | 1669 | |1;ext.html;520.5341219608453;529.9655522391047;531.3570579598685;532.092781807019;563.9125935480001;29509.55735663964;42348.729538113905;42432.57567424325;42798.572014279845;43395;36136.55453781852;41723.791381043055;42123;43733.7813310933;43830.780846095804;8564.571771411436;9560.091791279818;9782.315237933348;9813.313068085237;9813.705588832347;11556.30662160269;11816.995555377804;11819.172657913907;11943.34311612861;11977.520899164023;11534.942325288404;11627.418629068556;11632.12759043071;11639;11643.417829108554;11547.595834145806;11633.47649355781;11634.534618615246;11646.068314534818;11665.475053622607;11598.594049208321;11668.124890633237;11677.299362038304;11680;11681.649550513499;40057.79971100155;40198.397024044665;40289.79130626086;40335.39496907549;40364;3237.8219197944104;3246.626637936644;3251.6098068231727;3261.3314270574474;3415.7608967372294;28748.712512874812;28914.71085289141;28949.276268093254;29214;29436.55845162324;2646.2325925481587;2732.822366546188;2790.776737860966;2823.997480894294;2855.486012517746;494033;496090;496366;497272;497874;59743.70128149331;60319.39680603138;60385.39614603798;60411.39588604146;60564;64034.67982660057;64169;64200.357996419436;64208.35791642117;64217;16314.75527867083;16867.662646746998;16876.746848797276;16884.409045683344;16886.57783555421;13104.80342794859;13131.474741010252;13145.934270328587;13152.802707959388;13178.209307441584;15544.222788860512;15839.128847913475;15849.920750396173;15856.920715396349;15913.443029493912;6151.569390142737;6186.041163619646;6214.40963108504;6240.282367527701;6251.28110267316;965.2760429677742;997.4813097189566;998.4458625462793;1048.3814549415881;1131.530414877833;29126.563101553493;29268.707312927025;29368;30085.946991855617;30138.397232055675;1082.8862969388258;1095.8465814785936;1116.4194618798178;1175.2889501851428;1260.3131293445094;14643.974921755374;15136.924315378352;15175.116727821678;15190.772138417933;15680.843191568167;639664;643117;643777;644973;645450;60370.698146508985;60629.69685151546;60997.39002610006;61131.38868611346;61198.38801612016;1340.0686522866708;1377.4627895120711;1382.7925811128412;1493.4175671488129;1506.4576752369358;29851.701482984892;29942.401151977276;30604.693953060185;30784;31440;59167;59850.401495984486;60305.69847150736;60430;60654;523288;586481;588128;588634;589558;23303.06787728472;26225.606615901157;26658.600120998588;26738.19785406441;27547.586786198623;44672.776636116614;44874.551254487036;45480.772596136805;46276.07447850957;46570.76714616405;32108.839455802572;37513;37869;38025.61974380331;38966.41550376803;37257.813710931274;37470;37952.62047379491;37975;38235.80882095683;32441.351172975938;33082.669173308925;36855.81572092122;37293.81353093217;37925.620743792206;30102;32467.67532324741;32514.51228231531;34133;34314.82842585771;24067.398315041562;24582.385440364134;24751.25746227616;25022.499550009263;25098.62352064684 04:48:55 INFO - PROCESS | 1669 | |2;jquery.html;388.22549014715645;393.7263601796749;394.64679112194585;398.6053806731335;420.8421841809321;19405.708914366278;20102.698459523082;20467.385978420654;20469.795302046994;20637.793622063775;19575.314863979762;20021.099050542725;20164.59670806583;20788.584228315423;20836.583268334623;5624.746886390112;5739.167820666001;5939.227900372967;5940.138679891413;5949.375315591866;5797.072468405056;5809.593328467009;5890.410958904118;5894.616849904743;5911;5639.605227634067;5820.563457740686;5827.650340979533;5831.358550559435;5831.5626328025355;5484.61607687462;5592.189132575794;5644.266245388094;5666.121751128561;5701.71491425427;5082.1106306396605;5105.540501354859;5154.871128221825;5161.096808058574;5161.948380516222;13683.384247708877;23311.76688233113;23790.167344143047;24559.508809823703;25085.749142508525;2094.5077906692068;2183.585118827418;2218.0905828610353;2501.58723810571;2977.6277965254344;9930.255230857714;9979.30144889858;10026.89973100267;10251.84622230667;10360.740981475448;1751.1419404491708;1769.5222289981762;1800.9279628814832;1846.4645252876649;1866.150901339887;56887;81493.59253203755;81583;95381.9999999993;121367.3931630345;11172.832407513893;11639.41802909868;11653.825192622118;11664.941675291739;11787.174897757075;14925.402983880524;14992.925035374754;15010.474633387998;15023.173725445205;15035.248237588077;8619.569021548898;8659.350548708871;8710.303175745925;8711.738647840575;8838.55807209637;7139.678714457758;7326.3040011198855;8648.654053837901;8753;8776.551868941553;4539.7049191802425;4613.561711637389;4626.86119416418;4640.396748422683;4682.882927926829;1916.48254971157;1962.1268535501565;1968.3683603751056;1969.5076230942263;1974.1511150205263;892.455602082729;928.6563971330515;976.5068640336726;1024.9998759680764;1040.9167266618651;19644.3124490645;19919.900400497903;19970.70043949342;20113.497162571053;20381.898090509454;795.5028107432854;839.706102864005;908.9363744537819;937.3204426790659;960.8606752020918;9658.32391732586;10376.377417354983;10608.946955265328;10615.84076238857;10906.945465272624;535281;544094;544305;561160;630776;106807;127453;134490;138087.61912380747;146168.5383146155;1001.8146642871092;1030.1655658916302;1036.0727149201423;1037.2946396450336;1065.7122576904037;17812.732809008136;17840.10799460048;18110.54723631867;18203.54491137679;18558;49668.50331496639;51461;52613;53285.934281313384;54038.72980635072;75151;76008.61995690207;76428.2357176421;76828;78108.60945695236;6988.231294557493;7471.103467583925;7914.2085791420395;7940.682372705027;8312.70905518328;7659.88510172359;10733.550970618866;11612.6516204514;12397.690057748629;12897.484100636244;9491.762705932408;10743.677689669323;10780.514876830404;11105.278156919774;11168.497417616389;10663.093637040938;10830.40432776205;10911.662748535631;11094.278871873303;11111;11040.944795275973;11147.108231341312;11355.716107097389;11583.594574189863;11835;7952.761417157494;8002.159773223728;8101.311388531805;8350.331973442231;8356.916430835614;8184.83630327402;10132.898671013196;11038.227324087236;11370.715732106764;11445.141614379121 04:48:55 INFO - PROCESS | 1669 | |3;mootools.html;1376.256821316489;1606.1005836731429;1637.9099149546773;1881.9153138108784;2001.279539365827;7693.115291741445;8397.118302578225;8646.48121112732;8649.610767515462;8779.780505487366;8412.621432035574;8667.956660216705;8673.653053877837;8674.91325086749;8692.174243446878;2008.8895110768901;2224.7775222477703;2333.3466629343725;2379.488409991856;2412.8431651942656;2095.31902131807;2428.514297140579;2460.6063029915217;2466.6176742604835;2469.740677228892;2368.12379419615;2377.940551486209;2407.6870006899167;2417.371483414316;2423.0671191591186;2085.14509051289;2303.9078436862505;2358.84667496613;2398.832081754278;2406.4344878953434;2408.590539608271;2427.611582146848;2428.490017096412;2429.5019520998153;2431.525852458771;8650.307975361957;8744.562771861445;8748.30013598917;8750.693725719633;8762.211400973943;5115.897682046376;5158;5347.385050719139;5348.91976620351;5381.48875856793;3436.759426840122;3487.6512348765173;3563.803990780506;3579.033660911565;3584.1577229351083;1014.9644762433353;1038.1176000399676;1041.729150420896;1046.2310202001522;1047.0524823827675;11501.76996460066;11507.942460287728;11520.539178432853;11531.077513798877;11578.305301681927;4048.4534587830267;4720.253506202658;4785.401824771903;4849.345338379344;4853.514648535118;9790.314677972474;9799.069088436587;9802.460864652512;9806.558704858298;9851.261155413373;5315;5324.094903866352;5343.145096784496;5347.171188465794;5356.678599284055;3757.1546402059316;4321.65426765858;4487.842925497592;4507.459104907432;4766.880827979328;3764.7364684471822;3831.252905683365;3897.4738410314812;3918.2359439909515;3918.9810649230835;1302.856685764565;1330.05566048105;1331.8468376136675;1338.5248236875964;1342.3690865293297;911.9179273865251;929.7350255177242;934.1592566690033;948.9810203795886;1006.0442579549457;5753.51095156908;5757.309122905194;5809.912851307234;5862.824115276549;6041.305249896231;431.7172252174831;453.7640426977953;454.9021960278556;461.18830857690034;470.63760904104333;3398.660133986631;3923.9019024524614;4010.7794071326357;4024.9798751006056;4124.628783409447;10399.168066554657;10580.947095264475;10751.247412681194;10762.515686793957;10765.08496777767;10573.735656608493;10617.362958222531;10721.892781072245;10730.624428144978;10759.623413180501;7110.537815042109;7638.923610763933;7946.768250921116;8022.0774610919325;8185.508869467851;5364.946350536445;5469.453054694499;5868.149118377726;5953.1070339449425;6027.457528822427;9411.905880941376;9688.93421723624;9846.359986600848;9875.456849873326;9906.851397228904;9849.064338887794;9966.352187107816;10084.092431681183;10084.949575252076;10189.643362482277;7295.2340004298785;7381.889271660822;7510.324070833652;7541.095068591804;7644.35023023027;8082.19178082187;8145.063317718538;8161.387895907949;8165.918340816515;8174.223448772357;7119.6084215368655;7319.011933389137;7390.29792169743;7408.814779630552;7428.182899881117;6465.256495502889;7120.6795694194925;7181.102362204724;7201.3518783309755;7376.336129748348;6920.792376228721;6957.547759395623;6987.475939304674;6992.056072430157;7014.018037474656;4745.4542727585385;4825.276208568743;4908.7545622719435;4930.137225985366;5116.488351164854;4751.358566593604;4757.26262429324;5008;5016.197408414636;5059.519345662156 04:48:55 INFO - PROCESS | 1669 | |4;prototype.html;305.8684765550813;314.72933277381446;316.80199875078074;320.78026551811996;324.76292306616153;4724.763761811912;5280.762365693543;5310.867228319289;5338.519533242007;5353.384360798509;4668.766561671911;5201.505856943584;5238.188080847465;5274.973625131878;5296.576273898088;3769.5664998525176;3852.6532612064907;3913.7260391772443;3993.960060399402;4057.736247143941;3401.982990085046;3464.549608550885;3740.831662575191;3931.3906344516645;4034.0721634024194;3363.7140843028374;3376.6623337666283;3797.506324177853;3935.5277366716036;3973.5231772187376;3774.188549461865;3797.607507270402;4001.4598029265967;4005.4792876925812;4019.8995025124314;3071.784975051747;3089.601438417745;3409;3414.590249170091;3471.7916924984584;4765.451973023112;4936.827211047632;4943.233798761162;4971.135824065976;4975.925361119586;3955.920881582352;4372.890677733082;4509.6392288616835;4524.389207456984;4524.6606504511965;4803.838272777264;5243.868903277448;5245.659032162936;5466.753996070147;5476.945230547683;3534.240138370237;3644.1982763791925;3836.5396152461603;3842.51968503937;3845.6731177849915;4988.85479243925;5675.233843431125;5718.285214348207;5725.570582206309;5796.0150522480535;3859.575446700917;3885.057471264368;3885.42640230707;3892.3188442022533;3907.29668659644;3422.2299982504237;3697.280764444546;3830.0616348994677;3943.9014024649614;3950.6839452843706;4052.4124002019544;4550.704204226715;4554.248548989446;4557.475890272594;4564.3153526970555;5122.846314610568;5543.7782488700805;5580.358258800209;5603.8599035023635;5639.520640745583;1190.863050749175;1197.5988044005355;1259.2696236182992;1269.4224128021729;1281.4553814628746;730.3499885102157;811.5617566514135;855.9101294364127;860.6858496648804;861.190480947907;4621.630269578425;4772.475027746945;4933.753312334369;5190.97404512975;5205;4713.858584242478;4823.517648235219;5568.665880047129;5677.877167557909;5739.053056245756;4330.545292744279;4648.233041548175;4772.785224664898;4995.675281106716;5157.974210128926;2553.4254792671873;2589.8446093234465;2773.362126710868;2837.4183292425005;2849.772018238536;4780.709208513682;5223.634345595772;5538.086215774432;5566.471185237396;5588.636738611977;4469.508354081048;5082.695038297714;5267.736613169326;5392.757325920343;5500.257465242221;5087.100352798943;5262.394824595221;5544.944550554443;5558.722063896789;5652.123920792284;4804.183288840835;5268.525832675077;5411.269478620493;5415.810446634349;5439.776050388631;4058.7158898877974;5401.459854014724;5629.240052592848;5660.915086273627;5679.74441150141;4856.878578035577;5629.437056294338;5857.326407463196;5902.520941204619;5912.674802885883;3506.3162683276514;3731.8431286301334;3794.0704527390967;3845.5769865314232;3862;4728.905421891612;5186.455422180617;5658.481975520854;5716.085426331767;5728.255326807488;5396.379416367168;5457.890842183055;5506.724663766876;5512.4211957745465;5554.50009499147;5109.594861413141;5144.331236939174;5156.458571849902;5524.640898341595;5532.2254884315425;4860.805567777249;5252.737363131751;5387.461253874581;5422.728863556727;5430.565554755531;4161.877442504284;4855.980244148768;5404.432894375141;5412.134058550613;5434.375046869661;5019.372578427697;5293.200311893975;5339.546138578265;5350.598705097055;5353.411124776349 04:48:55 INFO - PROCESS | 1669 | |5;yui.html;632.3518393646511;675.6351570152117;702.1959855964917;721.1850608812042;750.969961201552;14527.709445811091;15229.847701522982;15241.923790381046;15243.390264389438;15244.314005869735;14008.929955350235;14042.508512202076;14049.578512644637;14202.71594568108;15563.533094007198;916.4043371808328;926.3237836379448;926.6710317837138;928.6517555916531;931.4737173496986;895.3508706187994;919.1957037592107;919.6229545886188;923.1414784250644;925.8842644669417;973.177664873181;978.9363691360039;981.151304121933;984.2322988069282;984.3601658921687;968.5931908598405;969.9563519641633;970.4613939263705;974.3131092579749;975.4927437732375;727.2073439950426;727.4035291061332;728.8870225115115;733.3216774483582;733.3253406865697;14640.267986600735;14675.63310917225;14691.706165876623;14695.70608587833;14745.41018359275;12389.318587477685;12439;12464.376781160905;12525;12977.221366718028;9874.60501579936;9879.456629885353;9912;9918.603255869757;10318.793624127551;3430.9656903431146;3522.6653467920764;3591.191981804099;3605.387084195693;3636.3272794533095;76730;77220.61389693072;77667.61166194189;79617;79973;9233.307501937246;9345.065493450737;9381.155695987258;9529.952350238344;9645.42127472354;21943.67084493734;21948;21961.560768784533;21963.450913726963;22026.779732202795;7092.787216383516;7209.0988626421695;7260.455465840083;7262.346388825031;7428.517146385576;3988.2821092203103;4081.5306239782803;4081.551029386765;4086.162336720965;4097.8975525612095;3486.372452958492;3598.3702852000797;3604.8377822997504;3605.6214097519905;3609.151849315409;542.8507160530806;572.3875453264967;614.9699253749951;621.9458018658331;647.058529839083;473.8436316015707;534.6337758635348;563.2986931270594;569.2429069337771;620.7051650466083;7923;8142.877856832152;8199.385046121564;8304.8339033219;8321.791955201168;532.9413764485903;560.8597850537366;564.2833601326292;588.682111659699;589.4105894105894;3849.172427927953;3868.613138686109;3890.4747859039235;3898.122922342507;3971.841126354971;83140;85994;86152;90540.5472972631;92974.53512732394;45815.77092114518;51427.74286128546;68894.50921117597;71203;74582.25417745754;57078.7146064267;58587;62855.71442855678;63634.36365636377;66291;7962.124166341813;7980.441369104107;8109.797255068434;8280.089190189196;8342.16578342161;40017.79946601607;40064.99837504086;40757.38863916984;41182;41898.79050604728;52050.47949520608;57840.421595783504;57890;58010.839783204945;58615.120773187584;10684.482416053981;11035.779284414428;11131.35134325814;11301.208915375844;11375.715607109889;11848.466818993338;11856.110791690486;11867.703307417383;12317.630471085882;12429;11306.208565400342;11445.656630301104;11676.591319303783;11864.11019173548;11882.249586801361;11092.889071109508;11315.151363647594;11544.711382215512;11645.359505227294;12375.69060773488;9540.570674319533;10907.29102608328;11124.555017799197;11365.715857103638;12013.918747312784;9091.545422728705;9203.76990575241;9273.536323183678;9305.069493050642;9365.391249568756;9304.674336398193;9508.762280942756;9552.331336806357;9555.61777528891;9585.281103917094 04:48:55 INFO - PROCESS | 1669 | __end_tp_report 04:48:55 INFO - PROCESS | 1669 | __start_cc_report 04:48:55 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1280 04:48:55 INFO - PROCESS | 1669 | __end_cc_report 04:48:55 INFO - PROCESS | 1669 | __startTimestamp1462794535960__endTimestamp 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | ------- Summary: start ------- 04:48:55 INFO - PROCESS | 1669 | Number of tests: 6 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5643.43 Median:5832.91 stddev:646.05 (11.1%) stddev-sans-first:622.68 04:48:55 INFO - PROCESS | 1669 | Values: 3358.7 3364.5 3391.1 3406.6 3440.0 5472.8 5565.8 5585.0 5612.2 5633.7 5510.1 5541.2 5546.8 5671.7 5710.8 4967.8 5073.4 5108.9 5138.9 5235.5 5196.6 5233.7 5240.9 5247.8 5255.7 5038.2 5044.9 5104.3 5120.5 5180.5 5098.3 5109.6 5109.9 5110.5 5132.5 5072.8 5130.3 5134.3 5172.6 5183.6 4433.4 5450.7 5513.5 5523.6 5559.0 6117.3 6150.6 6169.7 6188.7 6193.6 6221.5 6280.7 6288.2 6295.7 6322.9 5605.0 5692.2 5746.5 5756.7 5824.4 6337.6 6350.6 6355.7 6357.2 6361.6 5853.2 6067.6 6067.9 6069.1 6095.2 6072.2 6142.0 6146.4 6155.1 6200.4 6112.7 6121.0 6129.5 6130.2 6137.8 5951.4 6040.8 6108.9 6128.1 6155.2 4225.0 4818.7 4888.4 4899.9 4906.8 4347.2 4369.3 4371.1 4388.3 4429.5 5463.7 5588.1 5610.8 5616.6 5676.6 6057.2 6097.6 6098.8 6144.9 6267.3 5584.4 5591.7 5606.7 5612.4 5636.0 5222.0 5330.4 5336.3 5345.1 5348.4 6258.8 6279.3 6311.8 6340.4 6340.6 6149.8 6226.8 6235.6 6238.2 6260.2 6196.3 6271.3 6272.1 6279.9 6283.1 6029.6 6085.1 6105.1 6135.3 6145.0 5076.2 5621.5 6175.9 6206.1 6248.8 5120.5 5797.1 5853.0 5971.2 6029.8 5894.5 5923.1 5955.2 6028.8 6043.3 5038.3 5894.5 5901.7 5909.0 5922.3 5767.9 5858.9 5999.7 6002.6 6055.4 5784.4 5832.9 5892.8 5892.8 5910.7 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:70670.60 Median:17887.92 stddev:153624.91 (858.8%) stddev-sans-first:153965.58 04:48:55 INFO - PROCESS | 1669 | Values: 520.5 530.0 531.4 532.1 563.9 29509.6 42348.7 42432.6 42798.6 43395.0 36136.6 41723.8 42123.0 43733.8 43830.8 8564.6 9560.1 9782.3 9813.3 9813.7 11556.3 11817.0 11819.2 11943.3 11977.5 11534.9 11627.4 11632.1 11639.0 11643.4 11547.6 11633.5 11634.5 11646.1 11665.5 11598.6 11668.1 11677.3 11680.0 11681.6 40057.8 40198.4 40289.8 40335.4 40364.0 3237.8 3246.6 3251.6 3261.3 3415.8 28748.7 28914.7 28949.3 29214.0 29436.6 2646.2 2732.8 2790.8 2824.0 2855.5 494033.0 496090.0 496366.0 497272.0 497874.0 59743.7 60319.4 60385.4 60411.4 60564.0 64034.7 64169.0 64200.4 64208.4 64217.0 16314.8 16867.7 16876.7 16884.4 16886.6 13104.8 13131.5 13145.9 13152.8 13178.2 15544.2 15839.1 15849.9 15856.9 15913.4 6151.6 6186.0 6214.4 6240.3 6251.3 965.3 997.5 998.4 1048.4 1131.5 29126.6 29268.7 29368.0 30085.9 30138.4 1082.9 1095.8 1116.4 1175.3 1260.3 14644.0 15136.9 15175.1 15190.8 15680.8 639664.0 643117.0 643777.0 644973.0 645450.0 60370.7 60629.7 60997.4 61131.4 61198.4 1340.1 1377.5 1382.8 1493.4 1506.5 29851.7 29942.4 30604.7 30784.0 31440.0 59167.0 59850.4 60305.7 60430.0 60654.0 523288.0 586481.0 588128.0 588634.0 589558.0 23303.1 26225.6 26658.6 26738.2 27547.6 44672.8 44874.6 45480.8 46276.1 46570.8 32108.8 37513.0 37869.0 38025.6 38966.4 37257.8 37470.0 37952.6 37975.0 38235.8 32441.4 33082.7 36855.8 37293.8 37925.6 30102.0 32467.7 32514.5 34133.0 34314.8 24067.4 24582.4 24751.3 25022.5 25098.6 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:32974.94 Median:14031.69 stddev:94063.60 (670.4%) stddev-sans-first:94295.65 04:48:55 INFO - PROCESS | 1669 | Values: 388.2 393.7 394.6 398.6 420.8 19405.7 20102.7 20467.4 20469.8 20637.8 19575.3 20021.1 20164.6 20788.6 20836.6 5624.7 5739.2 5939.2 5940.1 5949.4 5797.1 5809.6 5890.4 5894.6 5911.0 5639.6 5820.6 5827.7 5831.4 5831.6 5484.6 5592.2 5644.3 5666.1 5701.7 5082.1 5105.5 5154.9 5161.1 5161.9 13683.4 23311.8 23790.2 24559.5 25085.7 2094.5 2183.6 2218.1 2501.6 2977.6 9930.3 9979.3 10026.9 10251.8 10360.7 1751.1 1769.5 1800.9 1846.5 1866.2 56887.0 81493.6 81583.0 95382.0 121367.4 11172.8 11639.4 11653.8 11664.9 11787.2 14925.4 14992.9 15010.5 15023.2 15035.2 8619.6 8659.4 8710.3 8711.7 8838.6 7139.7 7326.3 8648.7 8753.0 8776.6 4539.7 4613.6 4626.9 4640.4 4682.9 1916.5 1962.1 1968.4 1969.5 1974.2 892.5 928.7 976.5 1025.0 1040.9 19644.3 19919.9 19970.7 20113.5 20381.9 795.5 839.7 908.9 937.3 960.9 9658.3 10376.4 10608.9 10615.8 10906.9 535281.0 544094.0 544305.0 561160.0 630776.0 106807.0 127453.0 134490.0 138087.6 146168.5 1001.8 1030.2 1036.1 1037.3 1065.7 17812.7 17840.1 18110.5 18203.5 18558.0 49668.5 51461.0 52613.0 53285.9 54038.7 75151.0 76008.6 76428.2 76828.0 78108.6 6988.2 7471.1 7914.2 7940.7 8312.7 7659.9 10733.6 11612.7 12397.7 12897.5 9491.8 10743.7 10780.5 11105.3 11168.5 10663.1 10830.4 10911.7 11094.3 11111.0 11040.9 11147.1 11355.7 11583.6 11835.0 7952.8 8002.2 8101.3 8350.3 8356.9 8184.8 10132.9 11038.2 11370.7 11445.1 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5627.82 Median:4837.31 stddev:3169.36 (65.5%) stddev-sans-first:3162.14 04:48:55 INFO - PROCESS | 1669 | Values: 1376.3 1606.1 1637.9 1881.9 2001.3 7693.1 8397.1 8646.5 8649.6 8779.8 8412.6 8668.0 8673.7 8674.9 8692.2 2008.9 2224.8 2333.3 2379.5 2412.8 2095.3 2428.5 2460.6 2466.6 2469.7 2368.1 2377.9 2407.7 2417.4 2423.1 2085.1 2303.9 2358.8 2398.8 2406.4 2408.6 2427.6 2428.5 2429.5 2431.5 8650.3 8744.6 8748.3 8750.7 8762.2 5115.9 5158.0 5347.4 5348.9 5381.5 3436.8 3487.7 3563.8 3579.0 3584.2 1015.0 1038.1 1041.7 1046.2 1047.1 11501.8 11507.9 11520.5 11531.1 11578.3 4048.5 4720.3 4785.4 4849.3 4853.5 9790.3 9799.1 9802.5 9806.6 9851.3 5315.0 5324.1 5343.1 5347.2 5356.7 3757.2 4321.7 4487.8 4507.5 4766.9 3764.7 3831.3 3897.5 3918.2 3919.0 1302.9 1330.1 1331.8 1338.5 1342.4 911.9 929.7 934.2 949.0 1006.0 5753.5 5757.3 5809.9 5862.8 6041.3 431.7 453.8 454.9 461.2 470.6 3398.7 3923.9 4010.8 4025.0 4124.6 10399.2 10580.9 10751.2 10762.5 10765.1 10573.7 10617.4 10721.9 10730.6 10759.6 7110.5 7638.9 7946.8 8022.1 8185.5 5364.9 5469.5 5868.1 5953.1 6027.5 9411.9 9688.9 9846.4 9875.5 9906.9 9849.1 9966.4 10084.1 10084.9 10189.6 7295.2 7381.9 7510.3 7541.1 7644.4 8082.2 8145.1 8161.4 8165.9 8174.2 7119.6 7319.0 7390.3 7408.8 7428.2 6465.3 7120.7 7181.1 7201.4 7376.3 6920.8 6957.5 6987.5 6992.1 7014.0 4745.5 4825.3 4908.8 4930.1 5116.5 4751.4 4757.3 5008.0 5016.2 5059.5 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4391.40 Median:4935.29 stddev:1350.91 (27.4%) stddev-sans-first:1319.44 04:48:55 INFO - PROCESS | 1669 | Values: 305.9 314.7 316.8 320.8 324.8 4724.8 5280.8 5310.9 5338.5 5353.4 4668.8 5201.5 5238.2 5275.0 5296.6 3769.6 3852.7 3913.7 3994.0 4057.7 3402.0 3464.5 3740.8 3931.4 4034.1 3363.7 3376.7 3797.5 3935.5 3973.5 3774.2 3797.6 4001.5 4005.5 4019.9 3071.8 3089.6 3409.0 3414.6 3471.8 4765.5 4936.8 4943.2 4971.1 4975.9 3955.9 4372.9 4509.6 4524.4 4524.7 4803.8 5243.9 5245.7 5466.8 5476.9 3534.2 3644.2 3836.5 3842.5 3845.7 4988.9 5675.2 5718.3 5725.6 5796.0 3859.6 3885.1 3885.4 3892.3 3907.3 3422.2 3697.3 3830.1 3943.9 3950.7 4052.4 4550.7 4554.2 4557.5 4564.3 5122.8 5543.8 5580.4 5603.9 5639.5 1190.9 1197.6 1259.3 1269.4 1281.5 730.3 811.6 855.9 860.7 861.2 4621.6 4772.5 4933.8 5191.0 5205.0 4713.9 4823.5 5568.7 5677.9 5739.1 4330.5 4648.2 4772.8 4995.7 5158.0 2553.4 2589.8 2773.4 2837.4 2849.8 4780.7 5223.6 5538.1 5566.5 5588.6 4469.5 5082.7 5267.7 5392.8 5500.3 5087.1 5262.4 5544.9 5558.7 5652.1 4804.2 5268.5 5411.3 5415.8 5439.8 4058.7 5401.5 5629.2 5660.9 5679.7 4856.9 5629.4 5857.3 5902.5 5912.7 3506.3 3731.8 3794.1 3845.6 3862.0 4728.9 5186.5 5658.5 5716.1 5728.3 5396.4 5457.9 5506.7 5512.4 5554.5 5109.6 5144.3 5156.5 5524.6 5532.2 4860.8 5252.7 5387.5 5422.7 5430.6 4161.9 4856.0 5404.4 5412.1 5434.4 5019.4 5293.2 5339.5 5350.6 5353.4 04:48:55 INFO - PROCESS | 1669 | 04:48:55 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:16877.27 Median:58298.92 stddev:22973.59 (39.4%) stddev-sans-first:23005.65 04:48:55 INFO - PROCESS | 1669 | Values: 632.4 675.6 702.2 721.2 751.0 14527.7 15229.8 15241.9 15243.4 15244.3 14008.9 14042.5 14049.6 14202.7 15563.5 916.4 926.3 926.7 928.7 931.5 895.4 919.2 919.6 923.1 925.9 973.2 978.9 981.2 984.2 984.4 968.6 970.0 970.5 974.3 975.5 727.2 727.4 728.9 733.3 733.3 14640.3 14675.6 14691.7 14695.7 14745.4 12389.3 12439.0 12464.4 12525.0 12977.2 9874.6 9879.5 9912.0 9918.6 10318.8 3431.0 3522.7 3591.2 3605.4 3636.3 76730.0 77220.6 77667.6 79617.0 79973.0 9233.3 9345.1 9381.2 9530.0 9645.4 21943.7 21948.0 21961.6 21963.5 22026.8 7092.8 7209.1 7260.5 7262.3 7428.5 3988.3 4081.5 4081.6 4086.2 4097.9 3486.4 3598.4 3604.8 3605.6 3609.2 542.9 572.4 615.0 621.9 647.1 473.8 534.6 563.3 569.2 620.7 7923.0 8142.9 8199.4 8304.8 8321.8 532.9 560.9 564.3 588.7 589.4 3849.2 3868.6 3890.5 3898.1 3971.8 83140.0 85994.0 86152.0 90540.5 92974.5 45815.8 51427.7 68894.5 71203.0 74582.3 57078.7 58587.0 62855.7 63634.4 66291.0 7962.1 7980.4 8109.8 8280.1 8342.2 40017.8 40065.0 40757.4 41182.0 41898.8 52050.5 57840.4 57890.0 58010.8 58615.1 10684.5 11035.8 11131.4 11301.2 11375.7 11848.5 11856.1 11867.7 12317.6 12429.0 11306.2 11445.7 11676.6 11864.1 11882.2 11092.9 11315.2 11544.7 11645.4 12375.7 9540.6 10907.3 11124.6 11365.7 12013.9 9091.5 9203.8 9273.5 9305.1 9365.4 9304.7 9508.8 9552.3 9555.6 9585.3 04:48:55 INFO - PROCESS | 1669 | -------- Summary: end -------- 04:48:55 INFO - PROCESS | 1669 | 04:48:56 INFO - TEST-INFO | 1669: exit 0 04:48:56 INFO - TEST-OK | dromaeo_css | took 1115051ms 04:48:56 INFO - TEST-START | kraken 04:48:56 INFO - operating with platform_type : mac_ 04:48:56 INFO - Initialising browser for kraken test... 04:48:56 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpDmG8Dj/profile http://localhost:49276/getInfo.html) 04:48:58 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 04:48:58 INFO - PROCESS | 1674 | colorDepth:24 04:48:58 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 04:48:58 INFO - PROCESS | 1674 | __metrics 04:48:58 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:58 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:48:59 INFO - TEST-INFO | 1674: exit 0 04:48:59 INFO - Browser initialized. 04:48:59 INFO - Running cycle 1/1 for kraken test... 04:48:59 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpDmG8Dj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:11 INFO - PROCESS | 1676 | RSS: Main: 239185920 04:49:11 INFO - PROCESS | 1676 | 04:49:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:15 INFO - PROCESS | 1676 | RSS: Main: 287535104 04:49:15 INFO - PROCESS | 1676 | 04:49:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 04:49:18 INFO - PROCESS | 1676 | RSS: Main: 295936000 04:49:18 INFO - PROCESS | 1676 | 04:49:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 04:49:23 INFO - PROCESS | 1676 | RSS: Main: 383913984 04:49:23 INFO - PROCESS | 1676 | 04:49:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 04:49:25 INFO - PROCESS | 1676 | RSS: Main: 283316224 04:49:25 INFO - PROCESS | 1676 | 04:49:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:28 INFO - PROCESS | 1676 | RSS: Main: 286855168 04:49:28 INFO - PROCESS | 1676 | 04:49:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:32 INFO - PROCESS | 1676 | RSS: Main: 326578176 04:49:32 INFO - PROCESS | 1676 | 04:49:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 04:49:35 INFO - PROCESS | 1676 | RSS: Main: 329818112 04:49:35 INFO - PROCESS | 1676 | 04:49:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 04:49:39 INFO - PROCESS | 1676 | RSS: Main: 327634944 04:49:39 INFO - PROCESS | 1676 | 04:49:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:49:41 INFO - PROCESS | 1676 | RSS: Main: 316170240 04:49:41 INFO - PROCESS | 1676 | 04:49:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:49:44 INFO - PROCESS | 1676 | RSS: Main: 322990080 04:49:44 INFO - PROCESS | 1676 | 04:49:46 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:49:47 INFO - PROCESS | 1676 | RSS: Main: 324644864 04:49:47 INFO - PROCESS | 1676 | 04:49:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:49:50 INFO - PROCESS | 1676 | RSS: Main: 329248768 04:49:50 INFO - PROCESS | 1676 | 04:49:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:54 INFO - PROCESS | 1676 | RSS: Main: 327077888 04:49:54 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 04:49:56 INFO - PROCESS | 1676 | RSS: Main: 374050816 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | __start_tp_report 04:49:56 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 04:49:56 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 04:49:56 INFO - PROCESS | 1676 | |i|pagename|runs| 04:49:56 INFO - PROCESS | 1676 | |0;ai-astar;113;106;113;114;113;114;111;113;112;111 04:49:56 INFO - PROCESS | 1676 | |1;audio-beat-detection;152;119;139;195;116;141;139;119;183;145 04:49:56 INFO - PROCESS | 1676 | |2;audio-dft;186;185;189;184;185;230;186;185;186;186 04:49:56 INFO - PROCESS | 1676 | |3;audio-fft;79;73;58;125;56;63;58;59;109;56 04:49:56 INFO - PROCESS | 1676 | |4;audio-oscillator;95;99;136;80;81;77;101;81;80;101 04:49:56 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;88;85;88;85;87;84;88;86;86;84 04:49:56 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;92;92;93;92;93;92;92;93;92 04:49:56 INFO - PROCESS | 1676 | |7;imaging-desaturate;83;85;86;86;79;82;83;86;85;86 04:49:56 INFO - PROCESS | 1676 | |8;json-parse-financial;59;60;59;60;61;58;58;121;60;58 04:49:56 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;63;75;76;71;69;67;66;67;64 04:49:56 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;84;86;85;84;84;80;84;86;90 04:49:56 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;141;137;141;139;140;135;135;140;216;140 04:49:56 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;170;169;168;169;166;169;170;170;170;165 04:49:56 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;66;65;67;66;66 04:49:56 INFO - PROCESS | 1676 | __end_tp_report 04:49:56 INFO - PROCESS | 1676 | __start_cc_report 04:49:56 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2288 04:49:56 INFO - PROCESS | 1676 | __end_cc_report 04:49:56 INFO - PROCESS | 1676 | __startTimestamp1462794596425__endTimestamp 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | ------- Summary: start ------- 04:49:56 INFO - PROCESS | 1676 | Number of tests: 14 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.36 (2.1%) stddev-sans-first:2.47 04:49:56 INFO - PROCESS | 1676 | Values: 113.0 106.0 113.0 114.0 113.0 114.0 111.0 113.0 112.0 111.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:143.00 stddev:26.40 (18.5%) stddev-sans-first:27.87 04:49:56 INFO - PROCESS | 1676 | Values: 152.0 119.0 139.0 195.0 116.0 141.0 139.0 119.0 183.0 145.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:190.20 Median:186.00 stddev:14.05 (7.6%) stddev-sans-first:14.82 04:49:56 INFO - PROCESS | 1676 | Values: 186.0 185.0 189.0 184.0 185.0 230.0 186.0 185.0 186.0 186.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:73.60 Median:58.50 stddev:24.40 (41.7%) stddev-sans-first:25.80 04:49:56 INFO - PROCESS | 1676 | Values: 79.0 73.0 58.0 125.0 56.0 63.0 58.0 59.0 109.0 56.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:80.50 stddev:17.95 (22.3%) stddev-sans-first:19.02 04:49:56 INFO - PROCESS | 1676 | Values: 95.0 99.0 136.0 80.0 81.0 77.0 101.0 81.0 80.0 101.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.54 04:49:56 INFO - PROCESS | 1676 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 88.0 86.0 86.0 84.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:49:56 INFO - PROCESS | 1676 | Values: 92.0 92.0 92.0 93.0 92.0 93.0 92.0 92.0 93.0 92.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:85.50 stddev:2.33 (2.7%) stddev-sans-first:2.44 04:49:56 INFO - PROCESS | 1676 | Values: 83.0 85.0 86.0 86.0 79.0 82.0 83.0 86.0 85.0 86.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:59.50 stddev:19.56 (32.9%) stddev-sans-first:20.61 04:49:56 INFO - PROCESS | 1676 | Values: 59.0 60.0 59.0 60.0 61.0 58.0 58.0 121.0 60.0 58.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:4.65 (6.8%) stddev-sans-first:4.56 04:49:56 INFO - PROCESS | 1676 | Values: 63.0 63.0 75.0 76.0 71.0 69.0 67.0 66.0 67.0 64.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:2.49 (2.9%) stddev-sans-first:2.64 04:49:56 INFO - PROCESS | 1676 | Values: 85.0 84.0 86.0 85.0 84.0 84.0 80.0 84.0 86.0 90.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:140.00 stddev:24.56 (17.5%) stddev-sans-first:25.97 04:49:56 INFO - PROCESS | 1676 | Values: 141.0 137.0 141.0 139.0 140.0 135.0 135.0 140.0 216.0 140.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.50 stddev:1.78 (1.0%) stddev-sans-first:1.81 04:49:56 INFO - PROCESS | 1676 | Values: 170.0 169.0 168.0 169.0 166.0 169.0 170.0 170.0 170.0 165.0 04:49:56 INFO - PROCESS | 1676 | 04:49:56 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 04:49:56 INFO - PROCESS | 1676 | Values: 67.0 67.0 67.0 66.0 66.0 66.0 65.0 67.0 66.0 66.0 04:49:56 INFO - PROCESS | 1676 | -------- Summary: end -------- 04:49:56 INFO - PROCESS | 1676 | 04:49:57 INFO - TEST-INFO | 1676: exit 0 04:49:57 INFO - TEST-OK | kraken | took 60725ms 04:49:57 INFO - SUITE-END | took 1175s 04:49:57 INFO - Completed test suite (00:19:36) 04:49:57 INFO - javascript benchmark 04:49:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3358.680925312096, 3364.4953257011443, 3391.067456449477, 3406.5571475708152, 3439.965600343996, 5472.808451704191, 5565.777368905239, 5584.994700953826, 5612.214289999398, 5633.7183140843, 5510.1459273812625, 5541.168824676301, 5546.805861794838, 5671.6880571568545, 5710.800121995731, 4967.826126085588, 5073.391193056838, 5108.948910510884, 5138.948610513884, 5235.502627250424, 5196.636235463519, 5233.73831308435, 5240.868978275555, 5247.842564723045, 5255.658382205163, 5038.168702164138, 5044.899102017939, 5104.285400043997, 5120.539151476385, 5180.481951804827, 5098.337216161875, 5109.642325037249, 5109.8722531937, 5110.48895110486, 5132.5124113209185, 5072.822451214227, 5130.3436943042125, 5134.332536770234, 5172.61205409592, 5183.585313174937, 4433.445819272591, 5450.700211488366, 5513.476219759116, 5523.558115350762, 5558.999380111578, 6117.327094019654, 6150.600210986271, 6169.691515424211, 6188.659623720739, 6193.628382297076, 6221.471174950135, 6280.717367718418, 6288.245410550718, 6295.653739044351, 6322.9051564226575, 5605.019121653694, 5692.231548740909, 5746.482816546531, 5756.683382413924, 5824.417558244227, 6337.556371053982, 6350.618962862243, 6355.682215889187, 6357.237131544151, 6361.5546911716665, 5853.239078919713, 6067.588707074236, 6067.898410930598, 6069.061737007342, 6095.238095238095, 6072.210612620331, 6142.0, 6146.385361463907, 6155.138280640714, 6200.441960223511, 6112.663803490851, 6120.990036643993, 6129.509639228851, 6130.1724267224245, 6137.84655383619, 5951.375105613848, 6040.788572399945, 6108.8778224436155, 6128.050152226412, 6155.230596175478, 4224.957750422518, 4818.6626936114135, 4888.413390393175, 4899.877503062452, 4906.754662266872, 4347.195768782722, 4369.344598310279, 4371.0820727646915, 4388.253996820548, 4429.512753597101, 5463.726813659301, 5588.050031494575, 5610.803621873297, 5616.5787565931905, 5676.574256930747, 6057.18228039209, 6097.5731698780655, 6098.765064574895, 6144.893919094606, 6267.310595834455, 5584.357798853184, 5591.692456914828, 5606.6636001840025, 5612.410696876851, 5636.013697602903, 5222.033923724123, 5330.360356757214, 5336.306280183629, 5345.118055520796, 5348.358197016305, 6258.780942666985, 6279.309275979638, 6311.779087731999, 6340.397662222082, 6340.556161068681, 6149.754009839557, 6226.844328891632, 6235.625862448086, 6238.189035425366, 6260.217472815898, 6196.318404975359, 6271.278802937721, 6272.09054687077, 6279.874402512016, 6283.088952101828, 6029.60807547508, 6085.148079268817, 6105.114758359924, 6135.294441139326, 6145.047517634774, 5076.213186956028, 5621.5221706155435, 6175.876482470236, 6206.131141640264, 6248.812535623939, 5120.462351453044, 5797.101449275369, 5853.03424934881, 5971.193888825127, 6029.849253768691, 5894.498967587803, 5923.141144534105, 5955.225820643289, 6028.758849645965, 6043.3050199227655, 5038.319826823332, 5894.469497745052, 5901.704914754159, 5908.970455147697, 5922.289325280994, 5767.884642307215, 5858.912116318344, 5999.730012149551, 6002.579819412599, 6055.364186760326, 5784.363719990882, 5832.912506312324, 5892.793752218823, 5892.823215303548, 5910.704464776658], "value": 5603.041014053059, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.5341219608453, 529.9655522391047, 531.3570579598685, 532.092781807019, 563.9125935480001, 29509.55735663964, 42348.729538113905, 42432.57567424325, 42798.572014279845, 43395.0, 36136.55453781852, 41723.791381043055, 42123.0, 43733.7813310933, 43830.780846095804, 8564.571771411436, 9560.091791279818, 9782.315237933348, 9813.313068085237, 9813.705588832347, 11556.30662160269, 11816.995555377804, 11819.172657913907, 11943.34311612861, 11977.520899164023, 11534.942325288404, 11627.418629068556, 11632.12759043071, 11639.0, 11643.417829108554, 11547.595834145806, 11633.47649355781, 11634.534618615246, 11646.068314534818, 11665.475053622607, 11598.594049208321, 11668.124890633237, 11677.299362038304, 11680.0, 11681.649550513499, 40057.79971100155, 40198.397024044665, 40289.79130626086, 40335.39496907549, 40364.0, 3237.8219197944104, 3246.626637936644, 3251.6098068231727, 3261.3314270574474, 3415.7608967372294, 28748.712512874812, 28914.71085289141, 28949.276268093254, 29214.0, 29436.55845162324, 2646.2325925481587, 2732.822366546188, 2790.776737860966, 2823.997480894294, 2855.486012517746, 494033.0, 496090.0, 496366.0, 497272.0, 497874.0, 59743.70128149331, 60319.39680603138, 60385.39614603798, 60411.39588604146, 60564.0, 64034.67982660057, 64169.0, 64200.357996419436, 64208.35791642117, 64217.0, 16314.75527867083, 16867.662646746998, 16876.746848797276, 16884.409045683344, 16886.57783555421, 13104.80342794859, 13131.474741010252, 13145.934270328587, 13152.802707959388, 13178.209307441584, 15544.222788860512, 15839.128847913475, 15849.920750396173, 15856.920715396349, 15913.443029493912, 6151.569390142737, 6186.041163619646, 6214.40963108504, 6240.282367527701, 6251.28110267316, 965.2760429677742, 997.4813097189566, 998.4458625462793, 1048.3814549415881, 1131.530414877833, 29126.563101553493, 29268.707312927025, 29368.0, 30085.946991855617, 30138.397232055675, 1082.8862969388258, 1095.8465814785936, 1116.4194618798178, 1175.2889501851428, 1260.3131293445094, 14643.974921755374, 15136.924315378352, 15175.116727821678, 15190.772138417933, 15680.843191568167, 639664.0, 643117.0, 643777.0, 644973.0, 645450.0, 60370.698146508985, 60629.69685151546, 60997.39002610006, 61131.38868611346, 61198.38801612016, 1340.0686522866708, 1377.4627895120711, 1382.7925811128412, 1493.4175671488129, 1506.4576752369358, 29851.701482984892, 29942.401151977276, 30604.693953060185, 30784.0, 31440.0, 59167.0, 59850.401495984486, 60305.69847150736, 60430.0, 60654.0, 523288.0, 586481.0, 588128.0, 588634.0, 589558.0, 23303.06787728472, 26225.606615901157, 26658.600120998588, 26738.19785406441, 27547.586786198623, 44672.776636116614, 44874.551254487036, 45480.772596136805, 46276.07447850957, 46570.76714616405, 32108.839455802572, 37513.0, 37869.0, 38025.61974380331, 38966.41550376803, 37257.813710931274, 37470.0, 37952.62047379491, 37975.0, 38235.80882095683, 32441.351172975938, 33082.669173308925, 36855.81572092122, 37293.81353093217, 37925.620743792206, 30102.0, 32467.67532324741, 32514.51228231531, 34133.0, 34314.82842585771, 24067.398315041562, 24582.385440364134, 24751.25746227616, 25022.499550009263, 25098.62352064684], "value": 20227.49234001284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.22549014715645, 393.7263601796749, 394.64679112194585, 398.6053806731335, 420.8421841809321, 19405.708914366278, 20102.698459523082, 20467.385978420654, 20469.795302046994, 20637.793622063775, 19575.314863979762, 20021.099050542725, 20164.59670806583, 20788.584228315423, 20836.583268334623, 5624.746886390112, 5739.167820666001, 5939.227900372967, 5940.138679891413, 5949.375315591866, 5797.072468405056, 5809.593328467009, 5890.410958904118, 5894.616849904743, 5911.0, 5639.605227634067, 5820.563457740686, 5827.650340979533, 5831.358550559435, 5831.5626328025355, 5484.61607687462, 5592.189132575794, 5644.266245388094, 5666.121751128561, 5701.71491425427, 5082.1106306396605, 5105.540501354859, 5154.871128221825, 5161.096808058574, 5161.948380516222, 13683.384247708877, 23311.76688233113, 23790.167344143047, 24559.508809823703, 25085.749142508525, 2094.5077906692068, 2183.585118827418, 2218.0905828610353, 2501.58723810571, 2977.6277965254344, 9930.255230857714, 9979.30144889858, 10026.89973100267, 10251.84622230667, 10360.740981475448, 1751.1419404491708, 1769.5222289981762, 1800.9279628814832, 1846.4645252876649, 1866.150901339887, 56887.0, 81493.59253203755, 81583.0, 95381.9999999993, 121367.3931630345, 11172.832407513893, 11639.41802909868, 11653.825192622118, 11664.941675291739, 11787.174897757075, 14925.402983880524, 14992.925035374754, 15010.474633387998, 15023.173725445205, 15035.248237588077, 8619.569021548898, 8659.350548708871, 8710.303175745925, 8711.738647840575, 8838.55807209637, 7139.678714457758, 7326.3040011198855, 8648.654053837901, 8753.0, 8776.551868941553, 4539.7049191802425, 4613.561711637389, 4626.86119416418, 4640.396748422683, 4682.882927926829, 1916.48254971157, 1962.1268535501565, 1968.3683603751056, 1969.5076230942263, 1974.1511150205263, 892.455602082729, 928.6563971330515, 976.5068640336726, 1024.9998759680764, 1040.9167266618651, 19644.3124490645, 19919.900400497903, 19970.70043949342, 20113.497162571053, 20381.898090509454, 795.5028107432854, 839.706102864005, 908.9363744537819, 937.3204426790659, 960.8606752020918, 9658.32391732586, 10376.377417354983, 10608.946955265328, 10615.84076238857, 10906.945465272624, 535281.0, 544094.0, 544305.0, 561160.0, 630776.0, 106807.0, 127453.0, 134490.0, 138087.61912380747, 146168.5383146155, 1001.8146642871092, 1030.1655658916302, 1036.0727149201423, 1037.2946396450336, 1065.7122576904037, 17812.732809008136, 17840.10799460048, 18110.54723631867, 18203.54491137679, 18558.0, 49668.50331496639, 51461.0, 52613.0, 53285.934281313384, 54038.72980635072, 75151.0, 76008.61995690207, 76428.2357176421, 76828.0, 78108.60945695236, 6988.231294557493, 7471.103467583925, 7914.2085791420395, 7940.682372705027, 8312.70905518328, 7659.88510172359, 10733.550970618866, 11612.6516204514, 12397.690057748629, 12897.484100636244, 9491.762705932408, 10743.677689669323, 10780.514876830404, 11105.278156919774, 11168.497417616389, 10663.093637040938, 10830.40432776205, 10911.662748535631, 11094.278871873303, 11111.0, 11040.944795275973, 11147.108231341312, 11355.716107097389, 11583.594574189863, 11835.0, 7952.761417157494, 8002.159773223728, 8101.311388531805, 8350.331973442231, 8356.916430835614, 8184.83630327402, 10132.898671013196, 11038.227324087236, 11370.715732106764, 11445.141614379121], "value": 9336.569571019298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.256821316489, 1606.1005836731429, 1637.9099149546773, 1881.9153138108784, 2001.279539365827, 7693.115291741445, 8397.118302578225, 8646.48121112732, 8649.610767515462, 8779.780505487366, 8412.621432035574, 8667.956660216705, 8673.653053877837, 8674.91325086749, 8692.174243446878, 2008.8895110768901, 2224.7775222477703, 2333.3466629343725, 2379.488409991856, 2412.8431651942656, 2095.31902131807, 2428.514297140579, 2460.6063029915217, 2466.6176742604835, 2469.740677228892, 2368.12379419615, 2377.940551486209, 2407.6870006899167, 2417.371483414316, 2423.0671191591186, 2085.14509051289, 2303.9078436862505, 2358.84667496613, 2398.832081754278, 2406.4344878953434, 2408.590539608271, 2427.611582146848, 2428.490017096412, 2429.5019520998153, 2431.525852458771, 8650.307975361957, 8744.562771861445, 8748.30013598917, 8750.693725719633, 8762.211400973943, 5115.897682046376, 5158.0, 5347.385050719139, 5348.91976620351, 5381.48875856793, 3436.759426840122, 3487.6512348765173, 3563.803990780506, 3579.033660911565, 3584.1577229351083, 1014.9644762433353, 1038.1176000399676, 1041.729150420896, 1046.2310202001522, 1047.0524823827675, 11501.76996460066, 11507.942460287728, 11520.539178432853, 11531.077513798877, 11578.305301681927, 4048.4534587830267, 4720.253506202658, 4785.401824771903, 4849.345338379344, 4853.514648535118, 9790.314677972474, 9799.069088436587, 9802.460864652512, 9806.558704858298, 9851.261155413373, 5315.0, 5324.094903866352, 5343.145096784496, 5347.171188465794, 5356.678599284055, 3757.1546402059316, 4321.65426765858, 4487.842925497592, 4507.459104907432, 4766.880827979328, 3764.7364684471822, 3831.252905683365, 3897.4738410314812, 3918.2359439909515, 3918.9810649230835, 1302.856685764565, 1330.05566048105, 1331.8468376136675, 1338.5248236875964, 1342.3690865293297, 911.9179273865251, 929.7350255177242, 934.1592566690033, 948.9810203795886, 1006.0442579549457, 5753.51095156908, 5757.309122905194, 5809.912851307234, 5862.824115276549, 6041.305249896231, 431.7172252174831, 453.7640426977953, 454.9021960278556, 461.18830857690034, 470.63760904104333, 3398.660133986631, 3923.9019024524614, 4010.7794071326357, 4024.9798751006056, 4124.628783409447, 10399.168066554657, 10580.947095264475, 10751.247412681194, 10762.515686793957, 10765.08496777767, 10573.735656608493, 10617.362958222531, 10721.892781072245, 10730.624428144978, 10759.623413180501, 7110.537815042109, 7638.923610763933, 7946.768250921116, 8022.0774610919325, 8185.508869467851, 5364.946350536445, 5469.453054694499, 5868.149118377726, 5953.1070339449425, 6027.457528822427, 9411.905880941376, 9688.93421723624, 9846.359986600848, 9875.456849873326, 9906.851397228904, 9849.064338887794, 9966.352187107816, 10084.092431681183, 10084.949575252076, 10189.643362482277, 7295.2340004298785, 7381.889271660822, 7510.324070833652, 7541.095068591804, 7644.35023023027, 8082.19178082187, 8145.063317718538, 8161.387895907949, 8165.918340816515, 8174.223448772357, 7119.6084215368655, 7319.011933389137, 7390.29792169743, 7408.814779630552, 7428.182899881117, 6465.256495502889, 7120.6795694194925, 7181.102362204724, 7201.3518783309755, 7376.336129748348, 6920.792376228721, 6957.547759395623, 6987.475939304674, 6992.056072430157, 7014.018037474656, 4745.4542727585385, 4825.276208568743, 4908.7545622719435, 4930.137225985366, 5116.488351164854, 4751.358566593604, 4757.26262429324, 5008.0, 5016.197408414636, 5059.519345662156], "value": 4465.256934770596, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.8684765550813, 314.72933277381446, 316.80199875078074, 320.78026551811996, 324.76292306616153, 4724.763761811912, 5280.762365693543, 5310.867228319289, 5338.519533242007, 5353.384360798509, 4668.766561671911, 5201.505856943584, 5238.188080847465, 5274.973625131878, 5296.576273898088, 3769.5664998525176, 3852.6532612064907, 3913.7260391772443, 3993.960060399402, 4057.736247143941, 3401.982990085046, 3464.549608550885, 3740.831662575191, 3931.3906344516645, 4034.0721634024194, 3363.7140843028374, 3376.6623337666283, 3797.506324177853, 3935.5277366716036, 3973.5231772187376, 3774.188549461865, 3797.607507270402, 4001.4598029265967, 4005.4792876925812, 4019.8995025124314, 3071.784975051747, 3089.601438417745, 3409.0, 3414.590249170091, 3471.7916924984584, 4765.451973023112, 4936.827211047632, 4943.233798761162, 4971.135824065976, 4975.925361119586, 3955.920881582352, 4372.890677733082, 4509.6392288616835, 4524.389207456984, 4524.6606504511965, 4803.838272777264, 5243.868903277448, 5245.659032162936, 5466.753996070147, 5476.945230547683, 3534.240138370237, 3644.1982763791925, 3836.5396152461603, 3842.51968503937, 3845.6731177849915, 4988.85479243925, 5675.233843431125, 5718.285214348207, 5725.570582206309, 5796.0150522480535, 3859.575446700917, 3885.057471264368, 3885.42640230707, 3892.3188442022533, 3907.29668659644, 3422.2299982504237, 3697.280764444546, 3830.0616348994677, 3943.9014024649614, 3950.6839452843706, 4052.4124002019544, 4550.704204226715, 4554.248548989446, 4557.475890272594, 4564.3153526970555, 5122.846314610568, 5543.7782488700805, 5580.358258800209, 5603.8599035023635, 5639.520640745583, 1190.863050749175, 1197.5988044005355, 1259.2696236182992, 1269.4224128021729, 1281.4553814628746, 730.3499885102157, 811.5617566514135, 855.9101294364127, 860.6858496648804, 861.190480947907, 4621.630269578425, 4772.475027746945, 4933.753312334369, 5190.97404512975, 5205.0, 4713.858584242478, 4823.517648235219, 5568.665880047129, 5677.877167557909, 5739.053056245756, 4330.545292744279, 4648.233041548175, 4772.785224664898, 4995.675281106716, 5157.974210128926, 2553.4254792671873, 2589.8446093234465, 2773.362126710868, 2837.4183292425005, 2849.772018238536, 4780.709208513682, 5223.634345595772, 5538.086215774432, 5566.471185237396, 5588.636738611977, 4469.508354081048, 5082.695038297714, 5267.736613169326, 5392.757325920343, 5500.257465242221, 5087.100352798943, 5262.394824595221, 5544.944550554443, 5558.722063896789, 5652.123920792284, 4804.183288840835, 5268.525832675077, 5411.269478620493, 5415.810446634349, 5439.776050388631, 4058.7158898877974, 5401.459854014724, 5629.240052592848, 5660.915086273627, 5679.74441150141, 4856.878578035577, 5629.437056294338, 5857.326407463196, 5902.520941204619, 5912.674802885883, 3506.3162683276514, 3731.8431286301334, 3794.0704527390967, 3845.5769865314232, 3862.0, 4728.905421891612, 5186.455422180617, 5658.481975520854, 5716.085426331767, 5728.255326807488, 5396.379416367168, 5457.890842183055, 5506.724663766876, 5512.4211957745465, 5554.50009499147, 5109.594861413141, 5144.331236939174, 5156.458571849902, 5524.640898341595, 5532.2254884315425, 4860.805567777249, 5252.737363131751, 5387.461253874581, 5422.728863556727, 5430.565554755531, 4161.877442504284, 4855.980244148768, 5404.432894375141, 5412.134058550613, 5434.375046869661, 5019.372578427697, 5293.200311893975, 5339.546138578265, 5350.598705097055, 5353.411124776349], "value": 3961.755454094743, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.3518393646511, 675.6351570152117, 702.1959855964917, 721.1850608812042, 750.969961201552, 14527.709445811091, 15229.847701522982, 15241.923790381046, 15243.390264389438, 15244.314005869735, 14008.929955350235, 14042.508512202076, 14049.578512644637, 14202.71594568108, 15563.533094007198, 916.4043371808328, 926.3237836379448, 926.6710317837138, 928.6517555916531, 931.4737173496986, 895.3508706187994, 919.1957037592107, 919.6229545886188, 923.1414784250644, 925.8842644669417, 973.177664873181, 978.9363691360039, 981.151304121933, 984.2322988069282, 984.3601658921687, 968.5931908598405, 969.9563519641633, 970.4613939263705, 974.3131092579749, 975.4927437732375, 727.2073439950426, 727.4035291061332, 728.8870225115115, 733.3216774483582, 733.3253406865697, 14640.267986600735, 14675.63310917225, 14691.706165876623, 14695.70608587833, 14745.41018359275, 12389.318587477685, 12439.0, 12464.376781160905, 12525.0, 12977.221366718028, 9874.60501579936, 9879.456629885353, 9912.0, 9918.603255869757, 10318.793624127551, 3430.9656903431146, 3522.6653467920764, 3591.191981804099, 3605.387084195693, 3636.3272794533095, 76730.0, 77220.61389693072, 77667.61166194189, 79617.0, 79973.0, 9233.307501937246, 9345.065493450737, 9381.155695987258, 9529.952350238344, 9645.42127472354, 21943.67084493734, 21948.0, 21961.560768784533, 21963.450913726963, 22026.779732202795, 7092.787216383516, 7209.0988626421695, 7260.455465840083, 7262.346388825031, 7428.517146385576, 3988.2821092203103, 4081.5306239782803, 4081.551029386765, 4086.162336720965, 4097.8975525612095, 3486.372452958492, 3598.3702852000797, 3604.8377822997504, 3605.6214097519905, 3609.151849315409, 542.8507160530806, 572.3875453264967, 614.9699253749951, 621.9458018658331, 647.058529839083, 473.8436316015707, 534.6337758635348, 563.2986931270594, 569.2429069337771, 620.7051650466083, 7923.0, 8142.877856832152, 8199.385046121564, 8304.8339033219, 8321.791955201168, 532.9413764485903, 560.8597850537366, 564.2833601326292, 588.682111659699, 589.4105894105894, 3849.172427927953, 3868.613138686109, 3890.4747859039235, 3898.122922342507, 3971.841126354971, 83140.0, 85994.0, 86152.0, 90540.5472972631, 92974.53512732394, 45815.77092114518, 51427.74286128546, 68894.50921117597, 71203.0, 74582.25417745754, 57078.7146064267, 58587.0, 62855.71442855678, 63634.36365636377, 66291.0, 7962.124166341813, 7980.441369104107, 8109.797255068434, 8280.089190189196, 8342.16578342161, 40017.79946601607, 40064.99837504086, 40757.38863916984, 41182.0, 41898.79050604728, 52050.47949520608, 57840.421595783504, 57890.0, 58010.839783204945, 58615.120773187584, 10684.482416053981, 11035.779284414428, 11131.35134325814, 11301.208915375844, 11375.715607109889, 11848.466818993338, 11856.110791690486, 11867.703307417383, 12317.630471085882, 12429.0, 11306.208565400342, 11445.656630301104, 11676.591319303783, 11864.11019173548, 11882.249586801361, 11092.889071109508, 11315.151363647594, 11544.711382215512, 11645.359505227294, 12375.69060773488, 9540.570674319533, 10907.29102608328, 11124.555017799197, 11365.715857103638, 12013.918747312784, 9091.545422728705, 9203.76990575241, 9273.536323183678, 9305.069493050642, 9365.391249568756, 9304.674336398193, 9508.762280942756, 9552.331336806357, 9555.61777528891, 9585.281103917094], "value": 6738.24235364708, "unit": "score"}], "name": "dromaeo_css", "value": 7081.85667267977, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 113.0, 114.0, 113.0, 114.0, 111.0, 113.0, 112.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 139.0, 195.0, 116.0, 141.0, 139.0, 119.0, 183.0, 145.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 189.0, 184.0, 185.0, 230.0, 186.0, 185.0, 186.0, 186.0], "value": 190.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 125.0, 56.0, 63.0, 58.0, 59.0, 109.0, 56.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 136.0, 80.0, 81.0, 77.0, 101.0, 81.0, 80.0, 101.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 88.0, 86.0, 86.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 93.0, 92.0, 93.0, 92.0, 92.0, 93.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 86.0, 79.0, 82.0, 83.0, 86.0, 85.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 61.0, 58.0, 58.0, 121.0, 60.0, 58.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 75.0, 76.0, 71.0, 69.0, 67.0, 66.0, 67.0, 64.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 85.0, 84.0, 84.0, 80.0, 84.0, 86.0, 90.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 141.0, 139.0, 140.0, 135.0, 135.0, 140.0, 216.0, 140.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 168.0, 169.0, 166.0, 169.0, 170.0, 170.0, 170.0, 165.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1475.8, "alertThreshold": 2.0}]} 04:49:57 INFO - Return code: 0 04:49:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:49:57 ERROR - # TBPL SUCCESS # 04:49:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:57 INFO - 2016-05-09 04:49:57.744 screenresolution[1680:10058] starting screenresolution argv=screenresolution get 04:49:57 INFO - 2016-05-09 04:49:57.747 screenresolution[1680:10058] Display 0: 1600x1200x32@60 04:49:57 INFO - 2016-05-09 04:49:57.753 screenresolution[1681:10061] starting screenresolution argv=screenresolution list 04:49:57 INFO - Available Modes on Display 0 04:49:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:57 INFO - Intel Iris: 04:49:57 INFO - Chipset Model: Intel Iris 04:49:57 INFO - Type: GPU 04:49:57 INFO - Bus: Built-In 04:49:57 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:57 INFO - Vendor: Intel (0x8086) 04:49:57 INFO - Device ID: 0x0a2e 04:49:57 INFO - Revision ID: 0x0009 04:49:57 INFO - Displays: 04:49:57 INFO - Display: 04:49:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:57 INFO - Display Serial Number: 1600x1200 60 04:49:57 INFO - Main Display: Yes 04:49:57 INFO - Mirror: Off 04:49:57 INFO - Online: Yes 04:49:57 INFO - Rotation: Supported 04:49:57 INFO - Return code: 0 04:49:57 INFO - Running post-action listener: _resource_record_post_action 04:49:57 INFO - ##### 04:49:57 INFO - ##### Finished run-tests step (success) 04:49:57 INFO - ##### 04:49:57 INFO - Running post-run listener: _resource_record_post_run 04:49:59 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32566784; Write bytes: 696474112; Read time: 326; Write time: 2171 04:49:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376050176; Write bytes: 370771968; Read time: 28155; Write time: 765 04:49:59 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31616512; Write bytes: 296694272; Read time: 311; Write time: 1352 04:49:59 INFO - Running post-run listener: _upload_blobber_files 04:49:59 INFO - Blob upload gear active. 04:49:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:59 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 04:49:59 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'] 04:49:59 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 04:49:59 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:49:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:00 INFO - (blobuploader) - INFO - Done attempting. 04:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:02 INFO - (blobuploader) - INFO - Done attempting. 04:50:02 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:02 INFO - Return code: 0 04:50:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7040dd12b8217b672a1e537da61d4f09b6b72f72fe41e8f400538891b157bd6f6105ebaa75ac5dce120b17b095f68f9146f1d255365dcd3388c7a985369daa24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49784613a1dafdaef85aec4c3c753d1b838150628c767c8f11cd0e6b827389b30a487b268598c2a281439f2615c3b357daeb669cbe69da799f53712603e0df23"} 04:50:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:02 INFO - Contents: 04:50:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7040dd12b8217b672a1e537da61d4f09b6b72f72fe41e8f400538891b157bd6f6105ebaa75ac5dce120b17b095f68f9146f1d255365dcd3388c7a985369daa24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49784613a1dafdaef85aec4c3c753d1b838150628c767c8f11cd0e6b827389b30a487b268598c2a281439f2615c3b357daeb669cbe69da799f53712603e0df23"} 04:50:02 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:02 INFO - Copying logs to upload dir... 04:50:02 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.802186 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-09 04:50:02.126677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:50:02.134692) ========= 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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7040dd12b8217b672a1e537da61d4f09b6b72f72fe41e8f400538891b157bd6f6105ebaa75ac5dce120b17b095f68f9146f1d255365dcd3388c7a985369daa24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49784613a1dafdaef85aec4c3c753d1b838150628c767c8f11cd0e6b827389b30a487b268598c2a281439f2615c3b357daeb669cbe69da799f53712603e0df23"} build_url:https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/WaBLD2dJTA2bqZaegnxhcQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7040dd12b8217b672a1e537da61d4f09b6b72f72fe41e8f400538891b157bd6f6105ebaa75ac5dce120b17b095f68f9146f1d255365dcd3388c7a985369daa24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49784613a1dafdaef85aec4c3c753d1b838150628c767c8f11cd0e6b827389b30a487b268598c2a281439f2615c3b357daeb669cbe69da799f53712603e0df23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 04:50:02.162141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:50:02.165512) ========= 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.P7AlMQMUbA/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.9j8FFqMQsd/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:50:02.213990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:50:02.214331) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 04:50:02.214644) ========= ========= Total master_lag: 3.21 =========