builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0094 starttime: 1462104648.53 results: success (0) buildid: 20160501030217 builduid: d628f44785ef404384c251c21ad92714 revision: 1461a4071341c282afcf7b72e33036412d2251d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:48.532614) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:48.533338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:48.533618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.178974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.179366) ========= 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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-01 05:10:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2016-05-01 05:10:49 (14.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145563 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.476543) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.476854) ========= 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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.019650 ========= master_lag: 0.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.652349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.652705) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1461a4071341c282afcf7b72e33036412d2251d4 --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 1461a4071341c282afcf7b72e33036412d2251d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-01 05:10:49,745 truncating revision to first 12 chars 2016-05-01 05:10:49,746 Setting DEBUG logging. 2016-05-01 05:10:49,746 attempt 1/10 2016-05-01 05:10:49,746 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1461a4071341?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-01 05:10:50,345 unpacking tar archive at: mozilla-central-1461a4071341/testing/mozharness/ program finished with exit code 0 elapsedTime=0.850468 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.633841) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.634152) ========= script_repo_revision: 1461a4071341c282afcf7b72e33036412d2251d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.634563) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.634897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:51.128354) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2016-05-01 05:10:51.129022) ========= /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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:10:51 INFO - MultiFileLogger online at 20160501 05:10:51 in /builds/slave/test 05:10:51 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:10:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:51 INFO - {'append_to_log': False, 05:10:51 INFO - 'base_work_dir': '/builds/slave/test', 05:10:51 INFO - 'blob_upload_branch': 'Firefox', 05:10:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:51 INFO - 'branch': 'Firefox', 05:10:51 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:51 INFO - 'config_files': ('talos/mac_config.py',), 05:10:51 INFO - 'default_actions': ('clobber', 05:10:51 INFO - 'read-buildbot-config', 05:10:51 INFO - 'download-and-extract', 05:10:51 INFO - 'populate-webroot', 05:10:51 INFO - 'create-virtualenv', 05:10:51 INFO - 'install', 05:10:51 INFO - 'run-tests'), 05:10:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:51 INFO - 'download_minidump_stackwalk': True, 05:10:51 INFO - 'download_symbols': 'ondemand', 05:10:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:51 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:51 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:51 INFO - 'installer_path': 'installer.exe', 05:10:51 INFO - 'log_level': 'info', 05:10:51 INFO - 'log_name': 'talos', 05:10:51 INFO - 'log_to_console': True, 05:10:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:51 INFO - 'opt_config_files': (), 05:10:51 INFO - 'pip_index': False, 05:10:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:51 INFO - 'cmd': ('bash', 05:10:51 INFO - '-c', 05:10:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:51 INFO - 'enabled': True, 05:10:51 INFO - 'halt_on_failure': False, 05:10:51 INFO - 'name': 'check_screen_resolution'},), 05:10:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:51 INFO - 'cmd': ('bash', 05:10:51 INFO - '-c', 05:10:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:51 INFO - 'enabled': True, 05:10:51 INFO - 'halt_on_failure': False, 05:10:51 INFO - 'name': 'check_screen_resolution'},), 05:10:51 INFO - 'run_cmd_checks_enabled': True, 05:10:51 INFO - 'sps_profile': False, 05:10:51 INFO - 'sps_profile_interval': 0, 05:10:51 INFO - 'suite': 'dromaeojs-e10s', 05:10:51 INFO - 'system_bits': '32', 05:10:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:51 INFO - 'title': 't-yosemite-r7-0094', 05:10:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:51 INFO - 'use_talos_json': True, 05:10:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:51 INFO - 'work_dir': 'build'} 05:10:51 INFO - ##### 05:10:51 INFO - ##### Running clobber step. 05:10:51 INFO - ##### 05:10:51 INFO - Running pre-action listener: _resource_record_pre_action 05:10:51 INFO - Running main action method: clobber 05:10:51 INFO - rmtree: /builds/slave/test/build 05:10:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:52 INFO - Running post-action listener: _resource_record_post_action 05:10:52 INFO - ##### 05:10:52 INFO - ##### Running read-buildbot-config step. 05:10:52 INFO - ##### 05:10:52 INFO - Running pre-action listener: _resource_record_pre_action 05:10:52 INFO - Running main action method: read_buildbot_config 05:10:52 INFO - Using buildbot properties: 05:10:52 INFO - { 05:10:52 INFO - "project": "", 05:10:52 INFO - "product": "firefox", 05:10:52 INFO - "script_repo_revision": "production", 05:10:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:10:52 INFO - "repository": "", 05:10:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:10:52 INFO - "buildid": "20160501030217", 05:10:52 INFO - "stage_platform": "macosx64", 05:10:52 INFO - "basedir": "/builds/slave/test", 05:10:52 INFO - "nightly_build": "True", 05:10:52 INFO - "buildnumber": 219, 05:10:52 INFO - "slavename": "t-yosemite-r7-0094", 05:10:52 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:10:52 INFO - "platform": "yosemite_r7", 05:10:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:10:52 INFO - "branch": "mozilla-central", 05:10:52 INFO - "repo_path": "mozilla-central", 05:10:52 INFO - "revision": "1461a4071341c282afcf7b72e33036412d2251d4", 05:10:52 INFO - "pgo_build": "False", 05:10:52 INFO - "builduid": "d628f44785ef404384c251c21ad92714", 05:10:52 INFO - "slavebuilddir": "test" 05:10:52 INFO - } 05:10:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:10:52 INFO - Running post-action listener: _resource_record_post_action 05:10:52 INFO - ##### 05:10:52 INFO - ##### Running download-and-extract step. 05:10:52 INFO - ##### 05:10:52 INFO - Running pre-action listener: _resource_record_pre_action 05:10:52 INFO - Running main action method: download_and_extract 05:10:52 INFO - mkdir: /builds/slave/test/build/tests 05:10:52 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:10:52 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:10:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:11:23 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:11:23 INFO - retry: attempt #1 caught exception: timed out 05:11:23 INFO - retry: Failed, sleeping 30 seconds before retrying 05:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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 #2 05:12:14 INFO - Downloaded 1199 bytes. 05:12:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:14 INFO - Using the following test package requirements: 05:12:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:12:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:12:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:12:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'jsshell-mac.zip'], 05:12:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:12:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:12:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:12:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:12:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:12:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:12:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:12:14 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:12:14 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:12:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:16 INFO - Downloaded 18127869 bytes. 05:12:16 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:12:16 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:12:19 INFO - Return code: 0 05:12:19 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:12:19 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:12:19 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:12:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:22 INFO - Downloaded 18127869 bytes. 05:12:22 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:12:22 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:12:25 INFO - Return code: 0 05:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:25 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:12:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:12:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:12:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:26 INFO - Downloaded 10840152 bytes. 05:12:26 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:12:26 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:12:27 INFO - Return code: 0 05:12: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:12:27 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:12:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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:12:32 INFO - Downloaded 110657922 bytes. 05:12:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:32 INFO - mkdir: /builds/slave/test/properties 05:12:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:32 INFO - Contents: 05:12:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:34 INFO - Running post-action listener: _resource_record_post_action 05:12:34 INFO - Running post-action listener: set_extra_try_arguments 05:12:34 INFO - ##### 05:12:34 INFO - ##### Running populate-webroot step. 05:12:34 INFO - ##### 05:12:34 INFO - Running pre-action listener: _resource_record_pre_action 05:12:34 INFO - Running main action method: populate_webroot 05:12:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:34 INFO - '--tppagecycles', 05:12:34 INFO - '7'], 05:12:34 INFO - 'tests': ['tsvgm']}}, 05:12:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['damp', 'tps']}, 05:12:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'tests': ['damp', 'tps']}, 05:12:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['dromaeo_dom']}, 05:12:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['a11yr', 05:12:34 INFO - 'ts_paint', 05:12:34 INFO - 'tpaint', 05:12:34 INFO - 'sessionrestore', 05:12:34 INFO - 'sessionrestore_no_auto_restore', 05:12:34 INFO - 'tabpaint']}, 05:12:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['tsvgx', 05:12:34 INFO - 'tsvgr_opacity', 05:12:34 INFO - 'tart', 05:12:34 INFO - 'tscrollx', 05:12:34 INFO - 'cart']}, 05:12:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:34 INFO - 'tsvgr_opacity', 05:12:34 INFO - 'tart', 05:12:34 INFO - 'tscrollx', 05:12:34 INFO - 'cart']}, 05:12:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'talos_options': ['--disable-e10s'], 05:12:34 INFO - 'tests': ['tp5o']}, 05:12:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'tests': ['tp5o']}, 05:12:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'talos_options': ['--disable-e10s', 05:12:34 INFO - '--xperf_path', 05:12:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:34 INFO - 'tests': ['tp5n']}, 05:12:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:12:34 INFO - 'talos_options': ['--xperf_path', 05:12:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:34 INFO - 'tests': ['tp5n']}}, 05:12:34 INFO - 'talos.zip': {'path': '', 05:12:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:34 INFO - Running post-action listener: _resource_record_post_action 05:12:34 INFO - ##### 05:12:34 INFO - ##### Running create-virtualenv step. 05:12:34 INFO - ##### 05:12:34 INFO - Running pre-action listener: _resource_record_pre_action 05:12:34 INFO - Running main action method: create_virtualenv 05:12:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12:34 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:12:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:34 INFO - Using real prefix '/tools/python27' 05:12:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:37 INFO - Installing pip.................done. 05:12:37 INFO - Return code: 0 05:12:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:12:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:37 INFO - 'HOME': '/Users/cltbld', 05:12:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:37 INFO - 'LOGNAME': 'cltbld', 05:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:37 INFO - 'MOZ_NO_REMOTE': '1', 05:12:37 INFO - 'NO_EM_RESTART': '1', 05:12:37 INFO - 'PAGER': '/bin/cat', 05:12:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:37 INFO - 'PWD': '/builds/slave/test', 05:12:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:37 INFO - 'SHELL': '/bin/bash', 05:12:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:37 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:37 INFO - 'USER': 'cltbld', 05:12:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:37 INFO - 'XPC_FLAGS': '0x0', 05:12:37 INFO - 'XPC_SERVICE_NAME': '0', 05:12:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:38 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:12:38 INFO - Cleaning up... 05:12:38 INFO - Return code: 0 05:12:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:38 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:38 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:12:38 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:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:38 INFO - 'HOME': '/Users/cltbld', 05:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:38 INFO - 'LOGNAME': 'cltbld', 05:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:38 INFO - 'MOZ_NO_REMOTE': '1', 05:12:38 INFO - 'NO_EM_RESTART': '1', 05:12:38 INFO - 'PAGER': '/bin/cat', 05:12:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:38 INFO - 'PWD': '/builds/slave/test', 05:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:38 INFO - 'SHELL': '/bin/bash', 05:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:38 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:38 INFO - 'USER': 'cltbld', 05:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:38 INFO - 'XPC_FLAGS': '0x0', 05:12:38 INFO - 'XPC_SERVICE_NAME': '0', 05:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:38 INFO - Downloading/unpacking psutil>=0.7.1 05:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:41 INFO - Installing collected packages: psutil 05:12:41 INFO - Running setup.py install for psutil 05:12:41 INFO - building 'psutil._psutil_osx' extension 05:12:41 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:12:41 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:12:41 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:12:42 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:12:42 INFO - building 'psutil._psutil_posix' extension 05:12:42 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:12:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:42 INFO - ^ 05:12:42 INFO - 1 warning generated. 05:12:42 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:12:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:42 INFO - Successfully installed psutil 05:12:42 INFO - Cleaning up... 05:12:42 INFO - Return code: 0 05:12:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:12:42 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:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:42 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:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:42 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:42 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:12:42 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:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:42 INFO - 'HOME': '/Users/cltbld', 05:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:42 INFO - 'LOGNAME': 'cltbld', 05:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:42 INFO - 'MOZ_NO_REMOTE': '1', 05:12:42 INFO - 'NO_EM_RESTART': '1', 05:12:42 INFO - 'PAGER': '/bin/cat', 05:12:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:42 INFO - 'PWD': '/builds/slave/test', 05:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:42 INFO - 'SHELL': '/bin/bash', 05:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:42 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:42 INFO - 'USER': 'cltbld', 05:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:42 INFO - 'XPC_FLAGS': '0x0', 05:12:42 INFO - 'XPC_SERVICE_NAME': '0', 05:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:12:45 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:12:45 INFO - Installing collected packages: mozsystemmonitor 05:12:45 INFO - Running setup.py install for mozsystemmonitor 05:12:45 INFO - Successfully installed mozsystemmonitor 05:12:45 INFO - Cleaning up... 05:12:45 INFO - Return code: 0 05:12:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:12: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:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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', '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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:12: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 blobuploader==1.2.4 05:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:45 INFO - 'HOME': '/Users/cltbld', 05:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:45 INFO - 'LOGNAME': 'cltbld', 05:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:45 INFO - 'MOZ_NO_REMOTE': '1', 05:12:45 INFO - 'NO_EM_RESTART': '1', 05:12:45 INFO - 'PAGER': '/bin/cat', 05:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:45 INFO - 'PWD': '/builds/slave/test', 05:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:45 INFO - 'SHELL': '/bin/bash', 05:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:45 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:45 INFO - 'USER': 'cltbld', 05:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:45 INFO - 'XPC_FLAGS': '0x0', 05:12:45 INFO - 'XPC_SERVICE_NAME': '0', 05:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:12: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:12: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:12: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:12: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:12: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:12: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:12:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:12:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:12: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:12: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:12: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:12: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:12: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:12: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:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:12:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:12: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:12: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:12: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:12: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:12: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:12: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:12:49 INFO - Downloading docopt-0.6.1.tar.gz 05:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:12:49 INFO - Installing collected packages: blobuploader, requests, docopt 05:12:49 INFO - Running setup.py install for blobuploader 05:12:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:12:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:12:49 INFO - Running setup.py install for requests 05:12:49 INFO - Running setup.py install for docopt 05:12:50 INFO - Successfully installed blobuploader requests docopt 05:12:50 INFO - Cleaning up... 05:12:50 INFO - Return code: 0 05:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:50 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:12:50 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:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:50 INFO - 'HOME': '/Users/cltbld', 05:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:50 INFO - 'LOGNAME': 'cltbld', 05:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:50 INFO - 'MOZ_NO_REMOTE': '1', 05:12:50 INFO - 'NO_EM_RESTART': '1', 05:12:50 INFO - 'PAGER': '/bin/cat', 05:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:50 INFO - 'PWD': '/builds/slave/test', 05:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:50 INFO - 'SHELL': '/bin/bash', 05:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:50 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:50 INFO - 'USER': 'cltbld', 05:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:50 INFO - 'XPC_FLAGS': '0x0', 05:12:50 INFO - 'XPC_SERVICE_NAME': '0', 05:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-JXEjrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-EFiwWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-T7MYta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-h4Wgfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-miT_fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:50 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xrKH4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CqQ7UE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xkMETJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3Z6ImE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-u8CEZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-DINgkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-HGXaFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_I67Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-S3qp19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0_ZGlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-O2uSjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-pZTKcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:12:52 INFO - Running setup.py install for manifestparser 05:12:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:12:52 INFO - Running setup.py install for mozcrash 05:12:52 INFO - Running setup.py install for mozdebug 05:12:52 INFO - Running setup.py install for mozdevice 05:12:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:12:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:12:52 INFO - Running setup.py install for mozfile 05:12:52 INFO - Running setup.py install for mozhttpd 05:12:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Running setup.py install for mozinfo 05:12:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Running setup.py install for mozInstall 05:12:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Running setup.py install for mozleak 05:12:53 INFO - Running setup.py install for mozlog 05:12:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Running setup.py install for moznetwork 05:12:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:12:53 INFO - Running setup.py install for mozprocess 05:12:53 INFO - Running setup.py install for mozprofile 05:12:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:12:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:12:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:12:54 INFO - Running setup.py install for mozrunner 05:12:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:12:54 INFO - Running setup.py install for mozscreenshot 05:12:54 INFO - Running setup.py install for moztest 05:12:54 INFO - Running setup.py install for mozversion 05:12:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:12:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:12:54 INFO - Cleaning up... 05:12:54 INFO - Return code: 0 05:12:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:54 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:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:54 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:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:54 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:54 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:12:54 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:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:54 INFO - 'HOME': '/Users/cltbld', 05:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:54 INFO - 'LOGNAME': 'cltbld', 05:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:54 INFO - 'MOZ_NO_REMOTE': '1', 05:12:54 INFO - 'NO_EM_RESTART': '1', 05:12:54 INFO - 'PAGER': '/bin/cat', 05:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:54 INFO - 'PWD': '/builds/slave/test', 05:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:54 INFO - 'SHELL': '/bin/bash', 05:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:54 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:54 INFO - 'USER': 'cltbld', 05:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:54 INFO - 'XPC_FLAGS': '0x0', 05:12:54 INFO - 'XPC_SERVICE_NAME': '0', 05:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ztMMW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-nQajsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZWVYpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Zw6KzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-d0CwlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-sD47wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-V1ZIgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Gbe_f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BqOccG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:55 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:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-OwYXtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-YUpYzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-cqDdUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Oqdi5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xZGdBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fdQny4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-WAdOPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:56 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:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-IGquZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:59 INFO - Downloading blessings-1.6.tar.gz 05:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:12:59 INFO - Installing collected packages: blessings 05:12:59 INFO - Running setup.py install for blessings 05:12:59 INFO - Successfully installed blessings 05:12:59 INFO - Cleaning up... 05:12:59 INFO - Return code: 0 05:12:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:59 INFO - Reading from file tmpfile_stdout 05:12:59 INFO - Current package versions: 05:12:59 INFO - blessings == 1.6 05:12:59 INFO - blobuploader == 1.2.4 05:12:59 INFO - docopt == 0.6.1 05:12:59 INFO - manifestparser == 1.1 05:12:59 INFO - mozInstall == 1.12 05:12:59 INFO - mozcrash == 0.17 05:12:59 INFO - mozdebug == 0.1 05:12:59 INFO - mozdevice == 0.48 05:12:59 INFO - mozfile == 1.2 05:12:59 INFO - mozhttpd == 0.7 05:12:59 INFO - mozinfo == 0.9 05:12:59 INFO - mozleak == 0.1 05:12:59 INFO - mozlog == 3.1 05:12:59 INFO - moznetwork == 0.27 05:12:59 INFO - mozprocess == 0.22 05:12:59 INFO - mozprofile == 0.28 05:12:59 INFO - mozrunner == 6.11 05:12:59 INFO - mozscreenshot == 0.1 05:12:59 INFO - mozsystemmonitor == 0.0 05:12:59 INFO - moztest == 0.7 05:12:59 INFO - mozversion == 1.4 05:12:59 INFO - psutil == 3.1.1 05:12:59 INFO - requests == 1.2.3 05:12:59 INFO - wsgiref == 0.1.2 05:12:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12: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:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:59 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:12:59 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:12:59 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:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:59 INFO - 'HOME': '/Users/cltbld', 05:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:59 INFO - 'LOGNAME': 'cltbld', 05:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:59 INFO - 'MOZ_NO_REMOTE': '1', 05:12:59 INFO - 'NO_EM_RESTART': '1', 05:12:59 INFO - 'PAGER': '/bin/cat', 05:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:59 INFO - 'PWD': '/builds/slave/test', 05:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:59 INFO - 'SHELL': '/bin/bash', 05:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:12:59 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:59 INFO - 'USER': 'cltbld', 05:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:59 INFO - 'XPC_FLAGS': '0x0', 05:12:59 INFO - 'XPC_SERVICE_NAME': '0', 05:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 INFO - Cleaning up... 05:13:00 INFO - Return code: 0 05:13:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:00 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 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:13:00 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:13:00 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:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:00 INFO - 'HOME': '/Users/cltbld', 05:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:00 INFO - 'LOGNAME': 'cltbld', 05:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:00 INFO - 'MOZ_NO_REMOTE': '1', 05:13:00 INFO - 'NO_EM_RESTART': '1', 05:13:00 INFO - 'PAGER': '/bin/cat', 05:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:00 INFO - 'PWD': '/builds/slave/test', 05:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:00 INFO - 'SHELL': '/bin/bash', 05:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:13:00 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:00 INFO - 'USER': 'cltbld', 05:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:00 INFO - 'XPC_FLAGS': '0x0', 05:13:00 INFO - 'XPC_SERVICE_NAME': '0', 05:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:00 INFO - Downloading/unpacking jsonschema 05:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:02 INFO - Downloading/unpacking functools32 (from jsonschema) 05:13:02 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:13:02 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:13:02 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:13:02 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:13:02 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:13:02 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:13:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:03 INFO - warning: no files found matching '*.txt' 05:13:03 INFO - no previously-included directories found matching 'build' 05:13:03 INFO - no previously-included directories found matching 'dist' 05:13:03 INFO - no previously-included directories found matching '.git*' 05:13:03 INFO - Installing collected packages: jsonschema, functools32 05:13:03 INFO - Running setup.py install for functools32 05:13:03 INFO - warning: no files found matching '*.txt' 05:13:03 INFO - no previously-included directories found matching 'build' 05:13:03 INFO - no previously-included directories found matching 'dist' 05:13:03 INFO - no previously-included directories found matching '.git*' 05:13:03 INFO - Successfully installed jsonschema functools32 05:13:03 INFO - Cleaning up... 05:13:03 INFO - Return code: 0 05:13:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b17aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b18a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa93a5644d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, '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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:03 INFO - 'HOME': '/Users/cltbld', 05:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:03 INFO - 'LOGNAME': 'cltbld', 05:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:03 INFO - 'MOZ_NO_REMOTE': '1', 05:13:03 INFO - 'NO_EM_RESTART': '1', 05:13:03 INFO - 'PAGER': '/bin/cat', 05:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:03 INFO - 'PWD': '/builds/slave/test', 05:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:03 INFO - 'SHELL': '/bin/bash', 05:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:13:03 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:03 INFO - 'USER': 'cltbld', 05:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:03 INFO - 'XPC_FLAGS': '0x0', 05:13:03 INFO - 'XPC_SERVICE_NAME': '0', 05:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:03 INFO - Downloading/unpacking flake8 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:13:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:13:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:07 INFO - no previously-included directories found matching 'docs/_build' 05:13:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:13:07 INFO - Running setup.py install for pep8 05:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:07 INFO - no previously-included directories found matching 'docs/_build' 05:13:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:13:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:13:07 INFO - Cleaning up... 05:13:07 INFO - Return code: 0 05:13:07 INFO - Running post-action listener: _resource_record_post_action 05:13:07 INFO - Running post-action listener: _start_resource_monitoring 05:13:07 INFO - Starting resource monitoring. 05:13:07 INFO - ##### 05:13:07 INFO - ##### Running install step. 05:13:07 INFO - ##### 05:13:07 INFO - Running pre-action listener: _resource_record_pre_action 05:13:07 INFO - Running main action method: install 05:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:08 INFO - Reading from file tmpfile_stdout 05:13:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:08 INFO - Reading from file tmpfile_stdout 05:13:08 INFO - Output received: 05:13:08 INFO - Usage: mozinstall [options] installer 05:13:08 INFO - Options: 05:13:08 INFO - -h, --help show this help message and exit 05:13:08 INFO - -d DEST, --destination=DEST 05:13:08 INFO - Directory to install application into. [default: 05:13:08 INFO - "/builds/slave/test"] 05:13:08 INFO - --app=APP Application being installed. [default: firefox] 05:13:08 INFO - mkdir: /builds/slave/test/build/application 05:13:08 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:13:08 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:13:42 INFO - Reading from file tmpfile_stdout 05:13:42 INFO - Output received: 05:13:42 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:13:42 INFO - Running post-action listener: _resource_record_post_action 05:13:42 INFO - ##### 05:13:42 INFO - ##### Running run-tests step. 05:13:42 INFO - ##### 05:13:42 INFO - Running pre-action listener: _resource_record_pre_action 05:13:42 INFO - Running main action method: run_tests 05:13:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:42 INFO - 2016-05-01 05:13:42.289 screenresolution[1961:21202] starting screenresolution argv=screenresolution get 05:13:42 INFO - 2016-05-01 05:13:42.291 screenresolution[1961:21202] Display 0: 1600x1200x32@60 05:13:42 INFO - 2016-05-01 05:13:42.297 screenresolution[1962:21204] starting screenresolution argv=screenresolution list 05:13:42 INFO - Available Modes on Display 0 05:13:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:42 INFO - Intel Iris: 05:13:42 INFO - Chipset Model: Intel Iris 05:13:42 INFO - Type: GPU 05:13:42 INFO - Bus: Built-In 05:13:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:42 INFO - Vendor: Intel (0x8086) 05:13:42 INFO - Device ID: 0x0a2e 05:13:42 INFO - Revision ID: 0x0009 05:13:42 INFO - Displays: 05:13:42 INFO - Display: 05:13:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:42 INFO - Display Serial Number: 1600x1200 60 05:13:42 INFO - Main Display: Yes 05:13:42 INFO - Mirror: Off 05:13:42 INFO - Online: Yes 05:13:42 INFO - Rotation: Supported 05:13:42 INFO - Return code: 0 05:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:42 INFO - Python 2.7.3 05:13:42 INFO - Return code: 0 05:13:42 INFO - grabbing minidump binary from tooltool 05:13:42 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:13:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b019e00>, '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 0x10ae82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b014b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13:42 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:13:42 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:13:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:42 INFO - Return code: 0 05:13:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:13:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:13:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:13:43 INFO - Return code: 0 05:13:43 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/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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:13:43 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/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1ZrnBEO3PI/Render', 05:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:43 INFO - 'HOME': '/Users/cltbld', 05:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:43 INFO - 'LOGNAME': 'cltbld', 05:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:43 INFO - 'MOZ_NO_REMOTE': '1', 05:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:43 INFO - 'NO_EM_RESTART': '1', 05:13:43 INFO - 'PAGER': '/bin/cat', 05:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:43 INFO - 'PWD': '/builds/slave/test', 05:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:43 INFO - 'SHELL': '/bin/bash', 05:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ADO4FB7Oqs/Listeners', 05:13:43 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 05:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:43 INFO - 'USER': 'cltbld', 05:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:43 INFO - 'XPC_FLAGS': '0x0', 05:13:43 INFO - 'XPC_SERVICE_NAME': '0', 05:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:43 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/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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:13:43 INFO - mozversion application_buildid: 20160501030217 05:13:43 INFO - mozversion application_changeset: 1461a4071341c282afcf7b72e33036412d2251d4 05:13:43 INFO - mozversion application_display_name: Nightly 05:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:43 INFO - mozversion application_name: Firefox 05:13:43 INFO - mozversion application_remotingname: firefox 05:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:13:43 INFO - mozversion application_vendor: Mozilla 05:13:43 INFO - mozversion application_version: 49.0a1 05:13:43 INFO - mozversion platform_buildid: 20160501030217 05:13:43 INFO - mozversion platform_changeset: 1461a4071341c282afcf7b72e33036412d2251d4 05:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:13:43 INFO - mozversion platform_version: 49.0a1 05:13:43 INFO - using testdate: 1462104823 05:13:43 INFO - actual date: 1462104823 05:13:43 INFO - starting webserver on 'localhost:51820' 05:13:43 INFO - SUITE-START | Running 2 tests 05:13:43 INFO - TEST-START | dromaeo_css 05:13:43 INFO - operating with platform_type : mac_ 05:13:43 INFO - Initialising browser for dromaeo_css test... 05:13:43 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp_EMbJq/profile http://localhost:51820/getInfo.html) 05:13:46 INFO - PROCESS | 1980 | __metrics Screen width/height:1600/1200 05:13:46 INFO - PROCESS | 1980 | colorDepth:24 05:13:46 INFO - PROCESS | 1980 | Browser inner width/height: 1024/689 05:13:46 INFO - PROCESS | 1980 | __metrics 05:13:47 ERROR - PROCESS | 1980 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:13:47 INFO - TEST-INFO | 1980: exit 0 05:13:47 INFO - Browser initialized. 05:13:47 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:47 INFO - TEST-INFO | started process 1984 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp_EMbJq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:59 INFO - PROCESS | 1984 | RSS: Main: 249999360 05:13:59 INFO - PROCESS | 1984 | 05:14:49 INFO - PROCESS | 1984 | 1462104889180 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:17:00 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51820/tests/dromaeo/cssquery-ext.html) 05:17:01 INFO - PROCESS | 1984 | RSS: Main: 265224192 05:17:01 INFO - PROCESS | 1984 | 05:20:03 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-ext.html (next: http://localhost:51820/tests/dromaeo/cssquery-jquery.html) 05:20:04 INFO - PROCESS | 1984 | RSS: Main: 277049344 05:20:04 INFO - PROCESS | 1984 | 05:23:06 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51820/tests/dromaeo/cssquery-mootools.html) 05:23:06 INFO - PROCESS | 1984 | RSS: Main: 278503424 05:23:06 INFO - PROCESS | 1984 | 05:26:08 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51820/tests/dromaeo/cssquery-prototype.html) 05:26:09 INFO - PROCESS | 1984 | RSS: Main: 282071040 05:26:09 INFO - PROCESS | 1984 | 05:29:11 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51820/tests/dromaeo/cssquery-yui.html) 05:29:12 INFO - PROCESS | 1984 | RSS: Main: 282181632 05:29:12 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:51820/tests/dromaeo/cssquery-yui.html (next: http://localhost:51820/tests/dromaeo/cssquery-dojo.html) 05:32:15 INFO - PROCESS | 1984 | RSS: Main: 284139520 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | __start_tp_report 05:32:15 INFO - PROCESS | 1984 | _x_x_mozilla_page_load 05:32:15 INFO - PROCESS | 1984 | _x_x_mozilla_page_load_details 05:32:15 INFO - PROCESS | 1984 | |i|pagename|runs| 05:32:15 INFO - PROCESS | 1984 | |0;dojo.html;3422.4181889078855;3475.895723128307;3484.6515348465155;3493.8427770750313;3527.2416430467442;5752.395998420163;5780.046292361766;5796;5797.536197104232;5833.649981001143;5673.319201695792;5717.313922329316;5772.191893134954;5801.07182850744;5948.7620495180245;5232.398274198459;5254.211868219779;5280.102382594949;5305.204219367087;5338.06583847827;296.74299061363064;5194.168932970725;5269.209618557209;5288.338957630296;5311.495407936259;5270.947290527103;5280.075986702312;5281.683099014033;5288.92066619001;5301.3373328333955;5260.684358938476;5267.815626453055;5268.4994925482015;5298.231756395339;5312.070387682179;5157.123289040872;5179.378474583074;5219.42586315505;5271.683698978073;5295.841124766263;5539.418361072069;5684.658920464785;5702.800401985952;5712.942870571283;5726.79956201531;6100.217864923763;6164.352742961969;6239.126522286883;6254.186955695776;6259.342769009279;5178.249153872667;5744.454276843693;5933.732982015819;6153.077038444225;6171.7531298748;4917.483664215278;5872.119182122716;5897.4102589741115;5901.439363260441;5923.614965027303;6137.162486020116;6228.937710622927;6306.842328941813;6307.337729538424;6341.524385671093;6117.401149625483;6167.105769663373;6176.505879529716;6203.906941395882;6262.718177682015;5146.742662866842;6221.844453888689;6228.937710622927;6269.153664255267;6272.843178920563;6161.84595385119;6204.193454850841;6240.625562466266;6276.874462510724;6294.590851594632;5284.9735751320995;6193.256809182927;6221.4089661482085;6229.844253893689;6242.656653883989;4911.607071434277;4999.0501804657;5019.29729837823;5019.974900125476;5048.949510504848;4340.175366680321;4407.537208593116;4472.508024117409;4549.727016379028;4600.976995115003;5869.2956845178;5873.119032145127;5883.588148829541;6004.489618382399;6023.578349515492;6205.968970155211;6220.688965551704;6230.501559875199;6234.096056071844;6238.812835614939;5743.62666426681;5845.649261044266;5938.406159384027;5960.195373624505;5977.372375900468;5408.513233808976;5463.48096930791;5465.781368745285;5470;5471.7264136793;6163.414475624809;6416.615003099829;6436.292007879129;6436.935630643634;6440.999999999906;6056.90914636272;6107.856436553574;6116.640127388606;6117.949202904554;6212.223472065992;6337.683115844282;6374.968125159345;6380.968095159495;6398.744050237939;6416.165898433361;6233.283172435046;6234.1583886174785;6245.594036387621;6256.624602523863;6265.467435268053;6145.4161854623735;6162.414570615784;6170.660613666291;6172.660503672161;6246.6876656167915;5546.473085056913;5875.619229481154;6175.228096487939;6207.96896015517;6249.312575616587;6072.78745243932;6188.1027251049245;6204.5036397089025;6216.316205217332;6236.719347629277;5177.2492988515705;5847.327557330961;5873.295204575478;5958.19564358806;6011.519078473624;6185.783497577563;6191.9690401547705;6195.81412557624;6196.225471816023;6214.751409943552;5987.88024239504;6057.909131363122;6060.606060606046;6070.7875224366935;6125.111858780541 05:32:15 INFO - PROCESS | 1984 | |1;ext.html;495.52677193280397;519.9688018718876;535.0129011972908;573.2547688005593;589.9940601274827;32501.83749081254;34401.483977740325;38910.63812766554;39312.410313845314;39484.605153948454;41172.58827411733;41547.376789348185;41845.58154418455;41904.79047604758;42016.15967680645;9328.906710932906;9675.516224188763;9676.467794271312;9682.177014953737;9705.805883882318;9520.428774273565;9615.13463788259;9661.130498255156;9667.564959576835;9685.564149613285;9644.758881027996;9658;9724.319297649168;9726.465044422554;9734;9585.185259252972;9626.470544120071;9679;9686.370385924893;9699.321047526675;9515.096065873731;9621.18219951305;9632.27757918159;9633.710988670351;9658.903410965871;39678.801605991786;39726.801365992986;39863.60136398628;40024;40450.39324410136;2775.403288293027;2961.940761184764;2973.3310005248645;3063.341381602966;3305.5537502437105;22768;27095.45809083827;27261.863690681617;27549.724502755118;27859.16422507328;2631.2895518209975;2660.600909863517;2686.6104414859933;2710.4985577668;2745.1764470658723;354171;358326;359111;359699;360836.9999999974;33712.831435842665;34262.48606270908;34929.47605785915;35730;35807;34272;35251.82374088113;35310;35506.82246588802;35611.82194089013;12756.872431275755;12823.422945967453;15072.924635376752;15330.616734581725;15339.309731062129;12821;12867.163634363736;12917.483300668056;12919.741605168032;12995.350232488337;7955.4828936118965;7984.760457186294;8054.315383192495;8137.430379873471;8155.306798922039;2829.6887342392733;3278.4426647469986;3303.752218583616;3344.8662053517587;3345.0968238575447;940.6848705683536;975.2003357246989;980.6616717232544;1018.6434747838198;1041.833306670929;26003.5698036606;29814;30040.399192016037;30301.09096727102;30657.693423065484;966.9323147379756;1024.5696807340935;1073.7637719701654;1101.531848964187;1159.368144361325;13606.795898061537;14854.480093196686;15041.623959401102;15191.468298609496;15314.387424502895;563998;667707;673600;679675;680865;61395.38604613986;61549;61571;62281.688591556755;62604.373956260766;1432.1192466632945;1464.048368560423;1475.7417451945867;1477.194928763805;1479.8224213094281;24929.127480538093;29546.556801647563;29680.406391872475;30073.54889676613;30474.542881857233;45504;54131;55239.72380138074;55263;55265.44734552603;486107;491523;493643;494297.5285123551;494345;26289.868550657888;27404.58893116565;27471.587926180724;27533.86233068902;27696.307592310353;46613.53386466228;47562.76218618885;47612.52387476081;47923.76038119787;47973;38627.61372386353;39233.41149882697;39333.60666393414;39443.80278098706;40133.795986120465;38138.237235254586;38340;38442.84671459861;38487.03782405462;38984.415233770946;32516.83741581277;36714.082147946516;37545.81227093847;38376.80811595924;38648.22703545857;30020.84989575038;30896.53655195129;31959.680403196602;32093;32261.838690806395;23557.52884942326;24337.75662243355;24628.384290392165;24781.389209701312;25322.873385632953 05:32:15 INFO - PROCESS | 1984 | |2;jquery.html;381.29650794284555;389.4703203643045;393.26655786957326;396.40341286364037;399.32912706652814;17915;20448.795512044875;20728.27451039214;21118.472038199052;21220.68168977463;19962;20050.197992080335;20881.373558793224;20951.476213094644;20989.47526311843;5492.423295553969;5818.2145410369585;5856.887191433635;5970.940290597104;5993.790217342394;5958.5828991970575;5977.312609049972;6006.48944839687;6018.217631707894;6084.878302433949;5733.254676892019;5767.336756273019;5870.354261031304;5873.794417195399;5876.676782776967;5647.830565083054;5668.376478587352;5822.737976791033;5847.707614619273;5857.062869940832;4512.684112112153;5222.556082732972;5614.9612321720615;5649.661020338793;5846.093855452412;12012.279263244232;13033.739325213537;13085.542006029838;13530.458781648722;13542.93228533861;1764.682357175708;2074.512489564951;2591.9740802591923;2676.919692409211;2684.0874102804946;9662.71011869645;9714.659986900424;9726.610935562569;10166.796664066751;10198.337108088024;1684.9073300968441;1688.7720157778665;1695.152423788106;1737.8175291594328;1796.2186448894772;97184.51407742986;129172.99999999905;130572;131735.34132329372;132971;9291.67479138227;11409;11512.079033677286;11642.068634509218;11791.528338866348;14464.059836110619;14488.058276212012;14504.202268875102;14505.782413263809;14564.85435145635;7078.539894906815;8503.022152452548;8561.529115898686;8583.969923609082;8671.132886711279;8459.619317130744;8466.788330291793;8486.575671216415;8580.099089595627;8588.227059564544;4442.62278693606;4541.364209010741;4557.498675145731;4594.517575654575;4626.467956185014;1640.1389270632774;1951.9316823911095;1953.1991883327767;1957.0508303472805;1977.5352792093847;833.8457385383723;842.726114012936;926.7451450850936;937.5265490927038;940.4310392212612;19913.60172796536;20336.491587710425;20603.896980515;20719.378418647466;20819.58360832775;948.814981078683;968;982.1210017034714;1016.2682868334787;1019.3435667041058;10649.988251116132;10881.564737410574;10887.401192934465;10924.672259832218;10952.671419857417;554365;568777;601102;613858.9307053436;615738;116149;134472.3276383612;135047.64952350548;135701.64298357087;141258;990.6037584965927;992.7270000749852;1008.1884083312857;1009.2128140050772;1013.6502906497246;14640.487582934546;18045.09774511243;18303.45089647313;18532.536686582942;19874.304399346533;54562;55604.88790224092;56271.43728562826;56440.43559564352;56789.148162776764;66519;71210;78435.82346264696;78686.21313786789;81360;6454.644994525158;6832.795016149524;6896.344847239504;7813;8159.224873636995;10214.846777298199;12380.319082450551;12514.31171285588;12695.111342205957;12913.160644558073;9274.44353338802;9701.544768284813;10923.344599324066;10952.069074128789;11141.442927853413;10550.208734345108;10722.303050301705;10812;10931.726706832393;11026.393548354918;11242.887571124511;11405.707818092502;11428.028617567268;11550.364729939934;11593.420328983686;8037.156098609562;8104.149064348158;8297.958510207653;8318.792030199293;8329.000519937646;10581;10917.945410272898;11004.954529319702;11161.944190279322;11281.661550153509 05:32:15 INFO - PROCESS | 1984 | |3;mootools.html;1554.634660854699;1611.2346635348208;1821.471773185776;1888.4145914766432;1938.3215874443947;7822.256885595873;8361.247487726103;8523.744287671365;8657.091005444425;8675.13248675133;7180.67686954087;8479.491230526157;8522.508561001816;8582.656693732244;8583.141685831413;2157.9352619421443;2184.4429670434015;2339.754325795797;2352.929412117631;2383.594788885885;2359.3983534198755;2370.9644355334685;2395.1377504098596;2395.73646898841;2400.219928523228;2248.651459023854;2258.6837842702034;2271.818254539641;2359.7758212969825;2372.78644921957;2266.7393249776155;2356.363781778927;2377.95244095118;2391.0674836813655;2398.4363674536385;2328.860268383902;2335.614623587106;2344.8358614896965;2348.0842471436154;2349.5888219561507;8789.472631642122;8853.778655533599;8871.334649901282;8935.821283574358;8975.865362019575;5155.304033955405;5202.0896343140175;5319.5478384337375;5417.6207665463435;5420.376656684453;3448.6378930212218;3519.9296014079832;3603.8198090095652;3614.7288953328343;3626.7642603230706;1004.4174378860243;1024.7233247023337;1032.0711359776187;1042.0933787604831;1046.3460337289193;11618.94190529042;11851;11865.881341186563;11890;11906.940465297705;4162.812673429703;4680.15757163707;4751.429828420612;4800.455758448028;4847.563719265283;9808.607655693691;9809.754756131153;9818.361806482555;9905.009499050182;9907.653232136841;5271.841844744664;5325.147976323769;5362.490563396471;5366.651167674088;5386.272853164773;3929.430232616312;4363.956360436355;4809.230523116355;4830.154722923475;4851.223804191312;3886.902827429337;3894.941575876364;3973.3245348290284;4011.0373510357304;4039.0508230565792;1292.1536393662166;1311.3312210772383;1311.4098655605017;1319.8680131986916;1329.2290471526492;873.7466134820958;879.938404311692;880.1770344727701;915.9009188973258;918.807050519385;5055.418626857885;5617.438256174431;5715.085586306171;5840.795572154954;5882.411758824083;783.7256960063932;943.0852073488705;958.3674774648698;967.4533888353058;994.105305225303;3845.8653947111716;3979.621935916083;3982.6415622594104;3989.122393073519;4011.8194681239415;10073.942236065253;10259.28185027056;10262.43556604379;10378.169746420268;10538;10538.420386878795;10587.100096491884;10616.94691526553;10672.62645807393;10712.78574428507;8015.318697910627;8075.5558444286125;8145.633446494922;8322.959630046244;8335.083140854502;5780.682062486603;5860.736266867916;5893.469587736953;5931.525477961839;5948.791792287249;9651.855222171813;9714.659986900424;9898.356606820533;9902;9947.602095916372;8381.413301068867;9039.774005649911;10038.297319187586;10183.541740621542;10333.173346132442;6127.3566275540425;6787.93212067873;6866.279040700854;6998.790036298919;7069.752558660422;8183.918160818315;8227.382946279173;8270.297024752723;8283.37874659391;8283.95858020706;6551.95168772994;7085.433165346863;7155.534890232118;7314.853702926018;7396.96301518489;7139.428845692436;7171.426285897011;7247.057882475245;7252.528585641917;7336.229695881855;6848.623325716934;6978.511504194657;6980.685869136003;6985.231624521197;7016.403605693573;4832;4866.318715379779;4896.094222568836;4952.752362381794;5073.416557095834;4588.334691469785;4884.92672609904;4917.0903382874285;4932.3094766731965;5144.897102058013 05:32:15 INFO - PROCESS | 1984 | |4;prototype.html;268.39744772984653;293.5611261164559;295.253009884991;295.48732948334634;302.0801658948502;5533.778648854046;5611.214429979796;5634.211210430543;5638.041532939405;5653.1237658163;5036.924446133311;5462.6995515246645;5557.3886872444;5558.1093234735645;5584.441555844423;3965.662918651911;4075.1034772350035;4198.391233271173;4217.198732240864;4221.915561688765;3813.3517302058576;3910.7458015229063;3964.484616999786;3966.413434626158;4196.22369861575;3435.862565497377;3793.9810300948457;3886.650201481866;4211.663066954637;4226.795363321454;3850.8832438592776;4096.672266218725;4127.731697439687;4204.348326009474;4214.325707886739;3505.1237190702323;3507.2810073934784;3507.492143117683;3526.664966828147;3529.8941031769086;4192.909843440727;5331.946680533184;5344.545713614348;5346.625736198468;5356;4244.87756121939;4295.699301048928;4723.4568024677255;4726.456457507396;4765.21373973294;5853.678047707374;5858.2677165354335;5873.590338318772;5875.6474611523445;5876;3873.438351439054;3875.8837234883;3882.5340959084806;3888.8250028748776;3894.4742459767854;5725.799597014126;5812;6077.179580756586;6217.378262173836;6220.346863579304;3310.70203681665;3416.9487457688156;3605.351036813346;3779;3794.962050379516;3112.595362602847;3389.81356025416;3552.5559305086863;3819.3125237457525;3999.060220848098;3784.243151369682;4514.345419914094;4660.231061874821;4695.530446955346;4706.3646407735205;5777.277840269966;5869.148973398833;5953.79161729346;5967.045272756338;5982.192404025488;1246.7818131826893;1301.381843624271;1447.261896432827;1451.9419223231164;1458.0158393084837;773.5938632218131;870.616928551448;920.9953950230206;934.1965909317981;947.8815148106487;5224.608154388436;5988.431099045583;5999.220101386879;6006.669633170218;6020.337762846083;6077.75688972445;6630.602163870183;6635.303293154246;6641.369069938348;6649.933500665028;5233.947660523422;5626.381098079208;5660.320761508646;5678.744456499468;5932.04242599941;2957.8668959896854;3276.754243431752;3419.504171895111;3516.942588585706;3545.663161999606;6715.395614394728;7013.6142512161305;7023.613701246481;7043.612601306977;7058.435325173974;5981.790637327673;6656.234533028667;6914.688839002257;6954.582725036413;6977.546459480118;6612.867742645217;6682.83292917681;6812.48906332027;6947.756828510978;7045.436365090883;5532.806351777668;6605.669716514251;6669.533132680665;6679.630675711371;6681.09805176295;5218.869528261824;6462.450691691259;6636.668166591748;6656.30108838565;6672.59964402118;6930.5148639594745;6990.720371185095;6991.091158149408;6992;7019.6841142147705;3659.469376940382;4092.856750013735;4098.344264917599;4181.330987042059;4195.370694395743;5759.2512973313205;6104.032378953716;6701.229358623821;6741;6758.8509953307075;5048.570871475966;5584.771350302927;6321.620702757849;6457.836624730768;6527.706253218712;5478.589105817;5592.608517403743;6475.740970361133;6493.188351456068;6516.543841931019;4693.601043911169;5096.643234973516;5608.663480191201;5774.220480235114;5860.941390586039;5148.665336753099;5941.544321641226;5954.612950158225;5995.520358371407;6018.36807135262;5177.637565370538;5617.634853734494;5677.886442271214;5877.647341159544;5919.6744179070565 05:32:15 INFO - PROCESS | 1984 | |5;yui.html;569.8603842058695;717.8786685344803;728.7012324946771;756.2022066719609;773.9110002349728;13064.804027939577;13535.984801139904;14241.789447896928;15216.239188040596;16047.759283610725;15138.318775655094;15186.848131518682;15311.387544498235;15321.540353789404;15349.462768803094;869.6608322754131;909.0818273543736;910.0717268386242;912.7672443526908;913.5204017890594;875.212308921969;876.4040452492303;911.676354894013;913.1142791492275;922.1792604581927;945.7730144765276;972.7568107973007;972.8054389122168;974.9707508774748;979.5591983607369;943.0381011368435;946.8721722567468;965.5558443116175;966.5264020629911;967.4243824924159;705.8411857332111;711.9715211391538;716.8637958787814;722.8120688620944;723.1177962885272;14820.40718371264;14832.703345933021;14833.406663733438;14853.257337133207;14889.106653600818;11955.103367247491;11993.820092698617;12072.698182545419;12130.757384852253;12331.075169362244;9875.950620246853;9878.456684882329;9939;10039.849402258971;10048.09567138961;3380.746444016684;3439.208981934144;3442.122258824009;3443.1736383268085;3453.620101788801;73852.63073684597;79595.6020219901;82339;83008.58495707544;83559;9105.590248438835;9141.40580862255;9242.76893077665;9335.719928402159;9382.296327775444;19779;19947.800521994886;20137.49656258576;20159.496012599804;20318.492037699176;7268.890966635505;7325.853482930312;7371.852562948711;7522.059742532184;7600.239976002355;4073.5137280190247;4083.530394004668;4103.692223083281;4122.546519882811;4182.56083111275;3776.4524143998965;3943.5859234779937;3946.506686664167;3948.8420463181724;3962.9696278967676;674.814426032845;697.5814511293183;701.2251462134268;709.3509438863485;709.4572651921285;495.62085004971226;562.9155626656035;584.6433675457968;603.6695974544759;622.8100429368995;7144.285571442814;7929.207079292025;7955.443118981616;8049.3962952778775;8086.5148091114725;379.3645643547048;500.79424383533694;554.877926856091;556.3629644057531;640.414021170633;3781.470594116826;3790.2040571479747;3821.7898015609408;3842.1355195080882;3999.24014437256;71271.2872871275;97190;99115.50442247743;101110;101799;83169;83494;83787.74318385229;84220;84755.15244847596;58239;73171.63414183001;74953;75840;76020.61989690016;6478.546501744832;6836.248012718696;7368.673638745077;7621.504602200839;7645.617719114133;44749.776251118536;44830.3275450875;44935;45136.32295515504;46367;48713;62014;64441.03338450021;66329.66835165794;66844.66310673663;9879.456629885139;9937.850932235877;10066.44634545107;10282.280240383101;10363.94818025905;10695.67075261248;11211.887881121085;11244.775104498029;11343.376114313793;11416.257943233662;10140.29017968735;10378.169746420117;10435.16518678519;10530.947345263225;10542.8418573723;10165.54255058539;10230.590776368861;10275.075243227846;10476.68569942903;10752.83870741955;9921;9944.900550994687;10192.694219173436;10249.897501024894;10331.070203681404;8693.174148455886;8781.604827782892;8785.077566855389;9019.594118264826;9027.954860225656;8869.053838231419;9110.635574576943;9380.624775008922;9576;9637.18083962871 05:32:15 INFO - PROCESS | 1984 | __end_tp_report 05:32:15 INFO - PROCESS | 1984 | __start_cc_report 05:32:15 INFO - PROCESS | 1984 | _x_x_mozilla_cycle_collect,414 05:32:15 INFO - PROCESS | 1984 | __end_cc_report 05:32:15 INFO - PROCESS | 1984 | __startTimestamp1462105935269__endTimestamp 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | ------- Summary: start ------- 05:32:15 INFO - PROCESS | 1984 | Number of tests: 6 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#0] dojo.html Cycles:165 Average:5729.64 Median:5960.20 stddev:753.35 (12.6%) stddev-sans-first:733.60 05:32:15 INFO - PROCESS | 1984 | Values: 3422.4 3475.9 3484.7 3493.8 3527.2 5752.4 5780.0 5796.0 5797.5 5833.6 5673.3 5717.3 5772.2 5801.1 5948.8 5232.4 5254.2 5280.1 5305.2 5338.1 296.7 5194.2 5269.2 5288.3 5311.5 5270.9 5280.1 5281.7 5288.9 5301.3 5260.7 5267.8 5268.5 5298.2 5312.1 5157.1 5179.4 5219.4 5271.7 5295.8 5539.4 5684.7 5702.8 5712.9 5726.8 6100.2 6164.4 6239.1 6254.2 6259.3 5178.2 5744.5 5933.7 6153.1 6171.8 4917.5 5872.1 5897.4 5901.4 5923.6 6137.2 6228.9 6306.8 6307.3 6341.5 6117.4 6167.1 6176.5 6203.9 6262.7 5146.7 6221.8 6228.9 6269.2 6272.8 6161.8 6204.2 6240.6 6276.9 6294.6 5285.0 6193.3 6221.4 6229.8 6242.7 4911.6 4999.1 5019.3 5020.0 5048.9 4340.2 4407.5 4472.5 4549.7 4601.0 5869.3 5873.1 5883.6 6004.5 6023.6 6206.0 6220.7 6230.5 6234.1 6238.8 5743.6 5845.6 5938.4 5960.2 5977.4 5408.5 5463.5 5465.8 5470.0 5471.7 6163.4 6416.6 6436.3 6436.9 6441.0 6056.9 6107.9 6116.6 6117.9 6212.2 6337.7 6375.0 6381.0 6398.7 6416.2 6233.3 6234.2 6245.6 6256.6 6265.5 6145.4 6162.4 6170.7 6172.7 6246.7 5546.5 5875.6 6175.2 6208.0 6249.3 6072.8 6188.1 6204.5 6216.3 6236.7 5177.2 5847.3 5873.3 5958.2 6011.5 6185.8 6192.0 6195.8 6196.2 6214.8 5987.9 6057.9 6060.6 6070.8 6125.1 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#1] ext.html Cycles:180 Average:62461.29 Median:37842.02 stddev:138335.43 (365.6%) stddev-sans-first:138645.26 05:32:15 INFO - PROCESS | 1984 | Values: 495.5 520.0 535.0 573.3 590.0 32501.8 34401.5 38910.6 39312.4 39484.6 41172.6 41547.4 41845.6 41904.8 42016.2 9328.9 9675.5 9676.5 9682.2 9705.8 9520.4 9615.1 9661.1 9667.6 9685.6 9644.8 9658.0 9724.3 9726.5 9734.0 9585.2 9626.5 9679.0 9686.4 9699.3 9515.1 9621.2 9632.3 9633.7 9658.9 39678.8 39726.8 39863.6 40024.0 40450.4 2775.4 2961.9 2973.3 3063.3 3305.6 22768.0 27095.5 27261.9 27549.7 27859.2 2631.3 2660.6 2686.6 2710.5 2745.2 354171.0 358326.0 359111.0 359699.0 360837.0 33712.8 34262.5 34929.5 35730.0 35807.0 34272.0 35251.8 35310.0 35506.8 35611.8 12756.9 12823.4 15072.9 15330.6 15339.3 12821.0 12867.2 12917.5 12919.7 12995.4 7955.5 7984.8 8054.3 8137.4 8155.3 2829.7 3278.4 3303.8 3344.9 3345.1 940.7 975.2 980.7 1018.6 1041.8 26003.6 29814.0 30040.4 30301.1 30657.7 966.9 1024.6 1073.8 1101.5 1159.4 13606.8 14854.5 15041.6 15191.5 15314.4 563998.0 667707.0 673600.0 679675.0 680865.0 61395.4 61549.0 61571.0 62281.7 62604.4 1432.1 1464.0 1475.7 1477.2 1479.8 24929.1 29546.6 29680.4 30073.5 30474.5 45504.0 54131.0 55239.7 55263.0 55265.4 486107.0 491523.0 493643.0 494297.5 494345.0 26289.9 27404.6 27471.6 27533.9 27696.3 46613.5 47562.8 47612.5 47923.8 47973.0 38627.6 39233.4 39333.6 39443.8 40133.8 38138.2 38340.0 38442.8 38487.0 38984.4 32516.8 36714.1 37545.8 38376.8 38648.2 30020.8 30896.5 31959.7 32093.0 32261.8 23557.5 24337.8 24628.4 24781.4 25322.9 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#2] jquery.html Cycles:180 Average:34668.57 Median:2634.45 stddev:99136.25 (3763.1%) stddev-sans-first:99380.92 05:32:15 INFO - PROCESS | 1984 | Values: 381.3 389.5 393.3 396.4 399.3 17915.0 20448.8 20728.3 21118.5 21220.7 19962.0 20050.2 20881.4 20951.5 20989.5 5492.4 5818.2 5856.9 5970.9 5993.8 5958.6 5977.3 6006.5 6018.2 6084.9 5733.3 5767.3 5870.4 5873.8 5876.7 5647.8 5668.4 5822.7 5847.7 5857.1 4512.7 5222.6 5615.0 5649.7 5846.1 12012.3 13033.7 13085.5 13530.5 13542.9 1764.7 2074.5 2592.0 2676.9 2684.1 9662.7 9714.7 9726.6 10166.8 10198.3 1684.9 1688.8 1695.2 1737.8 1796.2 97184.5 129173.0 130572.0 131735.3 132971.0 9291.7 11409.0 11512.1 11642.1 11791.5 14464.1 14488.1 14504.2 14505.8 14564.9 7078.5 8503.0 8561.5 8584.0 8671.1 8459.6 8466.8 8486.6 8580.1 8588.2 4442.6 4541.4 4557.5 4594.5 4626.5 1640.1 1951.9 1953.2 1957.1 1977.5 833.8 842.7 926.7 937.5 940.4 19913.6 20336.5 20603.9 20719.4 20819.6 948.8 968.0 982.1 1016.3 1019.3 10650.0 10881.6 10887.4 10924.7 10952.7 554365.0 568777.0 601102.0 613858.9 615738.0 116149.0 134472.3 135047.6 135701.6 141258.0 990.6 992.7 1008.2 1009.2 1013.7 14640.5 18045.1 18303.5 18532.5 19874.3 54562.0 55604.9 56271.4 56440.4 56789.1 66519.0 71210.0 78435.8 78686.2 81360.0 6454.6 6832.8 6896.3 7813.0 8159.2 10214.8 12380.3 12514.3 12695.1 12913.2 9274.4 9701.5 10923.3 10952.1 11141.4 10550.2 10722.3 10812.0 10931.7 11026.4 11242.9 11405.7 11428.0 11550.4 11593.4 8037.2 8104.1 8298.0 8318.8 8329.0 10581.0 10917.9 11005.0 11161.9 11281.7 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#3] mootools.html Cycles:180 Average:5620.39 Median:5004.09 stddev:3132.27 (62.6%) stddev-sans-first:3126.15 05:32:15 INFO - PROCESS | 1984 | Values: 1554.6 1611.2 1821.5 1888.4 1938.3 7822.3 8361.2 8523.7 8657.1 8675.1 7180.7 8479.5 8522.5 8582.7 8583.1 2157.9 2184.4 2339.8 2352.9 2383.6 2359.4 2371.0 2395.1 2395.7 2400.2 2248.7 2258.7 2271.8 2359.8 2372.8 2266.7 2356.4 2378.0 2391.1 2398.4 2328.9 2335.6 2344.8 2348.1 2349.6 8789.5 8853.8 8871.3 8935.8 8975.9 5155.3 5202.1 5319.5 5417.6 5420.4 3448.6 3519.9 3603.8 3614.7 3626.8 1004.4 1024.7 1032.1 1042.1 1046.3 11618.9 11851.0 11865.9 11890.0 11906.9 4162.8 4680.2 4751.4 4800.5 4847.6 9808.6 9809.8 9818.4 9905.0 9907.7 5271.8 5325.1 5362.5 5366.7 5386.3 3929.4 4364.0 4809.2 4830.2 4851.2 3886.9 3894.9 3973.3 4011.0 4039.1 1292.2 1311.3 1311.4 1319.9 1329.2 873.7 879.9 880.2 915.9 918.8 5055.4 5617.4 5715.1 5840.8 5882.4 783.7 943.1 958.4 967.5 994.1 3845.9 3979.6 3982.6 3989.1 4011.8 10073.9 10259.3 10262.4 10378.2 10538.0 10538.4 10587.1 10616.9 10672.6 10712.8 8015.3 8075.6 8145.6 8323.0 8335.1 5780.7 5860.7 5893.5 5931.5 5948.8 9651.9 9714.7 9898.4 9902.0 9947.6 8381.4 9039.8 10038.3 10183.5 10333.2 6127.4 6787.9 6866.3 6998.8 7069.8 8183.9 8227.4 8270.3 8283.4 8284.0 6552.0 7085.4 7155.5 7314.9 7397.0 7139.4 7171.4 7247.1 7252.5 7336.2 6848.6 6978.5 6980.7 6985.2 7016.4 4832.0 4866.3 4896.1 4952.8 5073.4 4588.3 4884.9 4917.1 4932.3 5144.9 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#4] prototype.html Cycles:180 Average:4922.84 Median:5584.61 stddev:1672.67 (30.0%) stddev-sans-first:1640.47 05:32:15 INFO - PROCESS | 1984 | Values: 268.4 293.6 295.3 295.5 302.1 5533.8 5611.2 5634.2 5638.0 5653.1 5036.9 5462.7 5557.4 5558.1 5584.4 3965.7 4075.1 4198.4 4217.2 4221.9 3813.4 3910.7 3964.5 3966.4 4196.2 3435.9 3794.0 3886.7 4211.7 4226.8 3850.9 4096.7 4127.7 4204.3 4214.3 3505.1 3507.3 3507.5 3526.7 3529.9 4192.9 5331.9 5344.5 5346.6 5356.0 4244.9 4295.7 4723.5 4726.5 4765.2 5853.7 5858.3 5873.6 5875.6 5876.0 3873.4 3875.9 3882.5 3888.8 3894.5 5725.8 5812.0 6077.2 6217.4 6220.3 3310.7 3416.9 3605.4 3779.0 3795.0 3112.6 3389.8 3552.6 3819.3 3999.1 3784.2 4514.3 4660.2 4695.5 4706.4 5777.3 5869.1 5953.8 5967.0 5982.2 1246.8 1301.4 1447.3 1451.9 1458.0 773.6 870.6 921.0 934.2 947.9 5224.6 5988.4 5999.2 6006.7 6020.3 6077.8 6630.6 6635.3 6641.4 6649.9 5233.9 5626.4 5660.3 5678.7 5932.0 2957.9 3276.8 3419.5 3516.9 3545.7 6715.4 7013.6 7023.6 7043.6 7058.4 5981.8 6656.2 6914.7 6954.6 6977.5 6612.9 6682.8 6812.5 6947.8 7045.4 5532.8 6605.7 6669.5 6679.6 6681.1 5218.9 6462.5 6636.7 6656.3 6672.6 6930.5 6990.7 6991.1 6992.0 7019.7 3659.5 4092.9 4098.3 4181.3 4195.4 5759.3 6104.0 6701.2 6741.0 6758.9 5048.6 5584.8 6321.6 6457.8 6527.7 5478.6 5592.6 6475.7 6493.2 6516.5 4693.6 5096.6 5608.7 5774.2 5860.9 5148.7 5941.5 5954.6 5995.5 6018.4 5177.6 5617.6 5677.9 5877.6 5919.7 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - PROCESS | 1984 | [#5] yui.html Cycles:180 Average:17998.40 Median:36404.11 stddev:26032.58 (71.5%) stddev-sans-first:26072.72 05:32:15 INFO - PROCESS | 1984 | Values: 569.9 717.9 728.7 756.2 773.9 13064.8 13536.0 14241.8 15216.2 16047.8 15138.3 15186.8 15311.4 15321.5 15349.5 869.7 909.1 910.1 912.8 913.5 875.2 876.4 911.7 913.1 922.2 945.8 972.8 972.8 975.0 979.6 943.0 946.9 965.6 966.5 967.4 705.8 712.0 716.9 722.8 723.1 14820.4 14832.7 14833.4 14853.3 14889.1 11955.1 11993.8 12072.7 12130.8 12331.1 9876.0 9878.5 9939.0 10039.8 10048.1 3380.7 3439.2 3442.1 3443.2 3453.6 73852.6 79595.6 82339.0 83008.6 83559.0 9105.6 9141.4 9242.8 9335.7 9382.3 19779.0 19947.8 20137.5 20159.5 20318.5 7268.9 7325.9 7371.9 7522.1 7600.2 4073.5 4083.5 4103.7 4122.5 4182.6 3776.5 3943.6 3946.5 3948.8 3963.0 674.8 697.6 701.2 709.4 709.5 495.6 562.9 584.6 603.7 622.8 7144.3 7929.2 7955.4 8049.4 8086.5 379.4 500.8 554.9 556.4 640.4 3781.5 3790.2 3821.8 3842.1 3999.2 71271.3 97190.0 99115.5 101110.0 101799.0 83169.0 83494.0 83787.7 84220.0 84755.2 58239.0 73171.6 74953.0 75840.0 76020.6 6478.5 6836.2 7368.7 7621.5 7645.6 44749.8 44830.3 44935.0 45136.3 46367.0 48713.0 62014.0 64441.0 66329.7 66844.7 9879.5 9937.9 10066.4 10282.3 10363.9 10695.7 11211.9 11244.8 11343.4 11416.3 10140.3 10378.2 10435.2 10530.9 10542.8 10165.5 10230.6 10275.1 10476.7 10752.8 9921.0 9944.9 10192.7 10249.9 10331.1 8693.2 8781.6 8785.1 9019.6 9028.0 8869.1 9110.6 9380.6 9576.0 9637.2 05:32:15 INFO - PROCESS | 1984 | -------- Summary: end -------- 05:32:15 INFO - PROCESS | 1984 | 05:32:15 INFO - TEST-INFO | 1984: exit 0 05:32:15 INFO - TEST-OK | dromaeo_css | took 1111986ms 05:32:15 INFO - TEST-START | kraken 05:32:15 INFO - operating with platform_type : mac_ 05:32:15 INFO - Initialising browser for kraken test... 05:32:15 INFO - TEST-INFO | started process 1992 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpxKY_fP/profile http://localhost:51820/getInfo.html) 05:32:18 INFO - PROCESS | 1992 | __metrics Screen width/height:1600/1200 05:32:18 INFO - PROCESS | 1992 | colorDepth:24 05:32:18 INFO - PROCESS | 1992 | Browser inner width/height: 1024/689 05:32:18 INFO - PROCESS | 1992 | __metrics 05:32:18 ERROR - PROCESS | 1992 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:32:19 INFO - TEST-INFO | 1992: exit 0 05:32:19 INFO - Browser initialized. 05:32:19 INFO - Running cycle 1/1 for kraken test... 05:32:19 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpxKY_fP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:31 INFO - PROCESS | 1996 | RSS: Main: 250302464 05:32:31 INFO - PROCESS | 1996 | 05:32:34 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51820/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:35 INFO - PROCESS | 1996 | RSS: Main: 262418432 05:32:35 INFO - PROCESS | 1996 | 05:32:37 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51820/tests/kraken/driver.html?testName=audio-dft) 05:32:38 INFO - PROCESS | 1996 | RSS: Main: 267309056 05:32:38 INFO - PROCESS | 1996 | 05:32:42 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51820/tests/kraken/driver.html?testName=audio-fft) 05:32:42 INFO - PROCESS | 1996 | RSS: Main: 267710464 05:32:42 INFO - PROCESS | 1996 | 05:32:44 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51820/tests/kraken/driver.html?testName=audio-oscillator) 05:32:45 INFO - PROCESS | 1996 | RSS: Main: 268382208 05:32:45 INFO - PROCESS | 1996 | 05:32:47 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51820/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:48 INFO - PROCESS | 1996 | RSS: Main: 268808192 05:32:48 INFO - PROCESS | 1996 | 05:32:51 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51820/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:51 INFO - PROCESS | 1996 | RSS: Main: 268558336 05:32:51 INFO - PROCESS | 1996 | 05:32:54 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51820/tests/kraken/driver.html?testName=imaging-desaturate) 05:32:55 INFO - PROCESS | 1996 | RSS: Main: 268587008 05:32:55 INFO - PROCESS | 1996 | 05:32:58 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51820/tests/kraken/driver.html?testName=json-parse-financial) 05:32:58 INFO - PROCESS | 1996 | RSS: Main: 269074432 05:32:58 INFO - PROCESS | 1996 | 05:33:00 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51820/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:01 INFO - PROCESS | 1996 | RSS: Main: 268488704 05:33:01 INFO - PROCESS | 1996 | 05:33:03 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:03 INFO - PROCESS | 1996 | RSS: Main: 268562432 05:33:03 INFO - PROCESS | 1996 | 05:33:06 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:06 INFO - PROCESS | 1996 | RSS: Main: 269025280 05:33:06 INFO - PROCESS | 1996 | 05:33:09 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:10 INFO - PROCESS | 1996 | RSS: Main: 268955648 05:33:10 INFO - PROCESS | 1996 | 05:33:13 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:14 INFO - PROCESS | 1996 | RSS: Main: 268853248 05:33:14 INFO - PROCESS | 1996 | 05:33:15 INFO - PROCESS | 1996 | Cycle 1(1): loaded http://localhost:51820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51820/tests/kraken/driver.html?testName=ai-astar) 05:33:16 INFO - PROCESS | 1996 | RSS: Main: 270409728 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | __start_tp_report 05:33:16 INFO - PROCESS | 1996 | _x_x_mozilla_page_load 05:33:16 INFO - PROCESS | 1996 | _x_x_mozilla_page_load_details 05:33:16 INFO - PROCESS | 1996 | |i|pagename|runs| 05:33:16 INFO - PROCESS | 1996 | |0;ai-astar;118;112;117;116;117;135;172;119;116;118 05:33:16 INFO - PROCESS | 1996 | |1;audio-beat-detection;160;127;151;197;132;146;125;163;160;128 05:33:16 INFO - PROCESS | 1996 | |2;audio-dft;196;198;207;239;240;277;225;199;196;197 05:33:16 INFO - PROCESS | 1996 | |3;audio-fft;68;80;77;145;59;59;65;80;149;48 05:33:16 INFO - PROCESS | 1996 | |4;audio-oscillator;81;84;132;78;82;64;141;114;124;167 05:33:16 INFO - PROCESS | 1996 | |5;imaging-gaussian-blur;88;87;87;85;109;132;89;86;94;118 05:33:16 INFO - PROCESS | 1996 | |6;imaging-darkroom;132;153;95;95;94;96;93;96;94;94 05:33:16 INFO - PROCESS | 1996 | |7;imaging-desaturate;94;125;149;135;91;105;152;103;79;140 05:33:16 INFO - PROCESS | 1996 | |8;json-parse-financial;47;61;98;93;98;92;60;84;62;62 05:33:16 INFO - PROCESS | 1996 | |9;json-stringify-tinderbox;64;66;69;75;84;87;70;69;67;70 05:33:16 INFO - PROCESS | 1996 | |10;stanford-crypto-aes;88;86;85;86;76;103;114;113;112;84 05:33:16 INFO - PROCESS | 1996 | |11;stanford-crypto-ccm;141;158;157;160;151;155;155;158;174;172 05:33:16 INFO - PROCESS | 1996 | |12;stanford-crypto-pbkdf2;175;175;175;175;170;203;235;184;172;174 05:33:16 INFO - PROCESS | 1996 | |13;stanford-crypto-sha256-iterative;73;67;86;101;82;71;69;70;72;70 05:33:16 INFO - PROCESS | 1996 | __end_tp_report 05:33:16 INFO - PROCESS | 1996 | __start_cc_report 05:33:16 INFO - PROCESS | 1996 | _x_x_mozilla_cycle_collect,1063 05:33:16 INFO - PROCESS | 1996 | __end_cc_report 05:33:16 INFO - PROCESS | 1996 | __startTimestamp1462105996139__endTimestamp 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | ------- Summary: start ------- 05:33:16 INFO - PROCESS | 1996 | Number of tests: 14 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#0] ai-astar Cycles:10 Average:124.00 Median:118.00 stddev:17.93 (15.2%) stddev-sans-first:18.88 05:33:16 INFO - PROCESS | 1996 | Values: 118.0 112.0 117.0 116.0 117.0 135.0 172.0 119.0 116.0 118.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#1] audio-beat-detection Cycles:10 Average:148.90 Median:155.50 stddev:22.47 (14.5%) stddev-sans-first:23.47 05:33:16 INFO - PROCESS | 1996 | Values: 160.0 127.0 151.0 197.0 132.0 146.0 125.0 163.0 160.0 128.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#2] audio-dft Cycles:10 Average:217.40 Median:216.00 stddev:27.37 (12.7%) stddev-sans-first:27.91 05:33:16 INFO - PROCESS | 1996 | Values: 196.0 198.0 207.0 239.0 240.0 277.0 225.0 199.0 196.0 197.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#3] audio-fft Cycles:10 Average:83.00 Median:66.50 stddev:35.25 (53.0%) stddev-sans-first:36.96 05:33:16 INFO - PROCESS | 1996 | Values: 68.0 80.0 77.0 145.0 59.0 59.0 65.0 80.0 149.0 48.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#4] audio-oscillator Cycles:10 Average:106.70 Median:71.00 stddev:33.73 (47.5%) stddev-sans-first:34.47 05:33:16 INFO - PROCESS | 1996 | Values: 81.0 84.0 132.0 78.0 82.0 64.0 141.0 114.0 124.0 167.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:87.00 stddev:16.42 (18.9%) stddev-sans-first:17.05 05:33:16 INFO - PROCESS | 1996 | Values: 88.0 87.0 87.0 85.0 109.0 132.0 89.0 86.0 94.0 118.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:94.50 stddev:20.80 (22.0%) stddev-sans-first:19.48 05:33:16 INFO - PROCESS | 1996 | Values: 132.0 153.0 95.0 95.0 94.0 96.0 93.0 96.0 94.0 94.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#7] imaging-desaturate Cycles:10 Average:117.30 Median:150.50 stddev:26.15 (17.4%) stddev-sans-first:26.34 05:33:16 INFO - PROCESS | 1996 | Values: 94.0 125.0 149.0 135.0 91.0 105.0 152.0 103.0 79.0 140.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#8] json-parse-financial Cycles:10 Average:75.70 Median:88.00 stddev:19.12 (21.7%) stddev-sans-first:17.23 05:33:16 INFO - PROCESS | 1996 | Values: 47.0 61.0 98.0 93.0 98.0 92.0 60.0 84.0 62.0 62.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:70.00 stddev:7.67 (11.0%) stddev-sans-first:7.55 05:33:16 INFO - PROCESS | 1996 | Values: 64.0 66.0 69.0 75.0 84.0 87.0 70.0 69.0 67.0 70.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:84.50 stddev:14.26 (16.9%) stddev-sans-first:14.92 05:33:16 INFO - PROCESS | 1996 | Values: 88.0 86.0 85.0 86.0 76.0 103.0 114.0 113.0 112.0 84.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:158.00 stddev:9.50 (6.0%) stddev-sans-first:7.81 05:33:16 INFO - PROCESS | 1996 | Values: 141.0 158.0 157.0 160.0 151.0 155.0 155.0 158.0 174.0 172.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:175.00 stddev:20.35 (11.6%) stddev-sans-first:21.33 05:33:16 INFO - PROCESS | 1996 | Values: 175.0 175.0 175.0 175.0 170.0 203.0 235.0 184.0 172.0 174.0 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - PROCESS | 1996 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:71.50 stddev:10.61 (14.8%) stddev-sans-first:11.19 05:33:16 INFO - PROCESS | 1996 | Values: 73.0 67.0 86.0 101.0 82.0 71.0 69.0 70.0 72.0 70.0 05:33:16 INFO - PROCESS | 1996 | -------- Summary: end -------- 05:33:16 INFO - PROCESS | 1996 | 05:33:16 INFO - TEST-INFO | 1996: exit 0 05:33:16 INFO - TEST-OK | kraken | took 60999ms 05:33:16 INFO - SUITE-END | took 1172s 05:33:17 INFO - Completed test suite (00:19:33) 05:33:17 INFO - javascript benchmark 05:33:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3422.4181889078855, 3475.895723128307, 3484.6515348465155, 3493.8427770750313, 3527.2416430467442, 5752.395998420163, 5780.046292361766, 5796.0, 5797.536197104232, 5833.649981001143, 5673.319201695792, 5717.313922329316, 5772.191893134954, 5801.07182850744, 5948.7620495180245, 5232.398274198459, 5254.211868219779, 5280.102382594949, 5305.204219367087, 5338.06583847827, 296.74299061363064, 5194.168932970725, 5269.209618557209, 5288.338957630296, 5311.495407936259, 5270.947290527103, 5280.075986702312, 5281.683099014033, 5288.92066619001, 5301.3373328333955, 5260.684358938476, 5267.815626453055, 5268.4994925482015, 5298.231756395339, 5312.070387682179, 5157.123289040872, 5179.378474583074, 5219.42586315505, 5271.683698978073, 5295.841124766263, 5539.418361072069, 5684.658920464785, 5702.800401985952, 5712.942870571283, 5726.79956201531, 6100.217864923763, 6164.352742961969, 6239.126522286883, 6254.186955695776, 6259.342769009279, 5178.249153872667, 5744.454276843693, 5933.732982015819, 6153.077038444225, 6171.7531298748, 4917.483664215278, 5872.119182122716, 5897.4102589741115, 5901.439363260441, 5923.614965027303, 6137.162486020116, 6228.937710622927, 6306.842328941813, 6307.337729538424, 6341.524385671093, 6117.401149625483, 6167.105769663373, 6176.505879529716, 6203.906941395882, 6262.718177682015, 5146.742662866842, 6221.844453888689, 6228.937710622927, 6269.153664255267, 6272.843178920563, 6161.84595385119, 6204.193454850841, 6240.625562466266, 6276.874462510724, 6294.590851594632, 5284.9735751320995, 6193.256809182927, 6221.4089661482085, 6229.844253893689, 6242.656653883989, 4911.607071434277, 4999.0501804657, 5019.29729837823, 5019.974900125476, 5048.949510504848, 4340.175366680321, 4407.537208593116, 4472.508024117409, 4549.727016379028, 4600.976995115003, 5869.2956845178, 5873.119032145127, 5883.588148829541, 6004.489618382399, 6023.578349515492, 6205.968970155211, 6220.688965551704, 6230.501559875199, 6234.096056071844, 6238.812835614939, 5743.62666426681, 5845.649261044266, 5938.406159384027, 5960.195373624505, 5977.372375900468, 5408.513233808976, 5463.48096930791, 5465.781368745285, 5470.0, 5471.7264136793, 6163.414475624809, 6416.615003099829, 6436.292007879129, 6436.935630643634, 6440.999999999906, 6056.90914636272, 6107.856436553574, 6116.640127388606, 6117.949202904554, 6212.223472065992, 6337.683115844282, 6374.968125159345, 6380.968095159495, 6398.744050237939, 6416.165898433361, 6233.283172435046, 6234.1583886174785, 6245.594036387621, 6256.624602523863, 6265.467435268053, 6145.4161854623735, 6162.414570615784, 6170.660613666291, 6172.660503672161, 6246.6876656167915, 5546.473085056913, 5875.619229481154, 6175.228096487939, 6207.96896015517, 6249.312575616587, 6072.78745243932, 6188.1027251049245, 6204.5036397089025, 6216.316205217332, 6236.719347629277, 5177.2492988515705, 5847.327557330961, 5873.295204575478, 5958.19564358806, 6011.519078473624, 6185.783497577563, 6191.9690401547705, 6195.81412557624, 6196.225471816023, 6214.751409943552, 5987.88024239504, 6057.909131363122, 6060.606060606046, 6070.7875224366935, 6125.111858780541], "value": 5686.636118680901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.52677193280397, 519.9688018718876, 535.0129011972908, 573.2547688005593, 589.9940601274827, 32501.83749081254, 34401.483977740325, 38910.63812766554, 39312.410313845314, 39484.605153948454, 41172.58827411733, 41547.376789348185, 41845.58154418455, 41904.79047604758, 42016.15967680645, 9328.906710932906, 9675.516224188763, 9676.467794271312, 9682.177014953737, 9705.805883882318, 9520.428774273565, 9615.13463788259, 9661.130498255156, 9667.564959576835, 9685.564149613285, 9644.758881027996, 9658.0, 9724.319297649168, 9726.465044422554, 9734.0, 9585.185259252972, 9626.470544120071, 9679.0, 9686.370385924893, 9699.321047526675, 9515.096065873731, 9621.18219951305, 9632.27757918159, 9633.710988670351, 9658.903410965871, 39678.801605991786, 39726.801365992986, 39863.60136398628, 40024.0, 40450.39324410136, 2775.403288293027, 2961.940761184764, 2973.3310005248645, 3063.341381602966, 3305.5537502437105, 22768.0, 27095.45809083827, 27261.863690681617, 27549.724502755118, 27859.16422507328, 2631.2895518209975, 2660.600909863517, 2686.6104414859933, 2710.4985577668, 2745.1764470658723, 354171.0, 358326.0, 359111.0, 359699.0, 360836.9999999974, 33712.831435842665, 34262.48606270908, 34929.47605785915, 35730.0, 35807.0, 34272.0, 35251.82374088113, 35310.0, 35506.82246588802, 35611.82194089013, 12756.872431275755, 12823.422945967453, 15072.924635376752, 15330.616734581725, 15339.309731062129, 12821.0, 12867.163634363736, 12917.483300668056, 12919.741605168032, 12995.350232488337, 7955.4828936118965, 7984.760457186294, 8054.315383192495, 8137.430379873471, 8155.306798922039, 2829.6887342392733, 3278.4426647469986, 3303.752218583616, 3344.8662053517587, 3345.0968238575447, 940.6848705683536, 975.2003357246989, 980.6616717232544, 1018.6434747838198, 1041.833306670929, 26003.5698036606, 29814.0, 30040.399192016037, 30301.09096727102, 30657.693423065484, 966.9323147379756, 1024.5696807340935, 1073.7637719701654, 1101.531848964187, 1159.368144361325, 13606.795898061537, 14854.480093196686, 15041.623959401102, 15191.468298609496, 15314.387424502895, 563998.0, 667707.0, 673600.0, 679675.0, 680865.0, 61395.38604613986, 61549.0, 61571.0, 62281.688591556755, 62604.373956260766, 1432.1192466632945, 1464.048368560423, 1475.7417451945867, 1477.194928763805, 1479.8224213094281, 24929.127480538093, 29546.556801647563, 29680.406391872475, 30073.54889676613, 30474.542881857233, 45504.0, 54131.0, 55239.72380138074, 55263.0, 55265.44734552603, 486107.0, 491523.0, 493643.0, 494297.5285123551, 494345.0, 26289.868550657888, 27404.58893116565, 27471.587926180724, 27533.86233068902, 27696.307592310353, 46613.53386466228, 47562.76218618885, 47612.52387476081, 47923.76038119787, 47973.0, 38627.61372386353, 39233.41149882697, 39333.60666393414, 39443.80278098706, 40133.795986120465, 38138.237235254586, 38340.0, 38442.84671459861, 38487.03782405462, 38984.415233770946, 32516.83741581277, 36714.082147946516, 37545.81227093847, 38376.80811595924, 38648.22703545857, 30020.84989575038, 30896.53655195129, 31959.680403196602, 32093.0, 32261.838690806395, 23557.52884942326, 24337.75662243355, 24628.384290392165, 24781.389209701312, 25322.873385632953], "value": 17965.12704731164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.29650794284555, 389.4703203643045, 393.26655786957326, 396.40341286364037, 399.32912706652814, 17915.0, 20448.795512044875, 20728.27451039214, 21118.472038199052, 21220.68168977463, 19962.0, 20050.197992080335, 20881.373558793224, 20951.476213094644, 20989.47526311843, 5492.423295553969, 5818.2145410369585, 5856.887191433635, 5970.940290597104, 5993.790217342394, 5958.5828991970575, 5977.312609049972, 6006.48944839687, 6018.217631707894, 6084.878302433949, 5733.254676892019, 5767.336756273019, 5870.354261031304, 5873.794417195399, 5876.676782776967, 5647.830565083054, 5668.376478587352, 5822.737976791033, 5847.707614619273, 5857.062869940832, 4512.684112112153, 5222.556082732972, 5614.9612321720615, 5649.661020338793, 5846.093855452412, 12012.279263244232, 13033.739325213537, 13085.542006029838, 13530.458781648722, 13542.93228533861, 1764.682357175708, 2074.512489564951, 2591.9740802591923, 2676.919692409211, 2684.0874102804946, 9662.71011869645, 9714.659986900424, 9726.610935562569, 10166.796664066751, 10198.337108088024, 1684.9073300968441, 1688.7720157778665, 1695.152423788106, 1737.8175291594328, 1796.2186448894772, 97184.51407742986, 129172.99999999905, 130572.0, 131735.34132329372, 132971.0, 9291.67479138227, 11409.0, 11512.079033677286, 11642.068634509218, 11791.528338866348, 14464.059836110619, 14488.058276212012, 14504.202268875102, 14505.782413263809, 14564.85435145635, 7078.539894906815, 8503.022152452548, 8561.529115898686, 8583.969923609082, 8671.132886711279, 8459.619317130744, 8466.788330291793, 8486.575671216415, 8580.099089595627, 8588.227059564544, 4442.62278693606, 4541.364209010741, 4557.498675145731, 4594.517575654575, 4626.467956185014, 1640.1389270632774, 1951.9316823911095, 1953.1991883327767, 1957.0508303472805, 1977.5352792093847, 833.8457385383723, 842.726114012936, 926.7451450850936, 937.5265490927038, 940.4310392212612, 19913.60172796536, 20336.491587710425, 20603.896980515, 20719.378418647466, 20819.58360832775, 948.814981078683, 968.0, 982.1210017034714, 1016.2682868334787, 1019.3435667041058, 10649.988251116132, 10881.564737410574, 10887.401192934465, 10924.672259832218, 10952.671419857417, 554365.0, 568777.0, 601102.0, 613858.9307053436, 615738.0, 116149.0, 134472.3276383612, 135047.64952350548, 135701.64298357087, 141258.0, 990.6037584965927, 992.7270000749852, 1008.1884083312857, 1009.2128140050772, 1013.6502906497246, 14640.487582934546, 18045.09774511243, 18303.45089647313, 18532.536686582942, 19874.304399346533, 54562.0, 55604.88790224092, 56271.43728562826, 56440.43559564352, 56789.148162776764, 66519.0, 71210.0, 78435.82346264696, 78686.21313786789, 81360.0, 6454.644994525158, 6832.795016149524, 6896.344847239504, 7813.0, 8159.224873636995, 10214.846777298199, 12380.319082450551, 12514.31171285588, 12695.111342205957, 12913.160644558073, 9274.44353338802, 9701.544768284813, 10923.344599324066, 10952.069074128789, 11141.442927853413, 10550.208734345108, 10722.303050301705, 10812.0, 10931.726706832393, 11026.393548354918, 11242.887571124511, 11405.707818092502, 11428.028617567268, 11550.364729939934, 11593.420328983686, 8037.156098609562, 8104.149064348158, 8297.958510207653, 8318.792030199293, 8329.000519937646, 10581.0, 10917.945410272898, 11004.954529319702, 11161.944190279322, 11281.661550153509], "value": 9318.468152529962, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.634660854699, 1611.2346635348208, 1821.471773185776, 1888.4145914766432, 1938.3215874443947, 7822.256885595873, 8361.247487726103, 8523.744287671365, 8657.091005444425, 8675.13248675133, 7180.67686954087, 8479.491230526157, 8522.508561001816, 8582.656693732244, 8583.141685831413, 2157.9352619421443, 2184.4429670434015, 2339.754325795797, 2352.929412117631, 2383.594788885885, 2359.3983534198755, 2370.9644355334685, 2395.1377504098596, 2395.73646898841, 2400.219928523228, 2248.651459023854, 2258.6837842702034, 2271.818254539641, 2359.7758212969825, 2372.78644921957, 2266.7393249776155, 2356.363781778927, 2377.95244095118, 2391.0674836813655, 2398.4363674536385, 2328.860268383902, 2335.614623587106, 2344.8358614896965, 2348.0842471436154, 2349.5888219561507, 8789.472631642122, 8853.778655533599, 8871.334649901282, 8935.821283574358, 8975.865362019575, 5155.304033955405, 5202.0896343140175, 5319.5478384337375, 5417.6207665463435, 5420.376656684453, 3448.6378930212218, 3519.9296014079832, 3603.8198090095652, 3614.7288953328343, 3626.7642603230706, 1004.4174378860243, 1024.7233247023337, 1032.0711359776187, 1042.0933787604831, 1046.3460337289193, 11618.94190529042, 11851.0, 11865.881341186563, 11890.0, 11906.940465297705, 4162.812673429703, 4680.15757163707, 4751.429828420612, 4800.455758448028, 4847.563719265283, 9808.607655693691, 9809.754756131153, 9818.361806482555, 9905.009499050182, 9907.653232136841, 5271.841844744664, 5325.147976323769, 5362.490563396471, 5366.651167674088, 5386.272853164773, 3929.430232616312, 4363.956360436355, 4809.230523116355, 4830.154722923475, 4851.223804191312, 3886.902827429337, 3894.941575876364, 3973.3245348290284, 4011.0373510357304, 4039.0508230565792, 1292.1536393662166, 1311.3312210772383, 1311.4098655605017, 1319.8680131986916, 1329.2290471526492, 873.7466134820958, 879.938404311692, 880.1770344727701, 915.9009188973258, 918.807050519385, 5055.418626857885, 5617.438256174431, 5715.085586306171, 5840.795572154954, 5882.411758824083, 783.7256960063932, 943.0852073488705, 958.3674774648698, 967.4533888353058, 994.105305225303, 3845.8653947111716, 3979.621935916083, 3982.6415622594104, 3989.122393073519, 4011.8194681239415, 10073.942236065253, 10259.28185027056, 10262.43556604379, 10378.169746420268, 10538.0, 10538.420386878795, 10587.100096491884, 10616.94691526553, 10672.62645807393, 10712.78574428507, 8015.318697910627, 8075.5558444286125, 8145.633446494922, 8322.959630046244, 8335.083140854502, 5780.682062486603, 5860.736266867916, 5893.469587736953, 5931.525477961839, 5948.791792287249, 9651.855222171813, 9714.659986900424, 9898.356606820533, 9902.0, 9947.602095916372, 8381.413301068867, 9039.774005649911, 10038.297319187586, 10183.541740621542, 10333.173346132442, 6127.3566275540425, 6787.93212067873, 6866.279040700854, 6998.790036298919, 7069.752558660422, 8183.918160818315, 8227.382946279173, 8270.297024752723, 8283.37874659391, 8283.95858020706, 6551.95168772994, 7085.433165346863, 7155.534890232118, 7314.853702926018, 7396.96301518489, 7139.428845692436, 7171.426285897011, 7247.057882475245, 7252.528585641917, 7336.229695881855, 6848.623325716934, 6978.511504194657, 6980.685869136003, 6985.231624521197, 7016.403605693573, 4832.0, 4866.318715379779, 4896.094222568836, 4952.752362381794, 5073.416557095834, 4588.334691469785, 4884.92672609904, 4917.0903382874285, 4932.3094766731965, 5144.897102058013], "value": 4538.486835339194, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.39744772984653, 293.5611261164559, 295.253009884991, 295.48732948334634, 302.0801658948502, 5533.778648854046, 5611.214429979796, 5634.211210430543, 5638.041532939405, 5653.1237658163, 5036.924446133311, 5462.6995515246645, 5557.3886872444, 5558.1093234735645, 5584.441555844423, 3965.662918651911, 4075.1034772350035, 4198.391233271173, 4217.198732240864, 4221.915561688765, 3813.3517302058576, 3910.7458015229063, 3964.484616999786, 3966.413434626158, 4196.22369861575, 3435.862565497377, 3793.9810300948457, 3886.650201481866, 4211.663066954637, 4226.795363321454, 3850.8832438592776, 4096.672266218725, 4127.731697439687, 4204.348326009474, 4214.325707886739, 3505.1237190702323, 3507.2810073934784, 3507.492143117683, 3526.664966828147, 3529.8941031769086, 4192.909843440727, 5331.946680533184, 5344.545713614348, 5346.625736198468, 5356.0, 4244.87756121939, 4295.699301048928, 4723.4568024677255, 4726.456457507396, 4765.21373973294, 5853.678047707374, 5858.2677165354335, 5873.590338318772, 5875.6474611523445, 5876.0, 3873.438351439054, 3875.8837234883, 3882.5340959084806, 3888.8250028748776, 3894.4742459767854, 5725.799597014126, 5812.0, 6077.179580756586, 6217.378262173836, 6220.346863579304, 3310.70203681665, 3416.9487457688156, 3605.351036813346, 3779.0, 3794.962050379516, 3112.595362602847, 3389.81356025416, 3552.5559305086863, 3819.3125237457525, 3999.060220848098, 3784.243151369682, 4514.345419914094, 4660.231061874821, 4695.530446955346, 4706.3646407735205, 5777.277840269966, 5869.148973398833, 5953.79161729346, 5967.045272756338, 5982.192404025488, 1246.7818131826893, 1301.381843624271, 1447.261896432827, 1451.9419223231164, 1458.0158393084837, 773.5938632218131, 870.616928551448, 920.9953950230206, 934.1965909317981, 947.8815148106487, 5224.608154388436, 5988.431099045583, 5999.220101386879, 6006.669633170218, 6020.337762846083, 6077.75688972445, 6630.602163870183, 6635.303293154246, 6641.369069938348, 6649.933500665028, 5233.947660523422, 5626.381098079208, 5660.320761508646, 5678.744456499468, 5932.04242599941, 2957.8668959896854, 3276.754243431752, 3419.504171895111, 3516.942588585706, 3545.663161999606, 6715.395614394728, 7013.6142512161305, 7023.613701246481, 7043.612601306977, 7058.435325173974, 5981.790637327673, 6656.234533028667, 6914.688839002257, 6954.582725036413, 6977.546459480118, 6612.867742645217, 6682.83292917681, 6812.48906332027, 6947.756828510978, 7045.436365090883, 5532.806351777668, 6605.669716514251, 6669.533132680665, 6679.630675711371, 6681.09805176295, 5218.869528261824, 6462.450691691259, 6636.668166591748, 6656.30108838565, 6672.59964402118, 6930.5148639594745, 6990.720371185095, 6991.091158149408, 6992.0, 7019.6841142147705, 3659.469376940382, 4092.856750013735, 4098.344264917599, 4181.330987042059, 4195.370694395743, 5759.2512973313205, 6104.032378953716, 6701.229358623821, 6741.0, 6758.8509953307075, 5048.570871475966, 5584.771350302927, 6321.620702757849, 6457.836624730768, 6527.706253218712, 5478.589105817, 5592.608517403743, 6475.740970361133, 6493.188351456068, 6516.543841931019, 4693.601043911169, 5096.643234973516, 5608.663480191201, 5774.220480235114, 5860.941390586039, 5148.665336753099, 5941.544321641226, 5954.612950158225, 5995.520358371407, 6018.36807135262, 5177.637565370538, 5617.634853734494, 5677.886442271214, 5877.647341159544, 5919.6744179070565], "value": 4376.012767835314, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.8603842058695, 717.8786685344803, 728.7012324946771, 756.2022066719609, 773.9110002349728, 13064.804027939577, 13535.984801139904, 14241.789447896928, 15216.239188040596, 16047.759283610725, 15138.318775655094, 15186.848131518682, 15311.387544498235, 15321.540353789404, 15349.462768803094, 869.6608322754131, 909.0818273543736, 910.0717268386242, 912.7672443526908, 913.5204017890594, 875.212308921969, 876.4040452492303, 911.676354894013, 913.1142791492275, 922.1792604581927, 945.7730144765276, 972.7568107973007, 972.8054389122168, 974.9707508774748, 979.5591983607369, 943.0381011368435, 946.8721722567468, 965.5558443116175, 966.5264020629911, 967.4243824924159, 705.8411857332111, 711.9715211391538, 716.8637958787814, 722.8120688620944, 723.1177962885272, 14820.40718371264, 14832.703345933021, 14833.406663733438, 14853.257337133207, 14889.106653600818, 11955.103367247491, 11993.820092698617, 12072.698182545419, 12130.757384852253, 12331.075169362244, 9875.950620246853, 9878.456684882329, 9939.0, 10039.849402258971, 10048.09567138961, 3380.746444016684, 3439.208981934144, 3442.122258824009, 3443.1736383268085, 3453.620101788801, 73852.63073684597, 79595.6020219901, 82339.0, 83008.58495707544, 83559.0, 9105.590248438835, 9141.40580862255, 9242.76893077665, 9335.719928402159, 9382.296327775444, 19779.0, 19947.800521994886, 20137.49656258576, 20159.496012599804, 20318.492037699176, 7268.890966635505, 7325.853482930312, 7371.852562948711, 7522.059742532184, 7600.239976002355, 4073.5137280190247, 4083.530394004668, 4103.692223083281, 4122.546519882811, 4182.56083111275, 3776.4524143998965, 3943.5859234779937, 3946.506686664167, 3948.8420463181724, 3962.9696278967676, 674.814426032845, 697.5814511293183, 701.2251462134268, 709.3509438863485, 709.4572651921285, 495.62085004971226, 562.9155626656035, 584.6433675457968, 603.6695974544759, 622.8100429368995, 7144.285571442814, 7929.207079292025, 7955.443118981616, 8049.3962952778775, 8086.5148091114725, 379.3645643547048, 500.79424383533694, 554.877926856091, 556.3629644057531, 640.414021170633, 3781.470594116826, 3790.2040571479747, 3821.7898015609408, 3842.1355195080882, 3999.24014437256, 71271.2872871275, 97190.0, 99115.50442247743, 101110.0, 101799.0, 83169.0, 83494.0, 83787.74318385229, 84220.0, 84755.15244847596, 58239.0, 73171.63414183001, 74953.0, 75840.0, 76020.61989690016, 6478.546501744832, 6836.248012718696, 7368.673638745077, 7621.504602200839, 7645.617719114133, 44749.776251118536, 44830.3275450875, 44935.0, 45136.32295515504, 46367.0, 48713.0, 62014.0, 64441.03338450021, 66329.66835165794, 66844.66310673663, 9879.456629885139, 9937.850932235877, 10066.44634545107, 10282.280240383101, 10363.94818025905, 10695.67075261248, 11211.887881121085, 11244.775104498029, 11343.376114313793, 11416.257943233662, 10140.29017968735, 10378.169746420117, 10435.16518678519, 10530.947345263225, 10542.8418573723, 10165.54255058539, 10230.590776368861, 10275.075243227846, 10476.68569942903, 10752.83870741955, 9921.0, 9944.900550994687, 10192.694219173436, 10249.897501024894, 10331.070203681404, 8693.174148455886, 8781.604827782892, 8785.077566855389, 9019.594118264826, 9027.954860225656, 8869.053838231419, 9110.635574576943, 9380.624775008922, 9576.0, 9637.18083962871], "value": 6741.699621015672, "unit": "score"}], "name": "dromaeo_css", "value": 7094.22160454558, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 117.0, 116.0, 117.0, 135.0, 172.0, 119.0, 116.0, 118.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 127.0, 151.0, 197.0, 132.0, 146.0, 125.0, 163.0, 160.0, 128.0], "value": 148.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 207.0, 239.0, 240.0, 277.0, 225.0, 199.0, 196.0, 197.0], "value": 217.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 77.0, 145.0, 59.0, 59.0, 65.0, 80.0, 149.0, 48.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 132.0, 78.0, 82.0, 64.0, 141.0, 114.0, 124.0, 167.0], "value": 106.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 85.0, 109.0, 132.0, 89.0, 86.0, 94.0, 118.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 153.0, 95.0, 95.0, 94.0, 96.0, 93.0, 96.0, 94.0, 94.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 125.0, 149.0, 135.0, 91.0, 105.0, 152.0, 103.0, 79.0, 140.0], "value": 117.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 61.0, 98.0, 93.0, 98.0, 92.0, 60.0, 84.0, 62.0, 62.0], "value": 75.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 69.0, 75.0, 84.0, 87.0, 70.0, 69.0, 67.0, 70.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 86.0, 76.0, 103.0, 114.0, 113.0, 112.0, 84.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 158.0, 157.0, 160.0, 151.0, 155.0, 155.0, 158.0, 174.0, 172.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 175.0, 175.0, 170.0, 203.0, 235.0, 184.0, 172.0, 174.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 67.0, 86.0, 101.0, 82.0, 71.0, 69.0, 70.0, 72.0, 70.0], "value": 76.1, "unit": "score"}], "name": "kraken", "value": 1659.4999999999998, "alertThreshold": 2.0}]} 05:33:17 INFO - Return code: 0 05:33:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:33:17 ERROR - # TBPL SUCCESS # 05:33:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:17 INFO - 2016-05-01 05:33:17.468 screenresolution[2001:23931] starting screenresolution argv=screenresolution get 05:33:17 INFO - 2016-05-01 05:33:17.471 screenresolution[2001:23931] Display 0: 1600x1200x32@60 05:33:17 INFO - 2016-05-01 05:33:17.477 screenresolution[2002:23934] starting screenresolution argv=screenresolution list 05:33:17 INFO - Available Modes on Display 0 05:33:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:17 INFO - Intel Iris: 05:33:17 INFO - Chipset Model: Intel Iris 05:33:17 INFO - Type: GPU 05:33:17 INFO - Bus: Built-In 05:33:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:17 INFO - Vendor: Intel (0x8086) 05:33:17 INFO - Device ID: 0x0a2e 05:33:17 INFO - Revision ID: 0x0009 05:33:17 INFO - Displays: 05:33:17 INFO - Display: 05:33:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:17 INFO - Display Serial Number: 1600x1200 60 05:33:17 INFO - Main Display: Yes 05:33:17 INFO - Mirror: Off 05:33:17 INFO - Online: Yes 05:33:17 INFO - Rotation: Supported 05:33:17 INFO - Return code: 0 05:33:17 INFO - Running post-action listener: _resource_record_post_action 05:33:17 INFO - Running post-run listener: _resource_record_post_run 05:33:18 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 2465792; Write bytes: 702548480; Read time: 21; Write time: 2125 05:33:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 391553536; Write bytes: 387457024; Read time: 29097; Write time: 784 05:33:18 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 2465792; Write bytes: 299600384; Read time: 21; Write time: 1269 05:33:18 INFO - Running post-run listener: _upload_blobber_files 05:33:18 INFO - Blob upload gear active. 05:33:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:18 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:33:18 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:33:18 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:33:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:20 INFO - (blobuploader) - INFO - Done attempting. 05:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:33:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:21 INFO - (blobuploader) - INFO - Done attempting. 05:33:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:21 INFO - Return code: 0 05:33:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bb47abac8d989904d7894d7189996a965a7fdc2ebe521b86e6cd955abb6d0342a688919ace77a3bd5506b8bc69df783c1d73ea1f0c87bb733df5122c97120e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9477dccdd601e59739a4ede16e403fa5feae0b0df6cc9a4fd300f6ec4be7e01ec77d74cce3b444877aaf3e64b69344cfccf2d4057e6ef3bdc991a25141f0e6a"} 05:33:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:21 INFO - Contents: 05:33:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bb47abac8d989904d7894d7189996a965a7fdc2ebe521b86e6cd955abb6d0342a688919ace77a3bd5506b8bc69df783c1d73ea1f0c87bb733df5122c97120e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9477dccdd601e59739a4ede16e403fa5feae0b0df6cc9a4fd300f6ec4be7e01ec77d74cce3b444877aaf3e64b69344cfccf2d4057e6ef3bdc991a25141f0e6a"} 05:33:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:21 INFO - Copying logs to upload dir... 05:33:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1350.074175 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2016-05-01 05:33:21.259621) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 05:33:21.263385) ========= 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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/6bb47abac8d989904d7894d7189996a965a7fdc2ebe521b86e6cd955abb6d0342a688919ace77a3bd5506b8bc69df783c1d73ea1f0c87bb733df5122c97120e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9477dccdd601e59739a4ede16e403fa5feae0b0df6cc9a4fd300f6ec4be7e01ec77d74cce3b444877aaf3e64b69344cfccf2d4057e6ef3bdc991a25141f0e6a"} build_url:https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010567 build_url: 'https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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/6bb47abac8d989904d7894d7189996a965a7fdc2ebe521b86e6cd955abb6d0342a688919ace77a3bd5506b8bc69df783c1d73ea1f0c87bb733df5122c97120e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9477dccdd601e59739a4ede16e403fa5feae0b0df6cc9a4fd300f6ec4be7e01ec77d74cce3b444877aaf3e64b69344cfccf2d4057e6ef3bdc991a25141f0e6a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 05:33:21.290963) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:33:21.291301) ========= 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.1ZrnBEO3PI/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.ADO4FB7Oqs/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005361 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:33:21.339889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 05:33:21.340209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 05:33:21.340608) ========= ========= Total master_lag: 0.55 =========