builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1463910915.87 results: success (0) buildid: 20160522004024 builduid: 63cd0a1e6ce04fec96e7f8af48c79ac1 revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.868882) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.869321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.870032) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.023639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.023940) ========= 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.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-22 02:55:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 184M=0s 2016-05-22 02:55:16 (184 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128270 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.175161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.175452) ========= 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.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.027330 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.228551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 02:55:16.228881) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 110e5a10dce56ed02e43c58c179e6ac101df216d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 110e5a10dce56ed02e43c58c179e6ac101df216d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-22 02:55:16,304 truncating revision to first 12 chars 2016-05-22 02:55:16,304 Setting DEBUG logging. 2016-05-22 02:55:16,304 attempt 1/10 2016-05-22 02:55:16,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/110e5a10dce5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 02:55:17,259 unpacking tar archive at: mozilla-aurora-110e5a10dce5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.205879 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 02:55:17.453564) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:17.453927) ========= script_repo_revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:17.454347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:17.454664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:17.466895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 33 secs) (at 2016-05-22 02:55:17.467213) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:55:17 INFO - MultiFileLogger online at 20160522 02:55:17 in /builds/slave/test 02:55:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:55:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:55:17 INFO - {'append_to_log': False, 02:55:17 INFO - 'base_work_dir': '/builds/slave/test', 02:55:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:55:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:55:17 INFO - 'branch': 'Mozilla-Aurora', 02:55:17 INFO - 'buildbot_json_path': 'buildprops.json', 02:55:17 INFO - 'config_files': ('talos/mac_config.py',), 02:55:17 INFO - 'default_actions': ('clobber', 02:55:17 INFO - 'read-buildbot-config', 02:55:17 INFO - 'download-and-extract', 02:55:17 INFO - 'populate-webroot', 02:55:17 INFO - 'create-virtualenv', 02:55:17 INFO - 'install', 02:55:17 INFO - 'run-tests'), 02:55:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:55:17 INFO - 'download_minidump_stackwalk': True, 02:55:17 INFO - 'download_symbols': 'ondemand', 02:55:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:55:17 INFO - 'tooltool.py': '/tools/tooltool.py', 02:55:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:55:17 INFO - '/tools/misc-python/virtualenv.py')}, 02:55:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:55:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:55:17 INFO - 'installer_path': 'installer.exe', 02:55:17 INFO - 'log_level': 'info', 02:55:17 INFO - 'log_name': 'talos', 02:55:17 INFO - 'log_to_console': True, 02:55:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:55:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:55:17 INFO - 'opt_config_files': (), 02:55:17 INFO - 'pip_index': False, 02:55:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:55:17 INFO - 'cmd': ('bash', 02:55:17 INFO - '-c', 02:55:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:55:17 INFO - 'enabled': True, 02:55:17 INFO - 'halt_on_failure': False, 02:55:17 INFO - 'name': 'check_screen_resolution'},), 02:55:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:55:17 INFO - 'cmd': ('bash', 02:55:17 INFO - '-c', 02:55:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:55:17 INFO - 'enabled': True, 02:55:17 INFO - 'halt_on_failure': False, 02:55:17 INFO - 'name': 'check_screen_resolution'},), 02:55:17 INFO - 'run_cmd_checks_enabled': True, 02:55:17 INFO - 'sps_profile': False, 02:55:17 INFO - 'sps_profile_interval': 0, 02:55:17 INFO - 'suite': 'dromaeojs', 02:55:17 INFO - 'system_bits': '32', 02:55:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:55:17 INFO - 'title': 't-yosemite-r7-0024', 02:55:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:55:17 INFO - 'use_talos_json': True, 02:55:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:55:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:55:17 INFO - 'work_dir': 'build'} 02:55:17 INFO - ##### 02:55:17 INFO - ##### Running clobber step. 02:55:17 INFO - ##### 02:55:17 INFO - Running pre-action listener: _resource_record_pre_action 02:55:17 INFO - Running main action method: clobber 02:55:17 INFO - rmtree: /builds/slave/test/build 02:55:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:55:19 INFO - Running post-action listener: _resource_record_post_action 02:55:19 INFO - ##### 02:55:19 INFO - ##### Running read-buildbot-config step. 02:55:19 INFO - ##### 02:55:19 INFO - Running pre-action listener: _resource_record_pre_action 02:55:19 INFO - Running main action method: read_buildbot_config 02:55:19 INFO - Using buildbot properties: 02:55:19 INFO - { 02:55:19 INFO - "project": "", 02:55:19 INFO - "product": "firefox", 02:55:19 INFO - "script_repo_revision": "production", 02:55:19 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:55:19 INFO - "repository": "", 02:55:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:55:19 INFO - "buildid": "20160522004024", 02:55:19 INFO - "stage_platform": "macosx64", 02:55:19 INFO - "basedir": "/builds/slave/test", 02:55:19 INFO - "nightly_build": "True", 02:55:19 INFO - "buildnumber": 10, 02:55:19 INFO - "slavename": "t-yosemite-r7-0024", 02:55:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:55:19 INFO - "platform": "yosemite_r7", 02:55:19 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:55:19 INFO - "branch": "mozilla-aurora", 02:55:19 INFO - "repo_path": "releases/mozilla-aurora", 02:55:19 INFO - "revision": "110e5a10dce56ed02e43c58c179e6ac101df216d", 02:55:19 INFO - "pgo_build": "False", 02:55:19 INFO - "builduid": "63cd0a1e6ce04fec96e7f8af48c79ac1", 02:55:19 INFO - "slavebuilddir": "test" 02:55:19 INFO - } 02:55:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:55:19 INFO - Running post-action listener: _resource_record_post_action 02:55:19 INFO - ##### 02:55:19 INFO - ##### Running download-and-extract step. 02:55:19 INFO - ##### 02:55:19 INFO - Running pre-action listener: _resource_record_pre_action 02:55:19 INFO - Running main action method: download_and_extract 02:55:19 INFO - mkdir: /builds/slave/test/build/tests 02:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:19 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:55:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:55:49 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:55:49 INFO - retry: attempt #1 caught exception: timed out 02:55:49 INFO - retry: Failed, sleeping 30 seconds before retrying 02:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 02:56:40 INFO - Downloaded 1199 bytes. 02:56:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:56:40 INFO - Using the following test package requirements: 02:56:40 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:56:40 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:56:40 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:56:40 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'jsshell-mac.zip'], 02:56:40 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:56:40 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:56:40 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:56:40 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:56:40 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:56:40 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:56:40 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:56:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:56:40 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')]} 02:56:40 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:56:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:56:44 INFO - Downloaded 18102570 bytes. 02:56:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:56:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:56:47 INFO - Return code: 0 02:56:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:56:47 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')]} 02:56:47 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:56:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:56:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:56:48 INFO - Downloaded 18102570 bytes. 02:56:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:56:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:56:52 INFO - Return code: 0 02:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:52 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:56:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:56:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:56:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:56:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:56:53 INFO - Downloaded 10840145 bytes. 02:56:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:56:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:56:53 INFO - Return code: 0 02:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:53 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:56:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:56:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:56:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:56:59 INFO - Downloaded 114237977 bytes. 02:56:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:56:59 INFO - mkdir: /builds/slave/test/properties 02:56:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:56:59 INFO - Writing to file /builds/slave/test/properties/build_url 02:56:59 INFO - Contents: 02:56:59 INFO - build_url:https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:57:00 INFO - Running post-action listener: _resource_record_post_action 02:57:00 INFO - Running post-action listener: set_extra_try_arguments 02:57:00 INFO - ##### 02:57:00 INFO - ##### Running populate-webroot step. 02:57:00 INFO - ##### 02:57:00 INFO - Running pre-action listener: _resource_record_pre_action 02:57:00 INFO - Running main action method: populate_webroot 02:57:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:57:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:57:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:57:00 INFO - '--tppagecycles', 02:57:00 INFO - '7'], 02:57:00 INFO - 'tests': ['tsvgm']}}, 02:57:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['damp', 'tps']}, 02:57:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'tests': ['damp', 'tps']}, 02:57:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['dromaeo_dom']}, 02:57:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['a11yr', 02:57:00 INFO - 'ts_paint', 02:57:00 INFO - 'tpaint', 02:57:00 INFO - 'sessionrestore', 02:57:00 INFO - 'sessionrestore_no_auto_restore', 02:57:00 INFO - 'tabpaint']}, 02:57:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['tsvgx', 02:57:00 INFO - 'tsvgr_opacity', 02:57:00 INFO - 'tart', 02:57:00 INFO - 'tscrollx', 02:57:00 INFO - 'cart']}, 02:57:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:00 INFO - 'tsvgr_opacity', 02:57:00 INFO - 'tart', 02:57:00 INFO - 'tscrollx', 02:57:00 INFO - 'cart']}, 02:57:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'talos_options': ['--disable-e10s'], 02:57:00 INFO - 'tests': ['tp5o']}, 02:57:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'tests': ['tp5o']}, 02:57:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'talos_options': ['--disable-e10s', 02:57:00 INFO - '--xperf_path', 02:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:00 INFO - 'tests': ['tp5n']}, 02:57:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:00 INFO - 'talos_options': ['--xperf_path', 02:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:00 INFO - 'tests': ['tp5n']}}, 02:57:00 INFO - 'talos.zip': {'path': '', 02:57:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:57:00 INFO - Running post-action listener: _resource_record_post_action 02:57:00 INFO - ##### 02:57:00 INFO - ##### Running create-virtualenv step. 02:57:00 INFO - ##### 02:57:00 INFO - Running pre-action listener: _resource_record_pre_action 02:57:00 INFO - Running main action method: create_virtualenv 02:57:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:00 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 02:57:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:01 INFO - Using real prefix '/tools/python27' 02:57:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:04 INFO - Installing pip.................done. 02:57:04 INFO - Return code: 0 02:57:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:04 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')]} 02:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:04 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')]} 02:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:04 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:04 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 02:57:04 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 02:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:04 INFO - 'HOME': '/Users/cltbld', 02:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:04 INFO - 'LOGNAME': 'cltbld', 02:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:04 INFO - 'MOZ_NO_REMOTE': '1', 02:57:04 INFO - 'NO_EM_RESTART': '1', 02:57:04 INFO - 'PAGER': '/bin/cat', 02:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:04 INFO - 'PWD': '/builds/slave/test', 02:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:04 INFO - 'SHELL': '/bin/bash', 02:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:04 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:04 INFO - 'USER': 'cltbld', 02:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:04 INFO - 'XPC_FLAGS': '0x0', 02:57:04 INFO - 'XPC_SERVICE_NAME': '0', 02:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:04 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 02:57:04 INFO - Cleaning up... 02:57:04 INFO - Return code: 0 02:57:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:57:04 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')]} 02:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:05 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:05 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 02:57:05 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 02:57:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:05 INFO - 'HOME': '/Users/cltbld', 02:57:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:05 INFO - 'LOGNAME': 'cltbld', 02:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:05 INFO - 'MOZ_NO_REMOTE': '1', 02:57:05 INFO - 'NO_EM_RESTART': '1', 02:57:05 INFO - 'PAGER': '/bin/cat', 02:57:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:05 INFO - 'PWD': '/builds/slave/test', 02:57:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:05 INFO - 'SHELL': '/bin/bash', 02:57:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:05 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:05 INFO - 'USER': 'cltbld', 02:57:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:05 INFO - 'XPC_FLAGS': '0x0', 02:57:05 INFO - 'XPC_SERVICE_NAME': '0', 02:57:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:05 INFO - Downloading/unpacking psutil>=0.7.1 02:57:05 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 02:57:05 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 02:57:05 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 02:57:05 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 02:57:05 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 02:57:05 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 02:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:08 INFO - Installing collected packages: psutil 02:57:08 INFO - Running setup.py install for psutil 02:57:08 INFO - building 'psutil._psutil_osx' extension 02:57:08 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 02:57:09 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 02:57:09 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 02:57:09 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 02:57:09 INFO - building 'psutil._psutil_posix' extension 02:57:09 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 02:57:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:09 INFO - ^ 02:57:09 INFO - 1 warning generated. 02:57:09 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 02:57:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:09 INFO - Successfully installed psutil 02:57:09 INFO - Cleaning up... 02:57:09 INFO - Return code: 0 02:57:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02: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')]} 02:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:09 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:09 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 02:57:09 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 02:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:09 INFO - 'HOME': '/Users/cltbld', 02:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:09 INFO - 'LOGNAME': 'cltbld', 02:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:09 INFO - 'MOZ_NO_REMOTE': '1', 02:57:09 INFO - 'NO_EM_RESTART': '1', 02:57:09 INFO - 'PAGER': '/bin/cat', 02:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:09 INFO - 'PWD': '/builds/slave/test', 02:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:09 INFO - 'SHELL': '/bin/bash', 02:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:09 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:09 INFO - 'USER': 'cltbld', 02:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:09 INFO - 'XPC_FLAGS': '0x0', 02:57:09 INFO - 'XPC_SERVICE_NAME': '0', 02:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:57:12 INFO - Installing collected packages: mozsystemmonitor 02:57:12 INFO - Running setup.py install for mozsystemmonitor 02:57:12 INFO - Successfully installed mozsystemmonitor 02:57:12 INFO - Cleaning up... 02:57:12 INFO - Return code: 0 02:57:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:12 INFO - 'HOME': '/Users/cltbld', 02:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:12 INFO - 'LOGNAME': 'cltbld', 02:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:12 INFO - 'MOZ_NO_REMOTE': '1', 02:57:12 INFO - 'NO_EM_RESTART': '1', 02:57:12 INFO - 'PAGER': '/bin/cat', 02:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:12 INFO - 'PWD': '/builds/slave/test', 02:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:12 INFO - 'SHELL': '/bin/bash', 02:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:12 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:12 INFO - 'USER': 'cltbld', 02:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:12 INFO - 'XPC_FLAGS': '0x0', 02:57:12 INFO - 'XPC_SERVICE_NAME': '0', 02:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:13 INFO - Downloading/unpacking blobuploader==1.2.4 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:13 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 02:57:15 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:15 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 02:57:15 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 02:57:15 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 02:57:15 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 02:57:15 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 02:57:15 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 02:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57:16 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 02:57:16 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 02:57:16 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 02:57:16 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 02:57:16 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 02:57:16 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 02:57:16 INFO - Downloading docopt-0.6.1.tar.gz 02:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:16 INFO - Running setup.py install for blobuploader 02:57:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:16 INFO - Running setup.py install for requests 02:57:17 INFO - Running setup.py install for docopt 02:57:17 INFO - Successfully installed blobuploader requests docopt 02:57:17 INFO - Cleaning up... 02:57:17 INFO - Return code: 0 02:57:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:17 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:17 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 02:57:17 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 02:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:17 INFO - 'HOME': '/Users/cltbld', 02:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:17 INFO - 'LOGNAME': 'cltbld', 02:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:17 INFO - 'MOZ_NO_REMOTE': '1', 02:57:17 INFO - 'NO_EM_RESTART': '1', 02:57:17 INFO - 'PAGER': '/bin/cat', 02:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:17 INFO - 'PWD': '/builds/slave/test', 02:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:17 INFO - 'SHELL': '/bin/bash', 02:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:17 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:17 INFO - 'USER': 'cltbld', 02:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:17 INFO - 'XPC_FLAGS': '0x0', 02:57:17 INFO - 'XPC_SERVICE_NAME': '0', 02:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:17 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ed5fX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-HffTe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-d2i_t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4Bv4Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0JasEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BNP4hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ji10if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-htjJg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9g07Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1oLqst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:18 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-tcNtPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-sxfrfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-v4V_hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Kh5OHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-thdY7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-bk21Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:19 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-SCF7Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:19 INFO - Running setup.py install for manifestparser 02:57:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:19 INFO - Running setup.py install for mozcrash 02:57:20 INFO - Running setup.py install for mozdebug 02:57:20 INFO - Running setup.py install for mozdevice 02:57:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Running setup.py install for mozfile 02:57:20 INFO - Running setup.py install for mozhttpd 02:57:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Running setup.py install for mozinfo 02:57:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Running setup.py install for mozInstall 02:57:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:20 INFO - Running setup.py install for mozleak 02:57:21 INFO - Running setup.py install for mozlog 02:57:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Running setup.py install for moznetwork 02:57:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Running setup.py install for mozprocess 02:57:21 INFO - Running setup.py install for mozprofile 02:57:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Running setup.py install for mozrunner 02:57:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:21 INFO - Running setup.py install for mozscreenshot 02:57:22 INFO - Running setup.py install for moztest 02:57:22 INFO - Running setup.py install for mozversion 02:57:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:22 INFO - Cleaning up... 02:57:22 INFO - Return code: 0 02:57:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:22 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:22 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 02:57:22 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 02:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:22 INFO - 'HOME': '/Users/cltbld', 02:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:22 INFO - 'LOGNAME': 'cltbld', 02:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:22 INFO - 'MOZ_NO_REMOTE': '1', 02:57:22 INFO - 'NO_EM_RESTART': '1', 02:57:22 INFO - 'PAGER': '/bin/cat', 02:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:22 INFO - 'PWD': '/builds/slave/test', 02:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:22 INFO - 'SHELL': '/bin/bash', 02:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:22 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:22 INFO - 'USER': 'cltbld', 02:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:22 INFO - 'XPC_FLAGS': '0x0', 02:57:22 INFO - 'XPC_SERVICE_NAME': '0', 02:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:22 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-99guVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:22 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)) 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:22 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-UpcRPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:22 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)) 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:22 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0ZU7MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:22 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)) 02:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:22 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-rkdsRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-us8LcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QS4rrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-T7APef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Z3y50s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-bxtZBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-q332xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-XGrRVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-GD74r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:23 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)) 02:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-aUUXla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:24 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)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-JtLq2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:24 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)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-OuU8lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:24 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)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qBBM58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:24 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)) 02:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-O4DhHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:24 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)) 02:57:24 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)) 02:57:24 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)) 02:57:24 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)) 02:57:24 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)) 02:57:24 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)) 02:57:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02: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 02: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 02: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 02: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 02: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 02: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 02:57:27 INFO - Downloading blessings-1.6.tar.gz 02:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:27 INFO - Installing collected packages: blessings 02:57:27 INFO - Running setup.py install for blessings 02:57:27 INFO - Successfully installed blessings 02:57:27 INFO - Cleaning up... 02:57:27 INFO - Return code: 0 02:57:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:27 INFO - Reading from file tmpfile_stdout 02:57:27 INFO - Current package versions: 02:57:27 INFO - blessings == 1.6 02:57:27 INFO - blobuploader == 1.2.4 02:57:27 INFO - docopt == 0.6.1 02:57:27 INFO - manifestparser == 1.1 02:57:27 INFO - mozInstall == 1.12 02:57:27 INFO - mozcrash == 0.17 02:57:27 INFO - mozdebug == 0.1 02:57:27 INFO - mozdevice == 0.48 02:57:27 INFO - mozfile == 1.2 02:57:27 INFO - mozhttpd == 0.7 02:57:27 INFO - mozinfo == 0.9 02:57:27 INFO - mozleak == 0.1 02:57:27 INFO - mozlog == 3.1 02:57:27 INFO - moznetwork == 0.27 02:57:27 INFO - mozprocess == 0.22 02:57:27 INFO - mozprofile == 0.28 02:57:27 INFO - mozrunner == 6.11 02:57:27 INFO - mozscreenshot == 0.1 02:57:27 INFO - mozsystemmonitor == 0.0 02:57:27 INFO - moztest == 0.7 02:57:27 INFO - mozversion == 1.4 02:57:27 INFO - psutil == 3.1.1 02:57:27 INFO - requests == 1.2.3 02:57:27 INFO - wsgiref == 0.1.2 02:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:27 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:27 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 02:57:27 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 02:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:27 INFO - 'HOME': '/Users/cltbld', 02:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:27 INFO - 'LOGNAME': 'cltbld', 02:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:27 INFO - 'MOZ_NO_REMOTE': '1', 02:57:27 INFO - 'NO_EM_RESTART': '1', 02:57:27 INFO - 'PAGER': '/bin/cat', 02:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:27 INFO - 'PWD': '/builds/slave/test', 02:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:27 INFO - 'SHELL': '/bin/bash', 02:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:27 INFO - 'USER': 'cltbld', 02:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:27 INFO - 'XPC_FLAGS': '0x0', 02:57:27 INFO - 'XPC_SERVICE_NAME': '0', 02:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 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)) 02:57:28 INFO - Cleaning up... 02:57:28 INFO - Return code: 0 02:57:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57:28 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')]} 02:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:28 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')]} 02:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:28 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:28 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 02:57:28 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 02:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:28 INFO - 'HOME': '/Users/cltbld', 02:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:28 INFO - 'LOGNAME': 'cltbld', 02:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:28 INFO - 'MOZ_NO_REMOTE': '1', 02:57:28 INFO - 'NO_EM_RESTART': '1', 02:57:28 INFO - 'PAGER': '/bin/cat', 02:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:28 INFO - 'PWD': '/builds/slave/test', 02:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:28 INFO - 'SHELL': '/bin/bash', 02:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:28 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:28 INFO - 'USER': 'cltbld', 02:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:28 INFO - 'XPC_FLAGS': '0x0', 02:57:28 INFO - 'XPC_SERVICE_NAME': '0', 02:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:28 INFO - Downloading/unpacking jsonschema 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:31 INFO - Downloading/unpacking functools32 (from jsonschema) 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 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 02:57:31 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:31 INFO - warning: no files found matching '*.txt' 02:57:31 INFO - no previously-included directories found matching 'build' 02:57:31 INFO - no previously-included directories found matching 'dist' 02:57:31 INFO - no previously-included directories found matching '.git*' 02:57:31 INFO - Installing collected packages: jsonschema, functools32 02:57:31 INFO - Running setup.py install for functools32 02:57:31 INFO - warning: no files found matching '*.txt' 02:57:31 INFO - no previously-included directories found matching 'build' 02:57:31 INFO - no previously-included directories found matching 'dist' 02:57:31 INFO - no previously-included directories found matching '.git*' 02:57:31 INFO - Successfully installed jsonschema functools32 02:57:31 INFO - Cleaning up... 02:57:31 INFO - Return code: 0 02:57:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:57:31 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')]} 02:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:31 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')]} 02:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:31 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 0x1090b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090c1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9df85a4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, '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.gjoKNwOZOs/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.ILmoWQgCst/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 02:57:31 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 02:57:31 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 02:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:31 INFO - 'HOME': '/Users/cltbld', 02:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:31 INFO - 'LOGNAME': 'cltbld', 02:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:31 INFO - 'MOZ_NO_REMOTE': '1', 02:57:31 INFO - 'NO_EM_RESTART': '1', 02:57:31 INFO - 'PAGER': '/bin/cat', 02:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:31 INFO - 'PWD': '/builds/slave/test', 02:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:31 INFO - 'SHELL': '/bin/bash', 02:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:57:31 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:31 INFO - 'USER': 'cltbld', 02:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:31 INFO - 'XPC_FLAGS': '0x0', 02:57:31 INFO - 'XPC_SERVICE_NAME': '0', 02:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:32 INFO - Downloading/unpacking flake8 02:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:57:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:57:34 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 02:57:34 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 02:57:34 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 02:57:34 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 02:57:34 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 02:57:34 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 02:57:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:57:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:57:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:57:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:57:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:57:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:57:35 INFO - no previously-included directories found matching 'docs/_build' 02:57:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:57:36 INFO - Running setup.py install for pep8 02:57:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:57:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:57:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:57:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:57:36 INFO - no previously-included directories found matching 'docs/_build' 02:57:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:57:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:57:36 INFO - Cleaning up... 02:57:36 INFO - Return code: 0 02:57:36 INFO - Running post-action listener: _resource_record_post_action 02:57:36 INFO - Running post-action listener: _start_resource_monitoring 02:57:36 INFO - Starting resource monitoring. 02:57:36 INFO - ##### 02:57:36 INFO - ##### Running install step. 02:57:36 INFO - ##### 02:57:36 INFO - Running pre-action listener: _resource_record_pre_action 02:57:36 INFO - Running main action method: install 02:57:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:36 INFO - Reading from file tmpfile_stdout 02:57:36 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:36 INFO - Reading from file tmpfile_stdout 02:57:36 INFO - Output received: 02:57:36 INFO - Usage: mozinstall [options] installer 02:57:36 INFO - Options: 02:57:36 INFO - -h, --help show this help message and exit 02:57:36 INFO - -d DEST, --destination=DEST 02:57:36 INFO - Directory to install application into. [default: 02:57:36 INFO - "/builds/slave/test"] 02:57:36 INFO - --app=APP Application being installed. [default: firefox] 02:57:36 INFO - mkdir: /builds/slave/test/build/application 02:57:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:58:08 INFO - Reading from file tmpfile_stdout 02:58:08 INFO - Output received: 02:58:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:58:08 INFO - Running post-action listener: _resource_record_post_action 02:58:08 INFO - ##### 02:58:08 INFO - ##### Running run-tests step. 02:58:08 INFO - ##### 02:58:08 INFO - Running pre-action listener: _resource_record_pre_action 02:58:08 INFO - Running main action method: run_tests 02:58:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:08 INFO - 2016-05-22 02:58:08.325 screenresolution[1655:9338] starting screenresolution argv=screenresolution get 02:58:08 INFO - 2016-05-22 02:58:08.327 screenresolution[1655:9338] Display 0: 1600x1200x32@60 02:58:08 INFO - 2016-05-22 02:58:08.333 screenresolution[1656:9340] starting screenresolution argv=screenresolution list 02:58:08 INFO - Available Modes on Display 0 02:58:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:08 INFO - Intel Iris: 02:58:08 INFO - Chipset Model: Intel Iris 02:58:08 INFO - Type: GPU 02:58:08 INFO - Bus: Built-In 02:58:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:08 INFO - Vendor: Intel (0x8086) 02:58:08 INFO - Device ID: 0x0a2e 02:58:08 INFO - Revision ID: 0x0009 02:58:08 INFO - Displays: 02:58:08 INFO - Display: 02:58:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:08 INFO - Display Serial Number: 1600x1200 60 02:58:08 INFO - Main Display: Yes 02:58:08 INFO - Mirror: Off 02:58:08 INFO - Online: Yes 02:58:08 INFO - Rotation: Supported 02:58:08 INFO - Return code: 0 02:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:08 INFO - Python 2.7.3 02:58:08 INFO - Return code: 0 02:58:08 INFO - grabbing minidump binary from tooltool 02:58:08 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')]} 02:58:08 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f50e00>, '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 0x108db9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f4bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:08 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 02:58:08 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 02:58:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:08 INFO - Return code: 0 02:58:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:58:09 INFO - Return code: 0 02:58:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:58:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gjoKNwOZOs/Render', 02:58:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:09 INFO - 'HOME': '/Users/cltbld', 02:58:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:09 INFO - 'LOGNAME': 'cltbld', 02:58:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:09 INFO - 'MOZ_NO_REMOTE': '1', 02:58:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:09 INFO - 'NO_EM_RESTART': '1', 02:58:09 INFO - 'PAGER': '/bin/cat', 02:58:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:09 INFO - 'PWD': '/builds/slave/test', 02:58:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:09 INFO - 'SHELL': '/bin/bash', 02:58:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ILmoWQgCst/Listeners', 02:58:09 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:58:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:09 INFO - 'USER': 'cltbld', 02:58:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:09 INFO - 'XPC_FLAGS': '0x0', 02:58:09 INFO - 'XPC_SERVICE_NAME': '0', 02:58:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:58:10 INFO - mozversion application_buildid: 20160522004024 02:58:10 INFO - mozversion application_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 02:58:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:58:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:10 INFO - mozversion application_name: Firefox 02:58:10 INFO - mozversion application_remotingname: firefox-dev 02:58:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:10 INFO - mozversion application_vendor: Mozilla 02:58:10 INFO - mozversion application_version: 48.0a2 02:58:10 INFO - mozversion platform_buildid: 20160522004024 02:58:10 INFO - mozversion platform_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 02:58:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:10 INFO - mozversion platform_version: 48.0a2 02:58:10 INFO - using testdate: 1463911090 02:58:10 INFO - actual date: 1463911090 02:58:10 INFO - starting webserver on 'localhost:49280' 02:58:10 INFO - SUITE-START | Running 2 tests 02:58:10 INFO - TEST-START | dromaeo_css 02:58:10 INFO - operating with platform_type : mac_ 02:58:10 INFO - Initialising browser for dromaeo_css test... 02:58:10 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpOfTPPh/profile http://localhost:49280/getInfo.html) 02:58:12 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 02:58:12 INFO - PROCESS | 1674 | colorDepth:24 02:58:12 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 02:58:12 INFO - PROCESS | 1674 | __metrics 02:58:12 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:58:12 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:58:13 INFO - TEST-INFO | 1674: exit 0 02:58:13 INFO - Browser initialized. 02:58:13 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:13 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpOfTPPh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:25 INFO - PROCESS | 1678 | RSS: Main: 211173376 02:58:25 INFO - PROCESS | 1678 | 02:59:16 INFO - PROCESS | 1678 | 1463911156993 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] 03:01:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 03:01:29 INFO - PROCESS | 1678 | RSS: Main: 342913024 03:01:29 INFO - PROCESS | 1678 | 03:04:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 03:04:32 INFO - PROCESS | 1678 | RSS: Main: 357441536 03:04:32 INFO - PROCESS | 1678 | 03:07:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 03:07:35 INFO - PROCESS | 1678 | RSS: Main: 363069440 03:07:35 INFO - PROCESS | 1678 | 03:10:37 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 03:10:38 INFO - PROCESS | 1678 | RSS: Main: 352911360 03:10:38 INFO - PROCESS | 1678 | 03:13:41 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 03:13:42 INFO - PROCESS | 1678 | RSS: Main: 320204800 03:13:42 INFO - PROCESS | 1678 | 03:16:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 03:16:45 INFO - PROCESS | 1678 | RSS: Main: 366477312 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | __start_tp_report 03:16:45 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 03:16:45 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 03:16:45 INFO - PROCESS | 1678 | |i|pagename|runs| 03:16:45 INFO - PROCESS | 1678 | |0;dojo.html;3395.6943875051243;3451.499532567779;3465.4108801503735;3490.127468132967;3511.1046683095806;5666.660000399974;5691.971540142294;5694;5721.427857214287;5730.914036289459;5675.5459563234945;5741.598088133832;5743.655380677163;5744.770209191627;5764.365919748835;5118.129917913963;5133.2300154976865;5190.92213616796;5255.947440525584;5275;4369.410129632507;5272.446393128724;5296.735163241822;5303.337082864642;5312.07038768216;5192.299039629658;5238.947610523884;5270.710110943896;5284.4187139414635;5284.762185701653;5254.973725131388;5285.841424757264;5305.8938821223355;5307.946920530784;5333.4399888011585;5113.360829896263;5164.302819119408;5214.921776173361;5216.921746173811;5234.084035293847;4700.764961751899;5601.243832082658;5612.522935550483;5645.125005624135;5730.140478928194;6290.004739454676;6296.433321001086;6300.747970081191;6306.684665766739;6324.588901721373;6181.536384771161;6272.964960781511;6302.4958003359625;6309.14826498426;6317.178766760338;5864.472197502245;5911.556633252523;5935.881282374328;5962.642241465526;6036.939630603725;6321.683915804191;6326.715297811609;6334.904976425358;6341;6359.427651511386;6049.637021778707;6140.109684095781;6265.592736472115;6271.560990730696;6279.3720627937755;6232.18981532407;6277.686115694197;6294.559380843297;6304.338044505353;6317.684115794284;6164.938350616437;6217.813465596039;6291.937080629135;6300.716467758962;6302.653354065479;6265.530085243625;6300.716467758962;6300.810975670737;6316.463100636497;6329.968350158219;4862.343583616166;4977.626677999165;4986.650934434628;5002.799888004439;5030.094582975026;4402.889927751768;4416.227160246944;4450.777461126995;4474.753888536096;4499.910001799945;5677.545796336283;5705.62913410627;5713.285839270091;5717.542596592262;5755.079187330007;6109.283207920342;6225.1907252057845;6311.053341998737;6317.778877739349;6320.494360451153;5745.4541818528005;5761.971190144106;5762.654240745569;5765.250517432791;5769.48074673273;5431.6740995540395;5432.049391356497;5433.565314774809;5443;5458.863528411742;6215.285242197025;6309.463695585927;6391.073294372383;6405.167328247391;6425.1968503937005;6287.1512345832725;6289.622622642656;6294.433500984934;6316.526260530387;6319.399657032574;6324.810255692337;6340.239171299289;6359.427651511386;6360.363963603602;6367.968160159169;6113.480354169946;6146.5390095742105;6176.505879529537;6241.843953901189;6260.40526150015;6217.253929528486;6272.6236425814595;6299.622022678654;6301.243850737941;6309.842253943688;6176.382361763787;6204.627722336674;6212.658303793335;6223.315435302023;6235.251769787655;6041.6375017499095;6097.664628445478;6176.413240742122;6180.567360284737;6187.597806142767;4750.836045168818;6030.396960303934;6078.438194261433;6085.087236914498;6127.938720612737;6133.478654314434;6143.938560614337;6193;6197.411245931629;6216.160818289473;5919.437653422918;6036.69816509164;6051.878962420815;6118.43644382056;6127.6476951528675 03:16:45 INFO - PROCESS | 1678 | |1;ext.html;507.13786562843165;513.8920826626411;568.3095039526977;592.1916583863028;609.9390060993901;30962;31700.207494812643;39079.804600977026;40205.798971005104;40339.79830100853;43719.56280437195;44236.55763442365;44431.333529997;44464.777676111655;44495;9596.856047159281;9823.95088024559;9829.55766990487;9830;9831.213502919749;8658.350623703216;8877.600507977157;11685.415729213548;11869.584564540242;11871.28772273662;11469.885301147007;11562.59530916418;11575.710607234802;11600.70998225047;11621.535138594489;8683.565821708953;11130.05394541465;11181.385023823686;11186.272892261977;11662.125340599405;11410.20128590999;11547.133964952578;11559;11563.42182890852;11587.594434194847;33489.33021339584;37988.24023519514;38287;38710.83867483952;38994.80502597469;3171.270607760205;3187.585613870205;3371.393149233137;3771.6039815819217;3806.200697853454;22853.7714622855;26979.865100674568;27215.591766123525;28080.438391232063;28375.57436638452;2772.4177922636272;2791.078943948492;2795.6086147939304;2814.3879728032084;2933.4426458972725;345164;346389;346760;347011;348252;61455;61884;61938.69030654908;62030;62076.06885896715;64527.67736161289;67789.32210677929;69156.50482718607;69189;69336.65331673309;17023.31906723742;17226.96638201712;17269.96380217191;17270.568235794206;17315.65368692619;10761.623343182951;12066.577669781691;12277.342558754453;13105.148165369412;13273.402696878664;19163.808361916483;19241.711374329396;19262.518437039187;19334.709979350322;19739.703904441445;6973.755918542928;7383.520071195355;8125.309348705308;8322.62548185333;8621.439606425562;887.5961437545901;997.5261750668375;1009.3338396658318;1020.2450186861818;1101.300674071954;29460.852695736812;30050;30337.874931376777;30418.087457376314;30764.38471230563;1113.4877956140103;1115.6262652011505;1142.8857114288505;1210.6126039667397;1219.5914368686413;15034.17312047848;15218.467353642569;15276.618084547754;15573.299201535958;15670.608234793994;544560;547637;554508;555869;556391;59991;60527.09209361775;60611;60729.69635151796;60832;1365.542543248023;1483.3028476615973;1517.9316930737923;1522.596511924363;1533.3176736352439;29652;29716.257093572833;29772;30436.26073653382;30625.540616890317;48755;49068.50931490639;49097;49138;49160.508394915596;342880;344098;344340.11319773964;344348;344644;25695.486090277715;27116.457670846867;27155.86422067877;27156.91372345084;27226.72773272242;40969.79515102405;45745.08509829718;46855.8286042838;47675.5232447685;52559.47440525546;38163;38291;38849.80575097106;39339.80330098331;40166.995825104605;37921.62078379291;38392;38536.61463385443;38620.227595448494;38947.6105238944;37921.81039094787;38599;38764.41853372258;38995.220095598495;39088;34199.374782909945;35285;35904.461433078;36191.457128142574;36213.818930905174;26271.949122034905;26276.94892204367;26340.868295658398;26612.60081098824;26823.731762682124 03:16:45 INFO - PROCESS | 1678 | |2;jquery.html;400.92783299006175;402.50692901196027;403.3163787380388;407.37467986640513;416.3712793681542;18965.715514267264;19829;20466.795332046695;20477;20762.16951321949;20443.693344599844;20728;20866.89566552169;22101.005454754533;22107.77892221077;5683.318001839764;5921.171036054967;5963.1055341698875;5978.432048955342;6130.908036379458;5871.588988770788;5884.117382392633;5919.49684276837;5926.466618004379;5931.525477961753;5732.5127364174095;5930.169776231331;6196.194494715681;6217.813465596039;6221.911165546034;6153.938460615382;6200.503959683215;6207.534434917399;6212.813615591517;6219.378062193789;5635.380108188096;5930.466258036754;5951.166836642873;5953.791617293374;5978.282606087254;13561.660958476019;13725.313734313244;13822.723545529134;21970.011349489312;26629;2109.2090466075224;2187.9234226801987;2188.588147051651;2238.787315205053;2348.6359614259645;8084.353251739905;10138.138258248058;10492.580296788119;13269.203847769164;14513.621205985415;1676.228934690044;1712.032701523635;1720.9827901720948;1728.1186594836724;1753.929842806299;106718;108206.45896770545;109900;110647;111434.88565114327;9327.533623318806;11161.776764464665;11604.535818567163;11629.360385178727;11920.821187682192;15076.623084422978;15300.770488442682;15303.234838258042;15324;15338.15640139803;8860.202581767542;8890.511021893857;8902.42134261271;8960.955195224113;9025.50359730208;8852.557372131496;8863.955680221687;8883.244924181388;8998.820023599623;9008.729738107868;4672.556107169813;4698.577128058491;4710.364100846342;4715.905681886412;4764.38063051808;1930.6621341265222;1988.423357226417;1991.8506112041655;2011.3764732932837;2011.9920718525898;860.5697151424288;905.3933864310795;917.958691858868;934.1405906565975;971.8056388722142;16444.588885277724;16550.83449165493;20974.47563810917;21080.945952702303;21242.893785530974;920.9539523023956;961.8749562556962;964.3924327673661;988.8071825993862;1028.6305485366377;9327.600859871074;10830.566777328819;11047.447627618587;11161.497732602053;11202.943985280184;587473;605286.9735651382;611936;623807;634352;71492.2850771496;96756.54865177028;120393.19410208854;122116;128102;926.3746970794687;938.8967213606497;939.5302348825587;952.5570609666538;995.9950200249098;18070.03261396191;18524.536886577942;18763.90618046901;18770.873747575188;18890.244390224238;52100.95798084093;52288.73855630698;52598.47401525936;53298.23474759882;53737.731311343196;73569;74656.25343746711;75940;76962.61518692371;79516.60241698755;7841.960790196013;8281.964754405699;8385.538795366314;8429.11494293115;8658.48049117055;11949.820752688891;12717.109802314118;12783.360831958551;12989.610311690665;13015.869841301845;10831.566737330733;11228.66314010581;11266.549338026387;11292.983631473207;11613.593524226611;9079.364444489087;9842.950785246028;10646.20153488507;10922.279171537179;11514.88485115138;11298.43507824589;11437.942810285895;11539.480723367637;11550.249233799776;11795.705107372385;7465.962670186615;7725.266099720518;8405.831883362176;8738.650453981769;8769.956150219208;9906.504674766089;11025;11126.998570128728;11127;11377.317360958368 03:16:45 INFO - PROCESS | 1678 | |3;mootools.html;1440.5750303660418;1580.2414840876381;1611.4762702121814;1856.675081860674;1984.5733167369012;7998.160193179712;8685.782855428617;8857;8877.245434138098;8880.156385143417;8535.31717462603;8779.385443018991;8792.560371981408;8803.779905502366;8808.91191088089;2156.54712510373;2382.356763673807;2451.963220551693;2466.1738317663603;2487.7139129000216;2481.6029435290357;2482.553140434721;2489.103922587867;2489.614109812973;2495.7147069259345;2387.1048356866177;2436.61014237722;2455.877206139695;2457.1154384421593;2471.666325046123;2150.2796563151287;2385.7733515316017;2443.853368797869;2449.632555116729;2456.5823809952353;2150.6989021536842;2374.5725769361507;2434.805215582767;2471.5921872890954;2475.10896077412;8696.434731742416;8769.386142969995;8777.956110219471;8783.12168783123;8783.868241976375;5298.390685071227;5311.787528498855;5481.945180548183;5487.972560137214;5504.889902201933;3470.6876381125567;3540.3627347077518;3573.285342931424;3587.1749497615433;3587.210813620999;1018.9082982531608;1025.092792878308;1037.9117774989136;1048.9632862849764;1049.9527521261562;12232.81650775239;12246.571370002008;12257.509699612005;12261.386930653433;12271;4793.832215872428;4876;4877.512248775165;4932.161532539477;4985.977874535712;10225.795484090277;10334.638287659896;10338.844917326247;10339;10342.224333175041;5532.640378375393;5543.334799823966;5587.05020146576;5587.245721827582;5593.244911936918;4454.910901781946;4617.284320930261;4943.480934501826;5021.623378246646;5034.496550344937;4108.979455102705;4129.834806607702;4136.669066474675;4168.7290326128705;4185.560516145822;1127.2503784983057;1353.079905664138;1353.8375394952668;1364.3723887011888;1367.9589612311647;767.1139833492214;927.596495524449;942.8067246214509;949.3591825517748;972.7957129002847;5938.656188055788;5986.910196347058;6052.364501727343;6141.109539116803;6289.433950944437;455.07392456351164;473.1648640150138;476.5020553521578;478.0749250200872;479.70498143642106;3524.718022558189;3852.4991751072744;3897.4348719435525;3916.921661566753;3955.4462375267485;10812.62155824558;10857;11014.39420831846;11178.832317515244;11213.439328033728;10898;10997.065249453886;11024.724381890517;11043.613473528389;11059.778804423866;8047.235512626291;8479.364047696448;8543.786405339917;8572.785680358042;8588.055313915464;5909.704514774158;5914.319853216935;5967.582269241111;6031.60794548353;6039.335673075871;9294.907050929403;9483.099105584959;9681.128698417177;9836.163926066365;9906.356086854332;9569.28230382738;9874.617553542654;10080;10299.536520856429;10303.742406439898;6932.306769323229;7150.10623672041;7459.179490256176;7753.302202801775;7886.566238856918;8456.323494120332;8550.102239264788;8551.230389264996;8557.400981931454;8579;7364.852702945804;7454.888176677246;7535.86961955711;7563.281488258606;7603.315701586884;6914.343137401939;7283.271672832674;7287.8542429152185;7288.781336559911;7452.9627351865065;7065.6113913735235;7124.750633727795;7163.212046674966;7176.4258859290085;7212.675429605785;4873.001034787789;4922.556969872729;4939.777710002986;5020.924686129638;5056.039352523009;4978.751062446936;5041.168207245763;5042.319286896369;5147.305113809737;5303.337082864642 03:16:45 INFO - PROCESS | 1678 | |4;prototype.html;314.28656948726393;318.6256149024896;327.6101439287248;328.6171610074264;329.6555099920583;6501.252355979058;6608.2730899601065;6612.305707900673;6614.801555953329;6619.073329733835;6207.968960155193;6409.839754006152;6438.7746428875;6542.509311801622;6757.493188010894;4836.202026665596;4836.879078023042;4846.127697014536;4867.440244371889;4874.829380971649;4785.449673287564;4849.757512124397;4858.46556878743;4871.050145221666;4876.902461950776;4577.748223847704;4638.768061596924;4750.429948406196;4803.519648035203;4816.3016362627395;4751.596114330286;4759.167145749515;4781.402324709411;4784.784684689197;4799.400074990626;4088.4889388826396;4098.774567398792;4101.774402407867;4103.5691252418665;4112.09533902541;6291.339409362043;6320.810375688737;6339.651319177444;6348.968255158741;6353.936460635381;5763.6541807491685;5765.740541675635;5769.88460230793;5772.624779389368;5777.422257774231;6769.390754832039;6989.33601307875;6993.860122797516;7028.297170282982;7031.1211098612675;4673.906521869543;4696.178168820477;4707.293905914106;4721.315409265671;4729.337892695025;7403.111626604842;7403.555786652764;7403.888941665826;7420.888686669704;7433.026789284276;4765.213739732974;4766.785494652749;4767.809287628525;4786.44955830077;4786.473487916326;4040.595940405936;4810.182269014276;4839.032193561231;4841.515848415131;4855.174620314555;4387.42963414754;4651.6975246930915;5234.685918844882;5750.453706897922;5752.856178595569;6977.82555436108;6980.476464265201;7017.4035207006955;7023.613701246379;7035.753748618774;1604.0536083710433;1629.6414788746456;1634.3871048356866;1634.9264283107289;1635.5911022244438;952.1383148250844;971.7522031881825;993.5181437003048;995.3928103856641;995.5370752599931;6482.384173503509;6781.62701051437;6783.796486105326;6790.660466976631;6791.3548212919695;7334.009908662262;7335.449841261927;7336.339729424378;7339.339459448568;7360.447966402541;5893.174955506232;6113.99119145336;6577.769778057745;6590.110335104795;6643.900341494881;3731.1231860512758;3844.557875844258;3869.806509674505;3871.8064096795047;3885.6891448684037;7454.962725186339;7512.47412681118;7527.887081693779;7549.660265288076;7556.395488360918;6002.789902353484;6086.269647642312;7379.372753315921;7412.295831895961;7458.515196512318;5939.940600593939;6208.317085120633;7494.85010299791;7537.849243015109;7611.086669599684;5897.557683173865;6992;7303.123625165014;7319.487635865651;7333.816654583678;7127.572345659277;7144.9642751785905;7158.319959603829;7225.060742103493;7243.674034668558;7619.352355049883;7632.503887247533;7640.624687556293;7642.961785191039;7646.885296720442;4587.885302867321;4635.466921304093;4637.188492013952;4641.18779213643;4645.767711614473;7064.82337941535;7292.525985810906;7321.707131714885;7333.303336183054;7357.963210183915;7046.084009078787;7059.82350441243;7069.893951590833;7071.71713131469;7077.398421134261;6958.547694399847;7026.613536255555;7055.470839686941;7061.505694601328;7073.575585464889;6891.827704307432;6894.413974812197;6898.1377327834025;6903.102596662402;6912.308769123048;6800.251972282946;7028.683709232994;7041.612711300722;7043.612601306977;7054.576725396288;6634.104395906491;6720.8319792005595;6791.422729068085;6863.279355667583;6926.307369263033 03:16:45 INFO - PROCESS | 1678 | |5;yui.html;630.4420587779814;676.2426082787277;704.6864145455274;735.8086897406674;771.7607541662085;14431.92784036081;14931;15138.167400792952;15183.089014659114;15224.847751522482;15526.611834704137;15612.609684757886;15626.687466250669;15643.374265029384;15647.765283520755;741.770051284101;915.9450432974027;943.6319835264252;943.641416261823;945.2013048973606;741.0107506478857;891.3359547137392;930.144267274106;931.2177770672668;931.8229536388064;971.5142428785607;974.1476208317722;976.4043933200742;978.0757184460688;980.8430651095827;774.5701135869647;962.206179901577;972.8832540095163;972.902709729025;973.1047436358567;716.5127713155052;716.6488420673884;717.2934659360526;717.3579646216607;717.7236763845957;14848.925755371261;14966.70066598662;14991.625209369744;14999.775003374958;15003.099814011086;12562.246265224116;12603.243805371707;12707.745845083047;12755.170913890568;12939.935300323532;10024.849627255597;10087.7478063049;10158.949205253926;10170.542325595292;10760.73098172544;3453.671901169385;3468.358353704573;3493.283876805249;3543.5039094526787;3574.696150827183;61918.69040654813;64422;74477.62761186213;76136;84777.30445391119;9529.475878826732;9904.851427228597;9919.652812151684;9972.301938864355;10230.64192753265;22834.771652283263;22842.200522981617;22872.31383058511;22880.885595571915;22894.084236630682;7309.926900731031;7360.521566098294;7432.145303290083;7767.572783496849;7917.247861453153;4046.393041043808;4049.979750101231;4108.671306295549;4169.291220492523;4319.4816622005565;3552.4493703476;3611.133328001261;3616.764910280823;3618.222082252328;3625.8912232633065;638.4286063972719;656.150285380424;656.3043174235326;666.2271764752863;681.5467713970154;504.9166887463601;551.6028459509147;580.7270582826065;582.0803131052926;600.9399060093956;6620.801375958729;8131.024277086787;8178.3048440882;8274.172582741798;8344.207300306462;517.6324809385378;564.0270533329973;573.0744847072032;578.7829563913532;598.5899658733781;3742.830066337484;3811.352070148026;3845.4616353710503;3930.508686414198;3957.3668213085766;78650;79786.60106699546;80474;80559.791603126;81124;72618.63690681619;73199;73858.26141738621;74360;74460;67308.6634566824;67668.32331676719;68210;68321;68417.3158268421;7595.468317217742;7691.961540192263;7816.765497035098;7829.021372328459;7834.647440865061;47229.76385118053;47569;48372;48838.75580622074;49609;61950.69024654848;62744.686276568325;65306;66641.66679166573;66806;10896.61861834832;11086.057685096535;11158.10735141203;11241.325520468798;11355.261907975955;11502.654920352403;12061.095417843522;12095.39523023863;12242;12343.753124937271;11290.209685321948;11335.979761821476;11380.03269722083;11731.120165987415;11857;11248.88751112511;11340.149488788209;11474.368909710047;11540.480678369326;11670.591529296435;10994.780104398027;11107.72230694239;11282;11338.886611133783;11837.585684501346;9334.859977100214;9418.970616841756;9717.805643887225;9777.9022209777;9874.950625246827;8536.317094632539;9613.279004074582;9770.56032478526;9793.559289831832;9817.705468835946 03:16:45 INFO - PROCESS | 1678 | __end_tp_report 03:16:45 INFO - PROCESS | 1678 | __start_cc_report 03:16:45 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1234 03:16:45 INFO - PROCESS | 1678 | __end_cc_report 03:16:45 INFO - PROCESS | 1678 | __startTimestamp1463912205097__endTimestamp 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | ------- Summary: start ------- 03:16:45 INFO - PROCESS | 1678 | Number of tests: 6 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#0] dojo.html Cycles:165 Average:5780.50 Median:6078.44 stddev:658.09 (10.8%) stddev-sans-first:632.95 03:16:45 INFO - PROCESS | 1678 | Values: 3395.7 3451.5 3465.4 3490.1 3511.1 5666.7 5692.0 5694.0 5721.4 5730.9 5675.5 5741.6 5743.7 5744.8 5764.4 5118.1 5133.2 5190.9 5255.9 5275.0 4369.4 5272.4 5296.7 5303.3 5312.1 5192.3 5238.9 5270.7 5284.4 5284.8 5255.0 5285.8 5305.9 5307.9 5333.4 5113.4 5164.3 5214.9 5216.9 5234.1 4700.8 5601.2 5612.5 5645.1 5730.1 6290.0 6296.4 6300.7 6306.7 6324.6 6181.5 6273.0 6302.5 6309.1 6317.2 5864.5 5911.6 5935.9 5962.6 6036.9 6321.7 6326.7 6334.9 6341.0 6359.4 6049.6 6140.1 6265.6 6271.6 6279.4 6232.2 6277.7 6294.6 6304.3 6317.7 6164.9 6217.8 6291.9 6300.7 6302.7 6265.5 6300.7 6300.8 6316.5 6330.0 4862.3 4977.6 4986.7 5002.8 5030.1 4402.9 4416.2 4450.8 4474.8 4499.9 5677.5 5705.6 5713.3 5717.5 5755.1 6109.3 6225.2 6311.1 6317.8 6320.5 5745.5 5762.0 5762.7 5765.3 5769.5 5431.7 5432.0 5433.6 5443.0 5458.9 6215.3 6309.5 6391.1 6405.2 6425.2 6287.2 6289.6 6294.4 6316.5 6319.4 6324.8 6340.2 6359.4 6360.4 6368.0 6113.5 6146.5 6176.5 6241.8 6260.4 6217.3 6272.6 6299.6 6301.2 6309.8 6176.4 6204.6 6212.7 6223.3 6235.3 6041.6 6097.7 6176.4 6180.6 6187.6 4750.8 6030.4 6078.4 6085.1 6127.9 6133.5 6143.9 6193.0 6197.4 6216.2 5919.4 6036.7 6051.9 6118.4 6127.6 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#1] ext.html Cycles:180 Average:57661.43 Median:344496.00 stddev:112896.71 (32.8%) stddev-sans-first:113131.86 03:16:45 INFO - PROCESS | 1678 | Values: 507.1 513.9 568.3 592.2 609.9 30962.0 31700.2 39079.8 40205.8 40339.8 43719.6 44236.6 44431.3 44464.8 44495.0 9596.9 9824.0 9829.6 9830.0 9831.2 8658.4 8877.6 11685.4 11869.6 11871.3 11469.9 11562.6 11575.7 11600.7 11621.5 8683.6 11130.1 11181.4 11186.3 11662.1 11410.2 11547.1 11559.0 11563.4 11587.6 33489.3 37988.2 38287.0 38710.8 38994.8 3171.3 3187.6 3371.4 3771.6 3806.2 22853.8 26979.9 27215.6 28080.4 28375.6 2772.4 2791.1 2795.6 2814.4 2933.4 345164.0 346389.0 346760.0 347011.0 348252.0 61455.0 61884.0 61938.7 62030.0 62076.1 64527.7 67789.3 69156.5 69189.0 69336.7 17023.3 17227.0 17270.0 17270.6 17315.7 10761.6 12066.6 12277.3 13105.1 13273.4 19163.8 19241.7 19262.5 19334.7 19739.7 6973.8 7383.5 8125.3 8322.6 8621.4 887.6 997.5 1009.3 1020.2 1101.3 29460.9 30050.0 30337.9 30418.1 30764.4 1113.5 1115.6 1142.9 1210.6 1219.6 15034.2 15218.5 15276.6 15573.3 15670.6 544560.0 547637.0 554508.0 555869.0 556391.0 59991.0 60527.1 60611.0 60729.7 60832.0 1365.5 1483.3 1517.9 1522.6 1533.3 29652.0 29716.3 29772.0 30436.3 30625.5 48755.0 49068.5 49097.0 49138.0 49160.5 342880.0 344098.0 344340.1 344348.0 344644.0 25695.5 27116.5 27155.9 27156.9 27226.7 40969.8 45745.1 46855.8 47675.5 52559.5 38163.0 38291.0 38849.8 39339.8 40167.0 37921.6 38392.0 38536.6 38620.2 38947.6 37921.8 38599.0 38764.4 38995.2 39088.0 34199.4 35285.0 35904.5 36191.5 36213.8 26271.9 26276.9 26340.9 26612.6 26823.7 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#2] jquery.html Cycles:180 Average:34423.86 Median:405.35 stddev:101545.56 (25051.6%) stddev-sans-first:101798.28 03:16:45 INFO - PROCESS | 1678 | Values: 400.9 402.5 403.3 407.4 416.4 18965.7 19829.0 20466.8 20477.0 20762.2 20443.7 20728.0 20866.9 22101.0 22107.8 5683.3 5921.2 5963.1 5978.4 6130.9 5871.6 5884.1 5919.5 5926.5 5931.5 5732.5 5930.2 6196.2 6217.8 6221.9 6153.9 6200.5 6207.5 6212.8 6219.4 5635.4 5930.5 5951.2 5953.8 5978.3 13561.7 13725.3 13822.7 21970.0 26629.0 2109.2 2187.9 2188.6 2238.8 2348.6 8084.4 10138.1 10492.6 13269.2 14513.6 1676.2 1712.0 1721.0 1728.1 1753.9 106718.0 108206.5 109900.0 110647.0 111434.9 9327.5 11161.8 11604.5 11629.4 11920.8 15076.6 15300.8 15303.2 15324.0 15338.2 8860.2 8890.5 8902.4 8961.0 9025.5 8852.6 8864.0 8883.2 8998.8 9008.7 4672.6 4698.6 4710.4 4715.9 4764.4 1930.7 1988.4 1991.9 2011.4 2012.0 860.6 905.4 918.0 934.1 971.8 16444.6 16550.8 20974.5 21080.9 21242.9 921.0 961.9 964.4 988.8 1028.6 9327.6 10830.6 11047.4 11161.5 11202.9 587473.0 605287.0 611936.0 623807.0 634352.0 71492.3 96756.5 120393.2 122116.0 128102.0 926.4 938.9 939.5 952.6 996.0 18070.0 18524.5 18763.9 18770.9 18890.2 52101.0 52288.7 52598.5 53298.2 53737.7 73569.0 74656.3 75940.0 76962.6 79516.6 7842.0 8282.0 8385.5 8429.1 8658.5 11949.8 12717.1 12783.4 12989.6 13015.9 10831.6 11228.7 11266.5 11293.0 11613.6 9079.4 9843.0 10646.2 10922.3 11514.9 11298.4 11437.9 11539.5 11550.2 11795.7 7466.0 7725.3 8405.8 8738.7 8770.0 9906.5 11025.0 11127.0 11127.0 11377.3 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#3] mootools.html Cycles:180 Average:5794.06 Median:4935.97 stddev:3272.89 (66.3%) stddev-sans-first:3265.72 03:16:45 INFO - PROCESS | 1678 | Values: 1440.6 1580.2 1611.5 1856.7 1984.6 7998.2 8685.8 8857.0 8877.2 8880.2 8535.3 8779.4 8792.6 8803.8 8808.9 2156.5 2382.4 2452.0 2466.2 2487.7 2481.6 2482.6 2489.1 2489.6 2495.7 2387.1 2436.6 2455.9 2457.1 2471.7 2150.3 2385.8 2443.9 2449.6 2456.6 2150.7 2374.6 2434.8 2471.6 2475.1 8696.4 8769.4 8778.0 8783.1 8783.9 5298.4 5311.8 5481.9 5488.0 5504.9 3470.7 3540.4 3573.3 3587.2 3587.2 1018.9 1025.1 1037.9 1049.0 1050.0 12232.8 12246.6 12257.5 12261.4 12271.0 4793.8 4876.0 4877.5 4932.2 4986.0 10225.8 10334.6 10338.8 10339.0 10342.2 5532.6 5543.3 5587.1 5587.2 5593.2 4454.9 4617.3 4943.5 5021.6 5034.5 4109.0 4129.8 4136.7 4168.7 4185.6 1127.3 1353.1 1353.8 1364.4 1368.0 767.1 927.6 942.8 949.4 972.8 5938.7 5986.9 6052.4 6141.1 6289.4 455.1 473.2 476.5 478.1 479.7 3524.7 3852.5 3897.4 3916.9 3955.4 10812.6 10857.0 11014.4 11178.8 11213.4 10898.0 10997.1 11024.7 11043.6 11059.8 8047.2 8479.4 8543.8 8572.8 8588.1 5909.7 5914.3 5967.6 6031.6 6039.3 9294.9 9483.1 9681.1 9836.2 9906.4 9569.3 9874.6 10080.0 10299.5 10303.7 6932.3 7150.1 7459.2 7753.3 7886.6 8456.3 8550.1 8551.2 8557.4 8579.0 7364.9 7454.9 7535.9 7563.3 7603.3 6914.3 7283.3 7287.9 7288.8 7453.0 7065.6 7124.8 7163.2 7176.4 7212.7 4873.0 4922.6 4939.8 5020.9 5056.0 4978.8 5041.2 5042.3 5147.3 5303.3 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#4] prototype.html Cycles:180 Average:5727.05 Median:6610.29 stddev:1828.44 (27.7%) stddev-sans-first:1787.86 03:16:45 INFO - PROCESS | 1678 | Values: 314.3 318.6 327.6 328.6 329.7 6501.3 6608.3 6612.3 6614.8 6619.1 6208.0 6409.8 6438.8 6542.5 6757.5 4836.2 4836.9 4846.1 4867.4 4874.8 4785.4 4849.8 4858.5 4871.1 4876.9 4577.7 4638.8 4750.4 4803.5 4816.3 4751.6 4759.2 4781.4 4784.8 4799.4 4088.5 4098.8 4101.8 4103.6 4112.1 6291.3 6320.8 6339.7 6349.0 6353.9 5763.7 5765.7 5769.9 5772.6 5777.4 6769.4 6989.3 6993.9 7028.3 7031.1 4673.9 4696.2 4707.3 4721.3 4729.3 7403.1 7403.6 7403.9 7420.9 7433.0 4765.2 4766.8 4767.8 4786.4 4786.5 4040.6 4810.2 4839.0 4841.5 4855.2 4387.4 4651.7 5234.7 5750.5 5752.9 6977.8 6980.5 7017.4 7023.6 7035.8 1604.1 1629.6 1634.4 1634.9 1635.6 952.1 971.8 993.5 995.4 995.5 6482.4 6781.6 6783.8 6790.7 6791.4 7334.0 7335.4 7336.3 7339.3 7360.4 5893.2 6114.0 6577.8 6590.1 6643.9 3731.1 3844.6 3869.8 3871.8 3885.7 7455.0 7512.5 7527.9 7549.7 7556.4 6002.8 6086.3 7379.4 7412.3 7458.5 5939.9 6208.3 7494.9 7537.8 7611.1 5897.6 6992.0 7303.1 7319.5 7333.8 7127.6 7145.0 7158.3 7225.1 7243.7 7619.4 7632.5 7640.6 7643.0 7646.9 4587.9 4635.5 4637.2 4641.2 4645.8 7064.8 7292.5 7321.7 7333.3 7358.0 7046.1 7059.8 7069.9 7071.7 7077.4 6958.5 7026.6 7055.5 7061.5 7073.6 6891.8 6894.4 6898.1 6903.1 6912.3 6800.3 7028.7 7041.6 7043.6 7054.6 6634.1 6720.8 6791.4 6863.3 6926.3 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - PROCESS | 1678 | [#5] yui.html Cycles:180 Average:17508.12 Median:31259.82 stddev:23597.05 (75.5%) stddev-sans-first:23629.22 03:16:45 INFO - PROCESS | 1678 | Values: 630.4 676.2 704.7 735.8 771.8 14431.9 14931.0 15138.2 15183.1 15224.8 15526.6 15612.6 15626.7 15643.4 15647.8 741.8 915.9 943.6 943.6 945.2 741.0 891.3 930.1 931.2 931.8 971.5 974.1 976.4 978.1 980.8 774.6 962.2 972.9 972.9 973.1 716.5 716.6 717.3 717.4 717.7 14848.9 14966.7 14991.6 14999.8 15003.1 12562.2 12603.2 12707.7 12755.2 12939.9 10024.8 10087.7 10158.9 10170.5 10760.7 3453.7 3468.4 3493.3 3543.5 3574.7 61918.7 64422.0 74477.6 76136.0 84777.3 9529.5 9904.9 9919.7 9972.3 10230.6 22834.8 22842.2 22872.3 22880.9 22894.1 7309.9 7360.5 7432.1 7767.6 7917.2 4046.4 4050.0 4108.7 4169.3 4319.5 3552.4 3611.1 3616.8 3618.2 3625.9 638.4 656.2 656.3 666.2 681.5 504.9 551.6 580.7 582.1 600.9 6620.8 8131.0 8178.3 8274.2 8344.2 517.6 564.0 573.1 578.8 598.6 3742.8 3811.4 3845.5 3930.5 3957.4 78650.0 79786.6 80474.0 80559.8 81124.0 72618.6 73199.0 73858.3 74360.0 74460.0 67308.7 67668.3 68210.0 68321.0 68417.3 7595.5 7692.0 7816.8 7829.0 7834.6 47229.8 47569.0 48372.0 48838.8 49609.0 61950.7 62744.7 65306.0 66641.7 66806.0 10896.6 11086.1 11158.1 11241.3 11355.3 11502.7 12061.1 12095.4 12242.0 12343.8 11290.2 11336.0 11380.0 11731.1 11857.0 11248.9 11340.1 11474.4 11540.5 11670.6 10994.8 11107.7 11282.0 11338.9 11837.6 9334.9 9419.0 9717.8 9777.9 9875.0 8536.3 9613.3 9770.6 9793.6 9817.7 03:16:45 INFO - PROCESS | 1678 | -------- Summary: end -------- 03:16:45 INFO - PROCESS | 1678 | 03:16:45 INFO - TEST-INFO | 1678: exit 0 03:16:45 INFO - TEST-OK | dromaeo_css | took 1115639ms 03:16:45 INFO - TEST-START | kraken 03:16:45 INFO - operating with platform_type : mac_ 03:16:45 INFO - Initialising browser for kraken test... 03:16:45 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUE8mg7/profile http://localhost:49280/getInfo.html) 03:16:48 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 03:16:48 INFO - PROCESS | 1683 | colorDepth:24 03:16:48 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 03:16:48 INFO - PROCESS | 1683 | __metrics 03:16:48 INFO - PROCESS | 1683 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:16:48 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:16:48 INFO - TEST-INFO | 1683: exit 0 03:16:48 INFO - Browser initialized. 03:16:48 INFO - Running cycle 1/1 for kraken test... 03:16:48 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUE8mg7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:00 INFO - PROCESS | 1686 | RSS: Main: 210755584 03:17:00 INFO - PROCESS | 1686 | 03:17:03 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:04 INFO - PROCESS | 1686 | RSS: Main: 272056320 03:17:04 INFO - PROCESS | 1686 | 03:17:07 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 03:17:07 INFO - PROCESS | 1686 | RSS: Main: 286023680 03:17:07 INFO - PROCESS | 1686 | 03:17:10 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 03:17:11 INFO - PROCESS | 1686 | RSS: Main: 374243328 03:17:11 INFO - PROCESS | 1686 | 03:17:13 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 03:17:13 INFO - PROCESS | 1686 | RSS: Main: 277762048 03:17:13 INFO - PROCESS | 1686 | 03:17:16 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:16 INFO - PROCESS | 1686 | RSS: Main: 279977984 03:17:16 INFO - PROCESS | 1686 | 03:17:19 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:20 INFO - PROCESS | 1686 | RSS: Main: 319418368 03:17:20 INFO - PROCESS | 1686 | 03:17:23 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:23 INFO - PROCESS | 1686 | RSS: Main: 319586304 03:17:23 INFO - PROCESS | 1686 | 03:17:26 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 03:17:27 INFO - PROCESS | 1686 | RSS: Main: 318402560 03:17:27 INFO - PROCESS | 1686 | 03:17:29 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:29 INFO - PROCESS | 1686 | RSS: Main: 309039104 03:17:29 INFO - PROCESS | 1686 | 03:17:31 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:32 INFO - PROCESS | 1686 | RSS: Main: 315817984 03:17:32 INFO - PROCESS | 1686 | 03:17:34 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:34 INFO - PROCESS | 1686 | RSS: Main: 318521344 03:17:34 INFO - PROCESS | 1686 | 03:17:37 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:38 INFO - PROCESS | 1686 | RSS: Main: 322772992 03:17:38 INFO - PROCESS | 1686 | 03:17:41 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:41 INFO - PROCESS | 1686 | RSS: Main: 320966656 03:17:41 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 03:17:43 INFO - PROCESS | 1686 | RSS: Main: 385957888 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | __start_tp_report 03:17:43 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 03:17:43 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 03:17:43 INFO - PROCESS | 1686 | |i|pagename|runs| 03:17:43 INFO - PROCESS | 1686 | |0;ai-astar;112;112;118;112;111;112;113;113;114;133 03:17:43 INFO - PROCESS | 1686 | |1;audio-beat-detection;156;120;145;197;146;137;123;150;158;118 03:17:43 INFO - PROCESS | 1686 | |2;audio-dft;184;184;182;183;185;184;184;185;182;182 03:17:43 INFO - PROCESS | 1686 | |3;audio-fft;69;79;72;149;56;55;56;67;142;54 03:17:43 INFO - PROCESS | 1686 | |4;audio-oscillator;90;87;145;77;80;87;122;79;78;138 03:17:43 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;89;85;89;85;87;83;88;85;87;85 03:17:43 INFO - PROCESS | 1686 | |6;imaging-darkroom;94;93;122;148;128;144;101;101;85;90 03:17:43 INFO - PROCESS | 1686 | |7;imaging-desaturate;124;142;87;93;86;68;143;135;84;84 03:17:43 INFO - PROCESS | 1686 | |8;json-parse-financial;61;63;60;62;63;63;63;85;59;57 03:17:43 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;63;63;80;74;71;68;67;67;67;68 03:17:43 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;79;77;81;79;80;81;79;77;81;85 03:17:43 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;131;130;127;131;149;145;142;130;147;139 03:17:43 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;169;167;169;169;177;167;167;167;163;165 03:17:43 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;71;70;68;68;68;67;67;69;67;68 03:17:43 INFO - PROCESS | 1686 | __end_tp_report 03:17:43 INFO - PROCESS | 1686 | __start_cc_report 03:17:43 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,2051 03:17:43 INFO - PROCESS | 1686 | __end_cc_report 03:17:43 INFO - PROCESS | 1686 | __startTimestamp1463912263790__endTimestamp 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | ------- Summary: start ------- 03:17:43 INFO - PROCESS | 1686 | Number of tests: 14 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:115.00 Median:113.00 stddev:6.62 (5.9%) stddev-sans-first:6.93 03:17:43 INFO - PROCESS | 1686 | Values: 112.0 112.0 118.0 112.0 111.0 112.0 113.0 113.0 114.0 133.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:148.00 stddev:23.39 (15.8%) stddev-sans-first:24.46 03:17:43 INFO - PROCESS | 1686 | Values: 156.0 120.0 145.0 197.0 146.0 137.0 123.0 150.0 158.0 118.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.18 (0.6%) stddev-sans-first:1.24 03:17:43 INFO - PROCESS | 1686 | Values: 184.0 184.0 182.0 183.0 185.0 184.0 184.0 185.0 182.0 182.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:79.90 Median:61.50 stddev:35.61 (57.9%) stddev-sans-first:37.55 03:17:43 INFO - PROCESS | 1686 | Values: 69.0 79.0 72.0 149.0 56.0 55.0 56.0 67.0 142.0 54.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:79.50 stddev:26.28 (33.1%) stddev-sans-first:27.70 03:17:43 INFO - PROCESS | 1686 | Values: 90.0 87.0 145.0 77.0 80.0 87.0 122.0 79.0 78.0 138.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.00 (2.3%) stddev-sans-first:1.87 03:17:43 INFO - PROCESS | 1686 | Values: 89.0 85.0 89.0 85.0 87.0 83.0 88.0 85.0 87.0 85.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:116.50 stddev:23.09 (19.8%) stddev-sans-first:23.69 03:17:43 INFO - PROCESS | 1686 | Values: 94.0 93.0 122.0 148.0 128.0 144.0 101.0 101.0 85.0 90.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:104.60 Median:84.00 stddev:28.19 (33.6%) stddev-sans-first:29.01 03:17:43 INFO - PROCESS | 1686 | Values: 124.0 142.0 87.0 93.0 86.0 68.0 143.0 135.0 84.0 84.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.00 stddev:7.79 (12.4%) stddev-sans-first:8.21 03:17:43 INFO - PROCESS | 1686 | Values: 61.0 63.0 60.0 62.0 63.0 63.0 63.0 85.0 59.0 57.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:68.00 stddev:5.12 (7.5%) stddev-sans-first:4.98 03:17:43 INFO - PROCESS | 1686 | Values: 63.0 63.0 80.0 74.0 71.0 68.0 67.0 67.0 67.0 68.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.50 stddev:2.33 (2.9%) stddev-sans-first:2.45 03:17:43 INFO - PROCESS | 1686 | Values: 79.0 77.0 81.0 79.0 80.0 81.0 79.0 77.0 81.0 85.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:140.50 stddev:8.21 (5.8%) stddev-sans-first:8.41 03:17:43 INFO - PROCESS | 1686 | Values: 131.0 130.0 127.0 131.0 149.0 145.0 142.0 130.0 147.0 139.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:3.68 (2.2%) stddev-sans-first:3.89 03:17:43 INFO - PROCESS | 1686 | Values: 169.0 167.0 169.0 169.0 177.0 167.0 167.0 167.0 163.0 165.0 03:17:43 INFO - PROCESS | 1686 | 03:17:43 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.00 03:17:43 INFO - PROCESS | 1686 | Values: 71.0 70.0 68.0 68.0 68.0 67.0 67.0 69.0 67.0 68.0 03:17:43 INFO - PROCESS | 1686 | -------- Summary: end -------- 03:17:43 INFO - PROCESS | 1686 | 03:17:44 INFO - TEST-INFO | 1686: exit 0 03:17:44 INFO - TEST-OK | kraken | took 58943ms 03:17:44 INFO - SUITE-END | took 1174s 03:17:44 INFO - Completed test suite (00:19:34) 03:17:44 INFO - javascript benchmark 03:17:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.6943875051243, 3451.499532567779, 3465.4108801503735, 3490.127468132967, 3511.1046683095806, 5666.660000399974, 5691.971540142294, 5694.0, 5721.427857214287, 5730.914036289459, 5675.5459563234945, 5741.598088133832, 5743.655380677163, 5744.770209191627, 5764.365919748835, 5118.129917913963, 5133.2300154976865, 5190.92213616796, 5255.947440525584, 5275.0, 4369.410129632507, 5272.446393128724, 5296.735163241822, 5303.337082864642, 5312.07038768216, 5192.299039629658, 5238.947610523884, 5270.710110943896, 5284.4187139414635, 5284.762185701653, 5254.973725131388, 5285.841424757264, 5305.8938821223355, 5307.946920530784, 5333.4399888011585, 5113.360829896263, 5164.302819119408, 5214.921776173361, 5216.921746173811, 5234.084035293847, 4700.764961751899, 5601.243832082658, 5612.522935550483, 5645.125005624135, 5730.140478928194, 6290.004739454676, 6296.433321001086, 6300.747970081191, 6306.684665766739, 6324.588901721373, 6181.536384771161, 6272.964960781511, 6302.4958003359625, 6309.14826498426, 6317.178766760338, 5864.472197502245, 5911.556633252523, 5935.881282374328, 5962.642241465526, 6036.939630603725, 6321.683915804191, 6326.715297811609, 6334.904976425358, 6341.0, 6359.427651511386, 6049.637021778707, 6140.109684095781, 6265.592736472115, 6271.560990730696, 6279.3720627937755, 6232.18981532407, 6277.686115694197, 6294.559380843297, 6304.338044505353, 6317.684115794284, 6164.938350616437, 6217.813465596039, 6291.937080629135, 6300.716467758962, 6302.653354065479, 6265.530085243625, 6300.716467758962, 6300.810975670737, 6316.463100636497, 6329.968350158219, 4862.343583616166, 4977.626677999165, 4986.650934434628, 5002.799888004439, 5030.094582975026, 4402.889927751768, 4416.227160246944, 4450.777461126995, 4474.753888536096, 4499.910001799945, 5677.545796336283, 5705.62913410627, 5713.285839270091, 5717.542596592262, 5755.079187330007, 6109.283207920342, 6225.1907252057845, 6311.053341998737, 6317.778877739349, 6320.494360451153, 5745.4541818528005, 5761.971190144106, 5762.654240745569, 5765.250517432791, 5769.48074673273, 5431.6740995540395, 5432.049391356497, 5433.565314774809, 5443.0, 5458.863528411742, 6215.285242197025, 6309.463695585927, 6391.073294372383, 6405.167328247391, 6425.1968503937005, 6287.1512345832725, 6289.622622642656, 6294.433500984934, 6316.526260530387, 6319.399657032574, 6324.810255692337, 6340.239171299289, 6359.427651511386, 6360.363963603602, 6367.968160159169, 6113.480354169946, 6146.5390095742105, 6176.505879529537, 6241.843953901189, 6260.40526150015, 6217.253929528486, 6272.6236425814595, 6299.622022678654, 6301.243850737941, 6309.842253943688, 6176.382361763787, 6204.627722336674, 6212.658303793335, 6223.315435302023, 6235.251769787655, 6041.6375017499095, 6097.664628445478, 6176.413240742122, 6180.567360284737, 6187.597806142767, 4750.836045168818, 6030.396960303934, 6078.438194261433, 6085.087236914498, 6127.938720612737, 6133.478654314434, 6143.938560614337, 6193.0, 6197.411245931629, 6216.160818289473, 5919.437653422918, 6036.69816509164, 6051.878962420815, 6118.43644382056, 6127.6476951528675], "value": 5738.795755654594, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.13786562843165, 513.8920826626411, 568.3095039526977, 592.1916583863028, 609.9390060993901, 30962.0, 31700.207494812643, 39079.804600977026, 40205.798971005104, 40339.79830100853, 43719.56280437195, 44236.55763442365, 44431.333529997, 44464.777676111655, 44495.0, 9596.856047159281, 9823.95088024559, 9829.55766990487, 9830.0, 9831.213502919749, 8658.350623703216, 8877.600507977157, 11685.415729213548, 11869.584564540242, 11871.28772273662, 11469.885301147007, 11562.59530916418, 11575.710607234802, 11600.70998225047, 11621.535138594489, 8683.565821708953, 11130.05394541465, 11181.385023823686, 11186.272892261977, 11662.125340599405, 11410.20128590999, 11547.133964952578, 11559.0, 11563.42182890852, 11587.594434194847, 33489.33021339584, 37988.24023519514, 38287.0, 38710.83867483952, 38994.80502597469, 3171.270607760205, 3187.585613870205, 3371.393149233137, 3771.6039815819217, 3806.200697853454, 22853.7714622855, 26979.865100674568, 27215.591766123525, 28080.438391232063, 28375.57436638452, 2772.4177922636272, 2791.078943948492, 2795.6086147939304, 2814.3879728032084, 2933.4426458972725, 345164.0, 346389.0, 346760.0, 347011.0, 348252.0, 61455.0, 61884.0, 61938.69030654908, 62030.0, 62076.06885896715, 64527.67736161289, 67789.32210677929, 69156.50482718607, 69189.0, 69336.65331673309, 17023.31906723742, 17226.96638201712, 17269.96380217191, 17270.568235794206, 17315.65368692619, 10761.623343182951, 12066.577669781691, 12277.342558754453, 13105.148165369412, 13273.402696878664, 19163.808361916483, 19241.711374329396, 19262.518437039187, 19334.709979350322, 19739.703904441445, 6973.755918542928, 7383.520071195355, 8125.309348705308, 8322.62548185333, 8621.439606425562, 887.5961437545901, 997.5261750668375, 1009.3338396658318, 1020.2450186861818, 1101.300674071954, 29460.852695736812, 30050.0, 30337.874931376777, 30418.087457376314, 30764.38471230563, 1113.4877956140103, 1115.6262652011505, 1142.8857114288505, 1210.6126039667397, 1219.5914368686413, 15034.17312047848, 15218.467353642569, 15276.618084547754, 15573.299201535958, 15670.608234793994, 544560.0, 547637.0, 554508.0, 555869.0, 556391.0, 59991.0, 60527.09209361775, 60611.0, 60729.69635151796, 60832.0, 1365.542543248023, 1483.3028476615973, 1517.9316930737923, 1522.596511924363, 1533.3176736352439, 29652.0, 29716.257093572833, 29772.0, 30436.26073653382, 30625.540616890317, 48755.0, 49068.50931490639, 49097.0, 49138.0, 49160.508394915596, 342880.0, 344098.0, 344340.11319773964, 344348.0, 344644.0, 25695.486090277715, 27116.457670846867, 27155.86422067877, 27156.91372345084, 27226.72773272242, 40969.79515102405, 45745.08509829718, 46855.8286042838, 47675.5232447685, 52559.47440525546, 38163.0, 38291.0, 38849.80575097106, 39339.80330098331, 40166.995825104605, 37921.62078379291, 38392.0, 38536.61463385443, 38620.227595448494, 38947.6105238944, 37921.81039094787, 38599.0, 38764.41853372258, 38995.220095598495, 39088.0, 34199.374782909945, 35285.0, 35904.461433078, 36191.457128142574, 36213.818930905174, 26271.949122034905, 26276.94892204367, 26340.868295658398, 26612.60081098824, 26823.731762682124], "value": 19903.916614854177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.92783299006175, 402.50692901196027, 403.3163787380388, 407.37467986640513, 416.3712793681542, 18965.715514267264, 19829.0, 20466.795332046695, 20477.0, 20762.16951321949, 20443.693344599844, 20728.0, 20866.89566552169, 22101.005454754533, 22107.77892221077, 5683.318001839764, 5921.171036054967, 5963.1055341698875, 5978.432048955342, 6130.908036379458, 5871.588988770788, 5884.117382392633, 5919.49684276837, 5926.466618004379, 5931.525477961753, 5732.5127364174095, 5930.169776231331, 6196.194494715681, 6217.813465596039, 6221.911165546034, 6153.938460615382, 6200.503959683215, 6207.534434917399, 6212.813615591517, 6219.378062193789, 5635.380108188096, 5930.466258036754, 5951.166836642873, 5953.791617293374, 5978.282606087254, 13561.660958476019, 13725.313734313244, 13822.723545529134, 21970.011349489312, 26629.0, 2109.2090466075224, 2187.9234226801987, 2188.588147051651, 2238.787315205053, 2348.6359614259645, 8084.353251739905, 10138.138258248058, 10492.580296788119, 13269.203847769164, 14513.621205985415, 1676.228934690044, 1712.032701523635, 1720.9827901720948, 1728.1186594836724, 1753.929842806299, 106718.0, 108206.45896770545, 109900.0, 110647.0, 111434.88565114327, 9327.533623318806, 11161.776764464665, 11604.535818567163, 11629.360385178727, 11920.821187682192, 15076.623084422978, 15300.770488442682, 15303.234838258042, 15324.0, 15338.15640139803, 8860.202581767542, 8890.511021893857, 8902.42134261271, 8960.955195224113, 9025.50359730208, 8852.557372131496, 8863.955680221687, 8883.244924181388, 8998.820023599623, 9008.729738107868, 4672.556107169813, 4698.577128058491, 4710.364100846342, 4715.905681886412, 4764.38063051808, 1930.6621341265222, 1988.423357226417, 1991.8506112041655, 2011.3764732932837, 2011.9920718525898, 860.5697151424288, 905.3933864310795, 917.958691858868, 934.1405906565975, 971.8056388722142, 16444.588885277724, 16550.83449165493, 20974.47563810917, 21080.945952702303, 21242.893785530974, 920.9539523023956, 961.8749562556962, 964.3924327673661, 988.8071825993862, 1028.6305485366377, 9327.600859871074, 10830.566777328819, 11047.447627618587, 11161.497732602053, 11202.943985280184, 587473.0, 605286.9735651382, 611936.0, 623807.0, 634352.0, 71492.2850771496, 96756.54865177028, 120393.19410208854, 122116.0, 128102.0, 926.3746970794687, 938.8967213606497, 939.5302348825587, 952.5570609666538, 995.9950200249098, 18070.03261396191, 18524.536886577942, 18763.90618046901, 18770.873747575188, 18890.244390224238, 52100.95798084093, 52288.73855630698, 52598.47401525936, 53298.23474759882, 53737.731311343196, 73569.0, 74656.25343746711, 75940.0, 76962.61518692371, 79516.60241698755, 7841.960790196013, 8281.964754405699, 8385.538795366314, 8429.11494293115, 8658.48049117055, 11949.820752688891, 12717.109802314118, 12783.360831958551, 12989.610311690665, 13015.869841301845, 10831.566737330733, 11228.66314010581, 11266.549338026387, 11292.983631473207, 11613.593524226611, 9079.364444489087, 9842.950785246028, 10646.20153488507, 10922.279171537179, 11514.88485115138, 11298.43507824589, 11437.942810285895, 11539.480723367637, 11550.249233799776, 11795.705107372385, 7465.962670186615, 7725.266099720518, 8405.831883362176, 8738.650453981769, 8769.956150219208, 9906.504674766089, 11025.0, 11126.998570128728, 11127.0, 11377.317360958368], "value": 9503.442204892324, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.5750303660418, 1580.2414840876381, 1611.4762702121814, 1856.675081860674, 1984.5733167369012, 7998.160193179712, 8685.782855428617, 8857.0, 8877.245434138098, 8880.156385143417, 8535.31717462603, 8779.385443018991, 8792.560371981408, 8803.779905502366, 8808.91191088089, 2156.54712510373, 2382.356763673807, 2451.963220551693, 2466.1738317663603, 2487.7139129000216, 2481.6029435290357, 2482.553140434721, 2489.103922587867, 2489.614109812973, 2495.7147069259345, 2387.1048356866177, 2436.61014237722, 2455.877206139695, 2457.1154384421593, 2471.666325046123, 2150.2796563151287, 2385.7733515316017, 2443.853368797869, 2449.632555116729, 2456.5823809952353, 2150.6989021536842, 2374.5725769361507, 2434.805215582767, 2471.5921872890954, 2475.10896077412, 8696.434731742416, 8769.386142969995, 8777.956110219471, 8783.12168783123, 8783.868241976375, 5298.390685071227, 5311.787528498855, 5481.945180548183, 5487.972560137214, 5504.889902201933, 3470.6876381125567, 3540.3627347077518, 3573.285342931424, 3587.1749497615433, 3587.210813620999, 1018.9082982531608, 1025.092792878308, 1037.9117774989136, 1048.9632862849764, 1049.9527521261562, 12232.81650775239, 12246.571370002008, 12257.509699612005, 12261.386930653433, 12271.0, 4793.832215872428, 4876.0, 4877.512248775165, 4932.161532539477, 4985.977874535712, 10225.795484090277, 10334.638287659896, 10338.844917326247, 10339.0, 10342.224333175041, 5532.640378375393, 5543.334799823966, 5587.05020146576, 5587.245721827582, 5593.244911936918, 4454.910901781946, 4617.284320930261, 4943.480934501826, 5021.623378246646, 5034.496550344937, 4108.979455102705, 4129.834806607702, 4136.669066474675, 4168.7290326128705, 4185.560516145822, 1127.2503784983057, 1353.079905664138, 1353.8375394952668, 1364.3723887011888, 1367.9589612311647, 767.1139833492214, 927.596495524449, 942.8067246214509, 949.3591825517748, 972.7957129002847, 5938.656188055788, 5986.910196347058, 6052.364501727343, 6141.109539116803, 6289.433950944437, 455.07392456351164, 473.1648640150138, 476.5020553521578, 478.0749250200872, 479.70498143642106, 3524.718022558189, 3852.4991751072744, 3897.4348719435525, 3916.921661566753, 3955.4462375267485, 10812.62155824558, 10857.0, 11014.39420831846, 11178.832317515244, 11213.439328033728, 10898.0, 10997.065249453886, 11024.724381890517, 11043.613473528389, 11059.778804423866, 8047.235512626291, 8479.364047696448, 8543.786405339917, 8572.785680358042, 8588.055313915464, 5909.704514774158, 5914.319853216935, 5967.582269241111, 6031.60794548353, 6039.335673075871, 9294.907050929403, 9483.099105584959, 9681.128698417177, 9836.163926066365, 9906.356086854332, 9569.28230382738, 9874.617553542654, 10080.0, 10299.536520856429, 10303.742406439898, 6932.306769323229, 7150.10623672041, 7459.179490256176, 7753.302202801775, 7886.566238856918, 8456.323494120332, 8550.102239264788, 8551.230389264996, 8557.400981931454, 8579.0, 7364.852702945804, 7454.888176677246, 7535.86961955711, 7563.281488258606, 7603.315701586884, 6914.343137401939, 7283.271672832674, 7287.8542429152185, 7288.781336559911, 7452.9627351865065, 7065.6113913735235, 7124.750633727795, 7163.212046674966, 7176.4258859290085, 7212.675429605785, 4873.001034787789, 4922.556969872729, 4939.777710002986, 5020.924686129638, 5056.039352523009, 4978.751062446936, 5041.168207245763, 5042.319286896369, 5147.305113809737, 5303.337082864642], "value": 4580.058018608037, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.28656948726393, 318.6256149024896, 327.6101439287248, 328.6171610074264, 329.6555099920583, 6501.252355979058, 6608.2730899601065, 6612.305707900673, 6614.801555953329, 6619.073329733835, 6207.968960155193, 6409.839754006152, 6438.7746428875, 6542.509311801622, 6757.493188010894, 4836.202026665596, 4836.879078023042, 4846.127697014536, 4867.440244371889, 4874.829380971649, 4785.449673287564, 4849.757512124397, 4858.46556878743, 4871.050145221666, 4876.902461950776, 4577.748223847704, 4638.768061596924, 4750.429948406196, 4803.519648035203, 4816.3016362627395, 4751.596114330286, 4759.167145749515, 4781.402324709411, 4784.784684689197, 4799.400074990626, 4088.4889388826396, 4098.774567398792, 4101.774402407867, 4103.5691252418665, 4112.09533902541, 6291.339409362043, 6320.810375688737, 6339.651319177444, 6348.968255158741, 6353.936460635381, 5763.6541807491685, 5765.740541675635, 5769.88460230793, 5772.624779389368, 5777.422257774231, 6769.390754832039, 6989.33601307875, 6993.860122797516, 7028.297170282982, 7031.1211098612675, 4673.906521869543, 4696.178168820477, 4707.293905914106, 4721.315409265671, 4729.337892695025, 7403.111626604842, 7403.555786652764, 7403.888941665826, 7420.888686669704, 7433.026789284276, 4765.213739732974, 4766.785494652749, 4767.809287628525, 4786.44955830077, 4786.473487916326, 4040.595940405936, 4810.182269014276, 4839.032193561231, 4841.515848415131, 4855.174620314555, 4387.42963414754, 4651.6975246930915, 5234.685918844882, 5750.453706897922, 5752.856178595569, 6977.82555436108, 6980.476464265201, 7017.4035207006955, 7023.613701246379, 7035.753748618774, 1604.0536083710433, 1629.6414788746456, 1634.3871048356866, 1634.9264283107289, 1635.5911022244438, 952.1383148250844, 971.7522031881825, 993.5181437003048, 995.3928103856641, 995.5370752599931, 6482.384173503509, 6781.62701051437, 6783.796486105326, 6790.660466976631, 6791.3548212919695, 7334.009908662262, 7335.449841261927, 7336.339729424378, 7339.339459448568, 7360.447966402541, 5893.174955506232, 6113.99119145336, 6577.769778057745, 6590.110335104795, 6643.900341494881, 3731.1231860512758, 3844.557875844258, 3869.806509674505, 3871.8064096795047, 3885.6891448684037, 7454.962725186339, 7512.47412681118, 7527.887081693779, 7549.660265288076, 7556.395488360918, 6002.789902353484, 6086.269647642312, 7379.372753315921, 7412.295831895961, 7458.515196512318, 5939.940600593939, 6208.317085120633, 7494.85010299791, 7537.849243015109, 7611.086669599684, 5897.557683173865, 6992.0, 7303.123625165014, 7319.487635865651, 7333.816654583678, 7127.572345659277, 7144.9642751785905, 7158.319959603829, 7225.060742103493, 7243.674034668558, 7619.352355049883, 7632.503887247533, 7640.624687556293, 7642.961785191039, 7646.885296720442, 4587.885302867321, 4635.466921304093, 4637.188492013952, 4641.18779213643, 4645.767711614473, 7064.82337941535, 7292.525985810906, 7321.707131714885, 7333.303336183054, 7357.963210183915, 7046.084009078787, 7059.82350441243, 7069.893951590833, 7071.71713131469, 7077.398421134261, 6958.547694399847, 7026.613536255555, 7055.470839686941, 7061.505694601328, 7073.575585464889, 6891.827704307432, 6894.413974812197, 6898.1377327834025, 6903.102596662402, 6912.308769123048, 6800.251972282946, 7028.683709232994, 7041.612711300722, 7043.612601306977, 7054.576725396288, 6634.104395906491, 6720.8319792005595, 6791.422729068085, 6863.279355667583, 6926.307369263033], "value": 5107.434327469681, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.4420587779814, 676.2426082787277, 704.6864145455274, 735.8086897406674, 771.7607541662085, 14431.92784036081, 14931.0, 15138.167400792952, 15183.089014659114, 15224.847751522482, 15526.611834704137, 15612.609684757886, 15626.687466250669, 15643.374265029384, 15647.765283520755, 741.770051284101, 915.9450432974027, 943.6319835264252, 943.641416261823, 945.2013048973606, 741.0107506478857, 891.3359547137392, 930.144267274106, 931.2177770672668, 931.8229536388064, 971.5142428785607, 974.1476208317722, 976.4043933200742, 978.0757184460688, 980.8430651095827, 774.5701135869647, 962.206179901577, 972.8832540095163, 972.902709729025, 973.1047436358567, 716.5127713155052, 716.6488420673884, 717.2934659360526, 717.3579646216607, 717.7236763845957, 14848.925755371261, 14966.70066598662, 14991.625209369744, 14999.775003374958, 15003.099814011086, 12562.246265224116, 12603.243805371707, 12707.745845083047, 12755.170913890568, 12939.935300323532, 10024.849627255597, 10087.7478063049, 10158.949205253926, 10170.542325595292, 10760.73098172544, 3453.671901169385, 3468.358353704573, 3493.283876805249, 3543.5039094526787, 3574.696150827183, 61918.69040654813, 64422.0, 74477.62761186213, 76136.0, 84777.30445391119, 9529.475878826732, 9904.851427228597, 9919.652812151684, 9972.301938864355, 10230.64192753265, 22834.771652283263, 22842.200522981617, 22872.31383058511, 22880.885595571915, 22894.084236630682, 7309.926900731031, 7360.521566098294, 7432.145303290083, 7767.572783496849, 7917.247861453153, 4046.393041043808, 4049.979750101231, 4108.671306295549, 4169.291220492523, 4319.4816622005565, 3552.4493703476, 3611.133328001261, 3616.764910280823, 3618.222082252328, 3625.8912232633065, 638.4286063972719, 656.150285380424, 656.3043174235326, 666.2271764752863, 681.5467713970154, 504.9166887463601, 551.6028459509147, 580.7270582826065, 582.0803131052926, 600.9399060093956, 6620.801375958729, 8131.024277086787, 8178.3048440882, 8274.172582741798, 8344.207300306462, 517.6324809385378, 564.0270533329973, 573.0744847072032, 578.7829563913532, 598.5899658733781, 3742.830066337484, 3811.352070148026, 3845.4616353710503, 3930.508686414198, 3957.3668213085766, 78650.0, 79786.60106699546, 80474.0, 80559.791603126, 81124.0, 72618.63690681619, 73199.0, 73858.26141738621, 74360.0, 74460.0, 67308.6634566824, 67668.32331676719, 68210.0, 68321.0, 68417.3158268421, 7595.468317217742, 7691.961540192263, 7816.765497035098, 7829.021372328459, 7834.647440865061, 47229.76385118053, 47569.0, 48372.0, 48838.75580622074, 49609.0, 61950.69024654848, 62744.686276568325, 65306.0, 66641.66679166573, 66806.0, 10896.61861834832, 11086.057685096535, 11158.10735141203, 11241.325520468798, 11355.261907975955, 11502.654920352403, 12061.095417843522, 12095.39523023863, 12242.0, 12343.753124937271, 11290.209685321948, 11335.979761821476, 11380.03269722083, 11731.120165987415, 11857.0, 11248.88751112511, 11340.149488788209, 11474.368909710047, 11540.480678369326, 11670.591529296435, 10994.780104398027, 11107.72230694239, 11282.0, 11338.886611133783, 11837.585684501346, 9334.859977100214, 9418.970616841756, 9717.805643887225, 9777.9022209777, 9874.950625246827, 8536.317094632539, 9613.279004074582, 9770.56032478526, 9793.559289831832, 9817.705468835946], "value": 6855.010608228159, "unit": "score"}], "name": "dromaeo_css", "value": 7472.3975821193835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 118.0, 112.0, 111.0, 112.0, 113.0, 113.0, 114.0, 133.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 145.0, 197.0, 146.0, 137.0, 123.0, 150.0, 158.0, 118.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 183.0, 185.0, 184.0, 184.0, 185.0, 182.0, 182.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 72.0, 149.0, 56.0, 55.0, 56.0, 67.0, 142.0, 54.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 145.0, 77.0, 80.0, 87.0, 122.0, 79.0, 78.0, 138.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 85.0, 87.0, 83.0, 88.0, 85.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 122.0, 148.0, 128.0, 144.0, 101.0, 101.0, 85.0, 90.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 142.0, 87.0, 93.0, 86.0, 68.0, 143.0, 135.0, 84.0, 84.0], "value": 104.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 60.0, 62.0, 63.0, 63.0, 63.0, 85.0, 59.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 80.0, 74.0, 71.0, 68.0, 67.0, 67.0, 67.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 81.0, 79.0, 80.0, 81.0, 79.0, 77.0, 81.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 127.0, 131.0, 149.0, 145.0, 142.0, 130.0, 147.0, 139.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 169.0, 169.0, 177.0, 167.0, 167.0, 167.0, 163.0, 165.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 68.0, 68.0, 68.0, 67.0, 67.0, 69.0, 67.0, 68.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1508.8999999999999, "alertThreshold": 2.0}]} 03:17:45 INFO - Return code: 0 03:17:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:17:45 ERROR - # TBPL SUCCESS # 03:17:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:17:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:17:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:17:45 INFO - 2016-05-22 03:17:45.111 screenresolution[1690:11410] starting screenresolution argv=screenresolution get 03:17:45 INFO - 2016-05-22 03:17:45.113 screenresolution[1690:11410] Display 0: 1600x1200x32@60 03:17:45 INFO - 2016-05-22 03:17:45.119 screenresolution[1691:11413] starting screenresolution argv=screenresolution list 03:17:45 INFO - Available Modes on Display 0 03:17:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:17:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:17:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:17:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:17:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:17:45 INFO - Intel Iris: 03:17:45 INFO - Chipset Model: Intel Iris 03:17:45 INFO - Type: GPU 03:17:45 INFO - Bus: Built-In 03:17:45 INFO - VRAM (Dynamic, Max): 1536 MB 03:17:45 INFO - Vendor: Intel (0x8086) 03:17:45 INFO - Device ID: 0x0a2e 03:17:45 INFO - Revision ID: 0x0009 03:17:45 INFO - Displays: 03:17:45 INFO - Display: 03:17:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:17:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:17:45 INFO - Display Serial Number: 1600x1200 60 03:17:45 INFO - Main Display: Yes 03:17:45 INFO - Mirror: Off 03:17:45 INFO - Online: Yes 03:17:45 INFO - Rotation: Supported 03:17:45 INFO - Return code: 0 03:17:45 INFO - Running post-action listener: _resource_record_post_action 03:17:45 INFO - Running post-run listener: _resource_record_post_run 03:17:46 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 32730624; Write bytes: 653568000; Read time: 359; Write time: 1994 03:17:46 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340554240; Write bytes: 335463936; Read time: 26370; Write time: 650 03:17:46 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 31809024; Write bytes: 297718272; Read time: 346; Write time: 1289 03:17:46 INFO - Running post-run listener: _upload_blobber_files 03:17:46 INFO - Blob upload gear active. 03:17:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:17:46 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 03:17:46 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:17:46 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:17:46 INFO - (blobuploader) - INFO - Open directory for files ... 03:17:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:17:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:17:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:47 INFO - (blobuploader) - INFO - Done attempting. 03:17:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:17:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:17:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:49 INFO - (blobuploader) - INFO - Done attempting. 03:17:49 INFO - (blobuploader) - INFO - Iteration through files over. 03:17:49 INFO - Return code: 0 03:17:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:17:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:17:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae35971332c610190f1a0ec49dba6a3153d359808c309733182a74887329ac77a0d8cfb91acfc0502bd87c91cdd1962e0005c6af3a8c5ea8abb60ad316ae8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b77f4d969a9843231122a1e96e8fedff00d039ffd5dbdaf744914a8d9b372236608a6852b099dda532c996d6c591813471cb621a86f84e1e8437a13dae44b666"} 03:17:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:17:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:17:49 INFO - Contents: 03:17:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae35971332c610190f1a0ec49dba6a3153d359808c309733182a74887329ac77a0d8cfb91acfc0502bd87c91cdd1962e0005c6af3a8c5ea8abb60ad316ae8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b77f4d969a9843231122a1e96e8fedff00d039ffd5dbdaf744914a8d9b372236608a6852b099dda532c996d6c591813471cb621a86f84e1e8437a13dae44b666"} 03:17:49 INFO - Running post-run listener: copy_logs_to_upload_dir 03:17:49 INFO - Copying logs to upload dir... 03:17:49 INFO - mkdir: /builds/slave/test/build/upload/logs 03:17:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1351.733804 ========= master_lag: 2.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 33 secs) (at 2016-05-22 03:17:51.430966) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 03:17:51.434406) ========= 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.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae35971332c610190f1a0ec49dba6a3153d359808c309733182a74887329ac77a0d8cfb91acfc0502bd87c91cdd1962e0005c6af3a8c5ea8abb60ad316ae8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b77f4d969a9843231122a1e96e8fedff00d039ffd5dbdaf744914a8d9b372236608a6852b099dda532c996d6c591813471cb621a86f84e1e8437a13dae44b666"} build_url:https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae35971332c610190f1a0ec49dba6a3153d359808c309733182a74887329ac77a0d8cfb91acfc0502bd87c91cdd1962e0005c6af3a8c5ea8abb60ad316ae8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b77f4d969a9843231122a1e96e8fedff00d039ffd5dbdaf744914a8d9b372236608a6852b099dda532c996d6c591813471cb621a86f84e1e8437a13dae44b666"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 03:17:51.463728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 03:17:51.464056) ========= 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.gjoKNwOZOs/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.ILmoWQgCst/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005023 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 03:17:51.512475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 03:17:51.512757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 03:17:51.513204) ========= ========= Total master_lag: 2.36 =========