builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0124 starttime: 1460802565.89 results: success (0) buildid: 20160416015820 builduid: f73f446cae964803aef91dfa5e1d3343 revision: a5322c766f510d2a6544b519ffcc72a19879c779 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:25.892207) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:25.892739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:25.893056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:25.953191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:25.953561) ========= 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.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-04-16 03:29:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.75M=0.001s 2016-04-16 03:29:26 (9.75 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.594052 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:26.575520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:26.575854) ========= 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.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.025672 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:26.628611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:26.629023) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a5322c766f510d2a6544b519ffcc72a19879c779 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a5322c766f510d2a6544b519ffcc72a19879c779 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-04-16 03:29:26,700 truncating revision to first 12 chars 2016-04-16 03:29:26,700 Setting DEBUG logging. 2016-04-16 03:29:26,700 attempt 1/10 2016-04-16 03:29:26,700 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a5322c766f51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-16 03:29:27,432 unpacking tar archive at: mozilla-central-a5322c766f51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:27.624627) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:27.624912) ========= script_repo_revision: a5322c766f510d2a6544b519ffcc72a19879c779 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:27.625281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:27.625537) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 03:29:27.643587) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-04-16 03:29:27.643897) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:29:27 INFO - MultiFileLogger online at 20160416 03:29:27 in /builds/slave/test 03:29:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:29:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:27 INFO - {'append_to_log': False, 03:29:27 INFO - 'base_work_dir': '/builds/slave/test', 03:29:27 INFO - 'blob_upload_branch': 'Firefox', 03:29:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:27 INFO - 'branch': 'Firefox', 03:29:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:27 INFO - 'config_files': ('talos/mac_config.py',), 03:29:27 INFO - 'default_actions': ('clobber', 03:29:27 INFO - 'read-buildbot-config', 03:29:27 INFO - 'download-and-extract', 03:29:27 INFO - 'populate-webroot', 03:29:27 INFO - 'create-virtualenv', 03:29:27 INFO - 'install', 03:29:27 INFO - 'run-tests'), 03:29:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:27 INFO - 'download_minidump_stackwalk': True, 03:29:27 INFO - 'download_symbols': 'ondemand', 03:29:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:29:27 INFO - 'tooltool.py': '/tools/tooltool.py', 03:29:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:29:27 INFO - '/tools/misc-python/virtualenv.py')}, 03:29:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:27 INFO - 'installer_path': 'installer.exe', 03:29:27 INFO - 'log_level': 'info', 03:29:27 INFO - 'log_name': 'talos', 03:29:27 INFO - 'log_to_console': True, 03:29:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:29:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:29:27 INFO - 'opt_config_files': (), 03:29:27 INFO - 'pip_index': False, 03:29:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:27 INFO - 'cmd': ('bash', 03:29:27 INFO - '-c', 03:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:27 INFO - 'enabled': True, 03:29:27 INFO - 'halt_on_failure': False, 03:29:27 INFO - 'name': 'check_screen_resolution'},), 03:29:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:27 INFO - 'cmd': ('bash', 03:29:27 INFO - '-c', 03:29:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:27 INFO - 'enabled': True, 03:29:27 INFO - 'halt_on_failure': False, 03:29:27 INFO - 'name': 'check_screen_resolution'},), 03:29:27 INFO - 'run_cmd_checks_enabled': True, 03:29:27 INFO - 'sps_profile': False, 03:29:27 INFO - 'sps_profile_interval': 0, 03:29:27 INFO - 'suite': 'dromaeojs-e10s', 03:29:27 INFO - 'system_bits': '32', 03:29:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:27 INFO - 'title': 't-yosemite-r7-0124', 03:29:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:27 INFO - 'use_talos_json': True, 03:29:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:27 INFO - 'work_dir': 'build'} 03:29:27 INFO - ##### 03:29:27 INFO - ##### Running clobber step. 03:29:27 INFO - ##### 03:29:27 INFO - Running pre-action listener: _resource_record_pre_action 03:29:27 INFO - Running main action method: clobber 03:29:27 INFO - rmtree: /builds/slave/test/build 03:29:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:29 INFO - Running post-action listener: _resource_record_post_action 03:29:29 INFO - ##### 03:29:29 INFO - ##### Running read-buildbot-config step. 03:29:29 INFO - ##### 03:29:29 INFO - Running pre-action listener: _resource_record_pre_action 03:29:29 INFO - Running main action method: read_buildbot_config 03:29:29 INFO - Using buildbot properties: 03:29:29 INFO - { 03:29:29 INFO - "project": "", 03:29:29 INFO - "product": "firefox", 03:29:29 INFO - "script_repo_revision": "production", 03:29:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:29:29 INFO - "repository": "", 03:29:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:29:29 INFO - "buildid": "20160416015820", 03:29:29 INFO - "pgo_build": "False", 03:29:29 INFO - "basedir": "/builds/slave/test", 03:29:29 INFO - "buildnumber": 192, 03:29:29 INFO - "slavename": "t-yosemite-r7-0124", 03:29:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:29:29 INFO - "platform": "yosemite_r7", 03:29:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:29:29 INFO - "branch": "mozilla-central", 03:29:29 INFO - "repo_path": "mozilla-central", 03:29:29 INFO - "revision": "a5322c766f510d2a6544b519ffcc72a19879c779", 03:29:29 INFO - "stage_platform": "macosx64", 03:29:29 INFO - "builduid": "f73f446cae964803aef91dfa5e1d3343", 03:29:29 INFO - "slavebuilddir": "test" 03:29:29 INFO - } 03:29:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 03:29:29 INFO - Running post-action listener: _resource_record_post_action 03:29:29 INFO - ##### 03:29:29 INFO - ##### Running download-and-extract step. 03:29:29 INFO - ##### 03:29:29 INFO - Running pre-action listener: _resource_record_pre_action 03:29:29 INFO - Running main action method: download_and_extract 03:29:29 INFO - mkdir: /builds/slave/test/build/tests 03:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:29 INFO - https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:29:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 03:29:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 03:29:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 03:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 03:29:59 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 03:29:59 INFO - retry: attempt #1 caught exception: timed out 03:29:59 INFO - retry: Failed, sleeping 30 seconds before retrying 03:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #2 03:30:56 INFO - Downloaded 1199 bytes. 03:30:56 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 03:30:56 INFO - Using the following test package requirements: 03:30:56 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 03:30:56 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 03:30:56 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 03:30:56 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'jsshell-mac.zip'], 03:30:56 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 03:30:56 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 03:30:56 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 03:30:56 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 03:30:56 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 03:30:56 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:30:56 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 03:30:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:56 INFO - https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:30:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:30:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:30:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:31:01 INFO - Downloaded 18031921 bytes. 03:31:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:31:04 INFO - Return code: 0 03:31:04 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:31: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')]} 03:31:04 INFO - https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:31:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:31:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:31:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:31:06 INFO - Downloaded 18031921 bytes. 03:31:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:31:10 INFO - Return code: 0 03:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:10 INFO - https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:31:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:31:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:31:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:31:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:31:11 INFO - Downloaded 10841076 bytes. 03:31:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:31:11 INFO - Return code: 0 03:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:11 INFO - https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:31:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:31:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:31:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 03:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 03:31:15 INFO - Downloaded 109858634 bytes. 03:31:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:31:15 INFO - mkdir: /builds/slave/test/properties 03:31:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:15 INFO - Contents: 03:31:15 INFO - build_url:https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:31:16 INFO - Running post-action listener: _resource_record_post_action 03:31:16 INFO - Running post-action listener: set_extra_try_arguments 03:31:16 INFO - ##### 03:31:16 INFO - ##### Running populate-webroot step. 03:31:16 INFO - ##### 03:31:16 INFO - Running pre-action listener: _resource_record_pre_action 03:31:16 INFO - Running main action method: populate_webroot 03:31:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:16 INFO - '--tppagecycles', 03:31:16 INFO - '7'], 03:31:16 INFO - 'tests': ['tsvgm']}}, 03:31:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['damp', 'tps']}, 03:31:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'tests': ['damp', 'tps']}, 03:31:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['dromaeo_dom']}, 03:31:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['a11yr', 03:31:16 INFO - 'ts_paint', 03:31:16 INFO - 'tpaint', 03:31:16 INFO - 'sessionrestore', 03:31:16 INFO - 'sessionrestore_no_auto_restore', 03:31:16 INFO - 'tabpaint']}, 03:31:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['tsvgx', 03:31:16 INFO - 'tsvgr_opacity', 03:31:16 INFO - 'tart', 03:31:16 INFO - 'tscrollx', 03:31:16 INFO - 'cart']}, 03:31:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:16 INFO - 'tsvgr_opacity', 03:31:16 INFO - 'tart', 03:31:16 INFO - 'tscrollx', 03:31:16 INFO - 'cart']}, 03:31:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'talos_options': ['--disable-e10s'], 03:31:16 INFO - 'tests': ['tp5o']}, 03:31:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'tests': ['tp5o']}, 03:31:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'talos_options': ['--disable-e10s', 03:31:16 INFO - '--xperf_path', 03:31:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:16 INFO - 'tests': ['tp5n']}, 03:31:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:16 INFO - 'talos_options': ['--xperf_path', 03:31:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:16 INFO - 'tests': ['tp5n']}}, 03:31:16 INFO - 'talos.zip': {'path': '', 03:31:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:16 INFO - Running post-action listener: _resource_record_post_action 03:31:16 INFO - ##### 03:31:16 INFO - ##### Running create-virtualenv step. 03:31:16 INFO - ##### 03:31:16 INFO - Running pre-action listener: _resource_record_pre_action 03:31:16 INFO - Running main action method: create_virtualenv 03:31:16 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:16 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 03:31:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:16 INFO - Using real prefix '/tools/python27' 03:31:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:17 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:20 INFO - Installing pip.................done. 03:31:20 INFO - Return code: 0 03:31:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:20 INFO - 'HOME': '/Users/cltbld', 03:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:20 INFO - 'LOGNAME': 'cltbld', 03:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:20 INFO - 'MOZ_NO_REMOTE': '1', 03:31:20 INFO - 'NO_EM_RESTART': '1', 03:31:20 INFO - 'PAGER': '/bin/cat', 03:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:20 INFO - 'PWD': '/builds/slave/test', 03:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:20 INFO - 'SHELL': '/bin/bash', 03:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:20 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:20 INFO - 'USER': 'cltbld', 03:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:20 INFO - 'XPC_FLAGS': '0x0', 03:31:20 INFO - 'XPC_SERVICE_NAME': '0', 03:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:20 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 03:31:20 INFO - Cleaning up... 03:31:20 INFO - Return code: 0 03:31:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:20 INFO - 'HOME': '/Users/cltbld', 03:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:20 INFO - 'LOGNAME': 'cltbld', 03:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:20 INFO - 'MOZ_NO_REMOTE': '1', 03:31:20 INFO - 'NO_EM_RESTART': '1', 03:31:20 INFO - 'PAGER': '/bin/cat', 03:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:20 INFO - 'PWD': '/builds/slave/test', 03:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:20 INFO - 'SHELL': '/bin/bash', 03:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:20 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:20 INFO - 'USER': 'cltbld', 03:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:20 INFO - 'XPC_FLAGS': '0x0', 03:31:20 INFO - 'XPC_SERVICE_NAME': '0', 03:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:21 INFO - Downloading/unpacking psutil>=0.7.1 03:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:24 INFO - Installing collected packages: psutil 03:31:24 INFO - Running setup.py install for psutil 03:31:24 INFO - building 'psutil._psutil_osx' extension 03:31:24 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 03:31:24 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 03:31:24 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 03:31:25 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 03:31:25 INFO - building 'psutil._psutil_posix' extension 03:31:25 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 03:31:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:31:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:31:25 INFO - ^ 03:31:25 INFO - 1 warning generated. 03:31:25 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 03:31:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:25 INFO - Successfully installed psutil 03:31:25 INFO - Cleaning up... 03:31:25 INFO - Return code: 0 03:31:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:25 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:25 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 03:31:25 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 03:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:25 INFO - 'HOME': '/Users/cltbld', 03:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:25 INFO - 'LOGNAME': 'cltbld', 03:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:25 INFO - 'MOZ_NO_REMOTE': '1', 03:31:25 INFO - 'NO_EM_RESTART': '1', 03:31:25 INFO - 'PAGER': '/bin/cat', 03:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:25 INFO - 'PWD': '/builds/slave/test', 03:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:25 INFO - 'SHELL': '/bin/bash', 03:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:25 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:25 INFO - 'USER': 'cltbld', 03:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:25 INFO - 'XPC_FLAGS': '0x0', 03:31:25 INFO - 'XPC_SERVICE_NAME': '0', 03:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:31:25 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 03:31:25 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 03:31:25 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 03:31:25 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 03:31:25 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 03:31:25 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 03:31:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:31:28 INFO - Installing collected packages: mozsystemmonitor 03:31:28 INFO - Running setup.py install for mozsystemmonitor 03:31:28 INFO - Successfully installed mozsystemmonitor 03:31:28 INFO - Cleaning up... 03:31:28 INFO - Return code: 0 03:31:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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', '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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:31: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 blobuploader==1.2.4 03:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:28 INFO - 'HOME': '/Users/cltbld', 03:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:28 INFO - 'LOGNAME': 'cltbld', 03:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 03:31:28 INFO - 'NO_EM_RESTART': '1', 03:31:28 INFO - 'PAGER': '/bin/cat', 03:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:28 INFO - 'PWD': '/builds/slave/test', 03:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:28 INFO - 'SHELL': '/bin/bash', 03:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:28 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:28 INFO - 'USER': 'cltbld', 03:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - 'XPC_FLAGS': '0x0', 03:31:28 INFO - 'XPC_SERVICE_NAME': '0', 03:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:28 INFO - Downloading/unpacking blobuploader==1.2.4 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:33 INFO - Downloading docopt-0.6.1.tar.gz 03:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:33 INFO - Running setup.py install for blobuploader 03:31:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:33 INFO - Running setup.py install for requests 03:31:34 INFO - Running setup.py install for docopt 03:31:34 INFO - Successfully installed blobuploader requests docopt 03:31:34 INFO - Cleaning up... 03:31:34 INFO - Return code: 0 03:31:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:34 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:34 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 03:31:34 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 03:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:34 INFO - 'HOME': '/Users/cltbld', 03:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:34 INFO - 'LOGNAME': 'cltbld', 03:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:34 INFO - 'MOZ_NO_REMOTE': '1', 03:31:34 INFO - 'NO_EM_RESTART': '1', 03:31:34 INFO - 'PAGER': '/bin/cat', 03:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:34 INFO - 'PWD': '/builds/slave/test', 03:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:34 INFO - 'SHELL': '/bin/bash', 03:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:34 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:34 INFO - 'USER': 'cltbld', 03:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:34 INFO - 'XPC_FLAGS': '0x0', 03:31:34 INFO - 'XPC_SERVICE_NAME': '0', 03:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ZtkjG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ds6ZJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ErQ7NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-pIUd7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-6DBJub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-bhwNEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-uKBns8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-UFSmhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-VR94XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-fo2kvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-hCNXlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-X_sOBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-U2P4hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Zgqb84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-QurlYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-yPVQ_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-AIcJst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:36 INFO - Running setup.py install for manifestparser 03:31:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:36 INFO - Running setup.py install for mozcrash 03:31:36 INFO - Running setup.py install for mozdebug 03:31:36 INFO - Running setup.py install for mozdevice 03:31:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:36 INFO - Running setup.py install for mozfile 03:31:36 INFO - Running setup.py install for mozhttpd 03:31:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Running setup.py install for mozinfo 03:31:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Running setup.py install for mozInstall 03:31:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Running setup.py install for mozleak 03:31:37 INFO - Running setup.py install for mozlog 03:31:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Running setup.py install for moznetwork 03:31:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:37 INFO - Running setup.py install for mozprocess 03:31:38 INFO - Running setup.py install for mozprofile 03:31:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Running setup.py install for mozrunner 03:31:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Running setup.py install for mozscreenshot 03:31:38 INFO - Running setup.py install for moztest 03:31:38 INFO - Running setup.py install for mozversion 03:31:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:38 INFO - Cleaning up... 03:31:38 INFO - Return code: 0 03:31:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:38 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:38 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 03:31:38 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 03:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:38 INFO - 'HOME': '/Users/cltbld', 03:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:38 INFO - 'LOGNAME': 'cltbld', 03:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:38 INFO - 'MOZ_NO_REMOTE': '1', 03:31:38 INFO - 'NO_EM_RESTART': '1', 03:31:38 INFO - 'PAGER': '/bin/cat', 03:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:38 INFO - 'PWD': '/builds/slave/test', 03:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:38 INFO - 'SHELL': '/bin/bash', 03:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:38 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:38 INFO - 'USER': 'cltbld', 03:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:38 INFO - 'XPC_FLAGS': '0x0', 03:31:38 INFO - 'XPC_SERVICE_NAME': '0', 03:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-6XFwZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-opxNu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-BB_MZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-MKRaIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-UWBy3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-mQtJpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-G3aXzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-koaR4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-d0KGiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:39 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)) 03:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ZfNZeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-zZ9MSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-RvI3uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-SzvI5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-uSiXqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-r_K_ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-RUXx_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:40 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)) 03:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-11NnMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:40 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)) 03:31:40 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)) 03:31:40 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)) 03:31:40 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)) 03:31:40 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)) 03:31:40 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)) 03:31:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:43 INFO - Downloading blessings-1.6.tar.gz 03:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:43 INFO - Installing collected packages: blessings 03:31:43 INFO - Running setup.py install for blessings 03:31:43 INFO - Successfully installed blessings 03:31:43 INFO - Cleaning up... 03:31:43 INFO - Return code: 0 03:31:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:43 INFO - Reading from file tmpfile_stdout 03:31:43 INFO - Current package versions: 03:31:43 INFO - blessings == 1.6 03:31:43 INFO - blobuploader == 1.2.4 03:31:43 INFO - docopt == 0.6.1 03:31:43 INFO - manifestparser == 1.1 03:31:43 INFO - mozInstall == 1.12 03:31:43 INFO - mozcrash == 0.17 03:31:43 INFO - mozdebug == 0.1 03:31:43 INFO - mozdevice == 0.48 03:31:43 INFO - mozfile == 1.2 03:31:43 INFO - mozhttpd == 0.7 03:31:43 INFO - mozinfo == 0.9 03:31:43 INFO - mozleak == 0.1 03:31:43 INFO - mozlog == 3.1 03:31:43 INFO - moznetwork == 0.27 03:31:43 INFO - mozprocess == 0.22 03:31:43 INFO - mozprofile == 0.28 03:31:43 INFO - mozrunner == 6.11 03:31:43 INFO - mozscreenshot == 0.1 03:31:43 INFO - mozsystemmonitor == 0.0 03:31:43 INFO - moztest == 0.7 03:31:43 INFO - mozversion == 1.4 03:31:43 INFO - psutil == 3.1.1 03:31:43 INFO - requests == 1.2.3 03:31:43 INFO - wsgiref == 0.1.2 03:31:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:43 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')]} 03:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:43 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')]} 03:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:43 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:43 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 03:31:43 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 03:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:43 INFO - 'HOME': '/Users/cltbld', 03:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:43 INFO - 'LOGNAME': 'cltbld', 03:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:43 INFO - 'MOZ_NO_REMOTE': '1', 03:31:43 INFO - 'NO_EM_RESTART': '1', 03:31:43 INFO - 'PAGER': '/bin/cat', 03:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:43 INFO - 'PWD': '/builds/slave/test', 03:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:43 INFO - 'SHELL': '/bin/bash', 03:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:43 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:43 INFO - 'USER': 'cltbld', 03:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:43 INFO - 'XPC_FLAGS': '0x0', 03:31:43 INFO - 'XPC_SERVICE_NAME': '0', 03:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 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)) 03:31:44 INFO - Cleaning up... 03:31:44 INFO - Return code: 0 03:31:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:44 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:44 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 03:31:44 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 03:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:44 INFO - 'HOME': '/Users/cltbld', 03:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:44 INFO - 'LOGNAME': 'cltbld', 03:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:44 INFO - 'MOZ_NO_REMOTE': '1', 03:31:44 INFO - 'NO_EM_RESTART': '1', 03:31:44 INFO - 'PAGER': '/bin/cat', 03:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:44 INFO - 'PWD': '/builds/slave/test', 03:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:44 INFO - 'SHELL': '/bin/bash', 03:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:44 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:44 INFO - 'USER': 'cltbld', 03:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:44 INFO - 'XPC_FLAGS': '0x0', 03:31:44 INFO - 'XPC_SERVICE_NAME': '0', 03:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:44 INFO - Downloading/unpacking jsonschema 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:46 INFO - Downloading/unpacking functools32 (from jsonschema) 03:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:47 INFO - warning: no files found matching '*.txt' 03:31:47 INFO - no previously-included directories found matching 'build' 03:31:47 INFO - no previously-included directories found matching 'dist' 03:31:47 INFO - no previously-included directories found matching '.git*' 03:31:47 INFO - Installing collected packages: jsonschema, functools32 03:31:47 INFO - Running setup.py install for functools32 03:31:47 INFO - warning: no files found matching '*.txt' 03:31:47 INFO - no previously-included directories found matching 'build' 03:31:47 INFO - no previously-included directories found matching 'dist' 03:31:47 INFO - no previously-included directories found matching '.git*' 03:31:47 INFO - Successfully installed jsonschema functools32 03:31:47 INFO - Cleaning up... 03:31:47 INFO - Return code: 0 03:31:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:47 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 0x10973aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9571d7d340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, '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.WljiKTJyun/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.7qPqzouMnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 03:31:47 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 03:31:47 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 03:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:47 INFO - 'HOME': '/Users/cltbld', 03:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:47 INFO - 'LOGNAME': 'cltbld', 03:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:47 INFO - 'MOZ_NO_REMOTE': '1', 03:31:47 INFO - 'NO_EM_RESTART': '1', 03:31:47 INFO - 'PAGER': '/bin/cat', 03:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:47 INFO - 'PWD': '/builds/slave/test', 03:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:47 INFO - 'SHELL': '/bin/bash', 03:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:31:47 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:47 INFO - 'USER': 'cltbld', 03:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:47 INFO - 'XPC_FLAGS': '0x0', 03:31:47 INFO - 'XPC_SERVICE_NAME': '0', 03:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:47 INFO - Downloading/unpacking flake8 03:31:47 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 03:31:47 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 03:31:47 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 03:31:47 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 03:31:47 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 03:31:47 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 03:31:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:31:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:31:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:31:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:51 INFO - no previously-included directories found matching 'docs/_build' 03:31:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:31:51 INFO - Running setup.py install for pep8 03:31:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:51 INFO - no previously-included directories found matching 'docs/_build' 03:31:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:31:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:31:51 INFO - Cleaning up... 03:31:51 INFO - Return code: 0 03:31:51 INFO - Running post-action listener: _resource_record_post_action 03:31:51 INFO - Running post-action listener: _start_resource_monitoring 03:31:51 INFO - Starting resource monitoring. 03:31:51 INFO - ##### 03:31:51 INFO - ##### Running install step. 03:31:51 INFO - ##### 03:31:51 INFO - Running pre-action listener: _resource_record_pre_action 03:31:51 INFO - Running main action method: install 03:31:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:52 INFO - Reading from file tmpfile_stdout 03:31:52 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:52 INFO - Reading from file tmpfile_stdout 03:31:52 INFO - Output received: 03:31:52 INFO - Usage: mozinstall [options] installer 03:31:52 INFO - Options: 03:31:52 INFO - -h, --help show this help message and exit 03:31:52 INFO - -d DEST, --destination=DEST 03:31:52 INFO - Directory to install application into. [default: 03:31:52 INFO - "/builds/slave/test"] 03:31:52 INFO - --app=APP Application being installed. [default: firefox] 03:31:52 INFO - mkdir: /builds/slave/test/build/application 03:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:32:26 INFO - Reading from file tmpfile_stdout 03:32:26 INFO - Output received: 03:32:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:32:26 INFO - Running post-action listener: _resource_record_post_action 03:32:26 INFO - ##### 03:32:26 INFO - ##### Running run-tests step. 03:32:26 INFO - ##### 03:32:26 INFO - Running pre-action listener: _resource_record_pre_action 03:32:26 INFO - Running main action method: run_tests 03:32:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:32:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:32:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:32:26 INFO - 2016-04-16 03:32:26.226 screenresolution[1648:8420] starting screenresolution argv=screenresolution get 03:32:26 INFO - 2016-04-16 03:32:26.229 screenresolution[1648:8420] Display 0: 1600x1200x32@60 03:32:26 INFO - 2016-04-16 03:32:26.236 screenresolution[1649:8422] starting screenresolution argv=screenresolution list 03:32:26 INFO - Available Modes on Display 0 03:32:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:32:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:32:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:32:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:32:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:32:26 INFO - Intel Iris: 03:32:26 INFO - Chipset Model: Intel Iris 03:32:26 INFO - Type: GPU 03:32:26 INFO - Bus: Built-In 03:32:26 INFO - VRAM (Dynamic, Max): 1536 MB 03:32:26 INFO - Vendor: Intel (0x8086) 03:32:26 INFO - Device ID: 0x0a2e 03:32:26 INFO - Revision ID: 0x0009 03:32:26 INFO - Displays: 03:32:26 INFO - Display: 03:32:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:32:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:32:26 INFO - Display Serial Number: 1600x1200 60 03:32:26 INFO - Main Display: Yes 03:32:26 INFO - Mirror: Off 03:32:26 INFO - Online: Yes 03:32:26 INFO - Rotation: Supported 03:32:26 INFO - Return code: 0 03:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:32:26 INFO - Python 2.7.3 03:32:26 INFO - Return code: 0 03:32:26 INFO - grabbing minidump binary from tooltool 03:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:26 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 0x1095d9e00>, '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 0x109442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095d4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:32:26 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 03:32:26 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 03:32:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:32:26 INFO - Return code: 0 03:32:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:32:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:32:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:32:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:32:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:32:27 INFO - Return code: 0 03:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0124 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:32:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WljiKTJyun/Render', 03:32:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:27 INFO - 'HOME': '/Users/cltbld', 03:32:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:27 INFO - 'LOGNAME': 'cltbld', 03:32:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:27 INFO - 'MOZ_NO_REMOTE': '1', 03:32:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:27 INFO - 'NO_EM_RESTART': '1', 03:32:27 INFO - 'PAGER': '/bin/cat', 03:32:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:27 INFO - 'PWD': '/builds/slave/test', 03:32:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:27 INFO - 'SHELL': '/bin/bash', 03:32:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7qPqzouMnz/Listeners', 03:32:27 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:32:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:27 INFO - 'USER': 'cltbld', 03:32:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:27 INFO - 'XPC_FLAGS': '0x0', 03:32:27 INFO - 'XPC_SERVICE_NAME': '0', 03:32:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:32:27 INFO - mozversion application_buildid: 20160416015820 03:32:27 INFO - mozversion application_changeset: a5322c766f510d2a6544b519ffcc72a19879c779 03:32:27 INFO - mozversion application_display_name: Nightly 03:32:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:32:27 INFO - mozversion application_name: Firefox 03:32:27 INFO - mozversion application_remotingname: firefox 03:32:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:32:27 INFO - mozversion application_vendor: Mozilla 03:32:27 INFO - mozversion application_version: 48.0a1 03:32:27 INFO - mozversion platform_buildid: 20160416015820 03:32:27 INFO - mozversion platform_changeset: a5322c766f510d2a6544b519ffcc72a19879c779 03:32:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:32:27 INFO - mozversion platform_version: 48.0a1 03:32:27 INFO - using testdate: 1460802747 03:32:27 INFO - actual date: 1460802747 03:32:27 INFO - starting webserver on 'localhost:49278' 03:32:27 INFO - SUITE-START | Running 2 tests 03:32:27 INFO - TEST-START | dromaeo_css 03:32:27 INFO - operating with platform_type : mac_ 03:32:27 INFO - Initialising browser for dromaeo_css test... 03:32:27 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpn3Q9h1/profile http://localhost:49278/getInfo.html) 03:32:30 INFO - PROCESS | 1667 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 03:32:30 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 03:32:30 INFO - PROCESS | 1667 | colorDepth:24 03:32:30 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 03:32:30 INFO - PROCESS | 1667 | __metrics 03:32:30 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:32:31 INFO - TEST-INFO | 1667: exit 0 03:32:31 INFO - Browser initialized. 03:32:31 INFO - Running cycle 1/1 for dromaeo_css test... 03:32:31 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpn3Q9h1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:43 INFO - PROCESS | 1671 | RSS: Main: 247959552 03:32:43 INFO - PROCESS | 1671 | 03:33:33 INFO - PROCESS | 1671 | 1460802813045 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:35:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:35:45 INFO - PROCESS | 1671 | RSS: Main: 260362240 03:35:45 INFO - PROCESS | 1671 | 03:38:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:38:48 INFO - PROCESS | 1671 | RSS: Main: 272035840 03:38:48 INFO - PROCESS | 1671 | 03:41:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:41:50 INFO - PROCESS | 1671 | RSS: Main: 274038784 03:41:50 INFO - PROCESS | 1671 | 03:44:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:44:53 INFO - PROCESS | 1671 | RSS: Main: 275578880 03:44:53 INFO - PROCESS | 1671 | 03:47:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:47:56 INFO - PROCESS | 1671 | RSS: Main: 278511616 03:47:56 INFO - PROCESS | 1671 | 03:50:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:50:59 INFO - PROCESS | 1671 | RSS: Main: 279687168 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | __start_tp_report 03:50:59 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 03:50:59 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 03:50:59 INFO - PROCESS | 1671 | |i|pagename|runs| 03:50:59 INFO - PROCESS | 1671 | |0;dojo.html;2923.298408381989;3420.4014297497934;3440.8623655053793;3442.483627455881;3464.6708562686536;4496.6627502937245;5614.9719251403685;5689.203511508382;5714.857128571783;5764.538836893049;4584;5593.161025846125;5638.323401191852;5693.459121383472;5828.679422631753;5146.073706732786;5178.870528236796;5207.557357624588;5388.568914486832;5389.541888939425;5311.176767601028;5312.707801070939;5335.653182543142;5345.465453454663;5386.73066346683;5115.9744201278945;5155.587552995788;5178.352705911761;5207.4792520747815;5278.445763194886;5232.398274198478;5253.737313134328;5259.500347466985;5270.9736451317885;5280.419153893069;5207.739613019334;5254.264402983586;5257.447967963385;5265.526102650741;5267.683938963674;4491;5342.839714808562;5541.390447050821;5608.214849921014;5638.915416268759;4875.68308059975;5315.883664430474;5460.208269800856;6231.376862313778;6252.74989000444;6110.01202914832;6160.6919654017565;6248.625082495065;6262;6269.968650156766;5786.594938354317;5803.65178089316;5804.158397032406;5806.390329015392;5874.9412505874825;5186.818461353834;5252.842414727565;6297.905531417033;6325.335839736854;6382.393672601095;5406.783728650888;5894.0274854648505;6118;6132.386102851662;6158.568900176944;6123.831729140451;6156.5998210117095;6265.091561723524;6269.185005949197;6313.463355614824;4830.492798256203;6197.907031394533;6203.16257305267;6215.160953271341;6228.128062071313;6110.388961103854;6124.0201567748945;6166.075088736726;6222.782202622887;6225.439710426083;4111.527174374985;4923.556879880828;4925.236588328815;4934.235193545006;4978.551930326215;4377.5403582624;4394.406755088086;4404.955950440519;4420.403245561873;4455.465344158722;4739.739314337676;4773.952260477351;5532.75102620383;5683.26117604717;5727.742251598688;6218.440340369389;6248.218972628421;6264.2796078451565;6271.404216599416;6279.560430769802;5782.161586570008;5810.331811841608;5831.950248955144;5860.472557469848;5904.822855314347;5295.947040529622;5374.435684253175;5422.945770542323;5437.755301011464;5451.672899626035;6370.1718776558755;6381.202349706286;6382.84042898922;6393.552451328455;6423.710933007932;6294.559380843297;6299.212598425197;6301.558890877594;6305.5901366411035;6317.4630156437215;6192.59748116371;6294.433500984934;6321.905171422524;6405.583637063576;6408.807735767934;6174.536909731878;6186.597871138361;6228.0969259456215;6235;6242.469390101892;6111.755529778759;6252.9062064069985;6261;6267.968660156853;6289.7169627365965;6003.6397816131175;6095.75616975316;6128.167033931239;6147.907781383194;6154.78458253959;6123.763538041328;6127.816165515042;6130.080487926846;6157.84605384869;6191.81424557264;5732.082866741302;5805.680687562224;5980.671063091571;5992.460678538952;6005.429484198994;4503.639708823352;6125.235451818428;6130.9716851690155;6165.938340616716;6177.073438984188;6036.879262414639;6046.518558252457;6067.787627433018;6089.695515224133;6132.325444201045 03:50:59 INFO - PROCESS | 1671 | |1;ext.html;516.9017886601546;522.2323184918171;566.4420545762426;573.6185436684603;635.0696230023016;28659.856700716493;30297.93957211498;32864.34271314572;33477.16307092324;36991.075223119435;37174.07064823374;37737.62262377376;37771.81114094432;37824.86525404242;37864.43203351952;9233.95383023084;9333.253339732804;9392.81214375712;9483.478408687519;9513.28650351223;9389.906100939006;9406.811863762721;9411.200047995928;9420.057994200593;9531.38046027006;9403.247740180803;9408.952955235214;9471.81056378872;9503.382280151802;9567.569459374345;8049.436539442212;9503.477308748015;9513.71458856232;9560.139587437165;9564.760880977961;9356.90643093567;9394.577244024036;9423.48170850603;9461.574229159703;9509.286803489766;36345.45481817775;36510.45234321487;36562.8171859139;36586.451203231976;36889.63110368889;2482.2677310193535;3027.7275045246033;3228.1122691259857;3293.86824527021;3443.6212016678146;25393.11124110647;25478.23565293044;25684.614730779052;25700.357491062874;26229.73770262292;2555.3228394475477;2594.5718956372175;2632.605109233611;2701.8784154713085;2827.1659860341247;323251;323272;326889;327419;379213.10393448133;33338.3332333352;33612.327753445286;35081.298374032376;35801.46297805535;35889.92330230097;28706.71293287082;34445.65554344424;34452.138696532784;34796.78211262594;35051.29897402038;12291.877081229073;14727.852721472647;14802.111873287637;14876.479323223635;14919.328630211667;10541.683749487527;12256.44845981933;12392.380380980914;12499.3750312484;12510.687232819253;7937.880931786028;8021.077576078826;8024.317932975646;8076.798080047928;8123.512589244664;2706.6346043284298;3387.9153021174666;3411.4541673332146;3440.896773096811;3446.2418267981;1025.6256466389864;1109.6449136276315;1121.6018313498753;1144.5193018932068;1183.7928362536522;25533;28984.42031159365;29628.259293517403;29780;29821.85089074584;1023.518946095334;1095.5453486803046;1120.5461787975883;1137.8748337682848;1180.2564384437749;14686.339114739862;14763;14796.18620975835;14822.5903882909;14841.035332703339;559491;565783;577802;579501;579762;57930.42069579334;58036.709816450646;58848;58880.41119588835;58916.11625825616;1258.7734207842677;1471.3746657670447;1498.5354540092605;1500.9024413413092;1501.1668523969302;23847;26941.59587606148;29687;29921.70078299189;29974;59805.40194597998;60357.491062723784;60421;61086.69456652688;61228;501769.4911525419;525330.8667283348;537925;577729;595802;39172.41241381325;44346.55653443424;44636.55363446324;45120.54879451295;45706.31440528461;47449;47593.52406475891;48281.75859120682;48611;49558.00883982372;31002.0699379019;34469.482957756154;35029.47455788216;39278.80360598179;40354.1929161421;38370.616293836705;38466.615333846305;39033.41449878311;39172.2165556693;39584;32972.67027329792;34941;37983;38688.2262354757;38788;31902;33338.83314083995;33754.493682595275;33873.49189762205;34016;24570.63144052805;25040.874795625907;25074.62388064144;25363.365915851515;25651.743482565682 03:50:59 INFO - PROCESS | 1671 | |2;jquery.html;346.81445426696706;357.58520116664675;382.67855001798495;389.29148948912956;407.08406086305814;18911.62176756464;19603.313884014064;20279.188832446685;20699.275525356614;20707.689384659225;11770.941145294262;12052.975497082758;12053.39733013348;12314.322712250845;12378.628641140758;4444.333349997502;5431.592630552705;5464.207689884965;5465.535429488478;5467.507924286813;3956.2680904032704;5789.681567513785;6064.06007068905;6137.324894261627;6154.7230374633245;5990.066423313986;6075.149479072933;6160.045192995093;6173.259208894939;6524.934750652504;6684.966575167142;6719.0593316935665;6719.529632925697;6733.494987875904;6751.527393082486;5629.268195134648;5873.267386121104;5968.641881487125;6003.369646187175;6023.728932198018;12435.813462797974;13226.272555009471;13595.388207530685;13641.181529108286;13700.520481783089;1961.646903557359;1982.3557343863158;2140.4006878074165;2227.944301392462;2230.085664877409;9500.334976551645;9910;9911.152037032414;11325.660230193107;14092.647683807987;1697.7792886924744;1789.6241789224277;1794.4078454110113;1820.553964278747;1906.9809301906942;110015;117257.99999999914;124833;126737.36631316876;137582;19853.801461985484;20395.4901127473;20640;21419.143234270767;21439.78560214378;28764.424711505653;29459;35225;35778.74774382884;36046;10265.178785697126;10855.67432977012;10866;11123.555057797597;11174.832377514344;7340.4127669786285;8898.332625053146;9021.278297736297;9056.818863622691;9097.77255568616;9875.358101723508;9990.700278991642;9994.150497207818;10020;10143.036411540892;1671.3815888121474;1874.7656542932134;1986.7417235759258;2000.899955002244;2004.4387571480008;876.2814492116531;940.5156344482597;982.9115379615869;1050.1283934334483;1070.325694812279;16675;16977.726670499764;19824.90087549553;20125.295614653416;21075.946202689804;958.1903291718487;973.5083782689696;986.5757724178673;1001.2028409005707;1017.3692310767372;10702.250842440955;10738.946305268424;11150.944245278723;11472.885271147348;11534.826977595343;523315;564536;629329;633454;636753;80557.59721201357;93286;105309.47345263224;125282;147500;981.259149342242;1013.3565186106728;1042.187094066614;1060.3531845574194;1061.8354155105972;18096.72854907151;18212.908935455238;18482.907585461988;18708;18762;41034.9741256459;52596;52846.471535285695;53566.732166338916;54746;78046;78756.42487150339;78897.60551197207;79012;79930;6668.333166683293;7081.539699919489;8012.118666946748;8126.309263712651;8201.343892488705;11577.07383409342;12336.321502317458;12344;12469.87771100569;12643.747125057263;10690.62582809598;10785.676429707122;10824.458777061272;10871.184661150288;11000.559977600806;10226.130778883879;10581;10751.6236931707;10803.945980270362;11065.5020524075;10835.458227088771;11060.668179954615;11121.221514493907;11221.88778112241;11424.600138995096;7894.486858354188;8128;8135.38984576171;8143.877841832259;8376.371772117238;9478.905210947802;9874.802503949737;10674.039336459757;11123.499442525268;11124.944375278072 03:50:59 INFO - PROCESS | 1671 | |3;mootools.html;1561.4066654671224;1585.0885740699098;1614.9354025838968;1932.1112288347367;1933.3813179782476;7885.763427097182;8361;8571.357148213885;8773;8783.341249406296;8446.746597602081;8523.78690532737;8582.442141260814;8605.827883442327;8630.18013288738;2419.0444774314137;2476.950460990779;2480.541099896516;2484.6769919910384;2488.601823708207;2394.353524548371;2468.8024958003316;2473.4434752180796;2473.8268321217433;2475.4430253192977;2444.69441319835;2451.938701532467;2452.3746444656585;2462.5321188974126;2491.4643351679383;2448.9142879999295;2450.1179575352858;2457.299669594157;2476.529459402717;2478.2069737683946;2180.367693368921;2233.0174723121772;2361.913519780903;2453.3621258472926;2456.054419048679;8177.632006559719;8649.394542382035;8714.389992700513;8757.168069033496;8862.601182946719;4085.6935729820084;5245.370555533361;5275.841724748264;5316.20256961455;5327.014502317045;3412.795232286046;3553.9466907996402;3630.963690363089;3637.872674456408;3657.8536858525626;1009.7374682582508;1031.39147902737;1034.3948789957808;1047.2041248651003;1071.4535586850684;11187.944060279646;11188.160887933438;11257;11323.830142547951;11402.71493212668;3925.6270654287796;4141.751494910255;4687.367205427292;4903.264510323409;4948.678335908155;9933.453660048766;10048.547815348327;10052.949735251277;10072.74818129538;10091.142252908438;5398.973005135028;5411.648242864201;5447.5641948644015;5465.398806131402;5469.589780766459;4295.699301048897;4434.512203657595;4513.803841981878;4909.705417674951;4948.802047918115;3485.4074807282823;3992.480977472968;4044.69664775143;4068.1253530491085;4168.6039826216465;1350.716349566602;1367.7895062869432;1368.0423703407655;1377.1392879450343;1380.6893448974101;815.2581151152423;892.794657228828;913.5021413329752;947.9431234125974;962.2542529539522;5300.92048619271;5809.896119737236;5812.88374232513;5927.525797936241;6039.456448919618;463.6870112673932;473.6092723503124;476.7592365855221;995.4723996281982;1038.5564065948338;3933.350997085506;3981.203759248162;4035.414864844581;4057.6145266199665;4106.979465102655;9150.86273705895;10259.333143345659;10351;10536.631217907336;10707.250492465453;10455.268131230892;10550;10589.629362972413;10599.735006624896;10679.83980240297;8012.198780121941;8096.757097287091;8098.149694282133;8162.918370816335;8242.670293188325;5767.826965191051;5818.854528636818;5912.940870591411;5952.0476723724;5961.314448838439;9700.90299097;9891.752706182404;9936.304458688111;9940.751481213027;9943.900560994298;8907.465552066897;9449.952750236205;9846.261530385107;10067.194624430174;10160.796784064425;6480.935190648033;6834.453243740588;7015.36861682451;7061.2585678505075;7136.964315178391;8170.305524030524;8258.29804466603;8307.127751586238;8361.289290410139;8361.623726932425;7309.963450182715;7426.628668566442;7477.962610186914;7588.747856603599;7615.847683046419;7213.20654727969;7298.744543940937;7302.817429564091;7350.963245183953;7467.663955121924;6872.450203983769;6884.931150688429;6943.958406239105;6961.965190174017;7020.824479388056;3859.942100868433;4900.754962251802;4902.338184345068;5000.2499625056535;5124.5131712487255;3868.9553820467754;4613.2388155953895;4858.076965376567;4989.226669866177;5333.626646134733 03:50:59 INFO - PROCESS | 1671 | |4;prototype.html;321.7329616418372;323.2113642711783;330.18773816411624;331.3075671845842;331.83408295852075;5164.870878228042;5297.827890564869;5777.566682498814;6200.410960958701;6203.813885583429;5326.760295786689;5333;6108.3586223446455;6127.601705889125;6130.938690613092;4184.560621134783;4400.6919515634;4523.321501774727;4627.652926030544;4633.745144017078;4042.2117687051036;4566.2648193686355;4664.160451118797;4664.580187783099;4665.976670116662;3743.513343265385;3783.2244389900143;4433.88915277119;4607.746573938449;4635.837745678902;3864.903377415559;4607.723536587782;4622.352870598119;4625.861224163281;4637.907241855178;3343.164208947763;3980.681545476355;4010.5588385277597;4014.197160567898;4032.931273212601;5889.823305300848;5903.5572332074835;5922.496587790043;5925.34821169671;5931.733072011727;5297.496737809902;5366.91949620756;5405.837824865221;5414.07960646691;5429.755660995264;5055.797768089309;6463.93536064638;6506.1216735740545;6510;6531.510136739716;3559.501669766235;4055.371417430303;4304.504981927087;4418.497710778319;4452.710573812692;6870.31296870314;6939.299871531457;6949;6967.512274140812;6977.40692041172;3803.372443546784;4516.254817955;4532.9320060199125;4533.184026875128;4562.58936695699;3625.3655610268097;3714.832832522543;4435.377271434895;4627.305904114342;4678.883027924261;5205.765740541685;5374.91937620936;5386.676799392049;5419.783208671609;5438.428964958702;5971.4924231439945;6077.997130473511;6365.49076073913;6562.278149403562;6606.942889137715;1315.8946484952685;1427.3648226539299;1430.7853821926794;1525.7101150781316;1532.0501289200768;835.9331253499705;923.3536524432924;975.11264749078;981.3326937682302;997.9800403992025;4700.741459219707;4957.479464656231;4969.800518561809;6062;6072.119542666289;6129.540284478682;6223.910815607341;6481.8703625928165;6718.66406679664;6766.086578311963;5333.599980001515;5349.839504814861;5762.30852297727;5946.672932988728;6147.784827531015;3309.7517686173633;3577.3024260269517;3577.874774382884;3584.8386822593047;3595.748297619194;6290.339514350927;6764.83087922796;7143.821134553907;7198.992141100251;7201.711931522786;6126.95841706902;6657.800265992028;7011.228764835864;7048.295170482911;7049.929500704928;6855.046311801212;6926.965365173141;7066.646667666596;7154.570725756471;7197.640117994184;5679.204911312502;6425.710843012169;6485.189351331083;6649.301823308483;6847.417969472651;6513.218413790186;6703.597784132968;6744.831379215559;6758.628275444898;6771.322867713189;7147.49772611427;7172.820679482846;7187.820304492429;7190.604516751629;7195.13658361;4333.956660433355;4377.212101821703;4385.780710964503;4392.231359512137;4410.669199809963;6607.603543787388;6633.867322653617;6793.592384456949;6813.591184528944;6834.794956151323;5725.312962444534;5885.911711324419;6274.905876411766;6562.573432726857;6573.375529324707;5476.7535460903555;6388.872222555616;6552.049952756919;6568;6606.4384527315715;6128.938710612837;6203.16257305258;6433.356664333529;6447.6453795041725;6449.903251451138;6579.243387010363;6603.471722262304;6614.338566143347;6614.900776488453;6623.072769812326;6149.96925015372;6209.627422354673;6421.8715625688155;6451.96768517035;6480.935190648222 03:50:59 INFO - PROCESS | 1671 | |5;yui.html;667.2615506642875;673.1686367336339;750.5871770526211;777.3314949143736;782.4679218131672;14601;14831.703365932675;14860.108393496397;15031.699366012674;15164.84835151648;13948.511802086929;13966;14002.509912153077;14028.78956815646;14084.647883802885;753.2768542199495;929.4655573045492;944.0700909604035;944.551338114397;944.9291303152291;908.4867050116696;944.9952750236239;945.4941606240662;946.2760987844305;947.7109481608106;970.1123472023125;973.8393165127745;974.9610015599368;975.4781192062248;978.2907392140677;918.8070505193884;962.1052421248237;965.3869792681628;966.6858271061863;966.8018056298442;693.719043787267;721.8195451137216;721.8303698630817;726.9491135620509;728.1226122522401;14567;14575.781363279559;14660.266986650731;14959.700805983819;15059;12654.493820247179;12686.619401417973;12706.110572259946;12850.678733031655;12927.09510334277;10015.39907605546;10121.291509594332;10192.796144077078;10387.324823886498;10555.683329500127;3451.2062225687982;3484.7212223022098;3500.1424650960676;3517.9472307915403;3589.569251689788;83598.58200708957;85534;85887.14112858854;86240.70638940421;86607;7826.256505631955;9409;9449.952750236205;9615.759606009906;10244.59021639141;20708.068136933874;20873.060712267983;20913.89543052275;20921.163153473994;20935.47661308449;7351.22812104732;7480.9625951870985;7486.438517111238;7494.5503269803985;7547.50941188821;3539.4513850352723;4157.459530261047;4186.225548273518;4208.473940757405;4296.9570304297185;3432.8111953842777;3505.0536355184468;3506;3528.59421166564;3544.2379888323617;610.8900397928415;635.9427651511294;638.8626445314279;640.711679744117;651.4267444648679;467.6913237263458;510.9259157422153;522.9215617657381;568.2300482845685;579.2296245992819;7696.307332340116;7707.036620422447;7761.417893657998;7909;8106;486.8028448478372;505.3228673577424;524.3655177235512;539.2773683264367;562.1960596347262;3068.8465576721073;3151.7636177286795;3650.9737095912024;3658.0123366690846;3786.3184626766897;93123.53438232902;93707;95758;95879.52060239654;96351;44943.77528112404;58254.7087264561;75847;78219.60890195626;79398.80901786478;67870.32129678837;69225.96161057588;69877;69933;70227;6296.338884417253;7289.161746399232;7343.77968660941;7633.618319083913;7656.310932016146;43199.784001079795;44833.55166448424;45032.87417814581;45053;45234.416795412006;62403;62967;63410;63635.681821590595;63997;9491.193248573672;9525.714228573153;9599.952000239955;9704.417734935927;9799.902000979899;10105.39367637944;10367.963203679572;10390.532426040998;10646.73383165427;10655.30740501865;9763.36538125048;9966.651167209113;9999.15007224365;10029.849552256575;10057.29598928068;10005.599776008878;10006;10026.398416095057;10225.233107517115;10946.835797463204;9068.957069937042;9969.00594427066;9981.650642227487;10582.682519524427;11079.83380249313;7991.640376183202;9542.522873856418;9572.712818615453;9641.758956026155;9809.215262779104;9297.709238357871;9332.813343733224;9589.568469418753;9630.181434578139;9708.5145742714 03:50:59 INFO - PROCESS | 1671 | __end_tp_report 03:50:59 INFO - PROCESS | 1671 | __start_cc_report 03:50:59 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,410 03:50:59 INFO - PROCESS | 1671 | __end_cc_report 03:50:59 INFO - PROCESS | 1671 | __startTimestamp1460803859051__endTimestamp 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | ------- Summary: start ------- 03:50:59 INFO - PROCESS | 1671 | Number of tests: 6 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5672.27 Median:5860.47 stddev:689.68 (11.8%) stddev-sans-first:657.22 03:50:59 INFO - PROCESS | 1671 | Values: 2923.3 3420.4 3440.9 3442.5 3464.7 4496.7 5615.0 5689.2 5714.9 5764.5 4584.0 5593.2 5638.3 5693.5 5828.7 5146.1 5178.9 5207.6 5388.6 5389.5 5311.2 5312.7 5335.7 5345.5 5386.7 5116.0 5155.6 5178.4 5207.5 5278.4 5232.4 5253.7 5259.5 5271.0 5280.4 5207.7 5254.3 5257.4 5265.5 5267.7 4491.0 5342.8 5541.4 5608.2 5638.9 4875.7 5315.9 5460.2 6231.4 6252.7 6110.0 6160.7 6248.6 6262.0 6270.0 5786.6 5803.7 5804.2 5806.4 5874.9 5186.8 5252.8 6297.9 6325.3 6382.4 5406.8 5894.0 6118.0 6132.4 6158.6 6123.8 6156.6 6265.1 6269.2 6313.5 4830.5 6197.9 6203.2 6215.2 6228.1 6110.4 6124.0 6166.1 6222.8 6225.4 4111.5 4923.6 4925.2 4934.2 4978.6 4377.5 4394.4 4405.0 4420.4 4455.5 4739.7 4774.0 5532.8 5683.3 5727.7 6218.4 6248.2 6264.3 6271.4 6279.6 5782.2 5810.3 5832.0 5860.5 5904.8 5295.9 5374.4 5422.9 5437.8 5451.7 6370.2 6381.2 6382.8 6393.6 6423.7 6294.6 6299.2 6301.6 6305.6 6317.5 6192.6 6294.4 6321.9 6405.6 6408.8 6174.5 6186.6 6228.1 6235.0 6242.5 6111.8 6252.9 6261.0 6268.0 6289.7 6003.6 6095.8 6128.2 6147.9 6154.8 6123.8 6127.8 6130.1 6157.8 6191.8 5732.1 5805.7 5980.7 5992.5 6005.4 4503.6 6125.2 6131.0 6165.9 6177.1 6036.9 6046.5 6067.8 6089.7 6132.3 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:61233.73 Median:35441.38 stddev:133094.76 (375.5%) stddev-sans-first:133390.05 03:50:59 INFO - PROCESS | 1671 | Values: 516.9 522.2 566.4 573.6 635.1 28659.9 30297.9 32864.3 33477.2 36991.1 37174.1 37737.6 37771.8 37824.9 37864.4 9234.0 9333.3 9392.8 9483.5 9513.3 9389.9 9406.8 9411.2 9420.1 9531.4 9403.2 9409.0 9471.8 9503.4 9567.6 8049.4 9503.5 9513.7 9560.1 9564.8 9356.9 9394.6 9423.5 9461.6 9509.3 36345.5 36510.5 36562.8 36586.5 36889.6 2482.3 3027.7 3228.1 3293.9 3443.6 25393.1 25478.2 25684.6 25700.4 26229.7 2555.3 2594.6 2632.6 2701.9 2827.2 323251.0 323272.0 326889.0 327419.0 379213.1 33338.3 33612.3 35081.3 35801.5 35889.9 28706.7 34445.7 34452.1 34796.8 35051.3 12291.9 14727.9 14802.1 14876.5 14919.3 10541.7 12256.4 12392.4 12499.4 12510.7 7937.9 8021.1 8024.3 8076.8 8123.5 2706.6 3387.9 3411.5 3440.9 3446.2 1025.6 1109.6 1121.6 1144.5 1183.8 25533.0 28984.4 29628.3 29780.0 29821.9 1023.5 1095.5 1120.5 1137.9 1180.3 14686.3 14763.0 14796.2 14822.6 14841.0 559491.0 565783.0 577802.0 579501.0 579762.0 57930.4 58036.7 58848.0 58880.4 58916.1 1258.8 1471.4 1498.5 1500.9 1501.2 23847.0 26941.6 29687.0 29921.7 29974.0 59805.4 60357.5 60421.0 61086.7 61228.0 501769.5 525330.9 537925.0 577729.0 595802.0 39172.4 44346.6 44636.6 45120.5 45706.3 47449.0 47593.5 48281.8 48611.0 49558.0 31002.1 34469.5 35029.5 39278.8 40354.2 38370.6 38466.6 39033.4 39172.2 39584.0 32972.7 34941.0 37983.0 38688.2 38788.0 31902.0 33338.8 33754.5 33873.5 34016.0 24570.6 25040.9 25074.6 25363.4 25651.7 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34981.39 Median:20703.48 stddev:99767.12 (481.9%) stddev-sans-first:100013.10 03:50:59 INFO - PROCESS | 1671 | Values: 346.8 357.6 382.7 389.3 407.1 18911.6 19603.3 20279.2 20699.3 20707.7 11770.9 12053.0 12053.4 12314.3 12378.6 4444.3 5431.6 5464.2 5465.5 5467.5 3956.3 5789.7 6064.1 6137.3 6154.7 5990.1 6075.1 6160.0 6173.3 6524.9 6685.0 6719.1 6719.5 6733.5 6751.5 5629.3 5873.3 5968.6 6003.4 6023.7 12435.8 13226.3 13595.4 13641.2 13700.5 1961.6 1982.4 2140.4 2227.9 2230.1 9500.3 9910.0 9911.2 11325.7 14092.6 1697.8 1789.6 1794.4 1820.6 1907.0 110015.0 117258.0 124833.0 126737.4 137582.0 19853.8 20395.5 20640.0 21419.1 21439.8 28764.4 29459.0 35225.0 35778.7 36046.0 10265.2 10855.7 10866.0 11123.6 11174.8 7340.4 8898.3 9021.3 9056.8 9097.8 9875.4 9990.7 9994.2 10020.0 10143.0 1671.4 1874.8 1986.7 2000.9 2004.4 876.3 940.5 982.9 1050.1 1070.3 16675.0 16977.7 19824.9 20125.3 21075.9 958.2 973.5 986.6 1001.2 1017.4 10702.3 10738.9 11150.9 11472.9 11534.8 523315.0 564536.0 629329.0 633454.0 636753.0 80557.6 93286.0 105309.5 125282.0 147500.0 981.3 1013.4 1042.2 1060.4 1061.8 18096.7 18212.9 18482.9 18708.0 18762.0 41035.0 52596.0 52846.5 53566.7 54746.0 78046.0 78756.4 78897.6 79012.0 79930.0 6668.3 7081.5 8012.1 8126.3 8201.3 11577.1 12336.3 12344.0 12469.9 12643.7 10690.6 10785.7 10824.5 10871.2 11000.6 10226.1 10581.0 10751.6 10803.9 11065.5 10835.5 11060.7 11121.2 11221.9 11424.6 7894.5 8128.0 8135.4 8143.9 8376.4 9478.9 9874.8 10674.0 11123.5 11124.9 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5628.88 Median:4901.55 stddev:3117.01 (63.6%) stddev-sans-first:3110.77 03:50:59 INFO - PROCESS | 1671 | Values: 1561.4 1585.1 1614.9 1932.1 1933.4 7885.8 8361.0 8571.4 8773.0 8783.3 8446.7 8523.8 8582.4 8605.8 8630.2 2419.0 2477.0 2480.5 2484.7 2488.6 2394.4 2468.8 2473.4 2473.8 2475.4 2444.7 2451.9 2452.4 2462.5 2491.5 2448.9 2450.1 2457.3 2476.5 2478.2 2180.4 2233.0 2361.9 2453.4 2456.1 8177.6 8649.4 8714.4 8757.2 8862.6 4085.7 5245.4 5275.8 5316.2 5327.0 3412.8 3553.9 3631.0 3637.9 3657.9 1009.7 1031.4 1034.4 1047.2 1071.5 11187.9 11188.2 11257.0 11323.8 11402.7 3925.6 4141.8 4687.4 4903.3 4948.7 9933.5 10048.5 10052.9 10072.7 10091.1 5399.0 5411.6 5447.6 5465.4 5469.6 4295.7 4434.5 4513.8 4909.7 4948.8 3485.4 3992.5 4044.7 4068.1 4168.6 1350.7 1367.8 1368.0 1377.1 1380.7 815.3 892.8 913.5 947.9 962.3 5300.9 5809.9 5812.9 5927.5 6039.5 463.7 473.6 476.8 995.5 1038.6 3933.4 3981.2 4035.4 4057.6 4107.0 9150.9 10259.3 10351.0 10536.6 10707.3 10455.3 10550.0 10589.6 10599.7 10679.8 8012.2 8096.8 8098.1 8162.9 8242.7 5767.8 5818.9 5912.9 5952.0 5961.3 9700.9 9891.8 9936.3 9940.8 9943.9 8907.5 9450.0 9846.3 10067.2 10160.8 6480.9 6834.5 7015.4 7061.3 7137.0 8170.3 8258.3 8307.1 8361.3 8361.6 7310.0 7426.6 7478.0 7588.7 7615.8 7213.2 7298.7 7302.8 7351.0 7467.7 6872.5 6884.9 6944.0 6962.0 7020.8 3859.9 4900.8 4902.3 5000.2 5124.5 3869.0 4613.2 4858.1 4989.2 5333.6 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5270.59 Median:5939.20 stddev:1697.03 (28.6%) stddev-sans-first:1660.64 03:50:59 INFO - PROCESS | 1671 | Values: 321.7 323.2 330.2 331.3 331.8 5164.9 5297.8 5777.6 6200.4 6203.8 5326.8 5333.0 6108.4 6127.6 6130.9 4184.6 4400.7 4523.3 4627.7 4633.7 4042.2 4566.3 4664.2 4664.6 4666.0 3743.5 3783.2 4433.9 4607.7 4635.8 3864.9 4607.7 4622.4 4625.9 4637.9 3343.2 3980.7 4010.6 4014.2 4032.9 5889.8 5903.6 5922.5 5925.3 5931.7 5297.5 5366.9 5405.8 5414.1 5429.8 5055.8 6463.9 6506.1 6510.0 6531.5 3559.5 4055.4 4304.5 4418.5 4452.7 6870.3 6939.3 6949.0 6967.5 6977.4 3803.4 4516.3 4532.9 4533.2 4562.6 3625.4 3714.8 4435.4 4627.3 4678.9 5205.8 5374.9 5386.7 5419.8 5438.4 5971.5 6078.0 6365.5 6562.3 6606.9 1315.9 1427.4 1430.8 1525.7 1532.1 835.9 923.4 975.1 981.3 998.0 4700.7 4957.5 4969.8 6062.0 6072.1 6129.5 6223.9 6481.9 6718.7 6766.1 5333.6 5349.8 5762.3 5946.7 6147.8 3309.8 3577.3 3577.9 3584.8 3595.7 6290.3 6764.8 7143.8 7199.0 7201.7 6127.0 6657.8 7011.2 7048.3 7049.9 6855.0 6927.0 7066.6 7154.6 7197.6 5679.2 6425.7 6485.2 6649.3 6847.4 6513.2 6703.6 6744.8 6758.6 6771.3 7147.5 7172.8 7187.8 7190.6 7195.1 4334.0 4377.2 4385.8 4392.2 4410.7 6607.6 6633.9 6793.6 6813.6 6834.8 5725.3 5885.9 6274.9 6562.6 6573.4 5476.8 6388.9 6552.0 6568.0 6606.4 6128.9 6203.2 6433.4 6447.6 6449.9 6579.2 6603.5 6614.3 6614.9 6623.1 6150.0 6209.6 6421.9 6452.0 6480.9 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17648.74 Median:32318.86 stddev:25353.86 (78.4%) stddev-sans-first:25392.93 03:50:59 INFO - PROCESS | 1671 | Values: 667.3 673.2 750.6 777.3 782.5 14601.0 14831.7 14860.1 15031.7 15164.8 13948.5 13966.0 14002.5 14028.8 14084.6 753.3 929.5 944.1 944.6 944.9 908.5 945.0 945.5 946.3 947.7 970.1 973.8 975.0 975.5 978.3 918.8 962.1 965.4 966.7 966.8 693.7 721.8 721.8 726.9 728.1 14567.0 14575.8 14660.3 14959.7 15059.0 12654.5 12686.6 12706.1 12850.7 12927.1 10015.4 10121.3 10192.8 10387.3 10555.7 3451.2 3484.7 3500.1 3517.9 3589.6 83598.6 85534.0 85887.1 86240.7 86607.0 7826.3 9409.0 9450.0 9615.8 10244.6 20708.1 20873.1 20913.9 20921.2 20935.5 7351.2 7481.0 7486.4 7494.6 7547.5 3539.5 4157.5 4186.2 4208.5 4297.0 3432.8 3505.1 3506.0 3528.6 3544.2 610.9 635.9 638.9 640.7 651.4 467.7 510.9 522.9 568.2 579.2 7696.3 7707.0 7761.4 7909.0 8106.0 486.8 505.3 524.4 539.3 562.2 3068.8 3151.8 3651.0 3658.0 3786.3 93123.5 93707.0 95758.0 95879.5 96351.0 44943.8 58254.7 75847.0 78219.6 79398.8 67870.3 69226.0 69877.0 69933.0 70227.0 6296.3 7289.2 7343.8 7633.6 7656.3 43199.8 44833.6 45032.9 45053.0 45234.4 62403.0 62967.0 63410.0 63635.7 63997.0 9491.2 9525.7 9600.0 9704.4 9799.9 10105.4 10368.0 10390.5 10646.7 10655.3 9763.4 9966.7 9999.2 10029.8 10057.3 10005.6 10006.0 10026.4 10225.2 10946.8 9069.0 9969.0 9981.7 10582.7 11079.8 7991.6 9542.5 9572.7 9641.8 9809.2 9297.7 9332.8 9589.6 9630.2 9708.5 03:50:59 INFO - PROCESS | 1671 | -------- Summary: end -------- 03:50:59 INFO - PROCESS | 1671 | 03:50:59 INFO - TEST-INFO | 1671: exit 0 03:50:59 INFO - TEST-OK | dromaeo_css | took 1111767ms 03:50:59 INFO - TEST-START | kraken 03:50:59 INFO - operating with platform_type : mac_ 03:50:59 INFO - Initialising browser for kraken test... 03:50:59 INFO - TEST-INFO | started process 1703 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpeiS_O3/profile http://localhost:49278/getInfo.html) 03:51:02 INFO - PROCESS | 1703 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 03:51:02 INFO - PROCESS | 1703 | __metrics Screen width/height:1600/1200 03:51:02 INFO - PROCESS | 1703 | colorDepth:24 03:51:02 INFO - PROCESS | 1703 | Browser inner width/height: 1024/689 03:51:02 INFO - PROCESS | 1703 | __metrics 03:51:02 ERROR - PROCESS | 1703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:51:03 INFO - TEST-INFO | 1703: exit 0 03:51:03 INFO - Browser initialized. 03:51:03 INFO - Running cycle 1/1 for kraken test... 03:51:03 INFO - TEST-INFO | started process 1706 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpeiS_O3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:15 INFO - PROCESS | 1706 | RSS: Main: 247713792 03:51:15 INFO - PROCESS | 1706 | 03:51:18 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:51:19 INFO - PROCESS | 1706 | RSS: Main: 259907584 03:51:19 INFO - PROCESS | 1706 | 03:51:22 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:51:22 INFO - PROCESS | 1706 | RSS: Main: 269049856 03:51:22 INFO - PROCESS | 1706 | 03:51:26 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:51:26 INFO - PROCESS | 1706 | RSS: Main: 272052224 03:51:26 INFO - PROCESS | 1706 | 03:51:28 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:51:29 INFO - PROCESS | 1706 | RSS: Main: 274051072 03:51:29 INFO - PROCESS | 1706 | 03:51:31 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:51:32 INFO - PROCESS | 1706 | RSS: Main: 274665472 03:51:32 INFO - PROCESS | 1706 | 03:51:35 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:36 INFO - PROCESS | 1706 | RSS: Main: 274546688 03:51:36 INFO - PROCESS | 1706 | 03:51:39 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:39 INFO - PROCESS | 1706 | RSS: Main: 274804736 03:51:39 INFO - PROCESS | 1706 | 03:51:42 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:51:43 INFO - PROCESS | 1706 | RSS: Main: 274706432 03:51:43 INFO - PROCESS | 1706 | 03:51:44 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:45 INFO - PROCESS | 1706 | RSS: Main: 274690048 03:51:45 INFO - PROCESS | 1706 | 03:51:47 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:47 INFO - PROCESS | 1706 | RSS: Main: 274722816 03:51:47 INFO - PROCESS | 1706 | 03:51:49 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:50 INFO - PROCESS | 1706 | RSS: Main: 275140608 03:51:50 INFO - PROCESS | 1706 | 03:51:53 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:54 INFO - PROCESS | 1706 | RSS: Main: 275234816 03:51:54 INFO - PROCESS | 1706 | 03:51:57 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:58 INFO - PROCESS | 1706 | RSS: Main: 275177472 03:51:58 INFO - PROCESS | 1706 | 03:51:59 INFO - PROCESS | 1706 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:52:00 INFO - PROCESS | 1706 | RSS: Main: 276934656 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | __start_tp_report 03:52:00 INFO - PROCESS | 1706 | _x_x_mozilla_page_load 03:52:00 INFO - PROCESS | 1706 | _x_x_mozilla_page_load_details 03:52:00 INFO - PROCESS | 1706 | |i|pagename|runs| 03:52:00 INFO - PROCESS | 1706 | |0;ai-astar;149;157;97;168;173;183;115;117;115;117 03:52:00 INFO - PROCESS | 1706 | |1;audio-beat-detection;151;153;162;191;125;171;176;153;171;124 03:52:00 INFO - PROCESS | 1706 | |2;audio-dft;202;213;223;282;185;187;245;185;273;204 03:52:00 INFO - PROCESS | 1706 | |3;audio-fft;72;81;75;145;84;81;81;93;125;57 03:52:00 INFO - PROCESS | 1706 | |4;audio-oscillator;82;91;184;105;81;84;136;116;121;148 03:52:00 INFO - PROCESS | 1706 | |5;imaging-gaussian-blur;136;129;89;94;120;86;92;86;79;130 03:52:00 INFO - PROCESS | 1706 | |6;imaging-darkroom;96;113;127;96;123;148;97;95;96;104 03:52:00 INFO - PROCESS | 1706 | |7;imaging-desaturate;89;90;90;91;92;92;89;92;91;94 03:52:00 INFO - PROCESS | 1706 | |8;json-parse-financial;61;63;61;62;65;62;62;79;64;60 03:52:00 INFO - PROCESS | 1706 | |9;json-stringify-tinderbox;65;67;67;71;74;70;67;77;99;100 03:52:00 INFO - PROCESS | 1706 | |10;stanford-crypto-aes;86;85;83;85;83;86;86;84;86;86 03:52:00 INFO - PROCESS | 1706 | |11;stanford-crypto-ccm;134;134;158;131;143;158;157;162;185;168 03:52:00 INFO - PROCESS | 1706 | |12;stanford-crypto-pbkdf2;171;243;230;184;191;227;189;170;171;173 03:52:00 INFO - PROCESS | 1706 | |13;stanford-crypto-sha256-iterative;67;69;66;70;70;66;75;71;99;72 03:52:00 INFO - PROCESS | 1706 | __end_tp_report 03:52:00 INFO - PROCESS | 1706 | __start_cc_report 03:52:00 INFO - PROCESS | 1706 | _x_x_mozilla_cycle_collect,1064 03:52:00 INFO - PROCESS | 1706 | __end_cc_report 03:52:00 INFO - PROCESS | 1706 | __startTimestamp1460803920019__endTimestamp 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | ------- Summary: start ------- 03:52:00 INFO - PROCESS | 1706 | Number of tests: 14 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#0] ai-astar Cycles:10 Average:139.10 Median:162.50 stddev:30.26 (18.6%) stddev-sans-first:31.88 03:52:00 INFO - PROCESS | 1706 | Values: 149.0 157.0 97.0 168.0 173.0 183.0 115.0 117.0 115.0 117.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#1] audio-beat-detection Cycles:10 Average:157.70 Median:166.50 stddev:21.37 (12.8%) stddev-sans-first:22.53 03:52:00 INFO - PROCESS | 1706 | Values: 151.0 153.0 162.0 191.0 125.0 171.0 176.0 153.0 171.0 124.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#2] audio-dft Cycles:10 Average:219.90 Median:218.00 stddev:35.68 (16.4%) stddev-sans-first:37.25 03:52:00 INFO - PROCESS | 1706 | Values: 202.0 213.0 223.0 282.0 185.0 187.0 245.0 185.0 273.0 204.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#3] audio-fft Cycles:10 Average:89.40 Median:81.00 stddev:26.19 (32.3%) stddev-sans-first:27.01 03:52:00 INFO - PROCESS | 1706 | Values: 72.0 81.0 75.0 145.0 84.0 81.0 81.0 93.0 125.0 57.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#4] audio-oscillator Cycles:10 Average:114.80 Median:132.50 stddev:33.68 (25.4%) stddev-sans-first:33.57 03:52:00 INFO - PROCESS | 1706 | Values: 82.0 91.0 184.0 105.0 81.0 84.0 136.0 116.0 121.0 148.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#5] imaging-gaussian-blur Cycles:10 Average:104.10 Median:86.00 stddev:21.92 (25.5%) stddev-sans-first:19.98 03:52:00 INFO - PROCESS | 1706 | Values: 136.0 129.0 89.0 94.0 120.0 86.0 92.0 86.0 79.0 130.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:95.50 stddev:18.03 (18.9%) stddev-sans-first:18.45 03:52:00 INFO - PROCESS | 1706 | Values: 96.0 113.0 127.0 96.0 123.0 148.0 97.0 95.0 96.0 104.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:91.50 stddev:1.56 (1.7%) stddev-sans-first:1.48 03:52:00 INFO - PROCESS | 1706 | Values: 89.0 90.0 90.0 91.0 92.0 92.0 89.0 92.0 91.0 94.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:62.50 stddev:5.51 (8.8%) stddev-sans-first:5.74 03:52:00 INFO - PROCESS | 1706 | Values: 61.0 63.0 61.0 62.0 65.0 62.0 62.0 79.0 64.0 60.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:70.50 stddev:13.06 (18.5%) stddev-sans-first:13.26 03:52:00 INFO - PROCESS | 1706 | Values: 65.0 67.0 67.0 71.0 74.0 70.0 67.0 77.0 99.0 100.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.27 03:52:00 INFO - PROCESS | 1706 | Values: 86.0 85.0 83.0 85.0 83.0 86.0 86.0 84.0 86.0 86.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:158.00 stddev:17.33 (11.0%) stddev-sans-first:16.96 03:52:00 INFO - PROCESS | 1706 | Values: 134.0 134.0 158.0 131.0 143.0 158.0 157.0 162.0 185.0 168.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.90 Median:190.00 stddev:27.86 (14.7%) stddev-sans-first:28.18 03:52:00 INFO - PROCESS | 1706 | Values: 171.0 243.0 230.0 184.0 191.0 227.0 189.0 170.0 171.0 173.0 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - PROCESS | 1706 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:70.50 stddev:9.72 (13.8%) stddev-sans-first:10.11 03:52:00 INFO - PROCESS | 1706 | Values: 67.0 69.0 66.0 70.0 70.0 66.0 75.0 71.0 99.0 72.0 03:52:00 INFO - PROCESS | 1706 | -------- Summary: end -------- 03:52:00 INFO - PROCESS | 1706 | 03:52:00 INFO - TEST-INFO | 1706: exit 0 03:52:00 INFO - TEST-OK | kraken | took 61043ms 03:52:00 INFO - SUITE-END | took 1172s 03:52:01 INFO - Completed test suite (00:19:33) 03:52:01 INFO - javascript benchmark 03:52:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2923.298408381989, 3420.4014297497934, 3440.8623655053793, 3442.483627455881, 3464.6708562686536, 4496.6627502937245, 5614.9719251403685, 5689.203511508382, 5714.857128571783, 5764.538836893049, 4584.0, 5593.161025846125, 5638.323401191852, 5693.459121383472, 5828.679422631753, 5146.073706732786, 5178.870528236796, 5207.557357624588, 5388.568914486832, 5389.541888939425, 5311.176767601028, 5312.707801070939, 5335.653182543142, 5345.465453454663, 5386.73066346683, 5115.9744201278945, 5155.587552995788, 5178.352705911761, 5207.4792520747815, 5278.445763194886, 5232.398274198478, 5253.737313134328, 5259.500347466985, 5270.9736451317885, 5280.419153893069, 5207.739613019334, 5254.264402983586, 5257.447967963385, 5265.526102650741, 5267.683938963674, 4491.0, 5342.839714808562, 5541.390447050821, 5608.214849921014, 5638.915416268759, 4875.68308059975, 5315.883664430474, 5460.208269800856, 6231.376862313778, 6252.74989000444, 6110.01202914832, 6160.6919654017565, 6248.625082495065, 6262.0, 6269.968650156766, 5786.594938354317, 5803.65178089316, 5804.158397032406, 5806.390329015392, 5874.9412505874825, 5186.818461353834, 5252.842414727565, 6297.905531417033, 6325.335839736854, 6382.393672601095, 5406.783728650888, 5894.0274854648505, 6118.0, 6132.386102851662, 6158.568900176944, 6123.831729140451, 6156.5998210117095, 6265.091561723524, 6269.185005949197, 6313.463355614824, 4830.492798256203, 6197.907031394533, 6203.16257305267, 6215.160953271341, 6228.128062071313, 6110.388961103854, 6124.0201567748945, 6166.075088736726, 6222.782202622887, 6225.439710426083, 4111.527174374985, 4923.556879880828, 4925.236588328815, 4934.235193545006, 4978.551930326215, 4377.5403582624, 4394.406755088086, 4404.955950440519, 4420.403245561873, 4455.465344158722, 4739.739314337676, 4773.952260477351, 5532.75102620383, 5683.26117604717, 5727.742251598688, 6218.440340369389, 6248.218972628421, 6264.2796078451565, 6271.404216599416, 6279.560430769802, 5782.161586570008, 5810.331811841608, 5831.950248955144, 5860.472557469848, 5904.822855314347, 5295.947040529622, 5374.435684253175, 5422.945770542323, 5437.755301011464, 5451.672899626035, 6370.1718776558755, 6381.202349706286, 6382.84042898922, 6393.552451328455, 6423.710933007932, 6294.559380843297, 6299.212598425197, 6301.558890877594, 6305.5901366411035, 6317.4630156437215, 6192.59748116371, 6294.433500984934, 6321.905171422524, 6405.583637063576, 6408.807735767934, 6174.536909731878, 6186.597871138361, 6228.0969259456215, 6235.0, 6242.469390101892, 6111.755529778759, 6252.9062064069985, 6261.0, 6267.968660156853, 6289.7169627365965, 6003.6397816131175, 6095.75616975316, 6128.167033931239, 6147.907781383194, 6154.78458253959, 6123.763538041328, 6127.816165515042, 6130.080487926846, 6157.84605384869, 6191.81424557264, 5732.082866741302, 5805.680687562224, 5980.671063091571, 5992.460678538952, 6005.429484198994, 4503.639708823352, 6125.235451818428, 6130.9716851690155, 6165.938340616716, 6177.073438984188, 6036.879262414639, 6046.518558252457, 6067.787627433018, 6089.695515224133, 6132.325444201045], "value": 5630.886256597648, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.9017886601546, 522.2323184918171, 566.4420545762426, 573.6185436684603, 635.0696230023016, 28659.856700716493, 30297.93957211498, 32864.34271314572, 33477.16307092324, 36991.075223119435, 37174.07064823374, 37737.62262377376, 37771.81114094432, 37824.86525404242, 37864.43203351952, 9233.95383023084, 9333.253339732804, 9392.81214375712, 9483.478408687519, 9513.28650351223, 9389.906100939006, 9406.811863762721, 9411.200047995928, 9420.057994200593, 9531.38046027006, 9403.247740180803, 9408.952955235214, 9471.81056378872, 9503.382280151802, 9567.569459374345, 8049.436539442212, 9503.477308748015, 9513.71458856232, 9560.139587437165, 9564.760880977961, 9356.90643093567, 9394.577244024036, 9423.48170850603, 9461.574229159703, 9509.286803489766, 36345.45481817775, 36510.45234321487, 36562.8171859139, 36586.451203231976, 36889.63110368889, 2482.2677310193535, 3027.7275045246033, 3228.1122691259857, 3293.86824527021, 3443.6212016678146, 25393.11124110647, 25478.23565293044, 25684.614730779052, 25700.357491062874, 26229.73770262292, 2555.3228394475477, 2594.5718956372175, 2632.605109233611, 2701.8784154713085, 2827.1659860341247, 323251.0, 323272.0, 326889.0, 327419.0, 379213.10393448133, 33338.3332333352, 33612.327753445286, 35081.298374032376, 35801.46297805535, 35889.92330230097, 28706.71293287082, 34445.65554344424, 34452.138696532784, 34796.78211262594, 35051.29897402038, 12291.877081229073, 14727.852721472647, 14802.111873287637, 14876.479323223635, 14919.328630211667, 10541.683749487527, 12256.44845981933, 12392.380380980914, 12499.3750312484, 12510.687232819253, 7937.880931786028, 8021.077576078826, 8024.317932975646, 8076.798080047928, 8123.512589244664, 2706.6346043284298, 3387.9153021174666, 3411.4541673332146, 3440.896773096811, 3446.2418267981, 1025.6256466389864, 1109.6449136276315, 1121.6018313498753, 1144.5193018932068, 1183.7928362536522, 25533.0, 28984.42031159365, 29628.259293517403, 29780.0, 29821.85089074584, 1023.518946095334, 1095.5453486803046, 1120.5461787975883, 1137.8748337682848, 1180.2564384437749, 14686.339114739862, 14763.0, 14796.18620975835, 14822.5903882909, 14841.035332703339, 559491.0, 565783.0, 577802.0, 579501.0, 579762.0, 57930.42069579334, 58036.709816450646, 58848.0, 58880.41119588835, 58916.11625825616, 1258.7734207842677, 1471.3746657670447, 1498.5354540092605, 1500.9024413413092, 1501.1668523969302, 23847.0, 26941.59587606148, 29687.0, 29921.70078299189, 29974.0, 59805.40194597998, 60357.491062723784, 60421.0, 61086.69456652688, 61228.0, 501769.4911525419, 525330.8667283348, 537925.0, 577729.0, 595802.0, 39172.41241381325, 44346.55653443424, 44636.55363446324, 45120.54879451295, 45706.31440528461, 47449.0, 47593.52406475891, 48281.75859120682, 48611.0, 49558.00883982372, 31002.0699379019, 34469.482957756154, 35029.47455788216, 39278.80360598179, 40354.1929161421, 38370.616293836705, 38466.615333846305, 39033.41449878311, 39172.2165556693, 39584.0, 32972.67027329792, 34941.0, 37983.0, 38688.2262354757, 38788.0, 31902.0, 33338.83314083995, 33754.493682595275, 33873.49189762205, 34016.0, 24570.63144052805, 25040.874795625907, 25074.62388064144, 25363.365915851515, 25651.743482565682], "value": 17988.09962146211, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.81445426696706, 357.58520116664675, 382.67855001798495, 389.29148948912956, 407.08406086305814, 18911.62176756464, 19603.313884014064, 20279.188832446685, 20699.275525356614, 20707.689384659225, 11770.941145294262, 12052.975497082758, 12053.39733013348, 12314.322712250845, 12378.628641140758, 4444.333349997502, 5431.592630552705, 5464.207689884965, 5465.535429488478, 5467.507924286813, 3956.2680904032704, 5789.681567513785, 6064.06007068905, 6137.324894261627, 6154.7230374633245, 5990.066423313986, 6075.149479072933, 6160.045192995093, 6173.259208894939, 6524.934750652504, 6684.966575167142, 6719.0593316935665, 6719.529632925697, 6733.494987875904, 6751.527393082486, 5629.268195134648, 5873.267386121104, 5968.641881487125, 6003.369646187175, 6023.728932198018, 12435.813462797974, 13226.272555009471, 13595.388207530685, 13641.181529108286, 13700.520481783089, 1961.646903557359, 1982.3557343863158, 2140.4006878074165, 2227.944301392462, 2230.085664877409, 9500.334976551645, 9910.0, 9911.152037032414, 11325.660230193107, 14092.647683807987, 1697.7792886924744, 1789.6241789224277, 1794.4078454110113, 1820.553964278747, 1906.9809301906942, 110015.0, 117257.99999999914, 124833.0, 126737.36631316876, 137582.0, 19853.801461985484, 20395.4901127473, 20640.0, 21419.143234270767, 21439.78560214378, 28764.424711505653, 29459.0, 35225.0, 35778.74774382884, 36046.0, 10265.178785697126, 10855.67432977012, 10866.0, 11123.555057797597, 11174.832377514344, 7340.4127669786285, 8898.332625053146, 9021.278297736297, 9056.818863622691, 9097.77255568616, 9875.358101723508, 9990.700278991642, 9994.150497207818, 10020.0, 10143.036411540892, 1671.3815888121474, 1874.7656542932134, 1986.7417235759258, 2000.899955002244, 2004.4387571480008, 876.2814492116531, 940.5156344482597, 982.9115379615869, 1050.1283934334483, 1070.325694812279, 16675.0, 16977.726670499764, 19824.90087549553, 20125.295614653416, 21075.946202689804, 958.1903291718487, 973.5083782689696, 986.5757724178673, 1001.2028409005707, 1017.3692310767372, 10702.250842440955, 10738.946305268424, 11150.944245278723, 11472.885271147348, 11534.826977595343, 523315.0, 564536.0, 629329.0, 633454.0, 636753.0, 80557.59721201357, 93286.0, 105309.47345263224, 125282.0, 147500.0, 981.259149342242, 1013.3565186106728, 1042.187094066614, 1060.3531845574194, 1061.8354155105972, 18096.72854907151, 18212.908935455238, 18482.907585461988, 18708.0, 18762.0, 41034.9741256459, 52596.0, 52846.471535285695, 53566.732166338916, 54746.0, 78046.0, 78756.42487150339, 78897.60551197207, 79012.0, 79930.0, 6668.333166683293, 7081.539699919489, 8012.118666946748, 8126.309263712651, 8201.343892488705, 11577.07383409342, 12336.321502317458, 12344.0, 12469.87771100569, 12643.747125057263, 10690.62582809598, 10785.676429707122, 10824.458777061272, 10871.184661150288, 11000.559977600806, 10226.130778883879, 10581.0, 10751.6236931707, 10803.945980270362, 11065.5020524075, 10835.458227088771, 11060.668179954615, 11121.221514493907, 11221.88778112241, 11424.600138995096, 7894.486858354188, 8128.0, 8135.38984576171, 8143.877841832259, 8376.371772117238, 9478.905210947802, 9874.802503949737, 10674.039336459757, 11123.499442525268, 11124.944375278072], "value": 9856.78491381704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.4066654671224, 1585.0885740699098, 1614.9354025838968, 1932.1112288347367, 1933.3813179782476, 7885.763427097182, 8361.0, 8571.357148213885, 8773.0, 8783.341249406296, 8446.746597602081, 8523.78690532737, 8582.442141260814, 8605.827883442327, 8630.18013288738, 2419.0444774314137, 2476.950460990779, 2480.541099896516, 2484.6769919910384, 2488.601823708207, 2394.353524548371, 2468.8024958003316, 2473.4434752180796, 2473.8268321217433, 2475.4430253192977, 2444.69441319835, 2451.938701532467, 2452.3746444656585, 2462.5321188974126, 2491.4643351679383, 2448.9142879999295, 2450.1179575352858, 2457.299669594157, 2476.529459402717, 2478.2069737683946, 2180.367693368921, 2233.0174723121772, 2361.913519780903, 2453.3621258472926, 2456.054419048679, 8177.632006559719, 8649.394542382035, 8714.389992700513, 8757.168069033496, 8862.601182946719, 4085.6935729820084, 5245.370555533361, 5275.841724748264, 5316.20256961455, 5327.014502317045, 3412.795232286046, 3553.9466907996402, 3630.963690363089, 3637.872674456408, 3657.8536858525626, 1009.7374682582508, 1031.39147902737, 1034.3948789957808, 1047.2041248651003, 1071.4535586850684, 11187.944060279646, 11188.160887933438, 11257.0, 11323.830142547951, 11402.71493212668, 3925.6270654287796, 4141.751494910255, 4687.367205427292, 4903.264510323409, 4948.678335908155, 9933.453660048766, 10048.547815348327, 10052.949735251277, 10072.74818129538, 10091.142252908438, 5398.973005135028, 5411.648242864201, 5447.5641948644015, 5465.398806131402, 5469.589780766459, 4295.699301048897, 4434.512203657595, 4513.803841981878, 4909.705417674951, 4948.802047918115, 3485.4074807282823, 3992.480977472968, 4044.69664775143, 4068.1253530491085, 4168.6039826216465, 1350.716349566602, 1367.7895062869432, 1368.0423703407655, 1377.1392879450343, 1380.6893448974101, 815.2581151152423, 892.794657228828, 913.5021413329752, 947.9431234125974, 962.2542529539522, 5300.92048619271, 5809.896119737236, 5812.88374232513, 5927.525797936241, 6039.456448919618, 463.6870112673932, 473.6092723503124, 476.7592365855221, 995.4723996281982, 1038.5564065948338, 3933.350997085506, 3981.203759248162, 4035.414864844581, 4057.6145266199665, 4106.979465102655, 9150.86273705895, 10259.333143345659, 10351.0, 10536.631217907336, 10707.250492465453, 10455.268131230892, 10550.0, 10589.629362972413, 10599.735006624896, 10679.83980240297, 8012.198780121941, 8096.757097287091, 8098.149694282133, 8162.918370816335, 8242.670293188325, 5767.826965191051, 5818.854528636818, 5912.940870591411, 5952.0476723724, 5961.314448838439, 9700.90299097, 9891.752706182404, 9936.304458688111, 9940.751481213027, 9943.900560994298, 8907.465552066897, 9449.952750236205, 9846.261530385107, 10067.194624430174, 10160.796784064425, 6480.935190648033, 6834.453243740588, 7015.36861682451, 7061.2585678505075, 7136.964315178391, 8170.305524030524, 8258.29804466603, 8307.127751586238, 8361.289290410139, 8361.623726932425, 7309.963450182715, 7426.628668566442, 7477.962610186914, 7588.747856603599, 7615.847683046419, 7213.20654727969, 7298.744543940937, 7302.817429564091, 7350.963245183953, 7467.663955121924, 6872.450203983769, 6884.931150688429, 6943.958406239105, 6961.965190174017, 7020.824479388056, 3859.942100868433, 4900.754962251802, 4902.338184345068, 5000.2499625056535, 5124.5131712487255, 3868.9553820467754, 4613.2388155953895, 4858.076965376567, 4989.226669866177, 5333.626646134733], "value": 4533.27970894969, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.7329616418372, 323.2113642711783, 330.18773816411624, 331.3075671845842, 331.83408295852075, 5164.870878228042, 5297.827890564869, 5777.566682498814, 6200.410960958701, 6203.813885583429, 5326.760295786689, 5333.0, 6108.3586223446455, 6127.601705889125, 6130.938690613092, 4184.560621134783, 4400.6919515634, 4523.321501774727, 4627.652926030544, 4633.745144017078, 4042.2117687051036, 4566.2648193686355, 4664.160451118797, 4664.580187783099, 4665.976670116662, 3743.513343265385, 3783.2244389900143, 4433.88915277119, 4607.746573938449, 4635.837745678902, 3864.903377415559, 4607.723536587782, 4622.352870598119, 4625.861224163281, 4637.907241855178, 3343.164208947763, 3980.681545476355, 4010.5588385277597, 4014.197160567898, 4032.931273212601, 5889.823305300848, 5903.5572332074835, 5922.496587790043, 5925.34821169671, 5931.733072011727, 5297.496737809902, 5366.91949620756, 5405.837824865221, 5414.07960646691, 5429.755660995264, 5055.797768089309, 6463.93536064638, 6506.1216735740545, 6510.0, 6531.510136739716, 3559.501669766235, 4055.371417430303, 4304.504981927087, 4418.497710778319, 4452.710573812692, 6870.31296870314, 6939.299871531457, 6949.0, 6967.512274140812, 6977.40692041172, 3803.372443546784, 4516.254817955, 4532.9320060199125, 4533.184026875128, 4562.58936695699, 3625.3655610268097, 3714.832832522543, 4435.377271434895, 4627.305904114342, 4678.883027924261, 5205.765740541685, 5374.91937620936, 5386.676799392049, 5419.783208671609, 5438.428964958702, 5971.4924231439945, 6077.997130473511, 6365.49076073913, 6562.278149403562, 6606.942889137715, 1315.8946484952685, 1427.3648226539299, 1430.7853821926794, 1525.7101150781316, 1532.0501289200768, 835.9331253499705, 923.3536524432924, 975.11264749078, 981.3326937682302, 997.9800403992025, 4700.741459219707, 4957.479464656231, 4969.800518561809, 6062.0, 6072.119542666289, 6129.540284478682, 6223.910815607341, 6481.8703625928165, 6718.66406679664, 6766.086578311963, 5333.599980001515, 5349.839504814861, 5762.30852297727, 5946.672932988728, 6147.784827531015, 3309.7517686173633, 3577.3024260269517, 3577.874774382884, 3584.8386822593047, 3595.748297619194, 6290.339514350927, 6764.83087922796, 7143.821134553907, 7198.992141100251, 7201.711931522786, 6126.95841706902, 6657.800265992028, 7011.228764835864, 7048.295170482911, 7049.929500704928, 6855.046311801212, 6926.965365173141, 7066.646667666596, 7154.570725756471, 7197.640117994184, 5679.204911312502, 6425.710843012169, 6485.189351331083, 6649.301823308483, 6847.417969472651, 6513.218413790186, 6703.597784132968, 6744.831379215559, 6758.628275444898, 6771.322867713189, 7147.49772611427, 7172.820679482846, 7187.820304492429, 7190.604516751629, 7195.13658361, 4333.956660433355, 4377.212101821703, 4385.780710964503, 4392.231359512137, 4410.669199809963, 6607.603543787388, 6633.867322653617, 6793.592384456949, 6813.591184528944, 6834.794956151323, 5725.312962444534, 5885.911711324419, 6274.905876411766, 6562.573432726857, 6573.375529324707, 5476.7535460903555, 6388.872222555616, 6552.049952756919, 6568.0, 6606.4384527315715, 6128.938710612837, 6203.16257305258, 6433.356664333529, 6447.6453795041725, 6449.903251451138, 6579.243387010363, 6603.471722262304, 6614.338566143347, 6614.900776488453, 6623.072769812326, 6149.96925015372, 6209.627422354673, 6421.8715625688155, 6451.96768517035, 6480.935190648222], "value": 4712.835411997754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.2615506642875, 673.1686367336339, 750.5871770526211, 777.3314949143736, 782.4679218131672, 14601.0, 14831.703365932675, 14860.108393496397, 15031.699366012674, 15164.84835151648, 13948.511802086929, 13966.0, 14002.509912153077, 14028.78956815646, 14084.647883802885, 753.2768542199495, 929.4655573045492, 944.0700909604035, 944.551338114397, 944.9291303152291, 908.4867050116696, 944.9952750236239, 945.4941606240662, 946.2760987844305, 947.7109481608106, 970.1123472023125, 973.8393165127745, 974.9610015599368, 975.4781192062248, 978.2907392140677, 918.8070505193884, 962.1052421248237, 965.3869792681628, 966.6858271061863, 966.8018056298442, 693.719043787267, 721.8195451137216, 721.8303698630817, 726.9491135620509, 728.1226122522401, 14567.0, 14575.781363279559, 14660.266986650731, 14959.700805983819, 15059.0, 12654.493820247179, 12686.619401417973, 12706.110572259946, 12850.678733031655, 12927.09510334277, 10015.39907605546, 10121.291509594332, 10192.796144077078, 10387.324823886498, 10555.683329500127, 3451.2062225687982, 3484.7212223022098, 3500.1424650960676, 3517.9472307915403, 3589.569251689788, 83598.58200708957, 85534.0, 85887.14112858854, 86240.70638940421, 86607.0, 7826.256505631955, 9409.0, 9449.952750236205, 9615.759606009906, 10244.59021639141, 20708.068136933874, 20873.060712267983, 20913.89543052275, 20921.163153473994, 20935.47661308449, 7351.22812104732, 7480.9625951870985, 7486.438517111238, 7494.5503269803985, 7547.50941188821, 3539.4513850352723, 4157.459530261047, 4186.225548273518, 4208.473940757405, 4296.9570304297185, 3432.8111953842777, 3505.0536355184468, 3506.0, 3528.59421166564, 3544.2379888323617, 610.8900397928415, 635.9427651511294, 638.8626445314279, 640.711679744117, 651.4267444648679, 467.6913237263458, 510.9259157422153, 522.9215617657381, 568.2300482845685, 579.2296245992819, 7696.307332340116, 7707.036620422447, 7761.417893657998, 7909.0, 8106.0, 486.8028448478372, 505.3228673577424, 524.3655177235512, 539.2773683264367, 562.1960596347262, 3068.8465576721073, 3151.7636177286795, 3650.9737095912024, 3658.0123366690846, 3786.3184626766897, 93123.53438232902, 93707.0, 95758.0, 95879.52060239654, 96351.0, 44943.77528112404, 58254.7087264561, 75847.0, 78219.60890195626, 79398.80901786478, 67870.32129678837, 69225.96161057588, 69877.0, 69933.0, 70227.0, 6296.338884417253, 7289.161746399232, 7343.77968660941, 7633.618319083913, 7656.310932016146, 43199.784001079795, 44833.55166448424, 45032.87417814581, 45053.0, 45234.416795412006, 62403.0, 62967.0, 63410.0, 63635.681821590595, 63997.0, 9491.193248573672, 9525.714228573153, 9599.952000239955, 9704.417734935927, 9799.902000979899, 10105.39367637944, 10367.963203679572, 10390.532426040998, 10646.73383165427, 10655.30740501865, 9763.36538125048, 9966.651167209113, 9999.15007224365, 10029.849552256575, 10057.29598928068, 10005.599776008878, 10006.0, 10026.398416095057, 10225.233107517115, 10946.835797463204, 9068.957069937042, 9969.00594427066, 9981.650642227487, 10582.682519524427, 11079.83380249313, 7991.640376183202, 9542.522873856418, 9572.712818615453, 9641.758956026155, 9809.215262779104, 9297.709238357871, 9332.813343733224, 9589.568469418753, 9630.181434578139, 9708.5145742714], "value": 6650.875968471823, "unit": "score"}], "name": "dromaeo_css", "value": 7221.899699717652, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 157.0, 97.0, 168.0, 173.0, 183.0, 115.0, 117.0, 115.0, 117.0], "value": 139.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 162.0, 191.0, 125.0, 171.0, 176.0, 153.0, 171.0, 124.0], "value": 157.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 213.0, 223.0, 282.0, 185.0, 187.0, 245.0, 185.0, 273.0, 204.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 75.0, 145.0, 84.0, 81.0, 81.0, 93.0, 125.0, 57.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 184.0, 105.0, 81.0, 84.0, 136.0, 116.0, 121.0, 148.0], "value": 114.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 129.0, 89.0, 94.0, 120.0, 86.0, 92.0, 86.0, 79.0, 130.0], "value": 104.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 113.0, 127.0, 96.0, 123.0, 148.0, 97.0, 95.0, 96.0, 104.0], "value": 109.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 91.0, 92.0, 92.0, 89.0, 92.0, 91.0, 94.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 62.0, 65.0, 62.0, 62.0, 79.0, 64.0, 60.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 71.0, 74.0, 70.0, 67.0, 77.0, 99.0, 100.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 85.0, 83.0, 86.0, 86.0, 84.0, 86.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 158.0, 131.0, 143.0, 158.0, 157.0, 162.0, 185.0, 168.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 243.0, 230.0, 184.0, 191.0, 227.0, 189.0, 170.0, 171.0, 173.0], "value": 194.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 70.0, 70.0, 66.0, 75.0, 71.0, 99.0, 72.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1670.5000000000002, "alertThreshold": 2.0}]} 03:52:01 INFO - Return code: 0 03:52:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:52:01 ERROR - # TBPL SUCCESS # 03:52:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:52:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:52:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:52:01 INFO - 2016-04-16 03:52:01.336 screenresolution[1715:11355] starting screenresolution argv=screenresolution get 03:52:01 INFO - 2016-04-16 03:52:01.339 screenresolution[1715:11355] Display 0: 1600x1200x32@60 03:52:01 INFO - 2016-04-16 03:52:01.345 screenresolution[1716:11358] starting screenresolution argv=screenresolution list 03:52:01 INFO - Available Modes on Display 0 03:52:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:52:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:52:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:52:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:52:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:52:01 INFO - Intel Iris: 03:52:01 INFO - Chipset Model: Intel Iris 03:52:01 INFO - Type: GPU 03:52:01 INFO - Bus: Built-In 03:52:01 INFO - VRAM (Dynamic, Max): 1536 MB 03:52:01 INFO - Vendor: Intel (0x8086) 03:52:01 INFO - Device ID: 0x0a2e 03:52:01 INFO - Revision ID: 0x0009 03:52:01 INFO - Displays: 03:52:01 INFO - Display: 03:52:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:52:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:52:01 INFO - Display Serial Number: 1600x1200 60 03:52:01 INFO - Main Display: Yes 03:52:01 INFO - Mirror: Off 03:52:01 INFO - Online: Yes 03:52:01 INFO - Rotation: Supported 03:52:01 INFO - Return code: 0 03:52:01 INFO - Running post-action listener: _resource_record_post_action 03:52:01 INFO - Running post-run listener: _resource_record_post_run 03:52:02 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32620032; Write bytes: 698319872; Read time: 330; Write time: 2204 03:52:02 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 392135168; Write bytes: 386504704; Read time: 29857; Write time: 764 03:52:02 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30838272; Write bytes: 300518400; Read time: 311; Write time: 1409 03:52:02 INFO - Running post-run listener: _upload_blobber_files 03:52:02 INFO - Blob upload gear active. 03:52:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:52:02 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:52:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:52:03 INFO - (blobuploader) - INFO - Open directory for files ... 03:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:52:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:03 INFO - (blobuploader) - INFO - Done attempting. 03:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:52:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:04 INFO - (blobuploader) - INFO - Done attempting. 03:52:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:52:04 INFO - Return code: 0 03:52:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:52:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:52:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f1a520cfa9c3856ba89711a835d08ad2ae90c0928db93e969c74c96473f3f60be87f3379db1b0db35c2d9d571a745e041959df15b2c1c34eb4f90a66b606525", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde304c2efe098a996f7c489707664a467846659f8773f418873ddd393941fcd808e5bc58331f655627335181aa5916ae3fae600238054cc68826c108f4fa932"} 03:52:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:52:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:52:04 INFO - Contents: 03:52:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f1a520cfa9c3856ba89711a835d08ad2ae90c0928db93e969c74c96473f3f60be87f3379db1b0db35c2d9d571a745e041959df15b2c1c34eb4f90a66b606525", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde304c2efe098a996f7c489707664a467846659f8773f418873ddd393941fcd808e5bc58331f655627335181aa5916ae3fae600238054cc68826c108f4fa932"} 03:52:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:52:04 INFO - Copying logs to upload dir... 03:52:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:52:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1357.021647 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 37 secs) (at 2016-04-16 03:52:04.698868) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 03:52:04.702019) ========= 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.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f1a520cfa9c3856ba89711a835d08ad2ae90c0928db93e969c74c96473f3f60be87f3379db1b0db35c2d9d571a745e041959df15b2c1c34eb4f90a66b606525", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde304c2efe098a996f7c489707664a467846659f8773f418873ddd393941fcd808e5bc58331f655627335181aa5916ae3fae600238054cc68826c108f4fa932"} build_url:https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011017 build_url: 'https://queue.taskcluster.net/v1/task/F7zPas0STdGf2qgzb5HyOQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f1a520cfa9c3856ba89711a835d08ad2ae90c0928db93e969c74c96473f3f60be87f3379db1b0db35c2d9d571a745e041959df15b2c1c34eb4f90a66b606525", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde304c2efe098a996f7c489707664a467846659f8773f418873ddd393941fcd808e5bc58331f655627335181aa5916ae3fae600238054cc68826c108f4fa932"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 03:52:04.728954) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:52:04.729257) ========= 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.WljiKTJyun/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.7qPqzouMnz/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.005008 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 03:52:04.777841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 03:52:04.778156) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 03:52:04.778583) ========= ========= Total master_lag: 0.16 =========