builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1462968236.56 results: success (0) buildid: 20160511030221 builduid: b506c7b4dc59436f95acca86e65699f1 revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.560007) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.560433) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.560729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.579063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.579458) ========= 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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-11 05:03:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.41M=0.001s 2016-05-11 05:03:56 (9.41 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.703288) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.703629) ========= 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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.020127 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:56.752483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 05:03:56.752872) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 674a552743785c28c75866969aad513bd8eaf6ae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 674a552743785c28c75866969aad513bd8eaf6ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-11 05:03:56,830 truncating revision to first 12 chars 2016-05-11 05:03:56,830 Setting DEBUG logging. 2016-05-11 05:03:56,830 attempt 1/10 2016-05-11 05:03:56,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/674a55274378?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 05:03:57,945 unpacking tar archive at: mozilla-central-674a55274378/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367868 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 05:03:58.137598) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.137903) ========= script_repo_revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.138294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.138604) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 05:03:58.151702) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-11 05:03:58.152095) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:03:58 INFO - MultiFileLogger online at 20160511 05:03:58 in /builds/slave/test 05:03:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:03:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:58 INFO - {'append_to_log': False, 05:03:58 INFO - 'base_work_dir': '/builds/slave/test', 05:03:58 INFO - 'blob_upload_branch': 'Firefox', 05:03:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:58 INFO - 'branch': 'Firefox', 05:03:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:58 INFO - 'config_files': ('talos/mac_config.py',), 05:03:58 INFO - 'default_actions': ('clobber', 05:03:58 INFO - 'read-buildbot-config', 05:03:58 INFO - 'download-and-extract', 05:03:58 INFO - 'populate-webroot', 05:03:58 INFO - 'create-virtualenv', 05:03:58 INFO - 'install', 05:03:58 INFO - 'run-tests'), 05:03:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:58 INFO - 'download_minidump_stackwalk': True, 05:03:58 INFO - 'download_symbols': 'ondemand', 05:03:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:58 INFO - 'installer_path': 'installer.exe', 05:03:58 INFO - 'log_level': 'info', 05:03:58 INFO - 'log_name': 'talos', 05:03:58 INFO - 'log_to_console': True, 05:03:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:03:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:03:58 INFO - 'opt_config_files': (), 05:03:58 INFO - 'pip_index': False, 05:03:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:58 INFO - 'cmd': ('bash', 05:03:58 INFO - '-c', 05:03:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:58 INFO - 'enabled': True, 05:03:58 INFO - 'halt_on_failure': False, 05:03:58 INFO - 'name': 'check_screen_resolution'},), 05:03:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:03:58 INFO - 'cmd': ('bash', 05:03:58 INFO - '-c', 05:03:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:03:58 INFO - 'enabled': True, 05:03:58 INFO - 'halt_on_failure': False, 05:03:58 INFO - 'name': 'check_screen_resolution'},), 05:03:58 INFO - 'run_cmd_checks_enabled': True, 05:03:58 INFO - 'sps_profile': False, 05:03:58 INFO - 'sps_profile_interval': 0, 05:03:58 INFO - 'suite': 'dromaeojs-e10s', 05:03:58 INFO - 'system_bits': '32', 05:03:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:58 INFO - 'title': 't-yosemite-r7-0195', 05:03:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:58 INFO - 'use_talos_json': True, 05:03:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:58 INFO - 'work_dir': 'build'} 05:03:58 INFO - ##### 05:03:58 INFO - ##### Running clobber step. 05:03:58 INFO - ##### 05:03:58 INFO - Running pre-action listener: _resource_record_pre_action 05:03:58 INFO - Running main action method: clobber 05:03:58 INFO - rmtree: /builds/slave/test/build 05:03:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:03:59 INFO - Running post-action listener: _resource_record_post_action 05:03:59 INFO - ##### 05:03:59 INFO - ##### Finished clobber step (success) 05:03:59 INFO - ##### 05:03:59 INFO - ##### 05:03:59 INFO - ##### Running read-buildbot-config step. 05:03:59 INFO - ##### 05:03:59 INFO - Running pre-action listener: _resource_record_pre_action 05:03:59 INFO - Running main action method: read_buildbot_config 05:03:59 INFO - Using buildbot properties: 05:03:59 INFO - { 05:03:59 INFO - "project": "", 05:03:59 INFO - "product": "firefox", 05:03:59 INFO - "script_repo_revision": "production", 05:03:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:03:59 INFO - "repository": "", 05:03:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:03:59 INFO - "buildid": "20160511030221", 05:03:59 INFO - "stage_platform": "macosx64", 05:03:59 INFO - "basedir": "/builds/slave/test", 05:03:59 INFO - "nightly_build": "True", 05:03:59 INFO - "buildnumber": 271, 05:03:59 INFO - "slavename": "t-yosemite-r7-0195", 05:03:59 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:03:59 INFO - "platform": "yosemite_r7", 05:03:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:03:59 INFO - "branch": "mozilla-central", 05:03:59 INFO - "repo_path": "mozilla-central", 05:03:59 INFO - "revision": "674a552743785c28c75866969aad513bd8eaf6ae", 05:03:59 INFO - "pgo_build": "False", 05:03:59 INFO - "builduid": "b506c7b4dc59436f95acca86e65699f1", 05:03:59 INFO - "slavebuilddir": "test" 05:03:59 INFO - } 05:03:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:03:59 INFO - Running post-action listener: _resource_record_post_action 05:03:59 INFO - ##### 05:03:59 INFO - ##### Finished read-buildbot-config step (success) 05:03:59 INFO - ##### 05:03:59 INFO - ##### 05:03:59 INFO - ##### Running download-and-extract step. 05:03:59 INFO - ##### 05:03:59 INFO - Running pre-action listener: _resource_record_pre_action 05:03:59 INFO - Running main action method: download_and_extract 05:03:59 INFO - mkdir: /builds/slave/test/build/tests 05:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:03:59 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:03:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:03:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:03:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:03:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:04:01 INFO - Downloaded 1199 bytes. 05:04:01 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:04:01 INFO - Using the following test package requirements: 05:04:01 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:01 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:04:01 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:04:01 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'jsshell-mac.zip'], 05:04:01 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:04:01 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:04:01 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:04:01 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:04:01 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:04:01 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:04:01 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:04:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:01 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:02 INFO - Downloaded 18150554 bytes. 05:04:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:04:05 INFO - Return code: 0 05:04:05 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:05 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:04:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:04:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:06 INFO - Downloaded 18150554 bytes. 05:04:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:04:10 INFO - Return code: 0 05:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:10 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:04:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:04:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:04:11 INFO - Downloaded 10840152 bytes. 05:04:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:04:11 INFO - Return code: 0 05:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:11 INFO - https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:04:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:04:17 INFO - Downloaded 109983254 bytes. 05:04:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:17 INFO - mkdir: /builds/slave/test/properties 05:04:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:17 INFO - Contents: 05:04:17 INFO - build_url:https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:04:19 INFO - Running post-action listener: _resource_record_post_action 05:04:19 INFO - Running post-action listener: set_extra_try_arguments 05:04:19 INFO - ##### 05:04:19 INFO - ##### Finished download-and-extract step (success) 05:04:19 INFO - ##### 05:04:19 INFO - ##### 05:04:19 INFO - ##### Running populate-webroot step. 05:04:19 INFO - ##### 05:04:19 INFO - Running pre-action listener: _resource_record_pre_action 05:04:19 INFO - Running main action method: populate_webroot 05:04:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:19 INFO - '--tppagecycles', 05:04:19 INFO - '7'], 05:04:19 INFO - 'tests': ['tsvgm']}}, 05:04:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['damp', 'tps']}, 05:04:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'tests': ['damp', 'tps']}, 05:04:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['dromaeo_dom']}, 05:04:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['a11yr', 05:04:19 INFO - 'ts_paint', 05:04:19 INFO - 'tpaint', 05:04:19 INFO - 'sessionrestore', 05:04:19 INFO - 'sessionrestore_no_auto_restore', 05:04:19 INFO - 'tabpaint']}, 05:04:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['tsvgx', 05:04:19 INFO - 'tsvgr_opacity', 05:04:19 INFO - 'tart', 05:04:19 INFO - 'tscrollx', 05:04:19 INFO - 'cart']}, 05:04:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:19 INFO - 'tsvgr_opacity', 05:04:19 INFO - 'tart', 05:04:19 INFO - 'tscrollx', 05:04:19 INFO - 'cart']}, 05:04:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s'], 05:04:19 INFO - 'tests': ['tp5o']}, 05:04:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'tests': ['tp5o']}, 05:04:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'talos_options': ['--disable-e10s', 05:04:19 INFO - '--xperf_path', 05:04:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:19 INFO - 'tests': ['tp5n']}, 05:04:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:19 INFO - 'talos_options': ['--xperf_path', 05:04:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:19 INFO - 'tests': ['tp5n']}}, 05:04:19 INFO - 'talos.zip': {'path': '', 05:04:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:19 INFO - Running post-action listener: _resource_record_post_action 05:04:19 INFO - ##### 05:04:19 INFO - ##### Finished populate-webroot step (success) 05:04:19 INFO - ##### 05:04:19 INFO - ##### 05:04:19 INFO - ##### Running create-virtualenv step. 05:04:19 INFO - ##### 05:04:19 INFO - Running pre-action listener: _resource_record_pre_action 05:04:19 INFO - Running main action method: create_virtualenv 05:04:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:04:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:19 INFO - Using real prefix '/tools/python27' 05:04:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:22 INFO - Installing pip.................done. 05:04:22 INFO - Return code: 0 05:04:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:22 INFO - 'HOME': '/Users/cltbld', 05:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:22 INFO - 'LOGNAME': 'cltbld', 05:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:22 INFO - 'MOZ_NO_REMOTE': '1', 05:04:22 INFO - 'NO_EM_RESTART': '1', 05:04:22 INFO - 'PAGER': '/bin/cat', 05:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:22 INFO - 'PWD': '/builds/slave/test', 05:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:22 INFO - 'SHELL': '/bin/bash', 05:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:22 INFO - 'USER': 'cltbld', 05:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:22 INFO - 'XPC_FLAGS': '0x0', 05:04:22 INFO - 'XPC_SERVICE_NAME': '0', 05:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:04:23 INFO - Cleaning up... 05:04:23 INFO - Return code: 0 05:04:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:23 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 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:04:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:23 INFO - 'HOME': '/Users/cltbld', 05:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:23 INFO - 'LOGNAME': 'cltbld', 05:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:23 INFO - 'MOZ_NO_REMOTE': '1', 05:04:23 INFO - 'NO_EM_RESTART': '1', 05:04:23 INFO - 'PAGER': '/bin/cat', 05:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:23 INFO - 'PWD': '/builds/slave/test', 05:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:23 INFO - 'SHELL': '/bin/bash', 05:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:23 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:23 INFO - 'USER': 'cltbld', 05:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:23 INFO - 'XPC_FLAGS': '0x0', 05:04:23 INFO - 'XPC_SERVICE_NAME': '0', 05:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:23 INFO - Downloading/unpacking psutil>=0.7.1 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:25 INFO - Installing collected packages: psutil 05:04:25 INFO - Running setup.py install for psutil 05:04:26 INFO - building 'psutil._psutil_osx' extension 05:04:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:04:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:04:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:04:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:04:26 INFO - building 'psutil._psutil_posix' extension 05:04:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:04:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:26 INFO - ^ 05:04:26 INFO - 1 warning generated. 05:04:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:04:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:26 INFO - Successfully installed psutil 05:04:26 INFO - Cleaning up... 05:04:26 INFO - Return code: 0 05:04:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:04:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:26 INFO - 'HOME': '/Users/cltbld', 05:04:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:26 INFO - 'LOGNAME': 'cltbld', 05:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:26 INFO - 'MOZ_NO_REMOTE': '1', 05:04:26 INFO - 'NO_EM_RESTART': '1', 05:04:26 INFO - 'PAGER': '/bin/cat', 05:04:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:26 INFO - 'PWD': '/builds/slave/test', 05:04:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:26 INFO - 'SHELL': '/bin/bash', 05:04:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:26 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:26 INFO - 'USER': 'cltbld', 05:04:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:26 INFO - 'XPC_FLAGS': '0x0', 05:04:26 INFO - 'XPC_SERVICE_NAME': '0', 05:04:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:04:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:04:29 INFO - Installing collected packages: mozsystemmonitor 05:04:29 INFO - Running setup.py install for mozsystemmonitor 05:04:29 INFO - Successfully installed mozsystemmonitor 05:04:29 INFO - Cleaning up... 05:04:29 INFO - Return code: 0 05:04:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:29 INFO - 'HOME': '/Users/cltbld', 05:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:29 INFO - 'LOGNAME': 'cltbld', 05:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:29 INFO - 'MOZ_NO_REMOTE': '1', 05:04:29 INFO - 'NO_EM_RESTART': '1', 05:04:29 INFO - 'PAGER': '/bin/cat', 05:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:29 INFO - 'PWD': '/builds/slave/test', 05:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:29 INFO - 'SHELL': '/bin/bash', 05:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:29 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:29 INFO - 'USER': 'cltbld', 05:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:29 INFO - 'XPC_FLAGS': '0x0', 05:04:29 INFO - 'XPC_SERVICE_NAME': '0', 05:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:33 INFO - Downloading docopt-0.6.1.tar.gz 05:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:33 INFO - Running setup.py install for blobuploader 05:04:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:33 INFO - Running setup.py install for requests 05:04:34 INFO - Running setup.py install for docopt 05:04:34 INFO - Successfully installed blobuploader requests docopt 05:04:34 INFO - Cleaning up... 05:04:34 INFO - Return code: 0 05:04:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:35 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 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:35 INFO - 'HOME': '/Users/cltbld', 05:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:35 INFO - 'LOGNAME': 'cltbld', 05:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:35 INFO - 'MOZ_NO_REMOTE': '1', 05:04:35 INFO - 'NO_EM_RESTART': '1', 05:04:35 INFO - 'PAGER': '/bin/cat', 05:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:35 INFO - 'PWD': '/builds/slave/test', 05:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:35 INFO - 'SHELL': '/bin/bash', 05:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:35 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:35 INFO - 'USER': 'cltbld', 05:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:35 INFO - 'XPC_FLAGS': '0x0', 05:04:35 INFO - 'XPC_SERVICE_NAME': '0', 05:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ZpqimH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hlW9uO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-lHkGiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-winEyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-L3bKK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-v5xpmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-fQjWGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Qgqn1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-BKAirS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XbkrEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-fjeCcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-EYmf6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6qMuD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QYxEZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-gnQNWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:36 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Hn5yFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:37 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6fAj81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:37 INFO - Running setup.py install for manifestparser 05:04:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozcrash 05:04:37 INFO - Running setup.py install for mozdebug 05:04:37 INFO - Running setup.py install for mozdevice 05:04:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozfile 05:04:37 INFO - Running setup.py install for mozhttpd 05:04:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozinfo 05:04:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozInstall 05:04:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozleak 05:04:38 INFO - Running setup.py install for mozlog 05:04:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for moznetwork 05:04:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozprocess 05:04:38 INFO - Running setup.py install for mozprofile 05:04:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Running setup.py install for mozrunner 05:04:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Running setup.py install for mozscreenshot 05:04:39 INFO - Running setup.py install for moztest 05:04:39 INFO - Running setup.py install for mozversion 05:04:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:39 INFO - Cleaning up... 05:04:39 INFO - Return code: 0 05:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:39 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 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:04:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:39 INFO - 'HOME': '/Users/cltbld', 05:04:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:39 INFO - 'LOGNAME': 'cltbld', 05:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:39 INFO - 'MOZ_NO_REMOTE': '1', 05:04:39 INFO - 'NO_EM_RESTART': '1', 05:04:39 INFO - 'PAGER': '/bin/cat', 05:04:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:39 INFO - 'PWD': '/builds/slave/test', 05:04:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:39 INFO - 'SHELL': '/bin/bash', 05:04:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:39 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:39 INFO - 'USER': 'cltbld', 05:04:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:39 INFO - 'XPC_FLAGS': '0x0', 05:04:39 INFO - 'XPC_SERVICE_NAME': '0', 05:04:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Ec6Ni3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-oEKOf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ZvgZ_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-m8oeDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-SEZaWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GhEjWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GPDCrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Don5Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QSDm_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-t_vqAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-opHTPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-lmyx6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-549NDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-z0SJ84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-8QqHZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ETymDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-KqGHLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:04:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - Downloading blessings-1.6.tar.gz 05:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:44 INFO - Installing collected packages: blessings 05:04:44 INFO - Running setup.py install for blessings 05:04:44 INFO - Successfully installed blessings 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:45 INFO - Reading from file tmpfile_stdout 05:04:45 INFO - Current package versions: 05:04:45 INFO - blessings == 1.6 05:04:45 INFO - blobuploader == 1.2.4 05:04:45 INFO - docopt == 0.6.1 05:04:45 INFO - manifestparser == 1.1 05:04:45 INFO - mozInstall == 1.12 05:04:45 INFO - mozcrash == 0.17 05:04:45 INFO - mozdebug == 0.1 05:04:45 INFO - mozdevice == 0.48 05:04:45 INFO - mozfile == 1.2 05:04:45 INFO - mozhttpd == 0.7 05:04:45 INFO - mozinfo == 0.9 05:04:45 INFO - mozleak == 0.1 05:04:45 INFO - mozlog == 3.1 05:04:45 INFO - moznetwork == 0.27 05:04:45 INFO - mozprocess == 0.22 05:04:45 INFO - mozprofile == 0.28 05:04:45 INFO - mozrunner == 6.11 05:04:45 INFO - mozscreenshot == 0.1 05:04:45 INFO - mozsystemmonitor == 0.0 05:04:45 INFO - moztest == 0.7 05:04:45 INFO - mozversion == 1.4 05:04:45 INFO - psutil == 3.1.1 05:04:45 INFO - requests == 1.2.3 05:04:45 INFO - wsgiref == 0.1.2 05:04:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:45 INFO - 'HOME': '/Users/cltbld', 05:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:45 INFO - 'LOGNAME': 'cltbld', 05:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:45 INFO - 'MOZ_NO_REMOTE': '1', 05:04:45 INFO - 'NO_EM_RESTART': '1', 05:04:45 INFO - 'PAGER': '/bin/cat', 05:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:45 INFO - 'PWD': '/builds/slave/test', 05:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:45 INFO - 'SHELL': '/bin/bash', 05:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:45 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:45 INFO - 'USER': 'cltbld', 05:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:45 INFO - 'XPC_FLAGS': '0x0', 05:04:45 INFO - 'XPC_SERVICE_NAME': '0', 05:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:04:45 INFO - Cleaning up... 05:04:45 INFO - Return code: 0 05:04:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:45 INFO - 'HOME': '/Users/cltbld', 05:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:45 INFO - 'LOGNAME': 'cltbld', 05:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:45 INFO - 'MOZ_NO_REMOTE': '1', 05:04:45 INFO - 'NO_EM_RESTART': '1', 05:04:45 INFO - 'PAGER': '/bin/cat', 05:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:45 INFO - 'PWD': '/builds/slave/test', 05:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:45 INFO - 'SHELL': '/bin/bash', 05:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:45 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:45 INFO - 'USER': 'cltbld', 05:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:45 INFO - 'XPC_FLAGS': '0x0', 05:04:45 INFO - 'XPC_SERVICE_NAME': '0', 05:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Downloading/unpacking jsonschema 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:48 INFO - Downloading/unpacking functools32 (from jsonschema) 05:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:48 INFO - warning: no files found matching '*.txt' 05:04:48 INFO - no previously-included directories found matching 'build' 05:04:48 INFO - no previously-included directories found matching 'dist' 05:04:48 INFO - no previously-included directories found matching '.git*' 05:04:48 INFO - Installing collected packages: jsonschema, functools32 05:04:48 INFO - Running setup.py install for functools32 05:04:48 INFO - warning: no files found matching '*.txt' 05:04:48 INFO - no previously-included directories found matching 'build' 05:04:48 INFO - no previously-included directories found matching 'dist' 05:04:48 INFO - no previously-included directories found matching '.git*' 05:04:48 INFO - Successfully installed jsonschema functools32 05:04:48 INFO - Cleaning up... 05:04:48 INFO - Return code: 0 05:04:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc52bd9d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, '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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:04:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:04:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:04:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:48 INFO - 'HOME': '/Users/cltbld', 05:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:48 INFO - 'LOGNAME': 'cltbld', 05:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:48 INFO - 'MOZ_NO_REMOTE': '1', 05:04:48 INFO - 'NO_EM_RESTART': '1', 05:04:48 INFO - 'PAGER': '/bin/cat', 05:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:48 INFO - 'PWD': '/builds/slave/test', 05:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:48 INFO - 'SHELL': '/bin/bash', 05:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:04:48 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:48 INFO - 'USER': 'cltbld', 05:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:48 INFO - 'XPC_FLAGS': '0x0', 05:04:48 INFO - 'XPC_SERVICE_NAME': '0', 05:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:49 INFO - Downloading/unpacking flake8 05:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:04:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:04:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:04:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:04:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:04:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:04:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:04:52 INFO - no previously-included directories found matching 'docs/_build' 05:04:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:04:53 INFO - Running setup.py install for pep8 05:04:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:04:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:04:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:04:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:04:53 INFO - no previously-included directories found matching 'docs/_build' 05:04:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:04:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:04:53 INFO - Cleaning up... 05:04:53 INFO - Return code: 0 05:04:53 INFO - Running post-action listener: _resource_record_post_action 05:04:53 INFO - Running post-action listener: _start_resource_monitoring 05:04:53 INFO - Starting resource monitoring. 05:04:53 INFO - ##### 05:04:53 INFO - ##### Finished create-virtualenv step (success) 05:04:53 INFO - ##### 05:04:53 INFO - ##### 05:04:53 INFO - ##### Running install step. 05:04:53 INFO - ##### 05:04:53 INFO - Running pre-action listener: _resource_record_pre_action 05:04:53 INFO - Running main action method: install 05:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:53 INFO - Reading from file tmpfile_stdout 05:04:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:53 INFO - Reading from file tmpfile_stdout 05:04:53 INFO - Output received: 05:04:53 INFO - Usage: mozinstall [options] installer 05:04:53 INFO - Options: 05:04:53 INFO - -h, --help show this help message and exit 05:04:53 INFO - -d DEST, --destination=DEST 05:04:53 INFO - Directory to install application into. [default: 05:04:53 INFO - "/builds/slave/test"] 05:04:53 INFO - --app=APP Application being installed. [default: firefox] 05:04:53 INFO - mkdir: /builds/slave/test/build/application 05:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:05:27 INFO - Reading from file tmpfile_stdout 05:05:27 INFO - Output received: 05:05:27 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:05:27 INFO - Running post-action listener: _resource_record_post_action 05:05:27 INFO - ##### 05:05:27 INFO - ##### Finished install step (success) 05:05:27 INFO - ##### 05:05:27 INFO - ##### 05:05:27 INFO - ##### Running run-tests step. 05:05:27 INFO - ##### 05:05:27 INFO - Running pre-action listener: _resource_record_pre_action 05:05:27 INFO - Running main action method: run_tests 05:05:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:27 INFO - 2016-05-11 05:05:27.381 screenresolution[2172:11099] starting screenresolution argv=screenresolution get 05:05:27 INFO - 2016-05-11 05:05:27.384 screenresolution[2172:11099] Display 0: 1600x1200x32@60 05:05:27 INFO - 2016-05-11 05:05:27.390 screenresolution[2173:11102] starting screenresolution argv=screenresolution list 05:05:27 INFO - Available Modes on Display 0 05:05:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:27 INFO - Intel Iris: 05:05:27 INFO - Chipset Model: Intel Iris 05:05:27 INFO - Type: GPU 05:05:27 INFO - Bus: Built-In 05:05:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:27 INFO - Vendor: Intel (0x8086) 05:05:27 INFO - Device ID: 0x0a2e 05:05:27 INFO - Revision ID: 0x0009 05:05:27 INFO - Displays: 05:05:27 INFO - Display: 05:05:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:27 INFO - Display Serial Number: 1600x1200 60 05:05:27 INFO - Main Display: Yes 05:05:27 INFO - Mirror: Off 05:05:27 INFO - Online: Yes 05:05:27 INFO - Rotation: Supported 05:05:27 INFO - Return code: 0 05:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:27 INFO - Python 2.7.3 05:05:27 INFO - Return code: 0 05:05:27 INFO - grabbing minidump binary from tooltool 05:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e764d30>, '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 0x10e588db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e75bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:05:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:05:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:05:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:27 INFO - Return code: 0 05:05:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:05:28 INFO - Return code: 0 05:05:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnS5C1DyMt/Render', 05:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:28 INFO - 'HOME': '/Users/cltbld', 05:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:28 INFO - 'LOGNAME': 'cltbld', 05:05:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:28 INFO - 'MOZ_NO_REMOTE': '1', 05:05:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:28 INFO - 'NO_EM_RESTART': '1', 05:05:28 INFO - 'PAGER': '/bin/cat', 05:05:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:28 INFO - 'PWD': '/builds/slave/test', 05:05:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:28 INFO - 'SHELL': '/bin/bash', 05:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y4LCXOdZgq/Listeners', 05:05:28 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 05:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:28 INFO - 'USER': 'cltbld', 05:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:28 INFO - 'XPC_FLAGS': '0x0', 05:05:28 INFO - 'XPC_SERVICE_NAME': '0', 05:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:05:29 INFO - mozversion application_buildid: 20160511030221 05:05:29 INFO - mozversion application_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 05:05:29 INFO - mozversion application_display_name: Nightly 05:05:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:29 INFO - mozversion application_name: Firefox 05:05:29 INFO - mozversion application_remotingname: firefox 05:05:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:29 INFO - mozversion application_vendor: Mozilla 05:05:29 INFO - mozversion application_version: 49.0a1 05:05:29 INFO - mozversion platform_buildid: 20160511030221 05:05:29 INFO - mozversion platform_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 05:05:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:29 INFO - mozversion platform_version: 49.0a1 05:05:29 INFO - using testdate: 1462968329 05:05:29 INFO - actual date: 1462968329 05:05:29 INFO - starting webserver on 'localhost:49911' 05:05:29 INFO - SUITE-START | Running 2 tests 05:05:29 INFO - TEST-START | dromaeo_css 05:05:29 INFO - operating with platform_type : mac_ 05:05:29 INFO - Initialising browser for dromaeo_css test... 05:05:29 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpF3akkZ/profile http://localhost:49911/getInfo.html) 05:05:32 INFO - PROCESS | 2191 | __metrics Screen width/height:1600/1200 05:05:32 INFO - PROCESS | 2191 | colorDepth:24 05:05:32 INFO - PROCESS | 2191 | Browser inner width/height: 1024/689 05:05:32 INFO - PROCESS | 2191 | __metrics 05:05:32 ERROR - PROCESS | 2191 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:05:32 INFO - TEST-INFO | 2191: exit 0 05:05:32 INFO - Browser initialized. 05:05:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:32 INFO - TEST-INFO | started process 2195 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpF3akkZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:44 INFO - PROCESS | 2195 | RSS: Main: 249905152 05:05:44 INFO - PROCESS | 2195 | 05:06:34 INFO - PROCESS | 2195 | 1462968394455 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:08:46 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49911/tests/dromaeo/cssquery-ext.html) 05:08:47 INFO - PROCESS | 2195 | RSS: Main: 261410816 05:08:47 INFO - PROCESS | 2195 | 05:11:49 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-ext.html (next: http://localhost:49911/tests/dromaeo/cssquery-jquery.html) 05:11:50 INFO - PROCESS | 2195 | RSS: Main: 273297408 05:11:50 INFO - PROCESS | 2195 | 05:14:52 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49911/tests/dromaeo/cssquery-mootools.html) 05:14:52 INFO - PROCESS | 2195 | RSS: Main: 275140608 05:14:52 INFO - PROCESS | 2195 | 05:17:54 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49911/tests/dromaeo/cssquery-prototype.html) 05:17:55 INFO - PROCESS | 2195 | RSS: Main: 276738048 05:17:55 INFO - PROCESS | 2195 | 05:20:57 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49911/tests/dromaeo/cssquery-yui.html) 05:20:58 INFO - PROCESS | 2195 | RSS: Main: 279769088 05:20:58 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:49911/tests/dromaeo/cssquery-yui.html (next: http://localhost:49911/tests/dromaeo/cssquery-dojo.html) 05:24:01 INFO - PROCESS | 2195 | RSS: Main: 281096192 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | __start_tp_report 05:24:01 INFO - PROCESS | 2195 | _x_x_mozilla_page_load 05:24:01 INFO - PROCESS | 2195 | _x_x_mozilla_page_load_details 05:24:01 INFO - PROCESS | 2195 | |i|pagename|runs| 05:24:01 INFO - PROCESS | 2195 | |0;dojo.html;3203.6796320367953;3468.167639766456;3502.4571191465316;3528.717702583793;3530.346885826122;4522.592966633002;5840.123981402791;5853.941460585398;5927.614705044169;5963.79126730564;5708.514776244014;5822.505087067604;5841.766329346831;5880.500157486608;5946.07835785454;5210.817621383252;5243.0398496876005;5249.632525723201;5342.813001544947;5377;5383.407825139251;5394.109971854639;5436.782528698847;5445.047116754572;5464.12573988162;5192.428832828386;5263.999840030401;5273.815416460444;5288.365396152486;5313.867153321159;4846.1761500544635;4940.802367905279;5274.709890955995;5297.258383826267;5298.205269209611;4809.302651115569;5175.948240517584;5202.947970520322;5253.290805741252;5313.0967735485065;5611.775528978836;5707.115397113413;5741.827745167651;5833.679147646878;5855.912161317584;5762.250907382056;6280.434760871543;6286.748530058792;6310.400511951358;6317.431431171217;6223.719932602999;6228.346023667495;6297.559170858041;6297.622142671455;6299.834530611852;5934.703264836741;5997.790077347316;6009.819705408845;6011.19664100768;6044.667543285118;6339.11252424661;6376.649284289316;6386.936130638727;6425.485961123192;6426.35736426363;6134.865219626364;6178.938210617836;6179.84550386244;6258.217722784652;6275.3724627536885;6255.593386429868;6302.968485157545;6306.8423289417215;6308.589941653778;6332.050192471166;6144.569880108439;6165.260168779775;6216.689165541705;6296.244450665949;6329.240491141092;6198.318184999646;6280.685965701697;6288.968555157287;6290.654014029273;6304.936950630527;4838.814490449862;4961.776720047606;4962.206047032458;4963.751812409365;5000.374953130859;3615.663892665541;4406.823727050882;4417.293233082755;4438.045820148619;4492.9101417972115;5853.1220316952795;5894.263217097863;5936.821895343147;5989.8203053908455;5999.370066143079;6216.285127210429;6303.149074874833;6314.494840412756;6317.336679648571;6329.8734025320155;5945.405459454107;5977.880442391128;6006.939930600725;6011.458968692838;6053.182820319289;5407.729613519308;5457.590680698884;5476.17857321397;5484.698341591171;5498.422665620133;6515.869682606227;6520.967395163088;6527.314631963671;6534.869302613921;6553.606783593;6306.085617585516;6315.210598675166;6316.05259211122;6319.431251187415;6324.462420694109;6242.906356404748;6287.308396076336;6318.778842740482;6347.587406818542;6448.097266382616;6156.722947467464;6189.35011823752;6229.96885015572;6233.314335423009;6239.594426362272;6091.543134264859;6305.842353941005;6307.400796924285;6311.368863113652;6337.809865704036;6136.723847426966;6143.389059649542;6171.938280617316;6184.752609895734;6223.719932603134;6174.073888916699;6213.1922850029205;6229.408206220402;6271.090691849565;6271.686415679106;5942.851428714317;6162.630242185483;6166.352532984151;6220.502359811114;6228.283747369111;6133.8855759873895;6151.477124444473;6160.829442405929;6210.223722034746;6256.374362563707;6140.815775526741;6167.938320616737;6192.0711893214575;6198.876022479435;6200.627962322275 05:24:01 INFO - PROCESS | 2195 | |1;ext.html;499.6752111127767;500.0074851419933;517.5600739371534;568.5678884048123;573.3062993777529;38648.806755966216;41586.58413415865;41820.372694409605;41868.58131418681;42633.50782722605;32356;37759;43254.783726081405;43740.781296093475;43906.12187756251;8217.465864718773;8638.136186381344;9286;9572.090651388107;9714.61141554337;9666.371685840433;9721.125098741111;9756.365836220659;9827.410355378664;9847.064528869781;9791.804163916717;9833.950830245838;9863.556139973718;9867.358621689604;9881.802363952753;9769.316147869617;9769.560369783378;9801.754956126082;9814.411135331831;9820.607175712963;8150.9184908150755;9627.181689556395;9629.614815407445;9649.951750241204;9650;39858.60141398578;40753;40980;41135.38296925549;41139;3209.0533292678715;3244.545763593099;3257.2833976525126;3467.913302167466;3660.970499746798;29546.70453295461;30353.241168970733;31187.532187016986;31198.688013119805;31214.843925780453;2330.3591512334074;2664.214056853233;2789.693133755285;2806.4387122575567;2809.5364264896266;500801;502085;504705.95294046955;505905;507980;36149.27701446009;66144.66927665331;66735.66632166808;67070.66464667743;67118.66440667862;36183.819080904424;37194.62805371966;38200.61799382026;38240.80879595584;38263.2347353057;15398.538043858702;15614.609634758996;15675.451359202374;15689.450869219523;15700.214989250491;13103.934480327538;13148.145370551076;13157.94736421084;13171.67070823237;13208.33958302081;8371.790705232417;8384.664613415516;8384.748457546284;8438.451500652438;8460;3386.2888793353673;3396.966030339665;3403.4044042292994;3411.3688967541084;3448.620651728308;850.0182289455565;1005.2259759984771;1035.2804800663616;1047.4657924458581;1140.7604403075447;24799.5040099197;29588.408231835674;29633.407331853243;30010.549841751956;30679;1026.6817286641017;1088.2382332367374;1092.1481244629201;1092.6776600902754;1118.8601424821898;15460.037634941116;15654.686906261812;15801.446949356718;15962.36150553988;16166.75749863753;583749;665922;668770;674976;678548;51998.70003249949;63777;64338.67830660817;64521.67739161368;64578;1281.5706738242793;1429.4139402844783;1442.7330943775435;1495.1776522912355;1495.9551213463612;30236.395272093996;30375.848120759256;30545.694543055175;30771.922982695498;30859.382812344076;55318.170227445815;56193.15710264431;56677.14984275157;57170.71414643066;58115.418845812696;594819;601503;603026;604798;607967;27896.44207115887;27906.302342440787;28342.716572834834;28646.42707145887;29056.7094329054;38862.02844928899;47541.762291189705;48273;48339.75830120827;49139.26291105708;32065.67934320627;39361.40957885691;39531.2093758129;40540.797296013334;40745.38881916714;37974.62025379822;38838.41742373923;38868.6113138865;39029.2194156121;39152.608473914894;38555.22889542249;39450.40824387579;39660.80169599134;39694.22904824153;40444.786656400356;33007.66992330142;34233.486497702055;34773.652263477044;34895.82552087223;34960.650393495736;25443;25685.486290274464;25787.226383208534;25970;26125.346866328495 05:24:01 INFO - PROCESS | 2195 | |2;jquery.html;389.7934094929688;392.4270564975136;392.43489375299555;395.926753550593;420.495405513384;14575.854241457582;19933.700994485076;20803;21053.947302634882;21176.682349764767;20554.383368498933;20714.689279660815;21492.462688432795;22205.333839984785;22239.555208895814;5733.512651424634;6187.752489900398;6216.067589861512;6221.4400703936635;6224.221972253468;5950.404959504058;6070.332263451039;6105.419985101408;6116.571839971204;6192.225971753531;6014.308354539218;6145.047517634774;6150.6924653767355;6157.415045570664;6165.907511387333;5803.361630220694;6146.416090471398;6146.569740118149;6162.137300777895;6189.752409903621;5329.7601607927345;5337.412884582732;5362.35651721792;5367.141257398797;5367.463253674601;25280.87359563209;26464.86767566169;26730.866345668146;26766.5985010225;27160;2241.5180736141806;2277.6811246425514;2298.0348253733473;2353.9529209415887;2515.3208633668805;14999.625009374744;15528.990615610062;15652.921735391365;15706;15820.76268855968;1728.3259528783785;1772.787265528132;1800.774903137108;1819.2904767140826;1860.2001139510005;121012;124046.75953240442;124215;124431;127481.72518274792;8945.37382383227;11354.772904541864;11529.077673786078;11697.824532632018;11774.764504709858;14675.046122002035;14717.705645887023;14750.926245368704;14753.557393278219;14757.704845903021;8819.29445644347;8941.284697224206;9007.189352958265;9112.817743645222;9127.634894604142;9000.324975626723;9020.503872287087;9028.458292502472;9115.225205857443;9124.771880702903;5768.307803063659;6167.784127555514;6422.036694495863;7015.754448594377;7052.717891284394;1595.3060418717894;1928.9680021188597;1933.7679478462674;1942.0969249298862;1975.3185151122839;925.2228128372066;964.4695417520336;1007.09361574582;1021.2238698588977;1073.951672174754;18975.905120474585;21497.892510537346;21907.561848762936;22022.33932982013;22395.88802056012;986.8125056239292;1002.2433063037361;1002.3384566186428;1014.8071866345371;1037.870266216723;8792.856928599369;10924.890751092546;11392.145589080852;11687.590934317428;11755.353455560027;620919.8954005201;622181;624047;625732;641011;121669;123512;123756;125973.37013314875;127640.72359276474;1059.814532456817;1077.439731339699;1128.1257025805066;1153.9538418463335;1158.5192145259791;17787.73318400251;18352.99058551739;19305.420837374902;19529.121189546222;19693.40919772409;49321.753391232814;51471.74264128655;52815.94368112539;55801.44198557962;56002.159967601336;77605;78700.09749268943;78908;79124.60437697775;80226.59886700529;7899.368050555827;8089.110197878346;8266.13205613426;8312.875306870523;8633.654653813777;9398.95300523493;11067.83398249043;11335.433228338716;12093.972012379047;12475.750484990067;9467.905320946702;9616.855747163658;9875.506224688594;10830.241883068109;11089.556417743523;10709.3574385534;10753.838692419464;10820.080293174853;10898.237123401286;11027.889721103007;5772.913406298825;8433;9915.801683966136;10854.511546980248;11117.998760222914;8192.54940978252;8226.218509241613;8308.750737477885;8312.916870831214;8523.659053637784;10483.266171367932;10827.187960902806;11001.22991390595;11227.326360418401;11233.55065797359 05:24:01 INFO - PROCESS | 2195 | |3;mootools.html;1606.510014445594;1646.9505914822553;1903.7525121734172;1964.1323667445529;1993.0732209522569;8243.711470098548;8337.999440067186;8783.03386627472;8798.944126704797;8840.734777956664;8071.23323284287;8418.66325346987;8958.731238062868;9123.726288211348;9163.35856490046;2453.8036957043437;2498.4253621666935;2500.0749722602663;2500.9374765630914;2503.6244563315468;2047.2834507922182;2413.4690368119072;2446.987765061172;2477.752224777526;2498.8250822442437;2058.8764674119525;2108.641530939744;2444.841085329448;2484.9751502484924;2488.6515887775727;2359.421941624296;2437.4272046069073;2443.853368797869;2448.571499987495;2450.8848473944404;2442.3161514775893;2442.68245128134;2449.9265022049367;2467.95064098717;2478.083109249571;9046.502442365667;9155.908440915638;9165.450072995642;9172;9174.495402752846;4754.286856971447;5446.455354464561;5561.249231353756;5586.16207568868;5630.408807075239;3585.6772890439725;3611.747177697562;3641.761800987677;3683.3001729671546;3701.1117331840433;984.8867380251291;1033.302520798458;1037.8168887468291;1038.2524582300732;1041.3959903256093;12154.756904861943;12246.755064898653;12281.938590307082;12306;12324.630261092181;4952.925706114411;4959.107360675113;5062.4684408137355;5099.5410413063;5104.872378190501;10218.233632477446;10391.324563903323;10417.218708596885;10453.954604539636;10467.895321046844;5459.645123067067;5505.587080968943;5581.6371935824045;5592.888142237132;5607.186957891083;4512.9774351128035;4871.585915197248;5011.924821127686;5047.848564543069;5112.71880046601;4103.5691252418665;4164.770937598461;4196.685248606367;4221.092465120014;4235.5552666969525;1361.748076605831;1389.3608939887563;1391.476333414909;1393.5958572014003;1395.1350162899068;875.6978842299396;915.6428992692855;935.1490143969091;946.5220063867703;952.2953014769021;5947.672877991666;6058.333583305833;6128.724207410677;6154.569180157436;6202.534809889276;413.4418534977816;452.9524399938025;460.89860230749184;470.78343961777966;478.9856304310876;3929.8035098244973;3972.7219094663674;4003.49606692471;4031.75809451428;4036.1725846201457;10484.056434920742;11027.503762330554;11090;11123.777524449466;11188.160887933438;10617.946910265398;10845.132389408986;10987.505562249718;10997.230193886351;11088;8336.791580210544;8369.916300837036;8428.957855210685;8430.957845210734;8484.151584841466;5714.714264286852;5761.193432919311;5845.976954032943;5922.851928701817;6019.638821670538;9844.556994935101;10207.540660670436;10353.63762268317;10374.948125259325;10425;10434.217433692655;10456.058954694114;10506.684799456028;10573.9471302643;10610.787784244427;6523.836904077436;7071.292870713093;7703.922960770321;7814.53112813233;8517.318614510701;8436.53599052058;8557;8568.828623427371;8685.305175585812;8691.261242794433;5896.262967129109;6937.750254918013;7348.154962179204;7580.469367144247;7830.686772529035;6920.723171073302;6928.926016467455;7284.198738138906;7550.3582195514;7606.163322034461;7098.077249957472;7170.354668079898;7203.531770434905;7252.673629686782;7275.163356213891;4810.7113573185725;4836.975815120902;4940.92588611164;4966.404031516241;5010.5240002199735;4324.329728892027;4979.712835561579;4987.202047672355;5129.256257842667;5266.8946621068135 05:24:01 INFO - PROCESS | 2195 | |4;prototype.html;272.87447774023946;310.8647738233868;317.8188432593422;330.6643756587066;332.41660885146564;4160.482907840758;4896.8041278348865;5210.114280572302;5246.475352464751;5411.969297193569;5012.949870501303;5230.686158830473;5372.758225879835;5423.267858839055;5446.2647542581835;4174.916501669964;4184.184084103601;4187.722744562907;4201.143222608625;4202.096549241913;3221.710046095851;3545.85816567337;4183.393407955845;4195.958040419602;4224.936625950613;4156.605122513356;4172.5173521754505;4181.121964387467;4188.869005368549;4201.579842015804;3794.582595914447;4121.793910304503;4208.831646734127;4208.873733787991;4215.704900656955;3289.802611843273;3479.9130021749406;3557.804320762357;3673.779573225615;3702.852115844097;4866.610671146335;5007.39911210657;5221.634485586011;5240.659357141812;5284.973575132138;4064.959350406488;4277.593628605277;4659.277811939155;4804.615630749532;4834.323194752737;4640.67515273931;4807.557732680217;5760.798372056958;5780.335261444946;5936.426846885579;3202.439573074703;3419.863205471778;3543.8228088595465;3766.830492627811;4142.08874047709;5975.759090341122;6043.365446628129;6044.4888777805545;6049.516038716892;6059.666718330534;3596.352656521799;3642.8542858285373;3985.661218796377;4104.589541045872;4166.895827604334;3918.3730603103368;4180.077164504362;4181.038361176947;4189.413482112506;4216.039541613165;4092.815823287959;4575.656825738083;4779.378680771478;4793.832215872428;4798.712077275374;5486.561075113981;5816.720321529257;5823.883522329615;5846.912296315559;5848.2397288353095;1154.1978325064;1333.6332508560224;1373.9695228578566;1428.599992002241;1429.4776063492347;873.8334323677834;880.8854848869606;885.1104639837051;901.378049146087;902.1294450854956;4118.887900266911;4592.012717265804;4611.610466307261;4613.838515651936;4661.974365639554;4355.782210889506;4430.62339701129;4500.302453119772;4870.909899155391;4999.700017998932;4178.310578754532;4196.475440569929;4475.142815731435;4584;4623.3587076588;2034.8168664820237;2356.5286942611547;2385.7614238576352;2635.0118705485443;2672.6124711916664;4669.128485981098;4772.448954089865;5036.697798132123;5226.529612334908;5265.262863199156;4655;4783.904321913542;4809.78355973982;5163.793448262102;5200.115980283361;5300.443453437333;5746.856328591819;6263.96868015657;6284.277308109443;6285.842853928689;5022.447530771686;5101.8724531887;5424.806542560788;6070.544709146743;6163.9383606163365;5367.865803354947;5639.590102474382;5927.288725352985;5931.555133364928;5936.7922122725495;5206.401263854554;5432.891342173213;5700.942990570207;6170.321264660974;6259.78090766821;3720.925581488409;3999.3800960851113;4017.216642754635;4018.5579586246076;4088.6081642239624;5553.111502159635;5649.661020338793;5958.940410595838;5971.611845230046;5989.670568118621;5095.827959569246;5179.938112686966;5542.889142217053;5579.972100139474;5582.5533957282505;4917.557419832232;5079.949200507947;5496.642718223303;5792.768289268551;5862.648241105377;5093.904810465768;5104.744762761773;5639.238702775073;5695.686737229492;5742.253507044057;5472.945270547243;5601.29983752031;5755.942440575541;5765.549641402304;5784.2191304175085;4536.841210557746;5002.6498145130945;5657;5706.42935706426;5838.678872662044 05:24:01 INFO - PROCESS | 2195 | |5;yui.html;623.5471351750421;689.9995007239503;694.3299715774277;742.4580056558708;778.0624347661069;14199.71600567988;14242.28788560573;14384.784228236571;14999.625009374757;15479.767803482957;14235.501757438491;14337;14349.928250358733;14356.353964071615;14370.640733981656;730.9415246780271;904.4618452021037;914.4741773480259;924.6301479408223;930.6417029443645;932.1377725603824;934.4953724988499;935.6631612619485;935.9298052646045;938.3478482454697;985.9992108010375;987.3088837813559;988.2588058955783;988.3081842710096;990.529498488216;966.970990870275;968.5012218707371;980.8185485685135;983.5623147699313;985.6156099121348;694.3785312145652;696.4532841719226;719.9388052015585;728.1226122522401;731.9670614822346;14497.637559061108;14713;14756.48352307664;14783.965122441434;14786.03890747098;12382.504699812087;12547.811782823264;12548.81176782349;12604.558840440632;12779.744405111938;9959.402435853872;10032.799344013152;10046.4474453905;10323.84514232287;10449.164066874631;2669.7730692891128;3209.8876539321013;3335.533025376449;3401.8299085045896;3513.7013353864954;60272.794544108874;75025.24974750237;83814;83958.16041839565;86181.56909215476;9274.860877086849;9408.106229908148;9504.714858554255;9718.368306060083;9819.165370943407;21621;22068.668969965463;22149.778502215093;22345;22357;7343.596102214323;7389.963050184715;7391.260873912674;7520;7525.4355923305975;3934.1738234970976;3941.5664276929515;3965.0682089708894;4002.1195337025806;4043.838246470167;3537.5224344713647;3549.1836877518317;3579.391503444421;3636.3272794533095;3650.379435495972;612.3600837125126;622.1165944359021;627.2786295759913;634.2262439823409;637.1430426076919;520.232656831171;563.312758434709;583.877385748989;587.8706684529396;606.8500192135878;5939.316978547522;7763.340116090083;7907.920920790719;7939.960300198462;8003.119656837744;573.6185436684641;586.9236999190078;590.5865893874305;597.4473611908967;612.9233845769279;3595.7662751921043;3733.439984002367;3755.9436608450897;3932.8230229639735;3950.2692001979726;74928;78432.60783696045;81832;84263.57868210619;93836.53081734548;60335.79328413407;71437.2856271441;73235.63382183161;80799.19200808034;82132.4294056474;67512.66243668848;68191;68653.65673171602;69374.98125243686;69732;8024.358051355995;8126.75619731409;8211.21993410625;8252.05101413345;8291.129431409856;32356.058636481885;42752.358714618684;43468.91327716832;44219.33670995002;44832.551674484144;61255.77488450295;61999.38000619936;64148.358516414235;64264;65375.34624653692;10915.83626245591;10940;11349.943250283695;11357.318560886373;11689.941550292195;12001.099917506048;12084.818727719266;12322.199057061262;12359.32023738667;12484.500619975463;10542.736431589272;10889.727756806144;10899.891001090205;11004.614838480615;11742.236754610924;11166.1067114629;11573.18987670855;11630.127740419333;11682.824757628472;11754.177207595387;9187;10941.781164376827;11123.443827808738;11125.666230013112;11397.373144477133;8013.7979303105;9126.49804260772;9340.299477539347;9362.578683959102;10705.785884282426;9642.566084526354;9673.177779888789;9731.483508827814;9844.753881153028;9931.503424828874 05:24:01 INFO - PROCESS | 2195 | __end_tp_report 05:24:01 INFO - PROCESS | 2195 | __start_cc_report 05:24:01 INFO - PROCESS | 2195 | _x_x_mozilla_cycle_collect,428 05:24:01 INFO - PROCESS | 2195 | __end_cc_report 05:24:01 INFO - PROCESS | 2195 | __startTimestamp1462969441675__endTimestamp 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | ------- Summary: start ------- 05:24:01 INFO - PROCESS | 2195 | Number of tests: 6 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#0] dojo.html Cycles:165 Average:5826.03 Median:6143.39 stddev:676.16 (11.0%) stddev-sans-first:646.18 05:24:01 INFO - PROCESS | 2195 | Values: 3203.7 3468.2 3502.5 3528.7 3530.3 4522.6 5840.1 5853.9 5927.6 5963.8 5708.5 5822.5 5841.8 5880.5 5946.1 5210.8 5243.0 5249.6 5342.8 5377.0 5383.4 5394.1 5436.8 5445.0 5464.1 5192.4 5264.0 5273.8 5288.4 5313.9 4846.2 4940.8 5274.7 5297.3 5298.2 4809.3 5175.9 5202.9 5253.3 5313.1 5611.8 5707.1 5741.8 5833.7 5855.9 5762.3 6280.4 6286.7 6310.4 6317.4 6223.7 6228.3 6297.6 6297.6 6299.8 5934.7 5997.8 6009.8 6011.2 6044.7 6339.1 6376.6 6386.9 6425.5 6426.4 6134.9 6178.9 6179.8 6258.2 6275.4 6255.6 6303.0 6306.8 6308.6 6332.1 6144.6 6165.3 6216.7 6296.2 6329.2 6198.3 6280.7 6289.0 6290.7 6304.9 4838.8 4961.8 4962.2 4963.8 5000.4 3615.7 4406.8 4417.3 4438.0 4492.9 5853.1 5894.3 5936.8 5989.8 5999.4 6216.3 6303.1 6314.5 6317.3 6329.9 5945.4 5977.9 6006.9 6011.5 6053.2 5407.7 5457.6 5476.2 5484.7 5498.4 6515.9 6521.0 6527.3 6534.9 6553.6 6306.1 6315.2 6316.1 6319.4 6324.5 6242.9 6287.3 6318.8 6347.6 6448.1 6156.7 6189.4 6230.0 6233.3 6239.6 6091.5 6305.8 6307.4 6311.4 6337.8 6136.7 6143.4 6171.9 6184.8 6223.7 6174.1 6213.2 6229.4 6271.1 6271.7 5942.9 6162.6 6166.4 6220.5 6228.3 6133.9 6151.5 6160.8 6210.2 6256.4 6140.8 6167.9 6192.1 6198.9 6200.6 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#1] ext.html Cycles:180 Average:71013.87 Median:39257.01 stddev:158188.96 (403.0%) stddev-sans-first:158544.13 05:24:01 INFO - PROCESS | 2195 | Values: 499.7 500.0 517.6 568.6 573.3 38648.8 41586.6 41820.4 41868.6 42633.5 32356.0 37759.0 43254.8 43740.8 43906.1 8217.5 8638.1 9286.0 9572.1 9714.6 9666.4 9721.1 9756.4 9827.4 9847.1 9791.8 9834.0 9863.6 9867.4 9881.8 9769.3 9769.6 9801.8 9814.4 9820.6 8150.9 9627.2 9629.6 9650.0 9650.0 39858.6 40753.0 40980.0 41135.4 41139.0 3209.1 3244.5 3257.3 3467.9 3661.0 29546.7 30353.2 31187.5 31198.7 31214.8 2330.4 2664.2 2789.7 2806.4 2809.5 500801.0 502085.0 504706.0 505905.0 507980.0 36149.3 66144.7 66735.7 67070.7 67118.7 36183.8 37194.6 38200.6 38240.8 38263.2 15398.5 15614.6 15675.5 15689.5 15700.2 13103.9 13148.1 13157.9 13171.7 13208.3 8371.8 8384.7 8384.7 8438.5 8460.0 3386.3 3397.0 3403.4 3411.4 3448.6 850.0 1005.2 1035.3 1047.5 1140.8 24799.5 29588.4 29633.4 30010.5 30679.0 1026.7 1088.2 1092.1 1092.7 1118.9 15460.0 15654.7 15801.4 15962.4 16166.8 583749.0 665922.0 668770.0 674976.0 678548.0 51998.7 63777.0 64338.7 64521.7 64578.0 1281.6 1429.4 1442.7 1495.2 1496.0 30236.4 30375.8 30545.7 30771.9 30859.4 55318.2 56193.2 56677.1 57170.7 58115.4 594819.0 601503.0 603026.0 604798.0 607967.0 27896.4 27906.3 28342.7 28646.4 29056.7 38862.0 47541.8 48273.0 48339.8 49139.3 32065.7 39361.4 39531.2 40540.8 40745.4 37974.6 38838.4 38868.6 39029.2 39152.6 38555.2 39450.4 39660.8 39694.2 40444.8 33007.7 34233.5 34773.7 34895.8 34960.7 25443.0 25685.5 25787.2 25970.0 26125.3 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#2] jquery.html Cycles:180 Average:36120.97 Median:2325.99 stddev:104386.35 (4487.8%) stddev-sans-first:104644.71 05:24:01 INFO - PROCESS | 2195 | Values: 389.8 392.4 392.4 395.9 420.5 14575.9 19933.7 20803.0 21053.9 21176.7 20554.4 20714.7 21492.5 22205.3 22239.6 5733.5 6187.8 6216.1 6221.4 6224.2 5950.4 6070.3 6105.4 6116.6 6192.2 6014.3 6145.0 6150.7 6157.4 6165.9 5803.4 6146.4 6146.6 6162.1 6189.8 5329.8 5337.4 5362.4 5367.1 5367.5 25280.9 26464.9 26730.9 26766.6 27160.0 2241.5 2277.7 2298.0 2354.0 2515.3 14999.6 15529.0 15652.9 15706.0 15820.8 1728.3 1772.8 1800.8 1819.3 1860.2 121012.0 124046.8 124215.0 124431.0 127481.7 8945.4 11354.8 11529.1 11697.8 11774.8 14675.0 14717.7 14750.9 14753.6 14757.7 8819.3 8941.3 9007.2 9112.8 9127.6 9000.3 9020.5 9028.5 9115.2 9124.8 5768.3 6167.8 6422.0 7015.8 7052.7 1595.3 1929.0 1933.8 1942.1 1975.3 925.2 964.5 1007.1 1021.2 1074.0 18975.9 21497.9 21907.6 22022.3 22395.9 986.8 1002.2 1002.3 1014.8 1037.9 8792.9 10924.9 11392.1 11687.6 11755.4 620919.9 622181.0 624047.0 625732.0 641011.0 121669.0 123512.0 123756.0 125973.4 127640.7 1059.8 1077.4 1128.1 1154.0 1158.5 17787.7 18353.0 19305.4 19529.1 19693.4 49321.8 51471.7 52815.9 55801.4 56002.2 77605.0 78700.1 78908.0 79124.6 80226.6 7899.4 8089.1 8266.1 8312.9 8633.7 9399.0 11067.8 11335.4 12094.0 12475.8 9467.9 9616.9 9875.5 10830.2 11089.6 10709.4 10753.8 10820.1 10898.2 11027.9 5772.9 8433.0 9915.8 10854.5 11118.0 8192.5 8226.2 8308.8 8312.9 8523.7 10483.3 10827.2 11001.2 11227.3 11233.6 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#3] mootools.html Cycles:180 Average:5836.00 Median:2644.85 stddev:3309.47 (125.1%) stddev-sans-first:3303.50 05:24:01 INFO - PROCESS | 2195 | Values: 1606.5 1647.0 1903.8 1964.1 1993.1 8243.7 8338.0 8783.0 8798.9 8840.7 8071.2 8418.7 8958.7 9123.7 9163.4 2453.8 2498.4 2500.1 2500.9 2503.6 2047.3 2413.5 2447.0 2477.8 2498.8 2058.9 2108.6 2444.8 2485.0 2488.7 2359.4 2437.4 2443.9 2448.6 2450.9 2442.3 2442.7 2449.9 2468.0 2478.1 9046.5 9155.9 9165.5 9172.0 9174.5 4754.3 5446.5 5561.2 5586.2 5630.4 3585.7 3611.7 3641.8 3683.3 3701.1 984.9 1033.3 1037.8 1038.3 1041.4 12154.8 12246.8 12281.9 12306.0 12324.6 4952.9 4959.1 5062.5 5099.5 5104.9 10218.2 10391.3 10417.2 10454.0 10467.9 5459.6 5505.6 5581.6 5592.9 5607.2 4513.0 4871.6 5011.9 5047.8 5112.7 4103.6 4164.8 4196.7 4221.1 4235.6 1361.7 1389.4 1391.5 1393.6 1395.1 875.7 915.6 935.1 946.5 952.3 5947.7 6058.3 6128.7 6154.6 6202.5 413.4 453.0 460.9 470.8 479.0 3929.8 3972.7 4003.5 4031.8 4036.2 10484.1 11027.5 11090.0 11123.8 11188.2 10617.9 10845.1 10987.5 10997.2 11088.0 8336.8 8369.9 8429.0 8431.0 8484.2 5714.7 5761.2 5846.0 5922.9 6019.6 9844.6 10207.5 10353.6 10374.9 10425.0 10434.2 10456.1 10506.7 10573.9 10610.8 6523.8 7071.3 7703.9 7814.5 8517.3 8436.5 8557.0 8568.8 8685.3 8691.3 5896.3 6937.8 7348.2 7580.5 7830.7 6920.7 6928.9 7284.2 7550.4 7606.2 7098.1 7170.4 7203.5 7252.7 7275.2 4810.7 4837.0 4940.9 4966.4 5010.5 4324.3 4979.7 4987.2 5129.3 5266.9 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#4] prototype.html Cycles:180 Average:4496.17 Median:4822.05 stddev:1399.02 (29.0%) stddev-sans-first:1366.56 05:24:01 INFO - PROCESS | 2195 | Values: 272.9 310.9 317.8 330.7 332.4 4160.5 4896.8 5210.1 5246.5 5412.0 5012.9 5230.7 5372.8 5423.3 5446.3 4174.9 4184.2 4187.7 4201.1 4202.1 3221.7 3545.9 4183.4 4196.0 4224.9 4156.6 4172.5 4181.1 4188.9 4201.6 3794.6 4121.8 4208.8 4208.9 4215.7 3289.8 3479.9 3557.8 3673.8 3702.9 4866.6 5007.4 5221.6 5240.7 5285.0 4065.0 4277.6 4659.3 4804.6 4834.3 4640.7 4807.6 5760.8 5780.3 5936.4 3202.4 3419.9 3543.8 3766.8 4142.1 5975.8 6043.4 6044.5 6049.5 6059.7 3596.4 3642.9 3985.7 4104.6 4166.9 3918.4 4180.1 4181.0 4189.4 4216.0 4092.8 4575.7 4779.4 4793.8 4798.7 5486.6 5816.7 5823.9 5846.9 5848.2 1154.2 1333.6 1374.0 1428.6 1429.5 873.8 880.9 885.1 901.4 902.1 4118.9 4592.0 4611.6 4613.8 4662.0 4355.8 4430.6 4500.3 4870.9 4999.7 4178.3 4196.5 4475.1 4584.0 4623.4 2034.8 2356.5 2385.8 2635.0 2672.6 4669.1 4772.4 5036.7 5226.5 5265.3 4655.0 4783.9 4809.8 5163.8 5200.1 5300.4 5746.9 6264.0 6284.3 6285.8 5022.4 5101.9 5424.8 6070.5 6163.9 5367.9 5639.6 5927.3 5931.6 5936.8 5206.4 5432.9 5700.9 6170.3 6259.8 3720.9 3999.4 4017.2 4018.6 4088.6 5553.1 5649.7 5958.9 5971.6 5989.7 5095.8 5179.9 5542.9 5580.0 5582.6 4917.6 5079.9 5496.6 5792.8 5862.6 5093.9 5104.7 5639.2 5695.7 5742.3 5472.9 5601.3 5755.9 5765.5 5784.2 4536.8 5002.6 5657.0 5706.4 5838.7 05:24:01 INFO - PROCESS | 2195 | 05:24:01 INFO - PROCESS | 2195 | [#5] yui.html Cycles:180 Average:17400.70 Median:609.61 stddev:24010.17 (3938.6%) stddev-sans-first:24044.47 05:24:01 INFO - PROCESS | 2195 | Values: 623.5 690.0 694.3 742.5 778.1 14199.7 14242.3 14384.8 14999.6 15479.8 14235.5 14337.0 14349.9 14356.4 14370.6 730.9 904.5 914.5 924.6 930.6 932.1 934.5 935.7 935.9 938.3 986.0 987.3 988.3 988.3 990.5 967.0 968.5 980.8 983.6 985.6 694.4 696.5 719.9 728.1 732.0 14497.6 14713.0 14756.5 14784.0 14786.0 12382.5 12547.8 12548.8 12604.6 12779.7 9959.4 10032.8 10046.4 10323.8 10449.2 2669.8 3209.9 3335.5 3401.8 3513.7 60272.8 75025.2 83814.0 83958.2 86181.6 9274.9 9408.1 9504.7 9718.4 9819.2 21621.0 22068.7 22149.8 22345.0 22357.0 7343.6 7390.0 7391.3 7520.0 7525.4 3934.2 3941.6 3965.1 4002.1 4043.8 3537.5 3549.2 3579.4 3636.3 3650.4 612.4 622.1 627.3 634.2 637.1 520.2 563.3 583.9 587.9 606.9 5939.3 7763.3 7907.9 7940.0 8003.1 573.6 586.9 590.6 597.4 612.9 3595.8 3733.4 3755.9 3932.8 3950.3 74928.0 78432.6 81832.0 84263.6 93836.5 60335.8 71437.3 73235.6 80799.2 82132.4 67512.7 68191.0 68653.7 69375.0 69732.0 8024.4 8126.8 8211.2 8252.1 8291.1 32356.1 42752.4 43468.9 44219.3 44832.6 61255.8 61999.4 64148.4 64264.0 65375.3 10915.8 10940.0 11349.9 11357.3 11689.9 12001.1 12084.8 12322.2 12359.3 12484.5 10542.7 10889.7 10899.9 11004.6 11742.2 11166.1 11573.2 11630.1 11682.8 11754.2 9187.0 10941.8 11123.4 11125.7 11397.4 8013.8 9126.5 9340.3 9362.6 10705.8 9642.6 9673.2 9731.5 9844.8 9931.5 05:24:01 INFO - PROCESS | 2195 | -------- Summary: end -------- 05:24:01 INFO - PROCESS | 2195 | 05:24:02 INFO - TEST-INFO | 2195: exit 0 05:24:02 INFO - TEST-OK | dromaeo_css | took 1113187ms 05:24:02 INFO - TEST-START | kraken 05:24:02 INFO - operating with platform_type : mac_ 05:24:02 INFO - Initialising browser for kraken test... 05:24:02 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpDPlBwr/profile http://localhost:49911/getInfo.html) 05:24:05 INFO - PROCESS | 2203 | __metrics Screen width/height:1600/1200 05:24:05 INFO - PROCESS | 2203 | colorDepth:24 05:24:05 INFO - PROCESS | 2203 | Browser inner width/height: 1024/689 05:24:05 INFO - PROCESS | 2203 | __metrics 05:24:05 ERROR - PROCESS | 2203 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:24:05 INFO - TEST-INFO | 2203: exit 0 05:24:05 INFO - Browser initialized. 05:24:05 INFO - Running cycle 1/1 for kraken test... 05:24:05 INFO - TEST-INFO | started process 2207 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpDPlBwr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:17 INFO - PROCESS | 2207 | RSS: Main: 249835520 05:24:17 INFO - PROCESS | 2207 | 05:24:20 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:21 INFO - PROCESS | 2207 | RSS: Main: 261382144 05:24:21 INFO - PROCESS | 2207 | 05:24:24 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-dft) 05:24:24 INFO - PROCESS | 2207 | RSS: Main: 266235904 05:24:24 INFO - PROCESS | 2207 | 05:24:28 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-fft) 05:24:28 INFO - PROCESS | 2207 | RSS: Main: 267096064 05:24:28 INFO - PROCESS | 2207 | 05:24:31 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49911/tests/kraken/driver.html?testName=audio-oscillator) 05:24:31 INFO - PROCESS | 2207 | RSS: Main: 267223040 05:24:31 INFO - PROCESS | 2207 | 05:24:33 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:34 INFO - PROCESS | 2207 | RSS: Main: 268308480 05:24:34 INFO - PROCESS | 2207 | 05:24:37 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:37 INFO - PROCESS | 2207 | RSS: Main: 267522048 05:24:37 INFO - PROCESS | 2207 | 05:24:40 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49911/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:41 INFO - PROCESS | 2207 | RSS: Main: 267788288 05:24:41 INFO - PROCESS | 2207 | 05:24:44 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49911/tests/kraken/driver.html?testName=json-parse-financial) 05:24:45 INFO - PROCESS | 2207 | RSS: Main: 268161024 05:24:45 INFO - PROCESS | 2207 | 05:24:46 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:47 INFO - PROCESS | 2207 | RSS: Main: 267849728 05:24:47 INFO - PROCESS | 2207 | 05:24:49 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:50 INFO - PROCESS | 2207 | RSS: Main: 267911168 05:24:50 INFO - PROCESS | 2207 | 05:24:52 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:53 INFO - PROCESS | 2207 | RSS: Main: 268189696 05:24:53 INFO - PROCESS | 2207 | 05:24:56 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:56 INFO - PROCESS | 2207 | RSS: Main: 268238848 05:24:56 INFO - PROCESS | 2207 | 05:24:59 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:00 INFO - PROCESS | 2207 | RSS: Main: 268103680 05:25:00 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | Cycle 1(1): loaded http://localhost:49911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49911/tests/kraken/driver.html?testName=ai-astar) 05:25:02 INFO - PROCESS | 2207 | RSS: Main: 269901824 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | __start_tp_report 05:25:02 INFO - PROCESS | 2207 | _x_x_mozilla_page_load 05:25:02 INFO - PROCESS | 2207 | _x_x_mozilla_page_load_details 05:25:02 INFO - PROCESS | 2207 | |i|pagename|runs| 05:25:02 INFO - PROCESS | 2207 | |0;ai-astar;115;115;112;115;116;113;107;116;118;114 05:25:02 INFO - PROCESS | 2207 | |1;audio-beat-detection;154;120;145;197;118;140;151;119;181;146 05:25:02 INFO - PROCESS | 2207 | |2;audio-dft;202;199;209;239;195;199;200;202;205;198 05:25:02 INFO - PROCESS | 2207 | |3;audio-fft;76;80;79;181;84;83;88;99;121;84 05:25:02 INFO - PROCESS | 2207 | |4;audio-oscillator;80;84;144;79;81;84;119;82;82;125 05:25:02 INFO - PROCESS | 2207 | |5;imaging-gaussian-blur;88;87;88;86;88;85;93;121;89;99 05:25:02 INFO - PROCESS | 2207 | |6;imaging-darkroom;94;95;95;96;94;95;95;94;94;108 05:25:02 INFO - PROCESS | 2207 | |7;imaging-desaturate;88;89;90;89;92;89;90;91;90;91 05:25:02 INFO - PROCESS | 2207 | |8;json-parse-financial;58;62;61;62;61;61;81;172;58;53 05:25:02 INFO - PROCESS | 2207 | |9;json-stringify-tinderbox;64;67;67;79;79;77;82;67;59;80 05:25:02 INFO - PROCESS | 2207 | |10;stanford-crypto-aes;85;88;87;86;89;85;86;88;90;87 05:25:02 INFO - PROCESS | 2207 | |11;stanford-crypto-ccm;138;162;149;141;153;167;154;152;203;166 05:25:02 INFO - PROCESS | 2207 | |12;stanford-crypto-pbkdf2;170;172;210;176;168;195;235;165;232;210 05:25:02 INFO - PROCESS | 2207 | |13;stanford-crypto-sha256-iterative;68;71;69;75;98;87;81;68;67;81 05:25:02 INFO - PROCESS | 2207 | __end_tp_report 05:25:02 INFO - PROCESS | 2207 | __start_cc_report 05:25:02 INFO - PROCESS | 2207 | _x_x_mozilla_cycle_collect,1066 05:25:02 INFO - PROCESS | 2207 | __end_cc_report 05:25:02 INFO - PROCESS | 2207 | __startTimestamp1462969502658__endTimestamp 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | ------- Summary: start ------- 05:25:02 INFO - PROCESS | 2207 | Number of tests: 14 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.00 (2.6%) stddev-sans-first:3.16 05:25:02 INFO - PROCESS | 2207 | Values: 115.0 115.0 112.0 115.0 116.0 113.0 107.0 116.0 118.0 114.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:148.50 stddev:26.10 (17.6%) stddev-sans-first:27.56 05:25:02 INFO - PROCESS | 2207 | Values: 154.0 120.0 145.0 197.0 118.0 140.0 151.0 119.0 181.0 146.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#2] audio-dft Cycles:10 Average:204.80 Median:202.00 stddev:12.63 (6.3%) stddev-sans-first:13.36 05:25:02 INFO - PROCESS | 2207 | Values: 202.0 199.0 209.0 239.0 195.0 199.0 200.0 202.0 205.0 198.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#3] audio-fft Cycles:10 Average:97.50 Median:83.50 stddev:32.15 (38.5%) stddev-sans-first:33.15 05:25:02 INFO - PROCESS | 2207 | Values: 76.0 80.0 79.0 181.0 84.0 83.0 88.0 99.0 121.0 84.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:81.50 stddev:23.86 (29.3%) stddev-sans-first:24.60 05:25:02 INFO - PROCESS | 2207 | Values: 80.0 84.0 144.0 79.0 81.0 84.0 119.0 82.0 82.0 125.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:88.00 stddev:10.83 (12.3%) stddev-sans-first:11.37 05:25:02 INFO - PROCESS | 2207 | Values: 88.0 87.0 88.0 86.0 88.0 85.0 93.0 121.0 89.0 99.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:95.00 stddev:4.27 (4.5%) stddev-sans-first:4.47 05:25:02 INFO - PROCESS | 2207 | Values: 94.0 95.0 95.0 96.0 94.0 95.0 95.0 94.0 94.0 108.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#7] imaging-desaturate Cycles:10 Average:89.90 Median:90.00 stddev:1.20 (1.3%) stddev-sans-first:1.05 05:25:02 INFO - PROCESS | 2207 | Values: 88.0 89.0 90.0 89.0 92.0 89.0 90.0 91.0 90.0 91.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#8] json-parse-financial Cycles:10 Average:72.90 Median:61.00 stddev:35.57 (58.3%) stddev-sans-first:37.32 05:25:02 INFO - PROCESS | 2207 | Values: 58.0 62.0 61.0 62.0 61.0 61.0 81.0 172.0 58.0 53.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:78.00 stddev:8.13 (10.4%) stddev-sans-first:8.08 05:25:02 INFO - PROCESS | 2207 | Values: 64.0 67.0 67.0 79.0 79.0 77.0 82.0 67.0 59.0 80.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:1.66 (1.9%) stddev-sans-first:1.58 05:25:02 INFO - PROCESS | 2207 | Values: 85.0 88.0 87.0 86.0 89.0 85.0 86.0 88.0 90.0 87.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:158.00 stddev:18.35 (11.6%) stddev-sans-first:17.90 05:25:02 INFO - PROCESS | 2207 | Values: 138.0 162.0 149.0 141.0 153.0 167.0 154.0 152.0 203.0 166.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:202.50 stddev:26.94 (13.3%) stddev-sans-first:27.23 05:25:02 INFO - PROCESS | 2207 | Values: 170.0 172.0 210.0 176.0 168.0 195.0 235.0 165.0 232.0 210.0 05:25:02 INFO - PROCESS | 2207 | 05:25:02 INFO - PROCESS | 2207 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.50 Median:78.00 stddev:10.20 (13.1%) stddev-sans-first:10.35 05:25:02 INFO - PROCESS | 2207 | Values: 68.0 71.0 69.0 75.0 98.0 87.0 81.0 68.0 67.0 81.0 05:25:02 INFO - PROCESS | 2207 | -------- Summary: end -------- 05:25:02 INFO - PROCESS | 2207 | 05:25:03 INFO - TEST-INFO | 2207: exit 0 05:25:03 INFO - TEST-OK | kraken | took 61199ms 05:25:03 INFO - SUITE-END | took 1174s 05:25:03 INFO - Completed test suite (00:19:34) 05:25:03 INFO - javascript benchmark 05:25:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3203.6796320367953, 3468.167639766456, 3502.4571191465316, 3528.717702583793, 3530.346885826122, 4522.592966633002, 5840.123981402791, 5853.941460585398, 5927.614705044169, 5963.79126730564, 5708.514776244014, 5822.505087067604, 5841.766329346831, 5880.500157486608, 5946.07835785454, 5210.817621383252, 5243.0398496876005, 5249.632525723201, 5342.813001544947, 5377.0, 5383.407825139251, 5394.109971854639, 5436.782528698847, 5445.047116754572, 5464.12573988162, 5192.428832828386, 5263.999840030401, 5273.815416460444, 5288.365396152486, 5313.867153321159, 4846.1761500544635, 4940.802367905279, 5274.709890955995, 5297.258383826267, 5298.205269209611, 4809.302651115569, 5175.948240517584, 5202.947970520322, 5253.290805741252, 5313.0967735485065, 5611.775528978836, 5707.115397113413, 5741.827745167651, 5833.679147646878, 5855.912161317584, 5762.250907382056, 6280.434760871543, 6286.748530058792, 6310.400511951358, 6317.431431171217, 6223.719932602999, 6228.346023667495, 6297.559170858041, 6297.622142671455, 6299.834530611852, 5934.703264836741, 5997.790077347316, 6009.819705408845, 6011.19664100768, 6044.667543285118, 6339.11252424661, 6376.649284289316, 6386.936130638727, 6425.485961123192, 6426.35736426363, 6134.865219626364, 6178.938210617836, 6179.84550386244, 6258.217722784652, 6275.3724627536885, 6255.593386429868, 6302.968485157545, 6306.8423289417215, 6308.589941653778, 6332.050192471166, 6144.569880108439, 6165.260168779775, 6216.689165541705, 6296.244450665949, 6329.240491141092, 6198.318184999646, 6280.685965701697, 6288.968555157287, 6290.654014029273, 6304.936950630527, 4838.814490449862, 4961.776720047606, 4962.206047032458, 4963.751812409365, 5000.374953130859, 3615.663892665541, 4406.823727050882, 4417.293233082755, 4438.045820148619, 4492.9101417972115, 5853.1220316952795, 5894.263217097863, 5936.821895343147, 5989.8203053908455, 5999.370066143079, 6216.285127210429, 6303.149074874833, 6314.494840412756, 6317.336679648571, 6329.8734025320155, 5945.405459454107, 5977.880442391128, 6006.939930600725, 6011.458968692838, 6053.182820319289, 5407.729613519308, 5457.590680698884, 5476.17857321397, 5484.698341591171, 5498.422665620133, 6515.869682606227, 6520.967395163088, 6527.314631963671, 6534.869302613921, 6553.606783593, 6306.085617585516, 6315.210598675166, 6316.05259211122, 6319.431251187415, 6324.462420694109, 6242.906356404748, 6287.308396076336, 6318.778842740482, 6347.587406818542, 6448.097266382616, 6156.722947467464, 6189.35011823752, 6229.96885015572, 6233.314335423009, 6239.594426362272, 6091.543134264859, 6305.842353941005, 6307.400796924285, 6311.368863113652, 6337.809865704036, 6136.723847426966, 6143.389059649542, 6171.938280617316, 6184.752609895734, 6223.719932603134, 6174.073888916699, 6213.1922850029205, 6229.408206220402, 6271.090691849565, 6271.686415679106, 5942.851428714317, 6162.630242185483, 6166.352532984151, 6220.502359811114, 6228.283747369111, 6133.8855759873895, 6151.477124444473, 6160.829442405929, 6210.223722034746, 6256.374362563707, 6140.815775526741, 6167.938320616737, 6192.0711893214575, 6198.876022479435, 6200.627962322275], "value": 5781.474033903475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.6752111127767, 500.0074851419933, 517.5600739371534, 568.5678884048123, 573.3062993777529, 38648.806755966216, 41586.58413415865, 41820.372694409605, 41868.58131418681, 42633.50782722605, 32356.0, 37759.0, 43254.783726081405, 43740.781296093475, 43906.12187756251, 8217.465864718773, 8638.136186381344, 9286.0, 9572.090651388107, 9714.61141554337, 9666.371685840433, 9721.125098741111, 9756.365836220659, 9827.410355378664, 9847.064528869781, 9791.804163916717, 9833.950830245838, 9863.556139973718, 9867.358621689604, 9881.802363952753, 9769.316147869617, 9769.560369783378, 9801.754956126082, 9814.411135331831, 9820.607175712963, 8150.9184908150755, 9627.181689556395, 9629.614815407445, 9649.951750241204, 9650.0, 39858.60141398578, 40753.0, 40980.0, 41135.38296925549, 41139.0, 3209.0533292678715, 3244.545763593099, 3257.2833976525126, 3467.913302167466, 3660.970499746798, 29546.70453295461, 30353.241168970733, 31187.532187016986, 31198.688013119805, 31214.843925780453, 2330.3591512334074, 2664.214056853233, 2789.693133755285, 2806.4387122575567, 2809.5364264896266, 500801.0, 502085.0, 504705.95294046955, 505905.0, 507980.0, 36149.27701446009, 66144.66927665331, 66735.66632166808, 67070.66464667743, 67118.66440667862, 36183.819080904424, 37194.62805371966, 38200.61799382026, 38240.80879595584, 38263.2347353057, 15398.538043858702, 15614.609634758996, 15675.451359202374, 15689.450869219523, 15700.214989250491, 13103.934480327538, 13148.145370551076, 13157.94736421084, 13171.67070823237, 13208.33958302081, 8371.790705232417, 8384.664613415516, 8384.748457546284, 8438.451500652438, 8460.0, 3386.2888793353673, 3396.966030339665, 3403.4044042292994, 3411.3688967541084, 3448.620651728308, 850.0182289455565, 1005.2259759984771, 1035.2804800663616, 1047.4657924458581, 1140.7604403075447, 24799.5040099197, 29588.408231835674, 29633.407331853243, 30010.549841751956, 30679.0, 1026.6817286641017, 1088.2382332367374, 1092.1481244629201, 1092.6776600902754, 1118.8601424821898, 15460.037634941116, 15654.686906261812, 15801.446949356718, 15962.36150553988, 16166.75749863753, 583749.0, 665922.0, 668770.0, 674976.0, 678548.0, 51998.70003249949, 63777.0, 64338.67830660817, 64521.67739161368, 64578.0, 1281.5706738242793, 1429.4139402844783, 1442.7330943775435, 1495.1776522912355, 1495.9551213463612, 30236.395272093996, 30375.848120759256, 30545.694543055175, 30771.922982695498, 30859.382812344076, 55318.170227445815, 56193.15710264431, 56677.14984275157, 57170.71414643066, 58115.418845812696, 594819.0, 601503.0, 603026.0, 604798.0, 607967.0, 27896.44207115887, 27906.302342440787, 28342.716572834834, 28646.42707145887, 29056.7094329054, 38862.02844928899, 47541.762291189705, 48273.0, 48339.75830120827, 49139.26291105708, 32065.67934320627, 39361.40957885691, 39531.2093758129, 40540.797296013334, 40745.38881916714, 37974.62025379822, 38838.41742373923, 38868.6113138865, 39029.2194156121, 39152.608473914894, 38555.22889542249, 39450.40824387579, 39660.80169599134, 39694.22904824153, 40444.786656400356, 33007.66992330142, 34233.486497702055, 34773.652263477044, 34895.82552087223, 34960.650393495736, 25443.0, 25685.486290274464, 25787.226383208534, 25970.0, 26125.346866328495], "value": 19025.40592433039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.7934094929688, 392.4270564975136, 392.43489375299555, 395.926753550593, 420.495405513384, 14575.854241457582, 19933.700994485076, 20803.0, 21053.947302634882, 21176.682349764767, 20554.383368498933, 20714.689279660815, 21492.462688432795, 22205.333839984785, 22239.555208895814, 5733.512651424634, 6187.752489900398, 6216.067589861512, 6221.4400703936635, 6224.221972253468, 5950.404959504058, 6070.332263451039, 6105.419985101408, 6116.571839971204, 6192.225971753531, 6014.308354539218, 6145.047517634774, 6150.6924653767355, 6157.415045570664, 6165.907511387333, 5803.361630220694, 6146.416090471398, 6146.569740118149, 6162.137300777895, 6189.752409903621, 5329.7601607927345, 5337.412884582732, 5362.35651721792, 5367.141257398797, 5367.463253674601, 25280.87359563209, 26464.86767566169, 26730.866345668146, 26766.5985010225, 27160.0, 2241.5180736141806, 2277.6811246425514, 2298.0348253733473, 2353.9529209415887, 2515.3208633668805, 14999.625009374744, 15528.990615610062, 15652.921735391365, 15706.0, 15820.76268855968, 1728.3259528783785, 1772.787265528132, 1800.774903137108, 1819.2904767140826, 1860.2001139510005, 121012.0, 124046.75953240442, 124215.0, 124431.0, 127481.72518274792, 8945.37382383227, 11354.772904541864, 11529.077673786078, 11697.824532632018, 11774.764504709858, 14675.046122002035, 14717.705645887023, 14750.926245368704, 14753.557393278219, 14757.704845903021, 8819.29445644347, 8941.284697224206, 9007.189352958265, 9112.817743645222, 9127.634894604142, 9000.324975626723, 9020.503872287087, 9028.458292502472, 9115.225205857443, 9124.771880702903, 5768.307803063659, 6167.784127555514, 6422.036694495863, 7015.754448594377, 7052.717891284394, 1595.3060418717894, 1928.9680021188597, 1933.7679478462674, 1942.0969249298862, 1975.3185151122839, 925.2228128372066, 964.4695417520336, 1007.09361574582, 1021.2238698588977, 1073.951672174754, 18975.905120474585, 21497.892510537346, 21907.561848762936, 22022.33932982013, 22395.88802056012, 986.8125056239292, 1002.2433063037361, 1002.3384566186428, 1014.8071866345371, 1037.870266216723, 8792.856928599369, 10924.890751092546, 11392.145589080852, 11687.590934317428, 11755.353455560027, 620919.8954005201, 622181.0, 624047.0, 625732.0, 641011.0, 121669.0, 123512.0, 123756.0, 125973.37013314875, 127640.72359276474, 1059.814532456817, 1077.439731339699, 1128.1257025805066, 1153.9538418463335, 1158.5192145259791, 17787.73318400251, 18352.99058551739, 19305.420837374902, 19529.121189546222, 19693.40919772409, 49321.753391232814, 51471.74264128655, 52815.94368112539, 55801.44198557962, 56002.159967601336, 77605.0, 78700.09749268943, 78908.0, 79124.60437697775, 80226.59886700529, 7899.368050555827, 8089.110197878346, 8266.13205613426, 8312.875306870523, 8633.654653813777, 9398.95300523493, 11067.83398249043, 11335.433228338716, 12093.972012379047, 12475.750484990067, 9467.905320946702, 9616.855747163658, 9875.506224688594, 10830.241883068109, 11089.556417743523, 10709.3574385534, 10753.838692419464, 10820.080293174853, 10898.237123401286, 11027.889721103007, 5772.913406298825, 8433.0, 9915.801683966136, 10854.511546980248, 11117.998760222914, 8192.54940978252, 8226.218509241613, 8308.750737477885, 8312.916870831214, 8523.659053637784, 10483.266171367932, 10827.187960902806, 11001.22991390595, 11227.326360418401, 11233.55065797359], "value": 9860.233697245043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1606.510014445594, 1646.9505914822553, 1903.7525121734172, 1964.1323667445529, 1993.0732209522569, 8243.711470098548, 8337.999440067186, 8783.03386627472, 8798.944126704797, 8840.734777956664, 8071.23323284287, 8418.66325346987, 8958.731238062868, 9123.726288211348, 9163.35856490046, 2453.8036957043437, 2498.4253621666935, 2500.0749722602663, 2500.9374765630914, 2503.6244563315468, 2047.2834507922182, 2413.4690368119072, 2446.987765061172, 2477.752224777526, 2498.8250822442437, 2058.8764674119525, 2108.641530939744, 2444.841085329448, 2484.9751502484924, 2488.6515887775727, 2359.421941624296, 2437.4272046069073, 2443.853368797869, 2448.571499987495, 2450.8848473944404, 2442.3161514775893, 2442.68245128134, 2449.9265022049367, 2467.95064098717, 2478.083109249571, 9046.502442365667, 9155.908440915638, 9165.450072995642, 9172.0, 9174.495402752846, 4754.286856971447, 5446.455354464561, 5561.249231353756, 5586.16207568868, 5630.408807075239, 3585.6772890439725, 3611.747177697562, 3641.761800987677, 3683.3001729671546, 3701.1117331840433, 984.8867380251291, 1033.302520798458, 1037.8168887468291, 1038.2524582300732, 1041.3959903256093, 12154.756904861943, 12246.755064898653, 12281.938590307082, 12306.0, 12324.630261092181, 4952.925706114411, 4959.107360675113, 5062.4684408137355, 5099.5410413063, 5104.872378190501, 10218.233632477446, 10391.324563903323, 10417.218708596885, 10453.954604539636, 10467.895321046844, 5459.645123067067, 5505.587080968943, 5581.6371935824045, 5592.888142237132, 5607.186957891083, 4512.9774351128035, 4871.585915197248, 5011.924821127686, 5047.848564543069, 5112.71880046601, 4103.5691252418665, 4164.770937598461, 4196.685248606367, 4221.092465120014, 4235.5552666969525, 1361.748076605831, 1389.3608939887563, 1391.476333414909, 1393.5958572014003, 1395.1350162899068, 875.6978842299396, 915.6428992692855, 935.1490143969091, 946.5220063867703, 952.2953014769021, 5947.672877991666, 6058.333583305833, 6128.724207410677, 6154.569180157436, 6202.534809889276, 413.4418534977816, 452.9524399938025, 460.89860230749184, 470.78343961777966, 478.9856304310876, 3929.8035098244973, 3972.7219094663674, 4003.49606692471, 4031.75809451428, 4036.1725846201457, 10484.056434920742, 11027.503762330554, 11090.0, 11123.777524449466, 11188.160887933438, 10617.946910265398, 10845.132389408986, 10987.505562249718, 10997.230193886351, 11088.0, 8336.791580210544, 8369.916300837036, 8428.957855210685, 8430.957845210734, 8484.151584841466, 5714.714264286852, 5761.193432919311, 5845.976954032943, 5922.851928701817, 6019.638821670538, 9844.556994935101, 10207.540660670436, 10353.63762268317, 10374.948125259325, 10425.0, 10434.217433692655, 10456.058954694114, 10506.684799456028, 10573.9471302643, 10610.787784244427, 6523.836904077436, 7071.292870713093, 7703.922960770321, 7814.53112813233, 8517.318614510701, 8436.53599052058, 8557.0, 8568.828623427371, 8685.305175585812, 8691.261242794433, 5896.262967129109, 6937.750254918013, 7348.154962179204, 7580.469367144247, 7830.686772529035, 6920.723171073302, 6928.926016467455, 7284.198738138906, 7550.3582195514, 7606.163322034461, 7098.077249957472, 7170.354668079898, 7203.531770434905, 7252.673629686782, 7275.163356213891, 4810.7113573185725, 4836.975815120902, 4940.92588611164, 4966.404031516241, 5010.5240002199735, 4324.329728892027, 4979.712835561579, 4987.202047672355, 5129.256257842667, 5266.8946621068135], "value": 4613.2575538939345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.87447774023946, 310.8647738233868, 317.8188432593422, 330.6643756587066, 332.41660885146564, 4160.482907840758, 4896.8041278348865, 5210.114280572302, 5246.475352464751, 5411.969297193569, 5012.949870501303, 5230.686158830473, 5372.758225879835, 5423.267858839055, 5446.2647542581835, 4174.916501669964, 4184.184084103601, 4187.722744562907, 4201.143222608625, 4202.096549241913, 3221.710046095851, 3545.85816567337, 4183.393407955845, 4195.958040419602, 4224.936625950613, 4156.605122513356, 4172.5173521754505, 4181.121964387467, 4188.869005368549, 4201.579842015804, 3794.582595914447, 4121.793910304503, 4208.831646734127, 4208.873733787991, 4215.704900656955, 3289.802611843273, 3479.9130021749406, 3557.804320762357, 3673.779573225615, 3702.852115844097, 4866.610671146335, 5007.39911210657, 5221.634485586011, 5240.659357141812, 5284.973575132138, 4064.959350406488, 4277.593628605277, 4659.277811939155, 4804.615630749532, 4834.323194752737, 4640.67515273931, 4807.557732680217, 5760.798372056958, 5780.335261444946, 5936.426846885579, 3202.439573074703, 3419.863205471778, 3543.8228088595465, 3766.830492627811, 4142.08874047709, 5975.759090341122, 6043.365446628129, 6044.4888777805545, 6049.516038716892, 6059.666718330534, 3596.352656521799, 3642.8542858285373, 3985.661218796377, 4104.589541045872, 4166.895827604334, 3918.3730603103368, 4180.077164504362, 4181.038361176947, 4189.413482112506, 4216.039541613165, 4092.815823287959, 4575.656825738083, 4779.378680771478, 4793.832215872428, 4798.712077275374, 5486.561075113981, 5816.720321529257, 5823.883522329615, 5846.912296315559, 5848.2397288353095, 1154.1978325064, 1333.6332508560224, 1373.9695228578566, 1428.599992002241, 1429.4776063492347, 873.8334323677834, 880.8854848869606, 885.1104639837051, 901.378049146087, 902.1294450854956, 4118.887900266911, 4592.012717265804, 4611.610466307261, 4613.838515651936, 4661.974365639554, 4355.782210889506, 4430.62339701129, 4500.302453119772, 4870.909899155391, 4999.700017998932, 4178.310578754532, 4196.475440569929, 4475.142815731435, 4584.0, 4623.3587076588, 2034.8168664820237, 2356.5286942611547, 2385.7614238576352, 2635.0118705485443, 2672.6124711916664, 4669.128485981098, 4772.448954089865, 5036.697798132123, 5226.529612334908, 5265.262863199156, 4655.0, 4783.904321913542, 4809.78355973982, 5163.793448262102, 5200.115980283361, 5300.443453437333, 5746.856328591819, 6263.96868015657, 6284.277308109443, 6285.842853928689, 5022.447530771686, 5101.8724531887, 5424.806542560788, 6070.544709146743, 6163.9383606163365, 5367.865803354947, 5639.590102474382, 5927.288725352985, 5931.555133364928, 5936.7922122725495, 5206.401263854554, 5432.891342173213, 5700.942990570207, 6170.321264660974, 6259.78090766821, 3720.925581488409, 3999.3800960851113, 4017.216642754635, 4018.5579586246076, 4088.6081642239624, 5553.111502159635, 5649.661020338793, 5958.940410595838, 5971.611845230046, 5989.670568118621, 5095.827959569246, 5179.938112686966, 5542.889142217053, 5579.972100139474, 5582.5533957282505, 4917.557419832232, 5079.949200507947, 5496.642718223303, 5792.768289268551, 5862.648241105377, 5093.904810465768, 5104.744762761773, 5639.238702775073, 5695.686737229492, 5742.253507044057, 5472.945270547243, 5601.29983752031, 5755.942440575541, 5765.549641402304, 5784.2191304175085, 4536.841210557746, 5002.6498145130945, 5657.0, 5706.42935706426, 5838.678872662044], "value": 4055.2466167230154, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.5471351750421, 689.9995007239503, 694.3299715774277, 742.4580056558708, 778.0624347661069, 14199.71600567988, 14242.28788560573, 14384.784228236571, 14999.625009374757, 15479.767803482957, 14235.501757438491, 14337.0, 14349.928250358733, 14356.353964071615, 14370.640733981656, 730.9415246780271, 904.4618452021037, 914.4741773480259, 924.6301479408223, 930.6417029443645, 932.1377725603824, 934.4953724988499, 935.6631612619485, 935.9298052646045, 938.3478482454697, 985.9992108010375, 987.3088837813559, 988.2588058955783, 988.3081842710096, 990.529498488216, 966.970990870275, 968.5012218707371, 980.8185485685135, 983.5623147699313, 985.6156099121348, 694.3785312145652, 696.4532841719226, 719.9388052015585, 728.1226122522401, 731.9670614822346, 14497.637559061108, 14713.0, 14756.48352307664, 14783.965122441434, 14786.03890747098, 12382.504699812087, 12547.811782823264, 12548.81176782349, 12604.558840440632, 12779.744405111938, 9959.402435853872, 10032.799344013152, 10046.4474453905, 10323.84514232287, 10449.164066874631, 2669.7730692891128, 3209.8876539321013, 3335.533025376449, 3401.8299085045896, 3513.7013353864954, 60272.794544108874, 75025.24974750237, 83814.0, 83958.16041839565, 86181.56909215476, 9274.860877086849, 9408.106229908148, 9504.714858554255, 9718.368306060083, 9819.165370943407, 21621.0, 22068.668969965463, 22149.778502215093, 22345.0, 22357.0, 7343.596102214323, 7389.963050184715, 7391.260873912674, 7520.0, 7525.4355923305975, 3934.1738234970976, 3941.5664276929515, 3965.0682089708894, 4002.1195337025806, 4043.838246470167, 3537.5224344713647, 3549.1836877518317, 3579.391503444421, 3636.3272794533095, 3650.379435495972, 612.3600837125126, 622.1165944359021, 627.2786295759913, 634.2262439823409, 637.1430426076919, 520.232656831171, 563.312758434709, 583.877385748989, 587.8706684529396, 606.8500192135878, 5939.316978547522, 7763.340116090083, 7907.920920790719, 7939.960300198462, 8003.119656837744, 573.6185436684641, 586.9236999190078, 590.5865893874305, 597.4473611908967, 612.9233845769279, 3595.7662751921043, 3733.439984002367, 3755.9436608450897, 3932.8230229639735, 3950.2692001979726, 74928.0, 78432.60783696045, 81832.0, 84263.57868210619, 93836.53081734548, 60335.79328413407, 71437.2856271441, 73235.63382183161, 80799.19200808034, 82132.4294056474, 67512.66243668848, 68191.0, 68653.65673171602, 69374.98125243686, 69732.0, 8024.358051355995, 8126.75619731409, 8211.21993410625, 8252.05101413345, 8291.129431409856, 32356.058636481885, 42752.358714618684, 43468.91327716832, 44219.33670995002, 44832.551674484144, 61255.77488450295, 61999.38000619936, 64148.358516414235, 64264.0, 65375.34624653692, 10915.83626245591, 10940.0, 11349.943250283695, 11357.318560886373, 11689.941550292195, 12001.099917506048, 12084.818727719266, 12322.199057061262, 12359.32023738667, 12484.500619975463, 10542.736431589272, 10889.727756806144, 10899.891001090205, 11004.614838480615, 11742.236754610924, 11166.1067114629, 11573.18987670855, 11630.127740419333, 11682.824757628472, 11754.177207595387, 9187.0, 10941.781164376827, 11123.443827808738, 11125.666230013112, 11397.373144477133, 8013.7979303105, 9126.49804260772, 9340.299477539347, 9362.578683959102, 10705.785884282426, 9642.566084526354, 9673.177779888789, 9731.483508827814, 9844.753881153028, 9931.503424828874], "value": 6780.1947339456865, "unit": "score"}], "name": "dromaeo_css", "value": 7185.029847176416, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 112.0, 115.0, 116.0, 113.0, 107.0, 116.0, 118.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 145.0, 197.0, 118.0, 140.0, 151.0, 119.0, 181.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 209.0, 239.0, 195.0, 199.0, 200.0, 202.0, 205.0, 198.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 79.0, 181.0, 84.0, 83.0, 88.0, 99.0, 121.0, 84.0], "value": 97.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 144.0, 79.0, 81.0, 84.0, 119.0, 82.0, 82.0, 125.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 86.0, 88.0, 85.0, 93.0, 121.0, 89.0, 99.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 94.0, 94.0, 108.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 90.0, 89.0, 92.0, 89.0, 90.0, 91.0, 90.0, 91.0], "value": 89.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 61.0, 62.0, 61.0, 61.0, 81.0, 172.0, 58.0, 53.0], "value": 72.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 67.0, 79.0, 79.0, 77.0, 82.0, 67.0, 59.0, 80.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 87.0, 86.0, 89.0, 85.0, 86.0, 88.0, 90.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 162.0, 149.0, 141.0, 153.0, 167.0, 154.0, 152.0, 203.0, 166.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 210.0, 176.0, 168.0, 195.0, 235.0, 165.0, 232.0, 210.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 69.0, 75.0, 98.0, 87.0, 81.0, 68.0, 67.0, 81.0], "value": 76.5, "unit": "score"}], "name": "kraken", "value": 1598.1999999999998, "alertThreshold": 2.0}]} 05:25:03 INFO - Return code: 0 05:25:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:25:03 ERROR - # TBPL SUCCESS # 05:25:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:03 INFO - 2016-05-11 05:25:03.962 screenresolution[2212:13751] starting screenresolution argv=screenresolution get 05:25:03 INFO - 2016-05-11 05:25:03.965 screenresolution[2212:13751] Display 0: 1600x1200x32@60 05:25:03 INFO - 2016-05-11 05:25:03.971 screenresolution[2213:13754] starting screenresolution argv=screenresolution list 05:25:03 INFO - Available Modes on Display 0 05:25:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:04 INFO - Intel Iris: 05:25:04 INFO - Chipset Model: Intel Iris 05:25:04 INFO - Type: GPU 05:25:04 INFO - Bus: Built-In 05:25:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:04 INFO - Vendor: Intel (0x8086) 05:25:04 INFO - Device ID: 0x0a2e 05:25:04 INFO - Revision ID: 0x0009 05:25:04 INFO - Displays: 05:25:04 INFO - Display: 05:25:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:04 INFO - Display Serial Number: 1600x1200 60 05:25:04 INFO - Main Display: Yes 05:25:04 INFO - Mirror: Off 05:25:04 INFO - Online: Yes 05:25:04 INFO - Rotation: Supported 05:25:04 INFO - Return code: 0 05:25:04 INFO - Running post-action listener: _resource_record_post_action 05:25:04 INFO - ##### 05:25:04 INFO - ##### Finished run-tests step (success) 05:25:04 INFO - ##### 05:25:04 INFO - Running post-run listener: _resource_record_post_run 05:25:05 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1716224; Write bytes: 699087872; Read time: 21; Write time: 2075 05:25:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394768896; Write bytes: 398380032; Read time: 29639; Write time: 841 05:25:05 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1716224; Write bytes: 299859968; Read time: 21; Write time: 1231 05:25:05 INFO - Running post-run listener: _upload_blobber_files 05:25:05 INFO - Blob upload gear active. 05:25:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:25:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:06 INFO - (blobuploader) - INFO - Done attempting. 05:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:07 INFO - (blobuploader) - INFO - Done attempting. 05:25:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:07 INFO - Return code: 0 05:25:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf21fa366fe4c78a033075ee8dfd97418c986a12169aafcffe1acadf0e7e4d0e96e57d4a19bf3b42f10320526d58558cbdd6191e0eebda36573616bc77959f89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2ae197e52800d7529d674ad5000a106a622c3398089d19a7a4c124cf09fc40723d89b533ee80430a216450890512497f9a8f6e7931f8c65edf831275883335"} 05:25:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:07 INFO - Contents: 05:25:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf21fa366fe4c78a033075ee8dfd97418c986a12169aafcffe1acadf0e7e4d0e96e57d4a19bf3b42f10320526d58558cbdd6191e0eebda36573616bc77959f89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2ae197e52800d7529d674ad5000a106a622c3398089d19a7a4c124cf09fc40723d89b533ee80430a216450890512497f9a8f6e7931f8c65edf831275883335"} 05:25:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:07 INFO - Copying logs to upload dir... 05:25:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.340993 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-11 05:25:07.532547) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:07.535975) ========= 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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf21fa366fe4c78a033075ee8dfd97418c986a12169aafcffe1acadf0e7e4d0e96e57d4a19bf3b42f10320526d58558cbdd6191e0eebda36573616bc77959f89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2ae197e52800d7529d674ad5000a106a622c3398089d19a7a4c124cf09fc40723d89b533ee80430a216450890512497f9a8f6e7931f8c65edf831275883335"} build_url:https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010866 build_url: 'https://queue.taskcluster.net/v1/task/F-RHOoK_TVK_pj0ft3ad_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf21fa366fe4c78a033075ee8dfd97418c986a12169aafcffe1acadf0e7e4d0e96e57d4a19bf3b42f10320526d58558cbdd6191e0eebda36573616bc77959f89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad2ae197e52800d7529d674ad5000a106a622c3398089d19a7a4c124cf09fc40723d89b533ee80430a216450890512497f9a8f6e7931f8c65edf831275883335"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:07.564576) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:07.565217) ========= 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.nnS5C1DyMt/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.Y4LCXOdZgq/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005283 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 05:25:07.618054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 05:25:07.618776) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 05:25:07.619517) ========= ========= Total master_lag: 0.17 =========