builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1462978613.85 results: success (0) buildid: 20160511055210 builduid: 50fa9985e4634107b7dcd265f1723e55 revision: 91714307a799b978b0a94911cd99ee75a1de0ac5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.851864) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.852323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.852791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.871246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.871637) ========= 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.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-11 07:56:53-- 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% 145M=0s 2016-05-11 07:56:53 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.973517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:53.973903) ========= 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.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.020398 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:54.022782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 07:56:54.023123) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 91714307a799b978b0a94911cd99ee75a1de0ac5 --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 91714307a799b978b0a94911cd99ee75a1de0ac5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-11 07:56:54,094 truncating revision to first 12 chars 2016-05-11 07:56:54,094 Setting DEBUG logging. 2016-05-11 07:56:54,094 attempt 1/10 2016-05-11 07:56:54,094 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/91714307a799?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 07:56:54,979 unpacking tar archive at: mozilla-central-91714307a799/testing/mozharness/ program finished with exit code 0 elapsedTime=1.135104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 07:56:55.176955) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:55.177283) ========= script_repo_revision: 91714307a799b978b0a94911cd99ee75a1de0ac5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:55.177683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:55.177945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:56:55.192718) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 07:56:55.193343) ========= /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.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:56:55 INFO - MultiFileLogger online at 20160511 07:56:55 in /builds/slave/test 07:56:55 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 07:56:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:55 INFO - {'append_to_log': False, 07:56:55 INFO - 'base_work_dir': '/builds/slave/test', 07:56:55 INFO - 'blob_upload_branch': 'Firefox', 07:56:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:55 INFO - 'branch': 'Firefox', 07:56:55 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:55 INFO - 'config_files': ('talos/mac_config.py',), 07:56:55 INFO - 'default_actions': ('clobber', 07:56:55 INFO - 'read-buildbot-config', 07:56:55 INFO - 'download-and-extract', 07:56:55 INFO - 'populate-webroot', 07:56:55 INFO - 'create-virtualenv', 07:56:55 INFO - 'install', 07:56:55 INFO - 'run-tests'), 07:56:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:55 INFO - 'download_minidump_stackwalk': True, 07:56:55 INFO - 'download_symbols': 'ondemand', 07:56:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:55 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:55 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:55 INFO - 'installer_path': 'installer.exe', 07:56:55 INFO - 'log_level': 'info', 07:56:55 INFO - 'log_name': 'talos', 07:56:55 INFO - 'log_to_console': True, 07:56:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:56:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:56:55 INFO - 'opt_config_files': (), 07:56:55 INFO - 'pip_index': False, 07:56:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:55 INFO - 'cmd': ('bash', 07:56:55 INFO - '-c', 07:56:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:55 INFO - 'enabled': True, 07:56:55 INFO - 'halt_on_failure': False, 07:56:55 INFO - 'name': 'check_screen_resolution'},), 07:56:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:55 INFO - 'cmd': ('bash', 07:56:55 INFO - '-c', 07:56:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:55 INFO - 'enabled': True, 07:56:55 INFO - 'halt_on_failure': False, 07:56:55 INFO - 'name': 'check_screen_resolution'},), 07:56:55 INFO - 'run_cmd_checks_enabled': True, 07:56:55 INFO - 'sps_profile': False, 07:56:55 INFO - 'sps_profile_interval': 0, 07:56:55 INFO - 'suite': 'dromaeojs-e10s', 07:56:55 INFO - 'system_bits': '32', 07:56:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:55 INFO - 'title': 't-yosemite-r7-0113', 07:56:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:55 INFO - 'use_talos_json': True, 07:56:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:55 INFO - 'work_dir': 'build'} 07:56:55 INFO - ##### 07:56:55 INFO - ##### Running clobber step. 07:56:55 INFO - ##### 07:56:55 INFO - Running pre-action listener: _resource_record_pre_action 07:56:55 INFO - Running main action method: clobber 07:56:55 INFO - rmtree: /builds/slave/test/build 07:56:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:56 INFO - Running post-action listener: _resource_record_post_action 07:56:56 INFO - ##### 07:56:56 INFO - ##### Finished clobber step (success) 07:56:56 INFO - ##### 07:56:56 INFO - ##### 07:56:56 INFO - ##### Running read-buildbot-config step. 07:56:56 INFO - ##### 07:56:56 INFO - Running pre-action listener: _resource_record_pre_action 07:56:56 INFO - Running main action method: read_buildbot_config 07:56:56 INFO - Using buildbot properties: 07:56:56 INFO - { 07:56:56 INFO - "project": "", 07:56:56 INFO - "product": "firefox", 07:56:56 INFO - "script_repo_revision": "production", 07:56:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:56:56 INFO - "repository": "", 07:56:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:56:56 INFO - "buildid": "20160511055210", 07:56:56 INFO - "pgo_build": "False", 07:56:56 INFO - "basedir": "/builds/slave/test", 07:56:56 INFO - "buildnumber": 9, 07:56:56 INFO - "slavename": "t-yosemite-r7-0113", 07:56:56 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:56:56 INFO - "platform": "yosemite_r7", 07:56:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:56:56 INFO - "branch": "mozilla-central", 07:56:56 INFO - "repo_path": "mozilla-central", 07:56:56 INFO - "revision": "91714307a799b978b0a94911cd99ee75a1de0ac5", 07:56:56 INFO - "stage_platform": "macosx64", 07:56:56 INFO - "builduid": "50fa9985e4634107b7dcd265f1723e55", 07:56:56 INFO - "slavebuilddir": "test" 07:56:56 INFO - } 07:56:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:56:56 INFO - Running post-action listener: _resource_record_post_action 07:56:56 INFO - ##### 07:56:56 INFO - ##### Finished read-buildbot-config step (success) 07:56:56 INFO - ##### 07:56:56 INFO - ##### 07:56:56 INFO - ##### Running download-and-extract step. 07:56:56 INFO - ##### 07:56:56 INFO - Running pre-action listener: _resource_record_pre_action 07:56:56 INFO - Running main action method: download_and_extract 07:56:56 INFO - mkdir: /builds/slave/test/build/tests 07:56:56 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')]} 07:56:56 INFO - https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:56:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:56:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:56:58 INFO - Downloaded 1199 bytes. 07:56:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:58 INFO - Using the following test package requirements: 07:56:58 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:56:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:56:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:56:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'jsshell-mac.zip'], 07:56:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:56:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:56:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:56:58 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:56:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:56:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:58 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:56:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:58 INFO - https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:00 INFO - Downloaded 18150554 bytes. 07:57:00 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'] 07:57:00 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 07:57:03 INFO - Return code: 0 07:57:03 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 07:57: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')]} 07:57:03 INFO - https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:04 INFO - Downloaded 18150554 bytes. 07:57:04 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'] 07:57:04 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 07:57:07 INFO - Return code: 0 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:07 INFO - https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:57:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:57:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:57:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:08 INFO - Downloaded 10840152 bytes. 07:57:08 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'] 07:57:08 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 07:57:09 INFO - Return code: 0 07:57:09 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')]} 07:57:09 INFO - https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:57:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYMLr3kdQa22uAU6K3HVzg/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 07:57:12 INFO - Downloaded 109856803 bytes. 07:57:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:12 INFO - mkdir: /builds/slave/test/properties 07:57:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:57:12 INFO - Writing to file /builds/slave/test/properties/build_url 07:57:12 INFO - Contents: 07:57:12 INFO - build_url:https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:13 INFO - Running post-action listener: _resource_record_post_action 07:57:13 INFO - Running post-action listener: set_extra_try_arguments 07:57:13 INFO - ##### 07:57:13 INFO - ##### Finished download-and-extract step (success) 07:57:13 INFO - ##### 07:57:13 INFO - ##### 07:57:13 INFO - ##### Running populate-webroot step. 07:57:13 INFO - ##### 07:57:13 INFO - Running pre-action listener: _resource_record_pre_action 07:57:13 INFO - Running main action method: populate_webroot 07:57:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:57:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:57:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:57:13 INFO - '--tppagecycles', 07:57:13 INFO - '7'], 07:57:13 INFO - 'tests': ['tsvgm']}}, 07:57:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:57:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:57:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:57:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:57:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['damp', 'tps']}, 07:57:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'tests': ['damp', 'tps']}, 07:57:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['dromaeo_dom']}, 07:57:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:57:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'other-e10s': {'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['a11yr', 07:57:13 INFO - 'ts_paint', 07:57:13 INFO - 'tpaint', 07:57:13 INFO - 'sessionrestore', 07:57:13 INFO - 'sessionrestore_no_auto_restore', 07:57:13 INFO - 'tabpaint']}, 07:57:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['tsvgx', 07:57:13 INFO - 'tsvgr_opacity', 07:57:13 INFO - 'tart', 07:57:13 INFO - 'tscrollx', 07:57:13 INFO - 'cart']}, 07:57:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:57:13 INFO - 'tsvgr_opacity', 07:57:13 INFO - 'tart', 07:57:13 INFO - 'tscrollx', 07:57:13 INFO - 'cart']}, 07:57:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'talos_options': ['--disable-e10s'], 07:57:13 INFO - 'tests': ['tp5o']}, 07:57:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'tests': ['tp5o']}, 07:57:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'talos_options': ['--disable-e10s', 07:57:13 INFO - '--xperf_path', 07:57:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:13 INFO - 'tests': ['tp5n']}, 07:57:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:13 INFO - 'talos_options': ['--xperf_path', 07:57:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:13 INFO - 'tests': ['tp5n']}}, 07:57:13 INFO - 'talos.zip': {'path': '', 07:57:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:57:13 INFO - Running post-action listener: _resource_record_post_action 07:57:13 INFO - ##### 07:57:13 INFO - ##### Finished populate-webroot step (success) 07:57:13 INFO - ##### 07:57:13 INFO - ##### 07:57:13 INFO - ##### Running create-virtualenv step. 07:57:13 INFO - ##### 07:57:13 INFO - Running pre-action listener: _resource_record_pre_action 07:57:13 INFO - Running main action method: create_virtualenv 07:57:13 INFO - Creating virtualenv /builds/slave/test/build/venv 07:57:13 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 07:57:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:57:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:57:13 INFO - Using real prefix '/tools/python27' 07:57:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:57:14 INFO - Installing distribute.............................................................................................................................................................................................done. 07:57:17 INFO - Installing pip.................done. 07:57:17 INFO - Return code: 0 07:57:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:57:17 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')]} 07:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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')]} 07:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:17 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 07:57:17 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 07:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:17 INFO - 'HOME': '/Users/cltbld', 07:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:17 INFO - 'LOGNAME': 'cltbld', 07:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:17 INFO - 'MOZ_NO_REMOTE': '1', 07:57:17 INFO - 'NO_EM_RESTART': '1', 07:57:17 INFO - 'PAGER': '/bin/cat', 07:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:17 INFO - 'PWD': '/builds/slave/test', 07:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:17 INFO - 'SHELL': '/bin/bash', 07:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:17 INFO - 'USER': 'cltbld', 07:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:17 INFO - 'XPC_FLAGS': '0x0', 07:57:17 INFO - 'XPC_SERVICE_NAME': '0', 07:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:17 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 07:57:17 INFO - Cleaning up... 07:57:17 INFO - Return code: 0 07:57:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:57:17 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')]} 07:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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')]} 07:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:17 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 07:57:17 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 07:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:17 INFO - 'HOME': '/Users/cltbld', 07:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:17 INFO - 'LOGNAME': 'cltbld', 07:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:17 INFO - 'MOZ_NO_REMOTE': '1', 07:57:17 INFO - 'NO_EM_RESTART': '1', 07:57:17 INFO - 'PAGER': '/bin/cat', 07:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:17 INFO - 'PWD': '/builds/slave/test', 07:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:17 INFO - 'SHELL': '/bin/bash', 07:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:17 INFO - 'USER': 'cltbld', 07:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:17 INFO - 'XPC_FLAGS': '0x0', 07:57:17 INFO - 'XPC_SERVICE_NAME': '0', 07:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:17 INFO - Downloading/unpacking psutil>=0.7.1 07:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:20 INFO - Installing collected packages: psutil 07:57:20 INFO - Running setup.py install for psutil 07:57:20 INFO - building 'psutil._psutil_osx' extension 07:57:20 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 07:57:21 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 07:57:21 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 07:57:21 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 07:57:21 INFO - building 'psutil._psutil_posix' extension 07:57:21 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 07:57:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:57:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:57:21 INFO - ^ 07:57:21 INFO - 1 warning generated. 07:57:21 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 07:57:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:21 INFO - Successfully installed psutil 07:57:21 INFO - Cleaning up... 07:57:21 INFO - Return code: 0 07:57:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 07:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 07:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:21 INFO - 'HOME': '/Users/cltbld', 07:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:21 INFO - 'LOGNAME': 'cltbld', 07:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:21 INFO - 'MOZ_NO_REMOTE': '1', 07:57:21 INFO - 'NO_EM_RESTART': '1', 07:57:21 INFO - 'PAGER': '/bin/cat', 07:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:21 INFO - 'PWD': '/builds/slave/test', 07:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:21 INFO - 'SHELL': '/bin/bash', 07:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:21 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:21 INFO - 'USER': 'cltbld', 07:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:21 INFO - 'XPC_FLAGS': '0x0', 07:57:21 INFO - 'XPC_SERVICE_NAME': '0', 07:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:57:21 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 07:57:21 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 07:57:21 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 07:57:21 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 07:57:21 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 07:57:21 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 07:57:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:57:24 INFO - Installing collected packages: mozsystemmonitor 07:57:24 INFO - Running setup.py install for mozsystemmonitor 07:57:24 INFO - Successfully installed mozsystemmonitor 07:57:24 INFO - Cleaning up... 07:57:24 INFO - Return code: 0 07:57:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:24 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')]} 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:24 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')]} 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:24 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:24 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 07:57:24 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 07:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:24 INFO - 'HOME': '/Users/cltbld', 07:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:24 INFO - 'LOGNAME': 'cltbld', 07:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:24 INFO - 'MOZ_NO_REMOTE': '1', 07:57:24 INFO - 'NO_EM_RESTART': '1', 07:57:24 INFO - 'PAGER': '/bin/cat', 07:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:24 INFO - 'PWD': '/builds/slave/test', 07:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:24 INFO - 'SHELL': '/bin/bash', 07:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:24 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:24 INFO - 'USER': 'cltbld', 07:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:24 INFO - 'XPC_FLAGS': '0x0', 07:57:24 INFO - 'XPC_SERVICE_NAME': '0', 07:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:24 INFO - Downloading/unpacking blobuploader==1.2.4 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:27 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - Downloading docopt-0.6.1.tar.gz 07:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:28 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:28 INFO - Running setup.py install for blobuploader 07:57:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:28 INFO - Running setup.py install for requests 07:57:29 INFO - Running setup.py install for docopt 07:57:29 INFO - Successfully installed blobuploader requests docopt 07:57:29 INFO - Cleaning up... 07:57:29 INFO - Return code: 0 07:57:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:29 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:29 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 07:57:29 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 07:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:29 INFO - 'HOME': '/Users/cltbld', 07:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:29 INFO - 'LOGNAME': 'cltbld', 07:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:29 INFO - 'MOZ_NO_REMOTE': '1', 07:57:29 INFO - 'NO_EM_RESTART': '1', 07:57:29 INFO - 'PAGER': '/bin/cat', 07:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:29 INFO - 'PWD': '/builds/slave/test', 07:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:29 INFO - 'SHELL': '/bin/bash', 07:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:29 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:29 INFO - 'USER': 'cltbld', 07:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:29 INFO - 'XPC_FLAGS': '0x0', 07:57:29 INFO - 'XPC_SERVICE_NAME': '0', 07:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-haupVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wgAvI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-9UtWyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Sd39wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-FsJPMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-5lX4A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-E1Qwbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7DNgHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-znqLpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-1p0pr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-0vn5sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-LhODzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-eLQgUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-duDptn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-o0SwUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-fgtZJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-iNIzEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:31 INFO - Running setup.py install for manifestparser 07:57:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:31 INFO - Running setup.py install for mozcrash 07:57:31 INFO - Running setup.py install for mozdebug 07:57:31 INFO - Running setup.py install for mozdevice 07:57:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Running setup.py install for mozfile 07:57:32 INFO - Running setup.py install for mozhttpd 07:57:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Running setup.py install for mozinfo 07:57:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Running setup.py install for mozInstall 07:57:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Running setup.py install for mozleak 07:57:32 INFO - Running setup.py install for mozlog 07:57:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:32 INFO - Running setup.py install for moznetwork 07:57:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Running setup.py install for mozprocess 07:57:33 INFO - Running setup.py install for mozprofile 07:57:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Running setup.py install for mozrunner 07:57:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Running setup.py install for mozscreenshot 07:57:33 INFO - Running setup.py install for moztest 07:57:33 INFO - Running setup.py install for mozversion 07:57:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:33 INFO - Cleaning up... 07:57:34 INFO - Return code: 0 07:57:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:34 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:34 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 07:57:34 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 07:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:34 INFO - 'HOME': '/Users/cltbld', 07:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:34 INFO - 'LOGNAME': 'cltbld', 07:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:34 INFO - 'MOZ_NO_REMOTE': '1', 07:57:34 INFO - 'NO_EM_RESTART': '1', 07:57:34 INFO - 'PAGER': '/bin/cat', 07:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:34 INFO - 'PWD': '/builds/slave/test', 07:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:34 INFO - 'SHELL': '/bin/bash', 07:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:34 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:34 INFO - 'USER': 'cltbld', 07:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:34 INFO - 'XPC_FLAGS': '0x0', 07:57:34 INFO - 'XPC_SERVICE_NAME': '0', 07:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-guT6qP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-SEIaov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-J5_iPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-_XMCsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Sg3IEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-O5tN3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:34 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)) 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-lVm6dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7jm4QA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-tSP8cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-2ybNKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ULs6vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-GzyAGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-trqD7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-qY1_Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ZQ223c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ogh5_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:35 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)) 07:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-htlYIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:36 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)) 07:57:36 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)) 07:57:36 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)) 07:57:36 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)) 07:57:36 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)) 07:57:36 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)) 07:57:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:36 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 07:57:36 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 07:57:36 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 07:57:36 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 07:57:36 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 07:57:36 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 07:57:38 INFO - Downloading blessings-1.6.tar.gz 07:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:38 INFO - Installing collected packages: blessings 07:57:38 INFO - Running setup.py install for blessings 07:57:38 INFO - Successfully installed blessings 07:57:38 INFO - Cleaning up... 07:57:38 INFO - Return code: 0 07:57:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:39 INFO - Reading from file tmpfile_stdout 07:57:39 INFO - Current package versions: 07:57:39 INFO - blessings == 1.6 07:57:39 INFO - blobuploader == 1.2.4 07:57:39 INFO - docopt == 0.6.1 07:57:39 INFO - manifestparser == 1.1 07:57:39 INFO - mozInstall == 1.12 07:57:39 INFO - mozcrash == 0.17 07:57:39 INFO - mozdebug == 0.1 07:57:39 INFO - mozdevice == 0.48 07:57:39 INFO - mozfile == 1.2 07:57:39 INFO - mozhttpd == 0.7 07:57:39 INFO - mozinfo == 0.9 07:57:39 INFO - mozleak == 0.1 07:57:39 INFO - mozlog == 3.1 07:57:39 INFO - moznetwork == 0.27 07:57:39 INFO - mozprocess == 0.22 07:57:39 INFO - mozprofile == 0.28 07:57:39 INFO - mozrunner == 6.11 07:57:39 INFO - mozscreenshot == 0.1 07:57:39 INFO - mozsystemmonitor == 0.0 07:57:39 INFO - moztest == 0.7 07:57:39 INFO - mozversion == 1.4 07:57:39 INFO - psutil == 3.1.1 07:57:39 INFO - requests == 1.2.3 07:57:39 INFO - wsgiref == 0.1.2 07:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:39 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 07:57:39 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 07:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:39 INFO - 'HOME': '/Users/cltbld', 07:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:39 INFO - 'LOGNAME': 'cltbld', 07:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:39 INFO - 'MOZ_NO_REMOTE': '1', 07:57:39 INFO - 'NO_EM_RESTART': '1', 07:57:39 INFO - 'PAGER': '/bin/cat', 07:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:39 INFO - 'PWD': '/builds/slave/test', 07:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:39 INFO - 'SHELL': '/bin/bash', 07:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:39 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:39 INFO - 'USER': 'cltbld', 07:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:39 INFO - 'XPC_FLAGS': '0x0', 07:57:39 INFO - 'XPC_SERVICE_NAME': '0', 07:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 INFO - Cleaning up... 07:57:39 INFO - Return code: 0 07:57:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 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 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57:39 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 07:57:39 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 07:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:39 INFO - 'HOME': '/Users/cltbld', 07:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:39 INFO - 'LOGNAME': 'cltbld', 07:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:39 INFO - 'MOZ_NO_REMOTE': '1', 07:57:39 INFO - 'NO_EM_RESTART': '1', 07:57:39 INFO - 'PAGER': '/bin/cat', 07:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:39 INFO - 'PWD': '/builds/slave/test', 07:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:39 INFO - 'SHELL': '/bin/bash', 07:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:39 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:39 INFO - 'USER': 'cltbld', 07:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:39 INFO - 'XPC_FLAGS': '0x0', 07:57:39 INFO - 'XPC_SERVICE_NAME': '0', 07:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:39 INFO - Downloading/unpacking jsonschema 07:57:39 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 07:57:39 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 07:57:39 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 07:57:39 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 07:57:39 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 07:57:39 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 07:57:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:42 INFO - Downloading/unpacking functools32 (from jsonschema) 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57:42 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:42 INFO - warning: no files found matching '*.txt' 07:57:42 INFO - no previously-included directories found matching 'build' 07:57:42 INFO - no previously-included directories found matching 'dist' 07:57:42 INFO - no previously-included directories found matching '.git*' 07:57:42 INFO - Installing collected packages: jsonschema, functools32 07:57:42 INFO - Running setup.py install for functools32 07:57:42 INFO - warning: no files found matching '*.txt' 07:57:42 INFO - no previously-included directories found matching 'build' 07:57:42 INFO - no previously-included directories found matching 'dist' 07:57:42 INFO - no previously-included directories found matching '.git*' 07:57:42 INFO - Successfully installed jsonschema functools32 07:57:42 INFO - Cleaning up... 07:57:42 INFO - Return code: 0 07:57:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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')]} 07:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96595a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, '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.AP2beaH0Vn/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.gzYyhCMczs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 07:57: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', 'flake8'] in /builds/slave/test/build 07:57: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 flake8 07:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:42 INFO - 'HOME': '/Users/cltbld', 07:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:42 INFO - 'LOGNAME': 'cltbld', 07:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:42 INFO - 'MOZ_NO_REMOTE': '1', 07:57:42 INFO - 'NO_EM_RESTART': '1', 07:57:42 INFO - 'PAGER': '/bin/cat', 07:57:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:42 INFO - 'PWD': '/builds/slave/test', 07:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:42 INFO - 'SHELL': '/bin/bash', 07:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:57:42 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:42 INFO - 'USER': 'cltbld', 07:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:42 INFO - 'XPC_FLAGS': '0x0', 07:57:42 INFO - 'XPC_SERVICE_NAME': '0', 07:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:43 INFO - Downloading/unpacking flake8 07:57:43 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 07:57:43 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 07:57:43 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 07:57:43 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 07:57:43 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 07:57:43 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 07:57:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:57:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:57:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:57:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:46 INFO - no previously-included directories found matching 'docs/_build' 07:57:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:57:46 INFO - Running setup.py install for pep8 07:57:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:47 INFO - no previously-included directories found matching 'docs/_build' 07:57:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:57:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:57:47 INFO - Cleaning up... 07:57:47 INFO - Return code: 0 07:57:47 INFO - Running post-action listener: _resource_record_post_action 07:57:47 INFO - Running post-action listener: _start_resource_monitoring 07:57:47 INFO - Starting resource monitoring. 07:57:47 INFO - ##### 07:57:47 INFO - ##### Finished create-virtualenv step (success) 07:57:47 INFO - ##### 07:57:47 INFO - ##### 07:57:47 INFO - ##### Running install step. 07:57:47 INFO - ##### 07:57:47 INFO - Running pre-action listener: _resource_record_pre_action 07:57:47 INFO - Running main action method: install 07:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:47 INFO - Reading from file tmpfile_stdout 07:57:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:47 INFO - Reading from file tmpfile_stdout 07:57:47 INFO - Output received: 07:57:47 INFO - Usage: mozinstall [options] installer 07:57:47 INFO - Options: 07:57:47 INFO - -h, --help show this help message and exit 07:57:47 INFO - -d DEST, --destination=DEST 07:57:47 INFO - Directory to install application into. [default: 07:57:47 INFO - "/builds/slave/test"] 07:57:47 INFO - --app=APP Application being installed. [default: firefox] 07:57:47 INFO - mkdir: /builds/slave/test/build/application 07:57:47 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'] 07:57:47 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 07:58:21 INFO - Reading from file tmpfile_stdout 07:58:21 INFO - Output received: 07:58:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:58:21 INFO - Running post-action listener: _resource_record_post_action 07:58:21 INFO - ##### 07:58:21 INFO - ##### Finished install step (success) 07:58:21 INFO - ##### 07:58:21 INFO - ##### 07:58:21 INFO - ##### Running run-tests step. 07:58:21 INFO - ##### 07:58:21 INFO - Running pre-action listener: _resource_record_pre_action 07:58:21 INFO - Running main action method: run_tests 07:58:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:58:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:58:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:58:21 INFO - 2016-05-11 07:58:21.071 screenresolution[1927:25893] starting screenresolution argv=screenresolution get 07:58:21 INFO - 2016-05-11 07:58:21.074 screenresolution[1927:25893] Display 0: 1600x1200x32@60 07:58:21 INFO - 2016-05-11 07:58:21.080 screenresolution[1928:25896] starting screenresolution argv=screenresolution list 07:58:21 INFO - Available Modes on Display 0 07:58:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:58:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:58:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:58:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:58:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:58:21 INFO - Intel Iris: 07:58:21 INFO - Chipset Model: Intel Iris 07:58:21 INFO - Type: GPU 07:58:21 INFO - Bus: Built-In 07:58:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:58:21 INFO - Vendor: Intel (0x8086) 07:58:21 INFO - Device ID: 0x0a2e 07:58:21 INFO - Revision ID: 0x0009 07:58:21 INFO - Displays: 07:58:21 INFO - Display: 07:58:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:58:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:58:21 INFO - Display Serial Number: 1600x1200 60 07:58:21 INFO - Main Display: Yes 07:58:21 INFO - Mirror: Off 07:58:21 INFO - Online: Yes 07:58:21 INFO - Rotation: Supported 07:58:21 INFO - Return code: 0 07:58:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:58:21 INFO - Python 2.7.3 07:58:21 INFO - Return code: 0 07:58:21 INFO - grabbing minidump binary from tooltool 07:58:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:58:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b13d30>, '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 0x105937db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:58:21 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 07:58:21 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 07:58:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:58:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:58:21 INFO - Return code: 0 07:58:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:58:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:58:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:58:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:58:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:58:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:58:22 INFO - Return code: 0 07:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 07:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 07:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AP2beaH0Vn/Render', 07:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:22 INFO - 'HOME': '/Users/cltbld', 07:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:22 INFO - 'LOGNAME': 'cltbld', 07:58:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:58:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:22 INFO - 'MOZ_NO_REMOTE': '1', 07:58:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:58:22 INFO - 'NO_EM_RESTART': '1', 07:58:22 INFO - 'PAGER': '/bin/cat', 07:58:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:22 INFO - 'PWD': '/builds/slave/test', 07:58:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:22 INFO - 'SHELL': '/bin/bash', 07:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzYyhCMczs/Listeners', 07:58:22 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 07:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:22 INFO - 'USER': 'cltbld', 07:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:22 INFO - 'XPC_FLAGS': '0x0', 07:58:22 INFO - 'XPC_SERVICE_NAME': '0', 07:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 07:58:22 INFO - mozversion application_buildid: 20160511055210 07:58:22 INFO - mozversion application_changeset: 91714307a799b978b0a94911cd99ee75a1de0ac5 07:58:22 INFO - mozversion application_display_name: Nightly 07:58:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:58:22 INFO - mozversion application_name: Firefox 07:58:22 INFO - mozversion application_remotingname: firefox 07:58:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:58:22 INFO - mozversion application_vendor: Mozilla 07:58:22 INFO - mozversion application_version: 49.0a1 07:58:22 INFO - mozversion platform_buildid: 20160511055210 07:58:22 INFO - mozversion platform_changeset: 91714307a799b978b0a94911cd99ee75a1de0ac5 07:58:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:58:22 INFO - mozversion platform_version: 49.0a1 07:58:22 INFO - using testdate: 1462978702 07:58:22 INFO - actual date: 1462978702 07:58:22 INFO - starting webserver on 'localhost:52490' 07:58:22 INFO - SUITE-START | Running 2 tests 07:58:22 INFO - TEST-START | dromaeo_css 07:58:22 INFO - operating with platform_type : mac_ 07:58:22 INFO - Initialising browser for dromaeo_css test... 07:58:22 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp36Issx/profile http://localhost:52490/getInfo.html) 07:58:25 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 07:58:25 INFO - PROCESS | 1946 | colorDepth:24 07:58:25 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 07:58:25 INFO - PROCESS | 1946 | __metrics 07:58:25 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 07:58:26 INFO - TEST-INFO | 1946: exit 0 07:58:26 INFO - Browser initialized. 07:58:26 INFO - Running cycle 1/1 for dromaeo_css test... 07:58:26 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp36Issx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:38 INFO - PROCESS | 1949 | RSS: Main: 249548800 07:58:38 INFO - PROCESS | 1949 | 07:59:28 INFO - PROCESS | 1949 | 1462978768313 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] 08:01:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52490/tests/dromaeo/cssquery-ext.html) 08:01:40 INFO - PROCESS | 1949 | RSS: Main: 261345280 08:01:40 INFO - PROCESS | 1949 | 08:04:43 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-ext.html (next: http://localhost:52490/tests/dromaeo/cssquery-jquery.html) 08:04:43 INFO - PROCESS | 1949 | RSS: Main: 273088512 08:04:43 INFO - PROCESS | 1949 | 08:07:45 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52490/tests/dromaeo/cssquery-mootools.html) 08:07:46 INFO - PROCESS | 1949 | RSS: Main: 274878464 08:07:46 INFO - PROCESS | 1949 | 08:10:48 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52490/tests/dromaeo/cssquery-prototype.html) 08:10:48 INFO - PROCESS | 1949 | RSS: Main: 276742144 08:10:48 INFO - PROCESS | 1949 | 08:13:51 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52490/tests/dromaeo/cssquery-yui.html) 08:13:52 INFO - PROCESS | 1949 | RSS: Main: 279683072 08:13:52 INFO - PROCESS | 1949 | 08:16:54 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-yui.html (next: http://localhost:52490/tests/dromaeo/cssquery-dojo.html) 08:16:55 INFO - PROCESS | 1949 | RSS: Main: 280965120 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | __start_tp_report 08:16:55 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 08:16:55 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 08:16:55 INFO - PROCESS | 1949 | |i|pagename|runs| 08:16:55 INFO - PROCESS | 1949 | |0;dojo.html;3360.8991730248094;3425.4176789945705;3468.670476304751;3506.6161154485917;3528.7353448491353;5758.884822303551;5908.822735317942;5918.704064796753;5929.703514824258;5959.731812068457;4669.953300467003;5724.370319264877;5781.797637082703;5856.56075794315;5914.970425147868;5167.121589329804;5222.242774797652;5294.52349288564;5336.866578335534;5347.572194224472;645.30175131222;4716.221823399135;4854.514548545152;5188.9362680650565;5269.736513174326;5237.3715154181555;5239.816606418795;5242.135047717122;5248.763805628737;5252.816151434681;5263.394709608406;5291.841244762663;5329.040772660919;5330.92003619946;5341.626086173969;5142.485751424865;5159.17453207485;5209.60927930403;5210.426853046162;5288.444713305086;4952.504749525055;5173.689578625294;5684.715764211773;5695.886082278373;5699.202111704323;5734.627249228787;6172.938270617281;6265.906011409833;6283.277423096357;6295.779647712354;6189.381061893775;6202.224721909762;6267.4986001119805;6289.811305660792;6322.9051564226575;5937.435943585352;5993.760249590055;6007.9788817656545;6029.216201893813;6068.605540639845;6225.844353891189;6322.9051564226575;6380.553361264667;6467.126937863422;6501.4798816095545;6102.058340323574;6120.516985381255;6138.1713468681155;6140.385961403824;6144.66502728969;6229.688515574203;6295.6537390443045;6335.366463353628;6346.333634968262;6365.681715914186;5116.820911268088;6119.8681629442035;6224.408681175281;6263.6241825490615;6284.622922624657;6187.81436556904;6210.192674952227;6234.657093859882;6237.126802247689;6273.0904018917;4958.082754690394;4970.602351811846;4978.5270399312;4983.426905905795;4989.975050124726;3929.410588411761;4363.345498175299;4378.956210437855;4416.138852923713;4433.401490798766;5904.498117659961;5909.6158749681135;5966.791162309385;6029.336772954972;6036.943534881379;6287.591306565058;6297.748090076438;6309.2428908530355;6310.1481300024825;6357.268914074848;5811;5927.4665280125;5967.582269241111;5991.340952495222;6033.5173186145;5456.427075157131;5490.505854473036;5506.311711036121;5510.862228444241;5512.448755124456;6238.376162383725;6446.935530644727;6477.352264773485;6482.2869484357625;6503.544751867418;5256.710880901587;5632.352279487747;5868.38381969887;6305.96847015762;6326.240851097898;6392.16038838457;6418.19772528434;6423.582467139621;6428.100065990671;6432.742690292336;5190.299309593308;6156.29202641702;6165.327898333633;6211.068339749074;6253.030780229072;5211.9478805211465;5507.311586051744;6173.351798061139;6258.812235632939;6263.718132683949;6151.876962460815;6202.030081941895;6230.968845155745;6239.1577137085915;6256.374362563707;6086.482648974887;6166.722497487714;6174.289956655042;6196.132541444112;6244.750209991549;5137.743112844418;5964.910526342195;6184.783532576338;6195.1636529068;6202.193714817045;6202.224721909762;6206.689665516797;6209.875802483834;6218.906716399167;6224.284207316216;5949.405059494189;6111.84720381994;6113.29697084841;6134.865219626364;6151.231096112986 08:16:55 INFO - PROCESS | 1949 | |1;ext.html;499.9550938538456;520.1183993131641;535.1170568561872;567.7445149682641;570.9571782116342;38378;40845.59154408455;40891.79554102229;40902.795486022565;40915.79542102293;40787.38818917719;41326.58673413265;41841.58158418415;41854.744357669246;41889.37165942506;9702.272329575275;9815.50922453878;9857;9902.009799020112;9930;9679.564419601134;9783.41299522031;9787.36382135159;9788.706338809847;9836.950815245913;9768.951155244215;9810.90189098107;9815.65645202418;9829.459379734111;9837.803243935117;9629.855552166724;9716.854247186262;9749.902500975042;9765.023497650214;9797.559109840004;9548.952255238748;9591.664291749825;9593.232541396672;9598.712038638781;9629.56666949982;31843.363132737446;38938;39043.41434878479;39312;39343.60656393457;2812.9437411251865;3105.9223519412194;3139.984300078485;3379.188994641293;3396.626371099177;27473.038443654375;28208.15375538737;28285.575716364066;28331.57502637462;29532.557011644843;2638.1162310625964;2664.6669166354204;2688.3951111000065;2759.282586527497;2763.281546797847;447126;449625;452862;454376;454567.9999999967;33934.83032584871;34818.47772283418;34820.65179348225;34949;35716.64283357185;36386.27227455489;36524.90425287245;36634.53461861549;36698.082547936516;36735.4489682655;14999.250037498081;15115.622109447351;15176;15186.544403667907;15202.391904323924;12528.686782830502;12645.620631381073;12683.985281177484;12763.29801860888;12764.489420423264;7881.921180788233;8139.064007639079;8142.47073940192;8172.83654326922;8210;3440.3291358185415;3444.7244220462303;3452.5684289463816;3462.221000274918;3496.2133519957806;943.5518128888723;962.2638681408728;1004.2869562610482;1093.8031154392127;1159.5941420502925;29139.417211656073;29341;29767.851160744056;29796.702032979825;29886.85056574703;941.1388579449864;1069.3102948598112;1076.9461526923621;1077.6605369308643;1095.5508241621058;15364.078155310717;15842;16364.263608137424;16449.424270150488;16471.588210294838;612191.8780812224;651754;662515;666515;670939;53844.73077634587;60161.097583536284;60225.69887150624;60259.69870150621;60306;1278.284160869916;1414.4695739097838;1440.942362305496;1469.6252455623962;1478.9852101478848;27352.452950941268;28792.280192994505;29537.556936646397;29870.655820487696;30483;51111;51207.97584048373;51605.22592161196;51749.48250517597;51875.74062129665;570126;579626;580524;584205;584720;24903.750962490867;25890.87054564715;25981.960721570926;26068.8696556516;26183.08359207495;43914.341284880116;44018.89952751207;44487.55512444834;44970;45144.54855451403;36274.455883161245;36388.45417318795;36506.26987460289;37086;37237.81381093077;35935.82032089927;35950.82024589965;36295;36487.27025459529;36525;35817.82091089528;35830;36268.917142043225;36418.27163456663;36904.26191476102;27041.45917081608;28052.73762680725;29196.56205156967;35055.47416788699;35116;24382.75617243805;24600.876995615625;24615;24942.750572494042;25152.371190719645 08:16:55 INFO - PROCESS | 1949 | |2;jquery.html;349.6940177344823;392.1842567459686;395.88915103770955;402.2170991317999;418.46018635959615;14822.629434264149;20310.492237694045;20669.37991860243;20898.582028359422;21421.357359279227;20918.89540552295;21019.94900254985;21261.25585604504;21368.57262854742;21543.461413464673;5155.7422128893595;5167.560757335631;5215.322008138955;5599.272094627713;6088.512918966472;5586.329640443152;5932.525397968174;5957.255343082114;6092.817215483543;6107.175531303284;5717.971410142944;5974.6713930733795;5987.970060149693;5988.940110598882;6009.158717779493;5982.371850955652;6073.362296958822;6092.299385570627;6116.571839971181;6127.142200091991;5555.416681248451;5766.7116644168045;5879.617824841414;5897.911531327033;5927.555433342473;13567.796483052762;13671.931640341834;13710.725785484334;13816.723665526633;23201;1557.9610509737233;1956.8923709195988;1958.5201625601676;2001.3395579458743;2167.0031785378587;9287.535623218811;9656.565454554562;9802.166815820663;13961.441542338294;14901.254937253203;1577.5898266450806;1629.144699033017;1656.213298683128;1676.9077700726455;1747.3709464592737;108687;128686.7131328684;134521;136819;136938.31530842383;9234.86147707785;11692.766144677058;11794.351310677996;11803.350815705047;12157.270563766202;15081.849181508263;15154.318055687521;15163.09021458716;15163.696726065416;15228.162451065298;8617.396782225183;8853.73438796837;8871.911280887238;8945.418547794372;8946.373753837299;8753.649854005896;8757.430767000125;8825.955870220609;8881.111888811067;8910.153535414125;4585.77071146448;4630.305454181832;4667.409587678803;4675.7895894684825;4751.881202969884;1695.4828777223065;1795.5690634247949;1991.5120795404928;2034.1964923854994;2080.895955202234;888.5424006636588;896.852019416802;942.1661829281136;1045.9895401046044;1068.417712346773;17731.556711082067;18822.81177188238;19364.70952935707;20514.38456846297;20623.69064464034;835.2334811872967;918.5085979001202;962.6630679262203;966.6665000574791;989.2975987049275;8931.419457735226;10820.783584328427;11345.319280843176;11443.485043173077;11750.70623234426;578394;635682.8215858984;643686;644230;644472;124388;145283;145398;148462;149118.25440872944;932.6828878181451;1049.7113293844254;1069.3744159666683;1081.0486771640906;1085.647164671485;18864;19071.809281907004;19151.27724745001;19165;19200.80799192046;50699.746501267255;55752;56031.43968560262;56238.875222494506;57216;79810;80192.19807801847;83076.58461707653;84274.15725842663;85892.57053714691;7996;8090.116743963048;8139.019593198255;8199.22107399796;8251.2986396157;8895.421797583136;11465.14011449128;12822.67943301425;12895.16181448203;12923.676908077374;8573.74278771638;10648.307859989076;10878.94560527224;11022.338659680445;11080.556777729123;10587.047165755119;10624.256302059091;10886.945565272123;10985.780284394108;11048.668539943814;11056.557737690402;11129.054030407506;11161.553537858395;11350;11363.431828408711;8113.0669972953865;8118.391120666091;8277.917220827714;8395.580220989048;8485.363597730071;10805.945970270099;10818.729531761453;11013.063889569476;11042.116630669689;11063.612773552886 08:16:55 INFO - PROCESS | 1949 | |3;mootools.html;1584.841515848415;1622.3614149708792;1682.663467306539;1869.654113988912;1935.7870634230223;7132.536385134963;8073.233042860934;8645.135486451367;9216.631334746611;9235.307351948606;7472.775816725507;8803.823923521542;8844.38089333747;8951.418157819753;8955.820883582324;2055.403932859469;2324.8140148788057;2418.2382549496942;2457.803375729937;2460.8680007196713;2082.5522512659777;2415.6618073469726;2449.0448724997264;2464.3099932019063;2479.256223133053;2421.055788242587;2443.963340549893;2465.186488458813;2472.7279999200073;2484.4410007748293;2439.597466418039;2452.2765784093735;2458.4591389894103;2460.5817011108065;2469.09877894569;2398.8800559971933;2454.435479839629;2456.877156142186;2460.495598402323;2468.2965354873954;8970.955145224298;9041.412308199946;9052.637894484213;9061.456312621198;9072.775175351375;5340.504658695571;5420.945790542083;5531.1426728857095;5553.888922221533;5558.722063896789;3600.7299452541165;3611.043073585502;3635.7818530887967;3719.1817800083936;3733.45864849598;1042.7914417116608;1051.6792378324608;1055.6938487838518;1058.4866339825178;1077.7575045614753;11751.294922304689;11788.528458861636;11809.94095029528;11824.822627660593;11836.526538938431;4864.683795553348;4871.5128487151715;4920.926186107211;5001.974990125026;5022.070916880389;10160.949195254125;10161.4919254037;10231.386116832866;10248;10248.180145588334;5441.727913604304;5450.127979523258;5489.066858634042;5520.447955204528;5538.584606154554;4416.271315233015;4830.710157390497;4985.651004429727;4990.2764099205415;4996.07572599063;4158.563350848178;4175.791210439465;4188.994641286069;4205.3902184183125;4221.261279276115;1318.156379916854;1357.3756072206895;1366.2485632901864;1371.4582739817715;1373.5535950816025;720.6540860387043;921.4701546610726;945.7966537194399;949.767307009787;958.5878072428783;5804.709764511757;6052.909206361908;6093.512518998469;6103.481204097701;6164.6917654118015;457.76425141052385;461.2942198436398;474.5231042802031;474.62504621348745;475.5173498898621;3997.900052498711;4041.696872734498;4049.210403971197;4109.568495308009;4110.403991421287;10315;10651.09465695409;10842.186835987333;11015.504302306415;11098.889011109946;9329.44023358587;10218.335808172445;10908.963648453384;10929.836052459219;10937.398443085707;6980.267071957473;7598.278163574451;7745.496542724817;8182;8354.206350396702;5828.184054232495;5924.229850119457;5952.375000625046;5991.310999235144;5995.760169593342;9706.951465242628;9738.123568878835;10100.040496152853;10156.74608134773;10170.542325595217;10037.949810250902;10073;10228.897711022795;10269.897301026895;10401.167906567607;7087.751928682472;7398.11222653285;7642.579658118857;7714.6914123434435;7728.884066739116;8531.616077276662;8567.228949394585;8649.52427616462;8658.437201581877;8663.696770612998;7471.3649339806725;7597;7668.194839541768;7682.923170768221;7723.53658780475;7355.7057717690695;7377.77866664001;7439.2188820173105;7533.2466753324225;7580.583067931317;7049;7128.28717128283;7166.426685865223;7215.062041934515;7219.205887352282;4849.199882019428;4993.825216117419;5052.141136006814;5094.974525127351;5106.846794596168;5003.899922001612;5022.422421421583;5036.143855544639;5070.467600901852;5256.684598924076 08:16:55 INFO - PROCESS | 1949 | |4;prototype.html;262.6664136546586;319.8688537699542;322.04834713421843;326.0495655165193;326.4320083055481;5437.728113594319;5467.863303417406;5468.835934921949;5494.478024587668;5509.889802203958;5086.262491938666;5347.620308962513;5365.7585408656605;5377.892442151165;5404.621676482648;3418.9658103418897;3422.5037369581396;3655.5065066215984;4129.2154490646835;4133.5659755725665;4043.3934909763475;4107.712460127792;4117.711760176789;4143.70994030418;4151.958480415203;3563.5011098446107;3822.7384962962296;3967.7420967637154;4057.4725285712816;4089.488813898263;3717.9070523237;3902.6892646449364;4114.835406583733;4127.380892866064;4145.932010312377;3023.546468029791;3194.9201269968435;3597.262561174979;3600.8559657613664;3618.439141932978;4075.7554546727292;4559.287224101654;5125.179971204627;5178.300929374524;5192.221166824968;4483.50681425043;4565.817367305303;4784.6889952153;4798.33692300773;4826.22780355145;5259.76331065103;5520.475554822286;5682.431756824326;5711.915565594574;5732.932880908736;3986.7641031280396;3992.3412636914873;4004.759714417144;4007.7996100195164;4017.337139372;5744.942550574483;5987.916223145084;6013.338532761392;6039.275286965592;6046.790641871643;3259.429599820022;3638.92440466679;3787.791671458041;3914.9038269284647;4068.6338229559483;3284.8850290239725;3383.983080084584;4109.97945010273;4110.876673699794;4152.563980782034;4577.862664120082;4616.746078965689;4791.448983366888;4795.6163506919365;4802.351682522884;4716.504766999485;5495.505404513613;5598.468145526169;5733.8566535836935;5782.132680097935;1253.8903070782424;1331.7136815584502;1415.7824271126822;1420.3608376230738;1426.657602175491;795.8686816675299;829.5607121643943;843.1568431568431;890.8463539716083;893.5041052216083;4803.3995750531185;5049.343585333957;5136.666116702402;5191.9480805192225;5305.734713264398;5551.555875530029;5889.676067816225;5928.525717942554;5977.760889564455;5993.280806303184;5018.5985121190215;5130.743462826844;5398.46015398457;5410.862709229832;5412.56699464042;2762.6684797823987;2765.861706914646;3065.754739620825;3111.6110486189227;3121.3133110715607;5064.910404653158;5947.286325640951;5989.760409583568;6175.783847565313;6220.947087461127;4911.607071434277;5845.77238779861;6105.84735381619;6120.663363515049;6121.265448146251;5475.4250803665045;5777.971110144423;6155.84610384744;6158.445739883342;6177.413145751147;5253.579713622825;5399.595030372816;5600.739833537422;5920.319163296103;5921.5558833086825;5230.869228269324;5878.97118004339;5879.412558609237;5890.558208134321;5929.792457263975;4759.3574867393845;5807.883842323215;5865.120231965239;6002.879942401215;6128.724207410766;3539.955713064601;3787.6401741834484;4033.4554835096887;4045.878623641178;4060.7360521566;4793.976030119827;5248.343957005374;5478.534324582296;5533.723313834212;5709.200711900255;4666.253399456071;5129.025485157808;5225.372955245395;5451.48210919962;5524.640898341595;5141.25451809493;5745.798897038583;5816.70916454184;5825.50483208915;5847.561432892635;4961.900761984813;5067.391912970467;5251.763670634907;5424.159255315432;5711.485966263056;5058.6982922770785;5537.750801213874;5693.914591281051;5817.94182058174;5882.617539896349;4852.975735121302;4857.514248575114;5679.8864022720145;5712;5717.514011308919 08:16:55 INFO - PROCESS | 1949 | |5;yui.html;676.5602358466992;684.8150999230209;691.7060249394007;708.7802781137844;732.9047223860897;15121.470748523803;15179.241037948113;15405.537833864973;15555.611109722277;15572.922135389335;11542.480588373523;12911.483540658362;14345.426182952695;14665.780013299809;14678.412863485475;730.4667592657337;913.55235934392;918.0360621347592;934.6308208257734;937.3063932689829;921.0881227584676;937;938.1509733691021;943.0522325063336;943.9150476457118;940.6942743608321;966.5747071288646;990;990.8266053440619;991.5092029445435;959.5633986536144;973.2214228617113;983.1446641421937;986.1075726467558;991.7272749993738;695.7008486350867;695.9095317608729;723.2080871445759;735.3859527294716;738.5568658804727;14799.40802367904;15059;15060.774088388684;15079.924600376928;15105.169215693133;12893.742125157445;12902.67743306425;12989.350532473432;13047.86952130476;13182.538611148564;10126.696199114038;10222.795544089076;10250.487475626263;10372.325798823127;10524.368537887674;3485.9477107843404;3563.3585954528176;3614.620464851179;3631.8365673544754;3714.591394946554;83984.74022889661;85428.57285713531;85695.5715221426;85745.57127214386;87105.56447217724;9543.522823858779;9563;9701;9713.66002189934;9793.853092203623;22751.203707870147;22765.88617056904;22769.886150569142;22789.974451149737;22864.199753008565;6841.144856892888;7124.358807707331;7129.714811407485;7145.428365730729;7148.28517148281;4049.6152865477734;4090.5909409058854;4107.260693075215;4109.650679692199;4114.835406583763;3368.7136593389346;3475.756697031234;3485.6688614581576;3492.231709024011;3497;632.6141053957083;633.3223450907577;637.1557686065981;641.1728869758053;643.5012865029639;545.585355130096;547.3814589513903;573.4122524412511;583.2301362201853;595.4313630482878;7381.151167615685;7666.8083297918;7707.922920770833;7871.370290376756;7885.5662938538935;538.6229639252539;541.5586297167796;569.5443645083916;582.4990508162977;596.5555661032472;3495.213576945167;3549.0594992327055;3614.8192590370377;3695.4456831474954;3763.3790424580184;76062;81014.59492702616;84668.57665711755;89002;90490;68755;76392.99192722046;80101.59949200216;80386.5980670093;80661;74240.35965426906;74656;75704;75811.24188758041;76104;8087.353011758927;8108.918910810817;8124.350051995944;8181.542575133215;8306.335493160412;45963.65162728172;46433;46767.76616116898;46801.297980531;47139;63506.68246658737;66077.67844643176;66160;66343;66565.33434665592;9172.770680733034;10725.302855314692;11110.722231944266;11541.711457213636;11621;11990.640280791591;12076.637700868989;12248.265104093784;12269.815952760895;12663.176893501894;10863.67408977732;11399.430028498708;11902.940485297519;11932.701682458008;11947.641570752892;11655.30068195911;11683.357415342238;11696.649100526998;11938.64184074479;12059.57791477294;11324.660260192208;11404.087672986307;11411.885881141414;11464.197506174487;11794.882051179378;8659.696910608098;9418.387804792666;9537.809243814945;9725.610975560898;9952.502374881371;9607.471589062668;9673.758156046155;9685.612575496902;9764.072413120743;9861.408315501092 08:16:55 INFO - PROCESS | 1949 | __end_tp_report 08:16:55 INFO - PROCESS | 1949 | __start_cc_report 08:16:55 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,417 08:16:55 INFO - PROCESS | 1949 | __end_cc_report 08:16:55 INFO - PROCESS | 1949 | __startTimestamp1462979815095__endTimestamp 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | ------- Summary: start ------- 08:16:55 INFO - PROCESS | 1949 | Number of tests: 6 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5735.03 Median:6033.52 stddev:780.13 (12.9%) stddev-sans-first:759.96 08:16:55 INFO - PROCESS | 1949 | Values: 3360.9 3425.4 3468.7 3506.6 3528.7 5758.9 5908.8 5918.7 5929.7 5959.7 4670.0 5724.4 5781.8 5856.6 5915.0 5167.1 5222.2 5294.5 5336.9 5347.6 645.3 4716.2 4854.5 5188.9 5269.7 5237.4 5239.8 5242.1 5248.8 5252.8 5263.4 5291.8 5329.0 5330.9 5341.6 5142.5 5159.2 5209.6 5210.4 5288.4 4952.5 5173.7 5684.7 5695.9 5699.2 5734.6 6172.9 6265.9 6283.3 6295.8 6189.4 6202.2 6267.5 6289.8 6322.9 5937.4 5993.8 6008.0 6029.2 6068.6 6225.8 6322.9 6380.6 6467.1 6501.5 6102.1 6120.5 6138.2 6140.4 6144.7 6229.7 6295.7 6335.4 6346.3 6365.7 5116.8 6119.9 6224.4 6263.6 6284.6 6187.8 6210.2 6234.7 6237.1 6273.1 4958.1 4970.6 4978.5 4983.4 4990.0 3929.4 4363.3 4379.0 4416.1 4433.4 5904.5 5909.6 5966.8 6029.3 6036.9 6287.6 6297.7 6309.2 6310.1 6357.3 5811.0 5927.5 5967.6 5991.3 6033.5 5456.4 5490.5 5506.3 5510.9 5512.4 6238.4 6446.9 6477.4 6482.3 6503.5 5256.7 5632.4 5868.4 6306.0 6326.2 6392.2 6418.2 6423.6 6428.1 6432.7 5190.3 6156.3 6165.3 6211.1 6253.0 5211.9 5507.3 6173.4 6258.8 6263.7 6151.9 6202.0 6231.0 6239.2 6256.4 6086.5 6166.7 6174.3 6196.1 6244.8 5137.7 5964.9 6184.8 6195.2 6202.2 6202.2 6206.7 6209.9 6218.9 6224.3 5949.4 6111.8 6113.3 6134.9 6151.2 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:67286.18 Median:36524.95 stddev:152201.80 (416.7%) stddev-sans-first:152546.17 08:16:55 INFO - PROCESS | 1949 | Values: 500.0 520.1 535.1 567.7 571.0 38378.0 40845.6 40891.8 40902.8 40915.8 40787.4 41326.6 41841.6 41854.7 41889.4 9702.3 9815.5 9857.0 9902.0 9930.0 9679.6 9783.4 9787.4 9788.7 9837.0 9769.0 9810.9 9815.7 9829.5 9837.8 9629.9 9716.9 9749.9 9765.0 9797.6 9549.0 9591.7 9593.2 9598.7 9629.6 31843.4 38938.0 39043.4 39312.0 39343.6 2812.9 3105.9 3140.0 3379.2 3396.6 27473.0 28208.2 28285.6 28331.6 29532.6 2638.1 2664.7 2688.4 2759.3 2763.3 447126.0 449625.0 452862.0 454376.0 454568.0 33934.8 34818.5 34820.7 34949.0 35716.6 36386.3 36524.9 36634.5 36698.1 36735.4 14999.3 15115.6 15176.0 15186.5 15202.4 12528.7 12645.6 12684.0 12763.3 12764.5 7881.9 8139.1 8142.5 8172.8 8210.0 3440.3 3444.7 3452.6 3462.2 3496.2 943.6 962.3 1004.3 1093.8 1159.6 29139.4 29341.0 29767.9 29796.7 29886.9 941.1 1069.3 1076.9 1077.7 1095.6 15364.1 15842.0 16364.3 16449.4 16471.6 612191.9 651754.0 662515.0 666515.0 670939.0 53844.7 60161.1 60225.7 60259.7 60306.0 1278.3 1414.5 1440.9 1469.6 1479.0 27352.5 28792.3 29537.6 29870.7 30483.0 51111.0 51208.0 51605.2 51749.5 51875.7 570126.0 579626.0 580524.0 584205.0 584720.0 24903.8 25890.9 25982.0 26068.9 26183.1 43914.3 44018.9 44487.6 44970.0 45144.5 36274.5 36388.5 36506.3 37086.0 37237.8 35935.8 35950.8 36295.0 36487.3 36525.0 35817.8 35830.0 36268.9 36418.3 36904.3 27041.5 28052.7 29196.6 35055.5 35116.0 24382.8 24600.9 24615.0 24942.8 25152.4 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:36517.43 Median:21482.41 stddev:105611.53 (491.6%) stddev-sans-first:105872.88 08:16:55 INFO - PROCESS | 1949 | Values: 349.7 392.2 395.9 402.2 418.5 14822.6 20310.5 20669.4 20898.6 21421.4 20918.9 21019.9 21261.3 21368.6 21543.5 5155.7 5167.6 5215.3 5599.3 6088.5 5586.3 5932.5 5957.3 6092.8 6107.2 5718.0 5974.7 5988.0 5988.9 6009.2 5982.4 6073.4 6092.3 6116.6 6127.1 5555.4 5766.7 5879.6 5897.9 5927.6 13567.8 13671.9 13710.7 13816.7 23201.0 1558.0 1956.9 1958.5 2001.3 2167.0 9287.5 9656.6 9802.2 13961.4 14901.3 1577.6 1629.1 1656.2 1676.9 1747.4 108687.0 128686.7 134521.0 136819.0 136938.3 9234.9 11692.8 11794.4 11803.4 12157.3 15081.8 15154.3 15163.1 15163.7 15228.2 8617.4 8853.7 8871.9 8945.4 8946.4 8753.6 8757.4 8826.0 8881.1 8910.2 4585.8 4630.3 4667.4 4675.8 4751.9 1695.5 1795.6 1991.5 2034.2 2080.9 888.5 896.9 942.2 1046.0 1068.4 17731.6 18822.8 19364.7 20514.4 20623.7 835.2 918.5 962.7 966.7 989.3 8931.4 10820.8 11345.3 11443.5 11750.7 578394.0 635682.8 643686.0 644230.0 644472.0 124388.0 145283.0 145398.0 148462.0 149118.3 932.7 1049.7 1069.4 1081.0 1085.6 18864.0 19071.8 19151.3 19165.0 19200.8 50699.7 55752.0 56031.4 56238.9 57216.0 79810.0 80192.2 83076.6 84274.2 85892.6 7996.0 8090.1 8139.0 8199.2 8251.3 8895.4 11465.1 12822.7 12895.2 12923.7 8573.7 10648.3 10878.9 11022.3 11080.6 10587.0 10624.3 10886.9 10985.8 11048.7 11056.6 11129.1 11161.6 11350.0 11363.4 8113.1 8118.4 8277.9 8395.6 8485.4 10805.9 10818.7 11013.1 11042.1 11063.6 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5778.65 Median:4868.10 stddev:3217.65 (66.1%) stddev-sans-first:3211.24 08:16:55 INFO - PROCESS | 1949 | Values: 1584.8 1622.4 1682.7 1869.7 1935.8 7132.5 8073.2 8645.1 9216.6 9235.3 7472.8 8803.8 8844.4 8951.4 8955.8 2055.4 2324.8 2418.2 2457.8 2460.9 2082.6 2415.7 2449.0 2464.3 2479.3 2421.1 2444.0 2465.2 2472.7 2484.4 2439.6 2452.3 2458.5 2460.6 2469.1 2398.9 2454.4 2456.9 2460.5 2468.3 8971.0 9041.4 9052.6 9061.5 9072.8 5340.5 5420.9 5531.1 5553.9 5558.7 3600.7 3611.0 3635.8 3719.2 3733.5 1042.8 1051.7 1055.7 1058.5 1077.8 11751.3 11788.5 11809.9 11824.8 11836.5 4864.7 4871.5 4920.9 5002.0 5022.1 10160.9 10161.5 10231.4 10248.0 10248.2 5441.7 5450.1 5489.1 5520.4 5538.6 4416.3 4830.7 4985.7 4990.3 4996.1 4158.6 4175.8 4189.0 4205.4 4221.3 1318.2 1357.4 1366.2 1371.5 1373.6 720.7 921.5 945.8 949.8 958.6 5804.7 6052.9 6093.5 6103.5 6164.7 457.8 461.3 474.5 474.6 475.5 3997.9 4041.7 4049.2 4109.6 4110.4 10315.0 10651.1 10842.2 11015.5 11098.9 9329.4 10218.3 10909.0 10929.8 10937.4 6980.3 7598.3 7745.5 8182.0 8354.2 5828.2 5924.2 5952.4 5991.3 5995.8 9707.0 9738.1 10100.0 10156.7 10170.5 10037.9 10073.0 10228.9 10269.9 10401.2 7087.8 7398.1 7642.6 7714.7 7728.9 8531.6 8567.2 8649.5 8658.4 8663.7 7471.4 7597.0 7668.2 7682.9 7723.5 7355.7 7377.8 7439.2 7533.2 7580.6 7049.0 7128.3 7166.4 7215.1 7219.2 4849.2 4993.8 5052.1 5095.0 5106.8 5003.9 5022.4 5036.1 5070.5 5256.7 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4600.14 Median:5138.96 stddev:1432.54 (27.9%) stddev-sans-first:1399.08 08:16:55 INFO - PROCESS | 1949 | Values: 262.7 319.9 322.0 326.0 326.4 5437.7 5467.9 5468.8 5494.5 5509.9 5086.3 5347.6 5365.8 5377.9 5404.6 3419.0 3422.5 3655.5 4129.2 4133.6 4043.4 4107.7 4117.7 4143.7 4152.0 3563.5 3822.7 3967.7 4057.5 4089.5 3717.9 3902.7 4114.8 4127.4 4145.9 3023.5 3194.9 3597.3 3600.9 3618.4 4075.8 4559.3 5125.2 5178.3 5192.2 4483.5 4565.8 4784.7 4798.3 4826.2 5259.8 5520.5 5682.4 5711.9 5732.9 3986.8 3992.3 4004.8 4007.8 4017.3 5744.9 5987.9 6013.3 6039.3 6046.8 3259.4 3638.9 3787.8 3914.9 4068.6 3284.9 3384.0 4110.0 4110.9 4152.6 4577.9 4616.7 4791.4 4795.6 4802.4 4716.5 5495.5 5598.5 5733.9 5782.1 1253.9 1331.7 1415.8 1420.4 1426.7 795.9 829.6 843.2 890.8 893.5 4803.4 5049.3 5136.7 5191.9 5305.7 5551.6 5889.7 5928.5 5977.8 5993.3 5018.6 5130.7 5398.5 5410.9 5412.6 2762.7 2765.9 3065.8 3111.6 3121.3 5064.9 5947.3 5989.8 6175.8 6220.9 4911.6 5845.8 6105.8 6120.7 6121.3 5475.4 5778.0 6155.8 6158.4 6177.4 5253.6 5399.6 5600.7 5920.3 5921.6 5230.9 5879.0 5879.4 5890.6 5929.8 4759.4 5807.9 5865.1 6002.9 6128.7 3540.0 3787.6 4033.5 4045.9 4060.7 4794.0 5248.3 5478.5 5533.7 5709.2 4666.3 5129.0 5225.4 5451.5 5524.6 5141.3 5745.8 5816.7 5825.5 5847.6 4961.9 5067.4 5251.8 5424.2 5711.5 5058.7 5537.8 5693.9 5817.9 5882.6 4853.0 4857.5 5679.9 5712.0 5717.5 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:18232.61 Median:32070.00 stddev:25496.87 (79.5%) stddev-sans-first:25534.32 08:16:55 INFO - PROCESS | 1949 | Values: 676.6 684.8 691.7 708.8 732.9 15121.5 15179.2 15405.5 15555.6 15572.9 11542.5 12911.5 14345.4 14665.8 14678.4 730.5 913.6 918.0 934.6 937.3 921.1 937.0 938.2 943.1 943.9 940.7 966.6 990.0 990.8 991.5 959.6 973.2 983.1 986.1 991.7 695.7 695.9 723.2 735.4 738.6 14799.4 15059.0 15060.8 15079.9 15105.2 12893.7 12902.7 12989.4 13047.9 13182.5 10126.7 10222.8 10250.5 10372.3 10524.4 3485.9 3563.4 3614.6 3631.8 3714.6 83984.7 85428.6 85695.6 85745.6 87105.6 9543.5 9563.0 9701.0 9713.7 9793.9 22751.2 22765.9 22769.9 22790.0 22864.2 6841.1 7124.4 7129.7 7145.4 7148.3 4049.6 4090.6 4107.3 4109.7 4114.8 3368.7 3475.8 3485.7 3492.2 3497.0 632.6 633.3 637.2 641.2 643.5 545.6 547.4 573.4 583.2 595.4 7381.2 7666.8 7707.9 7871.4 7885.6 538.6 541.6 569.5 582.5 596.6 3495.2 3549.1 3614.8 3695.4 3763.4 76062.0 81014.6 84668.6 89002.0 90490.0 68755.0 76393.0 80101.6 80386.6 80661.0 74240.4 74656.0 75704.0 75811.2 76104.0 8087.4 8108.9 8124.4 8181.5 8306.3 45963.7 46433.0 46767.8 46801.3 47139.0 63506.7 66077.7 66160.0 66343.0 66565.3 9172.8 10725.3 11110.7 11541.7 11621.0 11990.6 12076.6 12248.3 12269.8 12663.2 10863.7 11399.4 11902.9 11932.7 11947.6 11655.3 11683.4 11696.6 11938.6 12059.6 11324.7 11404.1 11411.9 11464.2 11794.9 8659.7 9418.4 9537.8 9725.6 9952.5 9607.5 9673.8 9685.6 9764.1 9861.4 08:16:55 INFO - PROCESS | 1949 | -------- Summary: end -------- 08:16:55 INFO - PROCESS | 1949 | 08:16:55 INFO - TEST-INFO | 1949: exit 0 08:16:55 INFO - TEST-OK | dromaeo_css | took 1112959ms 08:16:55 INFO - TEST-START | kraken 08:16:55 INFO - operating with platform_type : mac_ 08:16:55 INFO - Initialising browser for kraken test... 08:16:55 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmppoelCK/profile http://localhost:52490/getInfo.html) 08:16:58 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 08:16:58 INFO - PROCESS | 1954 | colorDepth:24 08:16:58 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 08:16:58 INFO - PROCESS | 1954 | __metrics 08:16:58 ERROR - PROCESS | 1954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 08:16:59 INFO - TEST-INFO | 1954: exit 0 08:16:59 INFO - Browser initialized. 08:16:59 INFO - Running cycle 1/1 for kraken test... 08:16:59 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmppoelCK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:11 INFO - PROCESS | 1957 | RSS: Main: 249589760 08:17:11 INFO - PROCESS | 1957 | 08:17:13 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection) 08:17:14 INFO - PROCESS | 1957 | RSS: Main: 261525504 08:17:14 INFO - PROCESS | 1957 | 08:17:17 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-dft) 08:17:18 INFO - PROCESS | 1957 | RSS: Main: 266719232 08:17:18 INFO - PROCESS | 1957 | 08:17:21 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-fft) 08:17:22 INFO - PROCESS | 1957 | RSS: Main: 267169792 08:17:22 INFO - PROCESS | 1957 | 08:17:24 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator) 08:17:24 INFO - PROCESS | 1957 | RSS: Main: 267804672 08:17:24 INFO - PROCESS | 1957 | 08:17:27 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:17:27 INFO - PROCESS | 1957 | RSS: Main: 267902976 08:17:27 INFO - PROCESS | 1957 | 08:17:31 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom) 08:17:31 INFO - PROCESS | 1957 | RSS: Main: 268472320 08:17:31 INFO - PROCESS | 1957 | 08:17:34 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate) 08:17:35 INFO - PROCESS | 1957 | RSS: Main: 268771328 08:17:35 INFO - PROCESS | 1957 | 08:17:38 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial) 08:17:39 INFO - PROCESS | 1957 | RSS: Main: 269086720 08:17:39 INFO - PROCESS | 1957 | 08:17:41 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:41 INFO - PROCESS | 1957 | RSS: Main: 268513280 08:17:41 INFO - PROCESS | 1957 | 08:17:43 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:44 INFO - PROCESS | 1957 | RSS: Main: 268410880 08:17:44 INFO - PROCESS | 1957 | 08:17:46 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:47 INFO - PROCESS | 1957 | RSS: Main: 267997184 08:17:47 INFO - PROCESS | 1957 | 08:17:50 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:51 INFO - PROCESS | 1957 | RSS: Main: 268034048 08:17:51 INFO - PROCESS | 1957 | 08:17:54 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:54 INFO - PROCESS | 1957 | RSS: Main: 268009472 08:17:54 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52490/tests/kraken/driver.html?testName=ai-astar) 08:17:56 INFO - PROCESS | 1957 | RSS: Main: 270364672 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | __start_tp_report 08:17:56 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 08:17:56 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 08:17:56 INFO - PROCESS | 1957 | |i|pagename|runs| 08:17:56 INFO - PROCESS | 1957 | |0;ai-astar;113;116;108;116;116;116;107;116;116;115 08:17:56 INFO - PROCESS | 1957 | |1;audio-beat-detection;174;152;142;196;120;155;171;139;170;135 08:17:56 INFO - PROCESS | 1957 | |2;audio-dft;198;203;214;215;192;198;198;215;220;204 08:17:56 INFO - PROCESS | 1957 | |3;audio-fft;76;75;76;177;58;59;59;67;136;59 08:17:56 INFO - PROCESS | 1957 | |4;audio-oscillator;80;84;168;112;66;99;158;114;116;153 08:17:56 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;87;85;109;112;89;90;134;132;104;85 08:17:56 INFO - PROCESS | 1957 | |6;imaging-darkroom;95;108;153;94;99;102;95;130;123;151 08:17:56 INFO - PROCESS | 1957 | |7;imaging-desaturate;90;105;115;89;113;137;99;143;134;144 08:17:56 INFO - PROCESS | 1957 | |8;json-parse-financial;56;56;61;61;51;54;54;127;100;88 08:17:56 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;64;67;77;81;72;64;68;68;66;88 08:17:56 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;85;85;86;85;88;87;85;96;105;79 08:17:56 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;137;137;166;145;137;134;136;144;244;141 08:17:56 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;172;169;169;170;171;174;171;171;166;176 08:17:56 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;71;67;67;69;66;68;69;64;68;65 08:17:56 INFO - PROCESS | 1957 | __end_tp_report 08:17:56 INFO - PROCESS | 1957 | __start_cc_report 08:17:56 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1102 08:17:56 INFO - PROCESS | 1957 | __end_cc_report 08:17:56 INFO - PROCESS | 1957 | __startTimestamp1462979876704__endTimestamp 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | ------- Summary: start ------- 08:17:56 INFO - PROCESS | 1957 | Number of tests: 14 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:113.90 Median:116.00 stddev:3.51 (3.0%) stddev-sans-first:3.71 08:17:56 INFO - PROCESS | 1957 | Values: 113.0 116.0 108.0 116.0 116.0 116.0 107.0 116.0 116.0 115.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:155.40 Median:162.50 stddev:22.56 (13.9%) stddev-sans-first:22.90 08:17:56 INFO - PROCESS | 1957 | Values: 174.0 152.0 142.0 196.0 120.0 155.0 171.0 139.0 170.0 135.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:205.70 Median:209.00 stddev:9.56 (4.6%) stddev-sans-first:9.72 08:17:56 INFO - PROCESS | 1957 | Values: 198.0 203.0 214.0 215.0 192.0 198.0 198.0 215.0 220.0 204.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:84.20 Median:63.00 stddev:40.00 (63.5%) stddev-sans-first:42.32 08:17:56 INFO - PROCESS | 1957 | Values: 76.0 75.0 76.0 177.0 58.0 59.0 59.0 67.0 136.0 59.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:115.00 Median:117.00 stddev:34.89 (29.8%) stddev-sans-first:34.63 08:17:56 INFO - PROCESS | 1957 | Values: 80.0 84.0 168.0 112.0 66.0 99.0 158.0 114.0 116.0 153.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:85.00 stddev:18.82 (22.1%) stddev-sans-first:19.09 08:17:56 INFO - PROCESS | 1957 | Values: 87.0 85.0 109.0 112.0 89.0 90.0 134.0 132.0 104.0 85.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:115.00 Median:123.50 stddev:22.96 (18.6%) stddev-sans-first:23.18 08:17:56 INFO - PROCESS | 1957 | Values: 95.0 108.0 153.0 94.0 99.0 102.0 95.0 130.0 123.0 151.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:116.90 Median:143.50 stddev:21.33 (14.9%) stddev-sans-first:20.28 08:17:56 INFO - PROCESS | 1957 | Values: 90.0 105.0 115.0 89.0 113.0 137.0 99.0 143.0 134.0 144.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:70.80 Median:56.00 stddev:25.59 (45.7%) stddev-sans-first:26.58 08:17:56 INFO - PROCESS | 1957 | Values: 56.0 56.0 61.0 61.0 51.0 54.0 54.0 127.0 100.0 88.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:70.00 stddev:8.03 (11.5%) stddev-sans-first:8.05 08:17:56 INFO - PROCESS | 1957 | Values: 64.0 67.0 77.0 81.0 72.0 64.0 68.0 68.0 66.0 88.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:7.26 (8.5%) stddev-sans-first:7.62 08:17:56 INFO - PROCESS | 1957 | Values: 85.0 85.0 86.0 85.0 88.0 87.0 85.0 96.0 105.0 79.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:142.50 stddev:33.58 (23.6%) stddev-sans-first:35.17 08:17:56 INFO - PROCESS | 1957 | Values: 137.0 137.0 166.0 145.0 137.0 134.0 136.0 144.0 244.0 141.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:171.00 stddev:2.77 (1.6%) stddev-sans-first:2.91 08:17:56 INFO - PROCESS | 1957 | Values: 172.0 169.0 169.0 170.0 171.0 174.0 171.0 171.0 166.0 176.0 08:17:56 INFO - PROCESS | 1957 | 08:17:56 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:2.07 (3.0%) stddev-sans-first:1.73 08:17:56 INFO - PROCESS | 1957 | Values: 71.0 67.0 67.0 69.0 66.0 68.0 69.0 64.0 68.0 65.0 08:17:56 INFO - PROCESS | 1957 | -------- Summary: end -------- 08:17:56 INFO - PROCESS | 1957 | 08:17:57 INFO - TEST-INFO | 1957: exit 0 08:17:57 INFO - TEST-OK | kraken | took 61772ms 08:17:57 INFO - SUITE-END | took 1174s 08:17:57 INFO - Completed test suite (00:19:35) 08:17:57 INFO - javascript benchmark 08:17:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.8991730248094, 3425.4176789945705, 3468.670476304751, 3506.6161154485917, 3528.7353448491353, 5758.884822303551, 5908.822735317942, 5918.704064796753, 5929.703514824258, 5959.731812068457, 4669.953300467003, 5724.370319264877, 5781.797637082703, 5856.56075794315, 5914.970425147868, 5167.121589329804, 5222.242774797652, 5294.52349288564, 5336.866578335534, 5347.572194224472, 645.30175131222, 4716.221823399135, 4854.514548545152, 5188.9362680650565, 5269.736513174326, 5237.3715154181555, 5239.816606418795, 5242.135047717122, 5248.763805628737, 5252.816151434681, 5263.394709608406, 5291.841244762663, 5329.040772660919, 5330.92003619946, 5341.626086173969, 5142.485751424865, 5159.17453207485, 5209.60927930403, 5210.426853046162, 5288.444713305086, 4952.504749525055, 5173.689578625294, 5684.715764211773, 5695.886082278373, 5699.202111704323, 5734.627249228787, 6172.938270617281, 6265.906011409833, 6283.277423096357, 6295.779647712354, 6189.381061893775, 6202.224721909762, 6267.4986001119805, 6289.811305660792, 6322.9051564226575, 5937.435943585352, 5993.760249590055, 6007.9788817656545, 6029.216201893813, 6068.605540639845, 6225.844353891189, 6322.9051564226575, 6380.553361264667, 6467.126937863422, 6501.4798816095545, 6102.058340323574, 6120.516985381255, 6138.1713468681155, 6140.385961403824, 6144.66502728969, 6229.688515574203, 6295.6537390443045, 6335.366463353628, 6346.333634968262, 6365.681715914186, 5116.820911268088, 6119.8681629442035, 6224.408681175281, 6263.6241825490615, 6284.622922624657, 6187.81436556904, 6210.192674952227, 6234.657093859882, 6237.126802247689, 6273.0904018917, 4958.082754690394, 4970.602351811846, 4978.5270399312, 4983.426905905795, 4989.975050124726, 3929.410588411761, 4363.345498175299, 4378.956210437855, 4416.138852923713, 4433.401490798766, 5904.498117659961, 5909.6158749681135, 5966.791162309385, 6029.336772954972, 6036.943534881379, 6287.591306565058, 6297.748090076438, 6309.2428908530355, 6310.1481300024825, 6357.268914074848, 5811.0, 5927.4665280125, 5967.582269241111, 5991.340952495222, 6033.5173186145, 5456.427075157131, 5490.505854473036, 5506.311711036121, 5510.862228444241, 5512.448755124456, 6238.376162383725, 6446.935530644727, 6477.352264773485, 6482.2869484357625, 6503.544751867418, 5256.710880901587, 5632.352279487747, 5868.38381969887, 6305.96847015762, 6326.240851097898, 6392.16038838457, 6418.19772528434, 6423.582467139621, 6428.100065990671, 6432.742690292336, 5190.299309593308, 6156.29202641702, 6165.327898333633, 6211.068339749074, 6253.030780229072, 5211.9478805211465, 5507.311586051744, 6173.351798061139, 6258.812235632939, 6263.718132683949, 6151.876962460815, 6202.030081941895, 6230.968845155745, 6239.1577137085915, 6256.374362563707, 6086.482648974887, 6166.722497487714, 6174.289956655042, 6196.132541444112, 6244.750209991549, 5137.743112844418, 5964.910526342195, 6184.783532576338, 6195.1636529068, 6202.193714817045, 6202.224721909762, 6206.689665516797, 6209.875802483834, 6218.906716399167, 6224.284207316216, 5949.405059494189, 6111.84720381994, 6113.29697084841, 6134.865219626364, 6151.231096112986], "value": 5686.600315151239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.9550938538456, 520.1183993131641, 535.1170568561872, 567.7445149682641, 570.9571782116342, 38378.0, 40845.59154408455, 40891.79554102229, 40902.795486022565, 40915.79542102293, 40787.38818917719, 41326.58673413265, 41841.58158418415, 41854.744357669246, 41889.37165942506, 9702.272329575275, 9815.50922453878, 9857.0, 9902.009799020112, 9930.0, 9679.564419601134, 9783.41299522031, 9787.36382135159, 9788.706338809847, 9836.950815245913, 9768.951155244215, 9810.90189098107, 9815.65645202418, 9829.459379734111, 9837.803243935117, 9629.855552166724, 9716.854247186262, 9749.902500975042, 9765.023497650214, 9797.559109840004, 9548.952255238748, 9591.664291749825, 9593.232541396672, 9598.712038638781, 9629.56666949982, 31843.363132737446, 38938.0, 39043.41434878479, 39312.0, 39343.60656393457, 2812.9437411251865, 3105.9223519412194, 3139.984300078485, 3379.188994641293, 3396.626371099177, 27473.038443654375, 28208.15375538737, 28285.575716364066, 28331.57502637462, 29532.557011644843, 2638.1162310625964, 2664.6669166354204, 2688.3951111000065, 2759.282586527497, 2763.281546797847, 447126.0, 449625.0, 452862.0, 454376.0, 454567.9999999967, 33934.83032584871, 34818.47772283418, 34820.65179348225, 34949.0, 35716.64283357185, 36386.27227455489, 36524.90425287245, 36634.53461861549, 36698.082547936516, 36735.4489682655, 14999.250037498081, 15115.622109447351, 15176.0, 15186.544403667907, 15202.391904323924, 12528.686782830502, 12645.620631381073, 12683.985281177484, 12763.29801860888, 12764.489420423264, 7881.921180788233, 8139.064007639079, 8142.47073940192, 8172.83654326922, 8210.0, 3440.3291358185415, 3444.7244220462303, 3452.5684289463816, 3462.221000274918, 3496.2133519957806, 943.5518128888723, 962.2638681408728, 1004.2869562610482, 1093.8031154392127, 1159.5941420502925, 29139.417211656073, 29341.0, 29767.851160744056, 29796.702032979825, 29886.85056574703, 941.1388579449864, 1069.3102948598112, 1076.9461526923621, 1077.6605369308643, 1095.5508241621058, 15364.078155310717, 15842.0, 16364.263608137424, 16449.424270150488, 16471.588210294838, 612191.8780812224, 651754.0, 662515.0, 666515.0, 670939.0, 53844.73077634587, 60161.097583536284, 60225.69887150624, 60259.69870150621, 60306.0, 1278.284160869916, 1414.4695739097838, 1440.942362305496, 1469.6252455623962, 1478.9852101478848, 27352.452950941268, 28792.280192994505, 29537.556936646397, 29870.655820487696, 30483.0, 51111.0, 51207.97584048373, 51605.22592161196, 51749.48250517597, 51875.74062129665, 570126.0, 579626.0, 580524.0, 584205.0, 584720.0, 24903.750962490867, 25890.87054564715, 25981.960721570926, 26068.8696556516, 26183.08359207495, 43914.341284880116, 44018.89952751207, 44487.55512444834, 44970.0, 45144.54855451403, 36274.455883161245, 36388.45417318795, 36506.26987460289, 37086.0, 37237.81381093077, 35935.82032089927, 35950.82024589965, 36295.0, 36487.27025459529, 36525.0, 35817.82091089528, 35830.0, 36268.917142043225, 36418.27163456663, 36904.26191476102, 27041.45917081608, 28052.73762680725, 29196.56205156967, 35055.47416788699, 35116.0, 24382.75617243805, 24600.876995615625, 24615.0, 24942.750572494042, 25152.371190719645], "value": 18213.195633287774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.6940177344823, 392.1842567459686, 395.88915103770955, 402.2170991317999, 418.46018635959615, 14822.629434264149, 20310.492237694045, 20669.37991860243, 20898.582028359422, 21421.357359279227, 20918.89540552295, 21019.94900254985, 21261.25585604504, 21368.57262854742, 21543.461413464673, 5155.7422128893595, 5167.560757335631, 5215.322008138955, 5599.272094627713, 6088.512918966472, 5586.329640443152, 5932.525397968174, 5957.255343082114, 6092.817215483543, 6107.175531303284, 5717.971410142944, 5974.6713930733795, 5987.970060149693, 5988.940110598882, 6009.158717779493, 5982.371850955652, 6073.362296958822, 6092.299385570627, 6116.571839971181, 6127.142200091991, 5555.416681248451, 5766.7116644168045, 5879.617824841414, 5897.911531327033, 5927.555433342473, 13567.796483052762, 13671.931640341834, 13710.725785484334, 13816.723665526633, 23201.0, 1557.9610509737233, 1956.8923709195988, 1958.5201625601676, 2001.3395579458743, 2167.0031785378587, 9287.535623218811, 9656.565454554562, 9802.166815820663, 13961.441542338294, 14901.254937253203, 1577.5898266450806, 1629.144699033017, 1656.213298683128, 1676.9077700726455, 1747.3709464592737, 108687.0, 128686.7131328684, 134521.0, 136819.0, 136938.31530842383, 9234.86147707785, 11692.766144677058, 11794.351310677996, 11803.350815705047, 12157.270563766202, 15081.849181508263, 15154.318055687521, 15163.09021458716, 15163.696726065416, 15228.162451065298, 8617.396782225183, 8853.73438796837, 8871.911280887238, 8945.418547794372, 8946.373753837299, 8753.649854005896, 8757.430767000125, 8825.955870220609, 8881.111888811067, 8910.153535414125, 4585.77071146448, 4630.305454181832, 4667.409587678803, 4675.7895894684825, 4751.881202969884, 1695.4828777223065, 1795.5690634247949, 1991.5120795404928, 2034.1964923854994, 2080.895955202234, 888.5424006636588, 896.852019416802, 942.1661829281136, 1045.9895401046044, 1068.417712346773, 17731.556711082067, 18822.81177188238, 19364.70952935707, 20514.38456846297, 20623.69064464034, 835.2334811872967, 918.5085979001202, 962.6630679262203, 966.6665000574791, 989.2975987049275, 8931.419457735226, 10820.783584328427, 11345.319280843176, 11443.485043173077, 11750.70623234426, 578394.0, 635682.8215858984, 643686.0, 644230.0, 644472.0, 124388.0, 145283.0, 145398.0, 148462.0, 149118.25440872944, 932.6828878181451, 1049.7113293844254, 1069.3744159666683, 1081.0486771640906, 1085.647164671485, 18864.0, 19071.809281907004, 19151.27724745001, 19165.0, 19200.80799192046, 50699.746501267255, 55752.0, 56031.43968560262, 56238.875222494506, 57216.0, 79810.0, 80192.19807801847, 83076.58461707653, 84274.15725842663, 85892.57053714691, 7996.0, 8090.116743963048, 8139.019593198255, 8199.22107399796, 8251.2986396157, 8895.421797583136, 11465.14011449128, 12822.67943301425, 12895.16181448203, 12923.676908077374, 8573.74278771638, 10648.307859989076, 10878.94560527224, 11022.338659680445, 11080.556777729123, 10587.047165755119, 10624.256302059091, 10886.945565272123, 10985.780284394108, 11048.668539943814, 11056.557737690402, 11129.054030407506, 11161.553537858395, 11350.0, 11363.431828408711, 8113.0669972953865, 8118.391120666091, 8277.917220827714, 8395.580220989048, 8485.363597730071, 10805.945970270099, 10818.729531761453, 11013.063889569476, 11042.116630669689, 11063.612773552886], "value": 9519.101030962198, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1584.841515848415, 1622.3614149708792, 1682.663467306539, 1869.654113988912, 1935.7870634230223, 7132.536385134963, 8073.233042860934, 8645.135486451367, 9216.631334746611, 9235.307351948606, 7472.775816725507, 8803.823923521542, 8844.38089333747, 8951.418157819753, 8955.820883582324, 2055.403932859469, 2324.8140148788057, 2418.2382549496942, 2457.803375729937, 2460.8680007196713, 2082.5522512659777, 2415.6618073469726, 2449.0448724997264, 2464.3099932019063, 2479.256223133053, 2421.055788242587, 2443.963340549893, 2465.186488458813, 2472.7279999200073, 2484.4410007748293, 2439.597466418039, 2452.2765784093735, 2458.4591389894103, 2460.5817011108065, 2469.09877894569, 2398.8800559971933, 2454.435479839629, 2456.877156142186, 2460.495598402323, 2468.2965354873954, 8970.955145224298, 9041.412308199946, 9052.637894484213, 9061.456312621198, 9072.775175351375, 5340.504658695571, 5420.945790542083, 5531.1426728857095, 5553.888922221533, 5558.722063896789, 3600.7299452541165, 3611.043073585502, 3635.7818530887967, 3719.1817800083936, 3733.45864849598, 1042.7914417116608, 1051.6792378324608, 1055.6938487838518, 1058.4866339825178, 1077.7575045614753, 11751.294922304689, 11788.528458861636, 11809.94095029528, 11824.822627660593, 11836.526538938431, 4864.683795553348, 4871.5128487151715, 4920.926186107211, 5001.974990125026, 5022.070916880389, 10160.949195254125, 10161.4919254037, 10231.386116832866, 10248.0, 10248.180145588334, 5441.727913604304, 5450.127979523258, 5489.066858634042, 5520.447955204528, 5538.584606154554, 4416.271315233015, 4830.710157390497, 4985.651004429727, 4990.2764099205415, 4996.07572599063, 4158.563350848178, 4175.791210439465, 4188.994641286069, 4205.3902184183125, 4221.261279276115, 1318.156379916854, 1357.3756072206895, 1366.2485632901864, 1371.4582739817715, 1373.5535950816025, 720.6540860387043, 921.4701546610726, 945.7966537194399, 949.767307009787, 958.5878072428783, 5804.709764511757, 6052.909206361908, 6093.512518998469, 6103.481204097701, 6164.6917654118015, 457.76425141052385, 461.2942198436398, 474.5231042802031, 474.62504621348745, 475.5173498898621, 3997.900052498711, 4041.696872734498, 4049.210403971197, 4109.568495308009, 4110.403991421287, 10315.0, 10651.09465695409, 10842.186835987333, 11015.504302306415, 11098.889011109946, 9329.44023358587, 10218.335808172445, 10908.963648453384, 10929.836052459219, 10937.398443085707, 6980.267071957473, 7598.278163574451, 7745.496542724817, 8182.0, 8354.206350396702, 5828.184054232495, 5924.229850119457, 5952.375000625046, 5991.310999235144, 5995.760169593342, 9706.951465242628, 9738.123568878835, 10100.040496152853, 10156.74608134773, 10170.542325595217, 10037.949810250902, 10073.0, 10228.897711022795, 10269.897301026895, 10401.167906567607, 7087.751928682472, 7398.11222653285, 7642.579658118857, 7714.6914123434435, 7728.884066739116, 8531.616077276662, 8567.228949394585, 8649.52427616462, 8658.437201581877, 8663.696770612998, 7471.3649339806725, 7597.0, 7668.194839541768, 7682.923170768221, 7723.53658780475, 7355.7057717690695, 7377.77866664001, 7439.2188820173105, 7533.2466753324225, 7580.583067931317, 7049.0, 7128.28717128283, 7166.426685865223, 7215.062041934515, 7219.205887352282, 4849.199882019428, 4993.825216117419, 5052.141136006814, 5094.974525127351, 5106.846794596168, 5003.899922001612, 5022.422421421583, 5036.143855544639, 5070.467600901852, 5256.684598924076], "value": 4589.208145456077, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.6664136546586, 319.8688537699542, 322.04834713421843, 326.0495655165193, 326.4320083055481, 5437.728113594319, 5467.863303417406, 5468.835934921949, 5494.478024587668, 5509.889802203958, 5086.262491938666, 5347.620308962513, 5365.7585408656605, 5377.892442151165, 5404.621676482648, 3418.9658103418897, 3422.5037369581396, 3655.5065066215984, 4129.2154490646835, 4133.5659755725665, 4043.3934909763475, 4107.712460127792, 4117.711760176789, 4143.70994030418, 4151.958480415203, 3563.5011098446107, 3822.7384962962296, 3967.7420967637154, 4057.4725285712816, 4089.488813898263, 3717.9070523237, 3902.6892646449364, 4114.835406583733, 4127.380892866064, 4145.932010312377, 3023.546468029791, 3194.9201269968435, 3597.262561174979, 3600.8559657613664, 3618.439141932978, 4075.7554546727292, 4559.287224101654, 5125.179971204627, 5178.300929374524, 5192.221166824968, 4483.50681425043, 4565.817367305303, 4784.6889952153, 4798.33692300773, 4826.22780355145, 5259.76331065103, 5520.475554822286, 5682.431756824326, 5711.915565594574, 5732.932880908736, 3986.7641031280396, 3992.3412636914873, 4004.759714417144, 4007.7996100195164, 4017.337139372, 5744.942550574483, 5987.916223145084, 6013.338532761392, 6039.275286965592, 6046.790641871643, 3259.429599820022, 3638.92440466679, 3787.791671458041, 3914.9038269284647, 4068.6338229559483, 3284.8850290239725, 3383.983080084584, 4109.97945010273, 4110.876673699794, 4152.563980782034, 4577.862664120082, 4616.746078965689, 4791.448983366888, 4795.6163506919365, 4802.351682522884, 4716.504766999485, 5495.505404513613, 5598.468145526169, 5733.8566535836935, 5782.132680097935, 1253.8903070782424, 1331.7136815584502, 1415.7824271126822, 1420.3608376230738, 1426.657602175491, 795.8686816675299, 829.5607121643943, 843.1568431568431, 890.8463539716083, 893.5041052216083, 4803.3995750531185, 5049.343585333957, 5136.666116702402, 5191.9480805192225, 5305.734713264398, 5551.555875530029, 5889.676067816225, 5928.525717942554, 5977.760889564455, 5993.280806303184, 5018.5985121190215, 5130.743462826844, 5398.46015398457, 5410.862709229832, 5412.56699464042, 2762.6684797823987, 2765.861706914646, 3065.754739620825, 3111.6110486189227, 3121.3133110715607, 5064.910404653158, 5947.286325640951, 5989.760409583568, 6175.783847565313, 6220.947087461127, 4911.607071434277, 5845.77238779861, 6105.84735381619, 6120.663363515049, 6121.265448146251, 5475.4250803665045, 5777.971110144423, 6155.84610384744, 6158.445739883342, 6177.413145751147, 5253.579713622825, 5399.595030372816, 5600.739833537422, 5920.319163296103, 5921.5558833086825, 5230.869228269324, 5878.97118004339, 5879.412558609237, 5890.558208134321, 5929.792457263975, 4759.3574867393845, 5807.883842323215, 5865.120231965239, 6002.879942401215, 6128.724207410766, 3539.955713064601, 3787.6401741834484, 4033.4554835096887, 4045.878623641178, 4060.7360521566, 4793.976030119827, 5248.343957005374, 5478.534324582296, 5533.723313834212, 5709.200711900255, 4666.253399456071, 5129.025485157808, 5225.372955245395, 5451.48210919962, 5524.640898341595, 5141.25451809493, 5745.798897038583, 5816.70916454184, 5825.50483208915, 5847.561432892635, 4961.900761984813, 5067.391912970467, 5251.763670634907, 5424.159255315432, 5711.485966263056, 5058.6982922770785, 5537.750801213874, 5693.914591281051, 5817.94182058174, 5882.617539896349, 4852.975735121302, 4857.514248575114, 5679.8864022720145, 5712.0, 5717.514011308919], "value": 4141.701109337668, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.5602358466992, 684.8150999230209, 691.7060249394007, 708.7802781137844, 732.9047223860897, 15121.470748523803, 15179.241037948113, 15405.537833864973, 15555.611109722277, 15572.922135389335, 11542.480588373523, 12911.483540658362, 14345.426182952695, 14665.780013299809, 14678.412863485475, 730.4667592657337, 913.55235934392, 918.0360621347592, 934.6308208257734, 937.3063932689829, 921.0881227584676, 937.0, 938.1509733691021, 943.0522325063336, 943.9150476457118, 940.6942743608321, 966.5747071288646, 990.0, 990.8266053440619, 991.5092029445435, 959.5633986536144, 973.2214228617113, 983.1446641421937, 986.1075726467558, 991.7272749993738, 695.7008486350867, 695.9095317608729, 723.2080871445759, 735.3859527294716, 738.5568658804727, 14799.40802367904, 15059.0, 15060.774088388684, 15079.924600376928, 15105.169215693133, 12893.742125157445, 12902.67743306425, 12989.350532473432, 13047.86952130476, 13182.538611148564, 10126.696199114038, 10222.795544089076, 10250.487475626263, 10372.325798823127, 10524.368537887674, 3485.9477107843404, 3563.3585954528176, 3614.620464851179, 3631.8365673544754, 3714.591394946554, 83984.74022889661, 85428.57285713531, 85695.5715221426, 85745.57127214386, 87105.56447217724, 9543.522823858779, 9563.0, 9701.0, 9713.66002189934, 9793.853092203623, 22751.203707870147, 22765.88617056904, 22769.886150569142, 22789.974451149737, 22864.199753008565, 6841.144856892888, 7124.358807707331, 7129.714811407485, 7145.428365730729, 7148.28517148281, 4049.6152865477734, 4090.5909409058854, 4107.260693075215, 4109.650679692199, 4114.835406583763, 3368.7136593389346, 3475.756697031234, 3485.6688614581576, 3492.231709024011, 3497.0, 632.6141053957083, 633.3223450907577, 637.1557686065981, 641.1728869758053, 643.5012865029639, 545.585355130096, 547.3814589513903, 573.4122524412511, 583.2301362201853, 595.4313630482878, 7381.151167615685, 7666.8083297918, 7707.922920770833, 7871.370290376756, 7885.5662938538935, 538.6229639252539, 541.5586297167796, 569.5443645083916, 582.4990508162977, 596.5555661032472, 3495.213576945167, 3549.0594992327055, 3614.8192590370377, 3695.4456831474954, 3763.3790424580184, 76062.0, 81014.59492702616, 84668.57665711755, 89002.0, 90490.0, 68755.0, 76392.99192722046, 80101.59949200216, 80386.5980670093, 80661.0, 74240.35965426906, 74656.0, 75704.0, 75811.24188758041, 76104.0, 8087.353011758927, 8108.918910810817, 8124.350051995944, 8181.542575133215, 8306.335493160412, 45963.65162728172, 46433.0, 46767.76616116898, 46801.297980531, 47139.0, 63506.68246658737, 66077.67844643176, 66160.0, 66343.0, 66565.33434665592, 9172.770680733034, 10725.302855314692, 11110.722231944266, 11541.711457213636, 11621.0, 11990.640280791591, 12076.637700868989, 12248.265104093784, 12269.815952760895, 12663.176893501894, 10863.67408977732, 11399.430028498708, 11902.940485297519, 11932.701682458008, 11947.641570752892, 11655.30068195911, 11683.357415342238, 11696.649100526998, 11938.64184074479, 12059.57791477294, 11324.660260192208, 11404.087672986307, 11411.885881141414, 11464.197506174487, 11794.882051179378, 8659.696910608098, 9418.387804792666, 9537.809243814945, 9725.610975560898, 9952.502374881371, 9607.471589062668, 9673.758156046155, 9685.612575496902, 9764.072413120743, 9861.408315501092], "value": 6897.050691756018, "unit": "score"}], "name": "dromaeo_css", "value": 7110.652012044214, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 108.0, 116.0, 116.0, 116.0, 107.0, 116.0, 116.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 152.0, 142.0, 196.0, 120.0, 155.0, 171.0, 139.0, 170.0, 135.0], "value": 155.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 214.0, 215.0, 192.0, 198.0, 198.0, 215.0, 220.0, 204.0], "value": 205.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 177.0, 58.0, 59.0, 59.0, 67.0, 136.0, 59.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 168.0, 112.0, 66.0, 99.0, 158.0, 114.0, 116.0, 153.0], "value": 115.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 109.0, 112.0, 89.0, 90.0, 134.0, 132.0, 104.0, 85.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 153.0, 94.0, 99.0, 102.0, 95.0, 130.0, 123.0, 151.0], "value": 115.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 105.0, 115.0, 89.0, 113.0, 137.0, 99.0, 143.0, 134.0, 144.0], "value": 116.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 61.0, 61.0, 51.0, 54.0, 54.0, 127.0, 100.0, 88.0], "value": 70.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 77.0, 81.0, 72.0, 64.0, 68.0, 68.0, 66.0, 88.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 85.0, 88.0, 87.0, 85.0, 96.0, 105.0, 79.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 166.0, 145.0, 137.0, 134.0, 136.0, 144.0, 244.0, 141.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 169.0, 170.0, 171.0, 174.0, 171.0, 171.0, 166.0, 176.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 67.0, 69.0, 66.0, 68.0, 69.0, 64.0, 68.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1629.6000000000001, "alertThreshold": 2.0}]} 08:17:57 INFO - Return code: 0 08:17:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:17:58 ERROR - # TBPL SUCCESS # 08:17:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:58 INFO - 2016-05-11 08:17:58.049 screenresolution[1962:28454] starting screenresolution argv=screenresolution get 08:17:58 INFO - 2016-05-11 08:17:58.052 screenresolution[1962:28454] Display 0: 1600x1200x32@60 08:17:58 INFO - 2016-05-11 08:17:58.058 screenresolution[1963:28457] starting screenresolution argv=screenresolution list 08:17:58 INFO - Available Modes on Display 0 08:17:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:58 INFO - Intel Iris: 08:17:58 INFO - Chipset Model: Intel Iris 08:17:58 INFO - Type: GPU 08:17:58 INFO - Bus: Built-In 08:17:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:58 INFO - Vendor: Intel (0x8086) 08:17:58 INFO - Device ID: 0x0a2e 08:17:58 INFO - Revision ID: 0x0009 08:17:58 INFO - Displays: 08:17:58 INFO - Display: 08:17:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:58 INFO - Display Serial Number: 1600x1200 60 08:17:58 INFO - Main Display: Yes 08:17:58 INFO - Mirror: Off 08:17:58 INFO - Online: Yes 08:17:58 INFO - Rotation: Supported 08:17:58 INFO - Return code: 0 08:17:58 INFO - Running post-action listener: _resource_record_post_action 08:17:58 INFO - ##### 08:17:58 INFO - ##### Finished run-tests step (success) 08:17:58 INFO - ##### 08:17:58 INFO - Running post-run listener: _resource_record_post_run 08:17:59 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 745472; Write bytes: 695825920; Read time: 8; Write time: 2367 08:17:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375271936; Write bytes: 370161664; Read time: 28603; Write time: 714 08:17:59 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 745472; Write bytes: 298720768; Read time: 8; Write time: 1600 08:17:59 INFO - Running post-run listener: _upload_blobber_files 08:17:59 INFO - Blob upload gear active. 08:17:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:17:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:17:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:17:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:18:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:18:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:18:00 INFO - (blobuploader) - INFO - Done attempting. 08:18:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:18:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:18:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:18:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:18:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:18:01 INFO - (blobuploader) - INFO - Done attempting. 08:18:01 INFO - (blobuploader) - INFO - Iteration through files over. 08:18:01 INFO - Return code: 0 08:18:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:18:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:18:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4c00aee9dcb210634991dc0d2172c443ee17ed139bc86886c5c9bae4b44fb59deb6164e886e26a7977870aabf02070864195a9991f861fc0c039d6c30bdeddc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19ae897af1e5a33439ddccfe3adc1c824742e686bbf99a7efb49f37241bbb0df4701e0554b7e460cd9845a6db026e15677ade37aa944d692d631e4e58ead521"} 08:18:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:18:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:18:01 INFO - Contents: 08:18:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4c00aee9dcb210634991dc0d2172c443ee17ed139bc86886c5c9bae4b44fb59deb6164e886e26a7977870aabf02070864195a9991f861fc0c039d6c30bdeddc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19ae897af1e5a33439ddccfe3adc1c824742e686bbf99a7efb49f37241bbb0df4701e0554b7e460cd9845a6db026e15677ade37aa944d692d631e4e58ead521"} 08:18:01 INFO - Running post-run listener: copy_logs_to_upload_dir 08:18:01 INFO - Copying logs to upload dir... 08:18:01 INFO - mkdir: /builds/slave/test/build/upload/logs 08:18:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.567330 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 08:18:01.807490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:18:01.811244) ========= 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.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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/f4c00aee9dcb210634991dc0d2172c443ee17ed139bc86886c5c9bae4b44fb59deb6164e886e26a7977870aabf02070864195a9991f861fc0c039d6c30bdeddc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19ae897af1e5a33439ddccfe3adc1c824742e686bbf99a7efb49f37241bbb0df4701e0554b7e460cd9845a6db026e15677ade37aa944d692d631e4e58ead521"} build_url:https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/dYMLr3kdQa22uAU6K3HVzg/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/f4c00aee9dcb210634991dc0d2172c443ee17ed139bc86886c5c9bae4b44fb59deb6164e886e26a7977870aabf02070864195a9991f861fc0c039d6c30bdeddc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a19ae897af1e5a33439ddccfe3adc1c824742e686bbf99a7efb49f37241bbb0df4701e0554b7e460cd9845a6db026e15677ade37aa944d692d631e4e58ead521"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:18:01.843753) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:18:01.844069) ========= 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.AP2beaH0Vn/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.gzYyhCMczs/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:18:01.892526) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:18:01.892847) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:18:01.893170) ========= ========= Total master_lag: 0.18 =========