builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0228 starttime: 1467237607.44 results: success (0) buildid: 20160629130426 builduid: a895d54ff7bf40d0a65922a67a781542 revision: ee43fafecf41bc2048b100e1fe2e272144884d19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.438949) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.439373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.439658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.475576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.475956) ========= 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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-06-29 15:00:07-- 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% 18.9M=0.001s 2016-06-29 15:00:07 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085783 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.586945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.587467) ========= 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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.031226 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:07.639545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 15:00:07.642950) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ee43fafecf41bc2048b100e1fe2e272144884d19 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ee43fafecf41bc2048b100e1fe2e272144884d19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-06-29 15:00:07,722 truncating revision to first 12 chars 2016-06-29 15:00:07,722 Setting DEBUG logging. 2016-06-29 15:00:07,722 attempt 1/10 2016-06-29 15:00:07,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ee43fafecf41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 15:00:08,820 unpacking tar archive at: mozilla-beta-ee43fafecf41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.418562 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 15:00:09.083091) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:09.083415) ========= script_repo_revision: ee43fafecf41bc2048b100e1fe2e272144884d19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:09.083838) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:09.084137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 15:00:09.102258) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 15:00:09.102561) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:00:09 INFO - MultiFileLogger online at 20160629 15:00:09 in /builds/slave/test 15:00:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:00:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:00:09 INFO - {'append_to_log': False, 15:00:09 INFO - 'base_work_dir': '/builds/slave/test', 15:00:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:00:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:00:09 INFO - 'branch': 'Mozilla-Beta', 15:00:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:00:09 INFO - 'config_files': ('talos/mac_config.py',), 15:00:09 INFO - 'default_actions': ('clobber', 15:00:09 INFO - 'read-buildbot-config', 15:00:09 INFO - 'download-and-extract', 15:00:09 INFO - 'populate-webroot', 15:00:09 INFO - 'create-virtualenv', 15:00:09 INFO - 'install', 15:00:09 INFO - 'run-tests'), 15:00:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:00:09 INFO - 'download_minidump_stackwalk': True, 15:00:09 INFO - 'download_symbols': 'ondemand', 15:00:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:00:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:00:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:00:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:00:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:00:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:00:09 INFO - 'installer_path': 'installer.exe', 15:00:09 INFO - 'log_level': 'info', 15:00:09 INFO - 'log_name': 'talos', 15:00:09 INFO - 'log_to_console': True, 15:00:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:00:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:00:09 INFO - 'opt_config_files': (), 15:00:09 INFO - 'pip_index': False, 15:00:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:00:09 INFO - 'cmd': ('bash', 15:00:09 INFO - '-c', 15:00:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:00:09 INFO - 'enabled': True, 15:00:09 INFO - 'halt_on_failure': False, 15:00:09 INFO - 'name': 'check_screen_resolution'},), 15:00:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:00:09 INFO - 'cmd': ('bash', 15:00:09 INFO - '-c', 15:00:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:00:09 INFO - 'enabled': True, 15:00:09 INFO - 'halt_on_failure': False, 15:00:09 INFO - 'name': 'check_screen_resolution'},), 15:00:09 INFO - 'run_cmd_checks_enabled': True, 15:00:09 INFO - 'sps_profile': False, 15:00:09 INFO - 'sps_profile_interval': 0, 15:00:09 INFO - 'suite': 'dromaeojs-e10s', 15:00:09 INFO - 'system_bits': '32', 15:00:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:00:09 INFO - 'title': 't-yosemite-r7-0228', 15:00:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:00:09 INFO - 'use_talos_json': True, 15:00:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:00:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:00:09 INFO - 'work_dir': 'build'} 15:00:09 INFO - ##### 15:00:09 INFO - ##### Running clobber step. 15:00:09 INFO - ##### 15:00:09 INFO - Running pre-action listener: _resource_record_pre_action 15:00:09 INFO - Running main action method: clobber 15:00:09 INFO - rmtree: /builds/slave/test/build 15:00:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:00:11 INFO - Running post-action listener: _resource_record_post_action 15:00:11 INFO - ##### 15:00:11 INFO - ##### Running read-buildbot-config step. 15:00:11 INFO - ##### 15:00:11 INFO - Running pre-action listener: _resource_record_pre_action 15:00:11 INFO - Running main action method: read_buildbot_config 15:00:11 INFO - Using buildbot properties: 15:00:11 INFO - { 15:00:11 INFO - "project": "", 15:00:11 INFO - "product": "firefox", 15:00:11 INFO - "script_repo_revision": "production", 15:00:11 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:00:11 INFO - "repository": "", 15:00:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:00:11 INFO - "buildid": "20160629130426", 15:00:11 INFO - "pgo_build": "False", 15:00:11 INFO - "basedir": "/builds/slave/test", 15:00:11 INFO - "buildnumber": 9, 15:00:11 INFO - "slavename": "t-yosemite-r7-0228", 15:00:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:00:11 INFO - "platform": "yosemite_r7", 15:00:11 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:00:11 INFO - "branch": "mozilla-beta", 15:00:11 INFO - "repo_path": "releases/mozilla-beta", 15:00:11 INFO - "revision": "ee43fafecf41bc2048b100e1fe2e272144884d19", 15:00:11 INFO - "stage_platform": "macosx64", 15:00:11 INFO - "builduid": "a895d54ff7bf40d0a65922a67a781542", 15:00:11 INFO - "slavebuilddir": "test" 15:00:11 INFO - } 15:00:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 15:00:11 INFO - Running post-action listener: _resource_record_post_action 15:00:11 INFO - ##### 15:00:11 INFO - ##### Running download-and-extract step. 15:00:11 INFO - ##### 15:00:11 INFO - Running pre-action listener: _resource_record_pre_action 15:00:11 INFO - Running main action method: download_and_extract 15:00:11 INFO - mkdir: /builds/slave/test/build/tests 15:00: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')]} 15:00:11 INFO - https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:00:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:00:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:00:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 15:00:12 INFO - Downloaded 1165 bytes. 15:00:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:00:12 INFO - Using the following test package requirements: 15:00:12 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:00:12 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 15:00:12 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 15:00:12 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:00:12 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 15:00:12 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:00:12 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 15:00:12 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 15:00:12 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 15:00:12 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:00:12 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 15:00:12 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 15:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:12 INFO - https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:00:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:00:20 INFO - Downloaded 18350391 bytes. 15:00:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:00:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:00:23 INFO - Return code: 0 15:00:23 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:23 INFO - https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:00:24 INFO - Downloaded 18350391 bytes. 15:00:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:00:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:00:27 INFO - Return code: 0 15:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:27 INFO - https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 15:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 15:00:32 INFO - Downloaded 10840145 bytes. 15:00:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:00:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:00:33 INFO - Return code: 0 15:00:33 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')]} 15:00:33 INFO - https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:00:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:00:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:00:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 15:00:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 15:01:02 INFO - Downloaded 86198887 bytes. 15:01:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:01:02 INFO - mkdir: /builds/slave/test/properties 15:01:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:01:02 INFO - Writing to file /builds/slave/test/properties/build_url 15:01:02 INFO - Contents: 15:01:02 INFO - build_url:https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:01:04 INFO - Running post-action listener: _resource_record_post_action 15:01:04 INFO - Running post-action listener: set_extra_try_arguments 15:01:04 INFO - ##### 15:01:04 INFO - ##### Running populate-webroot step. 15:01:04 INFO - ##### 15:01:04 INFO - Running pre-action listener: _resource_record_pre_action 15:01:04 INFO - Running main action method: populate_webroot 15:01:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:01:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:01:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:01:04 INFO - '--tppagecycles', 15:01:04 INFO - '7'], 15:01:04 INFO - 'tests': ['tsvgm']}}, 15:01:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:01:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:01:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:01:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:01:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['damp', 'tps']}, 15:01:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'tests': ['damp', 'tps']}, 15:01:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['dromaeo_dom']}, 15:01:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:01:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['a11yr', 15:01:04 INFO - 'ts_paint', 15:01:04 INFO - 'tpaint', 15:01:04 INFO - 'sessionrestore', 15:01:04 INFO - 'sessionrestore_no_auto_restore', 15:01:04 INFO - 'tabpaint']}, 15:01:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['tsvgx', 15:01:04 INFO - 'tsvgr_opacity', 15:01:04 INFO - 'tart', 15:01:04 INFO - 'tscrollx', 15:01:04 INFO - 'cart']}, 15:01:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:01:04 INFO - 'tsvgr_opacity', 15:01:04 INFO - 'tart', 15:01:04 INFO - 'tscrollx', 15:01:04 INFO - 'cart']}, 15:01:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'talos_options': ['--disable-e10s'], 15:01:04 INFO - 'tests': ['tp5o']}, 15:01:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'tests': ['tp5o']}, 15:01:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'talos_options': ['--disable-e10s', 15:01:04 INFO - '--xperf_path', 15:01:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:04 INFO - 'tests': ['tp5n']}, 15:01:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:04 INFO - 'talos_options': ['--xperf_path', 15:01:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:04 INFO - 'tests': ['tp5n']}}, 15:01:04 INFO - 'talos.zip': {'path': '', 15:01:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:01:04 INFO - Running post-action listener: _resource_record_post_action 15:01:04 INFO - ##### 15:01:04 INFO - ##### Running create-virtualenv step. 15:01:04 INFO - ##### 15:01:04 INFO - Running pre-action listener: _resource_record_pre_action 15:01:04 INFO - Running main action method: create_virtualenv 15:01:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:01:04 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 15:01:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:01:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:01:04 INFO - Using real prefix '/tools/python27' 15:01:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:01:05 INFO - Installing distribute.............................................................................................................................................................................................done. 15:01:08 INFO - Installing pip.................done. 15:01:08 INFO - Return code: 0 15:01:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:08 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:08 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 15:01:08 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 15:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:08 INFO - 'HOME': '/Users/cltbld', 15:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:08 INFO - 'LOGNAME': 'cltbld', 15:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:08 INFO - 'MOZ_NO_REMOTE': '1', 15:01:08 INFO - 'NO_EM_RESTART': '1', 15:01:08 INFO - 'PAGER': '/bin/cat', 15:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:08 INFO - 'PWD': '/builds/slave/test', 15:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:08 INFO - 'SHELL': '/bin/bash', 15:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:08 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:08 INFO - 'USER': 'cltbld', 15:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:08 INFO - 'XPC_FLAGS': '0x0', 15:01:08 INFO - 'XPC_SERVICE_NAME': '0', 15:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:08 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 15:01:08 INFO - Cleaning up... 15:01:08 INFO - Return code: 0 15:01:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:08 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:08 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 15:01:08 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 15:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:08 INFO - 'HOME': '/Users/cltbld', 15:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:08 INFO - 'LOGNAME': 'cltbld', 15:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:08 INFO - 'MOZ_NO_REMOTE': '1', 15:01:08 INFO - 'NO_EM_RESTART': '1', 15:01:08 INFO - 'PAGER': '/bin/cat', 15:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:08 INFO - 'PWD': '/builds/slave/test', 15:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:08 INFO - 'SHELL': '/bin/bash', 15:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:08 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:08 INFO - 'USER': 'cltbld', 15:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:08 INFO - 'XPC_FLAGS': '0x0', 15:01:08 INFO - 'XPC_SERVICE_NAME': '0', 15:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:09 INFO - Downloading/unpacking psutil>=0.7.1 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:09 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 15:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:01:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:12 INFO - Installing collected packages: psutil 15:01:12 INFO - Running setup.py install for psutil 15:01:12 INFO - building 'psutil._psutil_osx' extension 15:01:12 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 15:01:13 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 15:01:13 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 15:01:13 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 15:01:13 INFO - building 'psutil._psutil_posix' extension 15:01:13 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 15:01:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:01:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:01:13 INFO - ^ 15:01:13 INFO - 1 warning generated. 15:01:13 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 15:01:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:13 INFO - Successfully installed psutil 15:01:13 INFO - Cleaning up... 15:01:13 INFO - Return code: 0 15:01:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:01:13 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')]} 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:13 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')]} 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:13 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:13 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 15:01:13 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 15:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:13 INFO - 'HOME': '/Users/cltbld', 15:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:13 INFO - 'LOGNAME': 'cltbld', 15:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:13 INFO - 'MOZ_NO_REMOTE': '1', 15:01:13 INFO - 'NO_EM_RESTART': '1', 15:01:13 INFO - 'PAGER': '/bin/cat', 15:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:13 INFO - 'PWD': '/builds/slave/test', 15:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:13 INFO - 'SHELL': '/bin/bash', 15:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:13 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:13 INFO - 'USER': 'cltbld', 15:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:13 INFO - 'XPC_FLAGS': '0x0', 15:01:13 INFO - 'XPC_SERVICE_NAME': '0', 15:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:01:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:01:16 INFO - Installing collected packages: mozsystemmonitor 15:01:16 INFO - Running setup.py install for mozsystemmonitor 15:01:16 INFO - Successfully installed mozsystemmonitor 15:01:16 INFO - Cleaning up... 15:01:16 INFO - Return code: 0 15:01:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:01:16 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')]} 15:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:16 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')]} 15:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:16 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:16 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 15:01:16 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 15:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:16 INFO - 'HOME': '/Users/cltbld', 15:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:16 INFO - 'LOGNAME': 'cltbld', 15:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:16 INFO - 'MOZ_NO_REMOTE': '1', 15:01:16 INFO - 'NO_EM_RESTART': '1', 15:01:16 INFO - 'PAGER': '/bin/cat', 15:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:16 INFO - 'PWD': '/builds/slave/test', 15:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:16 INFO - 'SHELL': '/bin/bash', 15:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:16 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:16 INFO - 'USER': 'cltbld', 15:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:16 INFO - 'XPC_FLAGS': '0x0', 15:01:16 INFO - 'XPC_SERVICE_NAME': '0', 15:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:01:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:01:19 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 15:01:19 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 15:01:19 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 15:01:19 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 15:01:19 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 15:01:19 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 15:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:01:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:01:20 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 15:01:20 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 15:01:20 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 15:01:20 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 15:01:20 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 15:01:20 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 15:01:20 INFO - Downloading docopt-0.6.1.tar.gz 15:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:01:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:01:20 INFO - Running setup.py install for blobuploader 15:01:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:01:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:01:20 INFO - Running setup.py install for requests 15:01:21 INFO - Running setup.py install for docopt 15:01:21 INFO - Successfully installed blobuploader requests docopt 15:01:21 INFO - Cleaning up... 15:01:21 INFO - Return code: 0 15:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:21 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:21 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 15:01:21 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 15:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:21 INFO - 'HOME': '/Users/cltbld', 15:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:21 INFO - 'LOGNAME': 'cltbld', 15:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:21 INFO - 'MOZ_NO_REMOTE': '1', 15:01:21 INFO - 'NO_EM_RESTART': '1', 15:01:21 INFO - 'PAGER': '/bin/cat', 15:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:21 INFO - 'PWD': '/builds/slave/test', 15:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:21 INFO - 'SHELL': '/bin/bash', 15:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:21 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:21 INFO - 'USER': 'cltbld', 15:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:21 INFO - 'XPC_FLAGS': '0x0', 15:01:21 INFO - 'XPC_SERVICE_NAME': '0', 15:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:01:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9S7vvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:01:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-6M_V41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-40Kod4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-MXvJ_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-rPdB4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QIbS5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-c2DmQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-1luBKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-A_HiwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-IUEZvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-m3xg7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:01:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-UZ6NMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:01:23 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ndXAnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:01:23 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7R3EKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:23 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ZxVrrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:01:23 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-txoQCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:01:23 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Dxk5zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:01:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:01:23 INFO - Running setup.py install for manifestparser 15:01:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:01:23 INFO - Running setup.py install for mozcrash 15:01:23 INFO - Running setup.py install for mozdebug 15:01:23 INFO - Running setup.py install for mozdevice 15:01:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Running setup.py install for mozfile 15:01:24 INFO - Running setup.py install for mozhttpd 15:01:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Running setup.py install for mozinfo 15:01:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Running setup.py install for mozInstall 15:01:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:01:24 INFO - Running setup.py install for mozleak 15:01:24 INFO - Running setup.py install for mozlog 15:01:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Running setup.py install for moznetwork 15:01:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Running setup.py install for mozprocess 15:01:25 INFO - Running setup.py install for mozprofile 15:01:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Running setup.py install for mozrunner 15:01:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:01:25 INFO - Running setup.py install for mozscreenshot 15:01:25 INFO - Running setup.py install for moztest 15:01:25 INFO - Running setup.py install for mozversion 15:01:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:01:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:01:26 INFO - Cleaning up... 15:01:26 INFO - Return code: 0 15:01:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:01: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')]} 15:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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')]} 15:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:26 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:26 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 15:01:26 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 15:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:26 INFO - 'HOME': '/Users/cltbld', 15:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:26 INFO - 'LOGNAME': 'cltbld', 15:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:26 INFO - 'MOZ_NO_REMOTE': '1', 15:01:26 INFO - 'NO_EM_RESTART': '1', 15:01:26 INFO - 'PAGER': '/bin/cat', 15:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:26 INFO - 'PWD': '/builds/slave/test', 15:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:26 INFO - 'SHELL': '/bin/bash', 15:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:26 INFO - 'USER': 'cltbld', 15:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:26 INFO - 'XPC_FLAGS': '0x0', 15:01:26 INFO - 'XPC_SERVICE_NAME': '0', 15:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yjDT1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:01:26 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)) 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9D5PfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:01:26 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)) 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-8aUoED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:01:26 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)) 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PzOpuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:01:26 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)) 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-oZvxOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:01:26 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)) 15:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:01:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Ww3fQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-EGpy7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-r_ACCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kwTnDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yMEGAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-DskqLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Z4oFBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ezqVGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ruvttx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:01:27 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)) 15:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gF_myZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:28 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)) 15:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:01:28 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ZwHRqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:01:28 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)) 15:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:01:28 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-GD3sgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:01:28 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)) 15:01:28 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)) 15:01:28 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)) 15:01:28 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)) 15:01:28 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)) 15:01:28 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)) 15:01:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01:30 INFO - Downloading blessings-1.6.tar.gz 15:01:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:01:30 INFO - Installing collected packages: blessings 15:01:30 INFO - Running setup.py install for blessings 15:01:30 INFO - Successfully installed blessings 15:01:30 INFO - Cleaning up... 15:01:31 INFO - Return code: 0 15:01:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:01:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:01:31 INFO - Reading from file tmpfile_stdout 15:01:31 INFO - Current package versions: 15:01:31 INFO - blessings == 1.6 15:01:31 INFO - blobuploader == 1.2.4 15:01:31 INFO - docopt == 0.6.1 15:01:31 INFO - manifestparser == 1.1 15:01:31 INFO - mozInstall == 1.12 15:01:31 INFO - mozcrash == 0.17 15:01:31 INFO - mozdebug == 0.1 15:01:31 INFO - mozdevice == 0.48 15:01:31 INFO - mozfile == 1.2 15:01:31 INFO - mozhttpd == 0.7 15:01:31 INFO - mozinfo == 0.9 15:01:31 INFO - mozleak == 0.1 15:01:31 INFO - mozlog == 3.1 15:01:31 INFO - moznetwork == 0.27 15:01:31 INFO - mozprocess == 0.22 15:01:31 INFO - mozprofile == 0.28 15:01:31 INFO - mozrunner == 6.11 15:01:31 INFO - mozscreenshot == 0.1 15:01:31 INFO - mozsystemmonitor == 0.0 15:01:31 INFO - moztest == 0.7 15:01:31 INFO - mozversion == 1.4 15:01:31 INFO - psutil == 3.1.1 15:01:31 INFO - requests == 1.2.3 15:01:31 INFO - wsgiref == 0.1.2 15:01:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:31 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:31 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 15:01:31 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 15:01:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:31 INFO - 'HOME': '/Users/cltbld', 15:01:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:31 INFO - 'LOGNAME': 'cltbld', 15:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:31 INFO - 'MOZ_NO_REMOTE': '1', 15:01:31 INFO - 'NO_EM_RESTART': '1', 15:01:31 INFO - 'PAGER': '/bin/cat', 15:01:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:31 INFO - 'PWD': '/builds/slave/test', 15:01:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:31 INFO - 'SHELL': '/bin/bash', 15:01:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:31 INFO - 'USER': 'cltbld', 15:01:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:31 INFO - 'XPC_FLAGS': '0x0', 15:01:31 INFO - 'XPC_SERVICE_NAME': '0', 15:01:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 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)) 15:01:31 INFO - Cleaning up... 15:01:31 INFO - Return code: 0 15:01:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:01:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:31 INFO - 'HOME': '/Users/cltbld', 15:01:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:31 INFO - 'LOGNAME': 'cltbld', 15:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:31 INFO - 'MOZ_NO_REMOTE': '1', 15:01:31 INFO - 'NO_EM_RESTART': '1', 15:01:31 INFO - 'PAGER': '/bin/cat', 15:01:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:31 INFO - 'PWD': '/builds/slave/test', 15:01:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:31 INFO - 'SHELL': '/bin/bash', 15:01:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:31 INFO - 'USER': 'cltbld', 15:01:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:31 INFO - 'XPC_FLAGS': '0x0', 15:01:31 INFO - 'XPC_SERVICE_NAME': '0', 15:01:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:31 INFO - Downloading/unpacking jsonschema 15:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:01:34 INFO - Downloading/unpacking functools32 (from jsonschema) 15:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:34 INFO - Downloading functools32-3.2.3-2.tar.gz 15:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:01:34 INFO - warning: no files found matching '*.txt' 15:01:34 INFO - no previously-included directories found matching 'build' 15:01:34 INFO - no previously-included directories found matching 'dist' 15:01:34 INFO - no previously-included directories found matching '.git*' 15:01:34 INFO - Installing collected packages: jsonschema, functools32 15:01:34 INFO - Running setup.py install for functools32 15:01:35 INFO - warning: no files found matching '*.txt' 15:01:35 INFO - no previously-included directories found matching 'build' 15:01:35 INFO - no previously-included directories found matching 'dist' 15:01:35 INFO - no previously-included directories found matching '.git*' 15:01:35 INFO - Successfully installed jsonschema functools32 15:01:35 INFO - Cleaning up... 15:01:35 INFO - Return code: 0 15:01:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:35 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 0x10b9aaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbfbd76e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, '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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 15:01:35 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 15:01:35 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 15:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:35 INFO - 'HOME': '/Users/cltbld', 15:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:35 INFO - 'LOGNAME': 'cltbld', 15:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:35 INFO - 'MOZ_NO_REMOTE': '1', 15:01:35 INFO - 'NO_EM_RESTART': '1', 15:01:35 INFO - 'PAGER': '/bin/cat', 15:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:35 INFO - 'PWD': '/builds/slave/test', 15:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:35 INFO - 'SHELL': '/bin/bash', 15:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:01:35 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:35 INFO - 'USER': 'cltbld', 15:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:35 INFO - 'XPC_FLAGS': '0x0', 15:01:35 INFO - 'XPC_SERVICE_NAME': '0', 15:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:35 INFO - Downloading/unpacking flake8 15:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:01:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:01:37 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 15:01:37 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 15:01:37 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 15:01:37 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 15:01:37 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 15:01:37 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 15:01:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:01:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:01:39 INFO - no previously-included directories found matching 'docs/_build' 15:01:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:01:39 INFO - Running setup.py install for pep8 15:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:01:39 INFO - no previously-included directories found matching 'docs/_build' 15:01:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:01:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:01:39 INFO - Cleaning up... 15:01:39 INFO - Return code: 0 15:01:39 INFO - Running post-action listener: _resource_record_post_action 15:01:39 INFO - Running post-action listener: _start_resource_monitoring 15:01:39 INFO - Starting resource monitoring. 15:01:39 INFO - ##### 15:01:39 INFO - ##### Running install step. 15:01:39 INFO - ##### 15:01:39 INFO - Running pre-action listener: _resource_record_pre_action 15:01:39 INFO - Running main action method: install 15:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:01:39 INFO - Reading from file tmpfile_stdout 15:01:39 INFO - Detecting whether we're running mozinstall >=1.0... 15:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:01:39 INFO - Reading from file tmpfile_stdout 15:01:39 INFO - Output received: 15:01:39 INFO - Usage: mozinstall [options] installer 15:01:39 INFO - Options: 15:01:39 INFO - -h, --help show this help message and exit 15:01:39 INFO - -d DEST, --destination=DEST 15:01:39 INFO - Directory to install application into. [default: 15:01:39 INFO - "/builds/slave/test"] 15:01:39 INFO - --app=APP Application being installed. [default: firefox] 15:01:39 INFO - mkdir: /builds/slave/test/build/application 15:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:02:01 INFO - Reading from file tmpfile_stdout 15:02:01 INFO - Output received: 15:02:01 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:02:01 INFO - Running post-action listener: _resource_record_post_action 15:02:01 INFO - ##### 15:02:01 INFO - ##### Running run-tests step. 15:02:01 INFO - ##### 15:02:01 INFO - Running pre-action listener: _resource_record_pre_action 15:02:01 INFO - Running main action method: run_tests 15:02:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:02:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:02:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:02:01 INFO - 2016-06-29 15:02:01.593 screenresolution[1643:7503] starting screenresolution argv=screenresolution get 15:02:01 INFO - 2016-06-29 15:02:01.596 screenresolution[1643:7503] Display 0: 1600x1200x32@60 15:02:01 INFO - 2016-06-29 15:02:01.602 screenresolution[1644:7505] starting screenresolution argv=screenresolution list 15:02:01 INFO - Available Modes on Display 0 15:02:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:02:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:02:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:02:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:02:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:02:01 INFO - Intel Iris: 15:02:01 INFO - Chipset Model: Intel Iris 15:02:01 INFO - Type: GPU 15:02:01 INFO - Bus: Built-In 15:02:01 INFO - VRAM (Dynamic, Max): 1536 MB 15:02:01 INFO - Vendor: Intel (0x8086) 15:02:01 INFO - Device ID: 0x0a2e 15:02:01 INFO - Revision ID: 0x0009 15:02:01 INFO - Displays: 15:02:01 INFO - Display: 15:02:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:02:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:02:01 INFO - Display Serial Number: 1600x1200 60 15:02:01 INFO - Main Display: Yes 15:02:01 INFO - Mirror: Off 15:02:01 INFO - Online: Yes 15:02:01 INFO - Rotation: Supported 15:02:01 INFO - Return code: 0 15:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:01 INFO - Python 2.7.3 15:02:01 INFO - Return code: 0 15:02:01 INFO - grabbing minidump binary from tooltool 15:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:01 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 0x10b849e00>, '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 0x10b6b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b844b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:01 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 15:02:01 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 15:02:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:01 INFO - Return code: 0 15:02:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:02:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:02:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:02:03 INFO - Return code: 0 15:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 15:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --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 15:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9kaIXvxgB/Render', 15:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:03 INFO - 'HOME': '/Users/cltbld', 15:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:03 INFO - 'LOGNAME': 'cltbld', 15:02:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:03 INFO - 'MOZ_NO_REMOTE': '1', 15:02:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:03 INFO - 'NO_EM_RESTART': '1', 15:02:03 INFO - 'PAGER': '/bin/cat', 15:02:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:03 INFO - 'PWD': '/builds/slave/test', 15:02:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:03 INFO - 'SHELL': '/bin/bash', 15:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjFWmjDjEJ/Listeners', 15:02:03 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 15:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:03 INFO - 'USER': 'cltbld', 15:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:03 INFO - 'XPC_FLAGS': '0x0', 15:02:03 INFO - 'XPC_SERVICE_NAME': '0', 15:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--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 15:02:03 INFO - mozversion application_buildid: 20160629130426 15:02:03 INFO - mozversion application_changeset: ee43fafecf41bc2048b100e1fe2e272144884d19 15:02:03 INFO - mozversion application_display_name: Firefox 15:02:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:03 INFO - mozversion application_name: Firefox 15:02:03 INFO - mozversion application_remotingname: firefox 15:02:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:02:03 INFO - mozversion application_vendor: Mozilla 15:02:03 INFO - mozversion application_version: 48.0 15:02:03 INFO - mozversion platform_buildid: 20160629130426 15:02:03 INFO - mozversion platform_changeset: ee43fafecf41bc2048b100e1fe2e272144884d19 15:02:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:02:03 INFO - mozversion platform_version: 48.0 15:02:03 INFO - using testdate: 1467237723 15:02:03 INFO - actual date: 1467237723 15:02:03 INFO - starting webserver on 'localhost:49271' 15:02:03 INFO - SUITE-START | Running 2 tests 15:02:03 INFO - TEST-START | dromaeo_css 15:02:03 INFO - operating with platform_type : mac_ 15:02:03 INFO - Initialising browser for dromaeo_css test... 15:02:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpfvWWIL/profile http://localhost:49271/getInfo.html) 15:02:06 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 15:02:06 INFO - PROCESS | 1662 | colorDepth:24 15:02:06 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 15:02:06 INFO - PROCESS | 1662 | __metrics 15:02:06 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:02:06 INFO - TEST-INFO | 1662: exit 0 15:02:06 INFO - Browser initialized. 15:02:06 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:06 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpfvWWIL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:18 INFO - PROCESS | 1666 | RSS: Main: 170651648 15:02:18 INFO - PROCESS | 1666 | 15:03:08 INFO - PROCESS | 1666 | 1467237788328 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] 15:05:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:05:20 INFO - PROCESS | 1666 | RSS: Main: 183336960 15:05:20 INFO - PROCESS | 1666 | 15:08:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:08:22 INFO - PROCESS | 1666 | RSS: Main: 194019328 15:08:22 INFO - PROCESS | 1666 | 15:11:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:11:24 INFO - PROCESS | 1666 | RSS: Main: 196296704 15:11:24 INFO - PROCESS | 1666 | 15:14:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:14:27 INFO - PROCESS | 1666 | RSS: Main: 200003584 15:14:27 INFO - PROCESS | 1666 | 15:17:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:17:30 INFO - PROCESS | 1666 | RSS: Main: 199168000 15:17:30 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 15:20:32 INFO - PROCESS | 1666 | RSS: Main: 204681216 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | __start_tp_report 15:20:32 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:20:32 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:20:32 INFO - PROCESS | 1666 | |i|pagename|runs| 15:20:32 INFO - PROCESS | 1666 | |0;dojo.html;3585;3657.7073834093276;3682.539682539681;3714.0529165062903;3731.738778285521;4997.550220480156;5455.717906292023;5712.285964254468;6072.170716112531;6119.7246123924415;6049.425304596057;6060.757569697218;6074.726637301321;6112.235970503687;6119.479844213247;5411.269478620395;5477.233187353773;5527.723613819313;5548.9722551387185;5617.915731264035;5234.476552344773;5439.265699130607;5467.863303417426;5657.264555607765;5679.091345384739;5506.504414602685;5511.9724401378135;5520.91718624221;5530.115181570949;5537.667739935597;5499.752511136989;5519.724013799294;5521.779128834842;5528.170774383874;5531.3362396512675;5370.462953704638;5434.673919564839;5455.454454554552;5470.589705772083;5604.019296623074;6048.637081775064;6065.332813390523;6096.542759293071;6111.2666480022235;6132.969335153341;6234.750609975596;6236.6258024518675;6370.808875733689;6371.681415929185;6401.967990160019;6259.280182778994;6315.147455093595;6339.397757213057;6343.492520598341;6348.587341822767;6131.9386806131815;6144.41628045335;6148.815535533941;6151.231096112986;6153.7230824613;6376.840578985562;6491.902621460587;6536.019597060479;6544.640044797487;6545.672716364163;6206.658633775095;6311.211098612674;6315.305316415191;6319.178506794087;6322.178116844872;6426.678666066678;6453.806385808433;6466.579672321284;6467.353264673495;6473.28793832678;6212.596181248205;6260.906086408617;6288.968555157287;6340.461060809883;6357.682115894187;5297.231901374356;6267.435930766253;6280.748770049238;6289.842753931097;6293.402126797947;5053.271233533924;5132.204508301219;5137.049645815536;5143.074246635641;5176.767045482962;4512.2329204035395;4606.332081848181;4654.697444666086;4655.790489427916;4665.626749860003;6149.354317796567;6171.352008039091;6197.5351848610635;6199.628022318585;6209.813705588839;5332.813351532678;5548.361938377138;6422.678866056678;6438.935610643927;6443.581167224115;5858.150568167592;6035;6098.32918378978;6125.632462052291;6140.232470941132;5550.888982220333;5632.60571759981;5637.802676906289;5645.9717701412055;5671.914921276184;6639.800805975829;6641.169853768279;6677.933220667828;6700.296468870796;6726.730930762715;6248.812535623939;6331.778387756406;6359;6377.904331435125;6420.486361091008;6008.819735407945;6250.499960003098;6631.104800851823;6658.667066646552;6683.699233534406;6232.937670623236;6303.46420554258;6331.145295385064;6332.683365831782;6340.270868849957;6336.334684858023;6405.2313722351755;6425.646589437626;6434.967825160844;6477.287498375269;5204.375474943031;6262.718177682106;6272.247330320391;6278.0269058296035;6279.843003924939;6163.7842675506145;6191.9690401547705;6267.2165979252595;6268.561200715906;6272.8118156455375;5303.04545181871;5946.583739138218;5979.551533634908;6139.706800529315;6227.906581401373;6239.251289845248;6245.68771561411;6246.063090536274;6270.122182894307;6270.310265870659;6158.414950579688;6182.072689096672;6182.28903676083;6188.412100850412;6217.378262173927 15:20:32 INFO - PROCESS | 1666 | |1;ext.html;529.2775361631371;541.7535021565187;575.9638983453408;612.6171143035602;631.2235949781768;31398.05805825826;40038;41238;41315;41406.17187656249;42475.57524424755;42814.715558533215;43013.7849310753;43021;43066.92332691677;9658.275629327793;9807.65673201438;9810.70567882961;9863.457509836957;9889.357191782547;9740.318177727562;9798.510074496247;9805.852912206323;9894.90105098947;9962.501874906262;9882.160016398615;9917.454540000297;9938.701838944808;9963.302568820185;9969.401835889834;9875.654352097677;9908;9944.651937182163;9955.303128780988;10005.399676019426;9699.272554558436;9733.367331123454;9791.167750741195;9861.654842080565;9864.753381165456;40411.39382909259;41278;41562.506249624814;42030.579694203276;42097;3234.579504664402;3307.6361600223954;3380.5943286805496;3467.7745946513396;3493.7205023598303;29725.42125415288;29830.403391932257;29987.400251995055;30001.249968750737;30350.937717179793;2805.14443094856;2816.690164081949;2837.4466978939117;2846.9430611387656;2853.743163115309;353117;358223;358846;358928.9999999974;359850;38166.427503586914;66276;66682;66692.99960500597;66706.33293667098;36973;37373.065673357836;37393.87818365399;37458.812705936296;37752;16566.254518546695;16885.24016419264;16892.239849206817;16925.830741692425;16927.407540736014;13849.723005539832;13926.094803837717;14191.71616567663;14336.49822256216;14443.988920775655;8218.958905205554;8347.499150051017;8360.080391156967;8360.540170290571;8612.526311052952;3586.4261718124976;3597.9100522487147;3645.799481028516;3660.798656073888;3667.3032123896373;1096.7861267053008;1117.273772048159;1153.4348169396935;1208.238809549978;1209.7580483903255;31086.68913310883;31293.217669558442;31393.52909706356;31454.842725786682;31637;1164.67971307891;1187.1776822577474;1223.7246619510538;1234.5925644764975;1338.4646141543267;15767.684646307009;16041.87706860532;16173.676526469404;16261.024338539726;16469.505914822334;331121;333121;336821;336987;338117;64585.677071614344;64660.70678586496;65070.67464662647;65074.67462662656;65333.02000469997;1542.96142596436;1563.3590228006462;1573.8032745906762;1576.8265490796002;1581.430684953416;30476.695233047387;31021.844890776305;31053.378932421678;31433.899813506418;31612.051638450885;45985.540144598126;55253.447465526435;55620.721896390256;55867.72066139643;55880.72059639676;428406;538892;558941;561440;563323;28178.43643127167;44356.556434436534;44412.33381499345;46644;46644;44706.77646611746;45076.549234507234;45098.54901450944;45743.54256457393;46259.76870115628;36549.26901462009;38303.04242393962;40153.59846401498;40323.790286291456;41925.95185120274;36113.81943090373;36355.272894541435;36374;36760.44859327059;36844.26311473809;37771.244575108896;37971.670991515166;38778.030549235365;38916.41625375565;39742.40386394264;32268.709251629673;34043.31913361669;34285;34386.82806585951;34387.65612343844;26197.607035894856;26247.737522624528;26261.34346641349;26410.207693769218;26682.19953401401 15:20:32 INFO - PROCESS | 1666 | |2;jquery.html;418.1010826722546;420.59412666776564;422.76113995592505;429.4696050377783;430.15045285561;20666.17335306588;20690.482737931557;21374.465638359048;22608.095676172932;22711.886440567814;21740.45648858779;21836.672449913265;21947.231846885363;22296.885155742228;22561.210357637487;5892.175095486636;6154.292256390505;6218.968905155468;6219.937800622004;6256.781012664557;4485.7084289521235;5729.197912292282;6099.146119543268;6175.41333573312;6718.193816741998;6530;6655.966720166392;6660.966695166518;6697.8995315070315;6701.731930722765;5355.892882142355;6547.345265473462;6705.128333316687;6714.932850671528;6721.798346049602;5763.855903602402;5858.414158584107;5860.384659610722;5891.322497912752;5901.7934372297195;13451.596452106452;14015.159090454605;14191;14203.78694319586;14206.218657973808;2298.471351589127;2357.8938947747392;2561.3852675357966;2580.458103798205;2678.263477543672;10094.9495252524;10116.94941525295;10481.685549433529;11816.586419475363;15629.921850390789;1529.7246495630782;1578.5027716269337;1813.1442977310787;1856;1878.079740926936;87648.56175719081;108494;122009.8868154177;123008;124626.75373246243;12428.813567796491;12527.060470464752;12576.87423125757;12633.178843375152;12673.556425525247;16015.359385624677;16032;16053.43812966564;16070.598235044217;16089.436869709503;9434.622615095457;9438;9614.9038509614;9618.903810961941;9641.228701703847;7653.579053152019;9305.720828375159;9573.712788616353;9596.376235544667;9634.421934683942;4879.975205206946;4891.923776769105;4930.926036109461;4953.455119936804;4996.475370086088;1856.4152292027968;2011.5675129847152;2072.9067191976396;2177.4556360909774;2177.9782202177776;859.750672305037;996.0189213624574;1063.8404239364002;1078.4607696151925;1087.7389426537575;19808.108635111454;20605.48486287825;20839.374818755463;20999.685004724943;21219.8939005304;1002.5789168549227;1040.3445829127602;1063.1654151491043;1074.065562960214;1091.7270682329417;10539;11017.779644407066;11325.263857849213;11452.484638191301;11487.081033517466;529860;539375;540394;647868;650719;112406.43796780964;122972;124046.37976810058;131059;140133;930.7254359963827;1098.2092893117103;1107;1147.2485521983115;1156.346664134761;18489.53776155607;18868.24527018904;18905.90547047311;18920.810791892454;19008.904955475133;51633.483665162865;51912;52300.38498075157;52310;53585.12452064159;74699;76113.61943190248;77751;77769;78066.2193378059;8249.958750206211;8326;8378.958105209435;8388.245057944814;8474.66101355939;9636.71089867305;13173.802392964304;13256.602301930958;13460.990425717915;13467.528636497676;11056.336619802838;11388.829167562328;11649.242799218024;11697.590584329508;11753.941230294136;10922.344659320466;11198.44007799623;11404.82892756626;11415;11558.959693627614;5965.761369545343;8875.600597972978;9189.264858811413;9649.662261821084;10557.049865511834;7502.962485187539;7803.258690424401;8318.875216871873;8400.537970411686;8474.915250847413;7934.642941067781;10348.327358721659;11491.540338386705;11774;11810.940945295219 15:20:32 INFO - PROCESS | 1666 | |3;mootools.html;1497.9325930333137;1629.3645478263475;1867.3650958674048;1950.912208950598;2015.758109026917;8270.793230169249;9011.864822027675;9124.1332073453;9258.490783006931;9299.953500232506;8929.241014513775;8951.060138685425;9072.455652660845;9087.363884528086;9121.996580376153;2484.8757562121914;2504.4239824840297;2505.84964902106;2514.4342522932284;2515.2705715342527;2457.6436416719566;2514.509670614231;2525.911593094242;2530.607755797854;2532.734062923385;2469.7283298837115;2517.874106294687;2550.3879069023487;2553.9744602553924;2561.1804222648666;2514.5599520084;2536.8224224304213;2537.416394229319;2549.821512494126;2558.5522533556646;2508.059477695864;2523.40699935515;2526.6715327007555;2527.1155095716535;2560.398306397995;9267.30495212855;9269.165775080208;9309.487978161198;9313.674021409286;9325.580348884316;5576.972115139439;5675.460831221028;5707.486326230618;5721.628094173865;5727.9427205727825;3203.4874420092674;3365.478350855621;3599.4420864766003;3687.2441149564274;3757.868474603376;1052.8420736889452;1067.124957534814;1072.158355690779;1072.2601405030582;1074.9623763168252;11873.465694043702;11955.462004209832;12130.757384852343;12313.26120432777;12515.937420312932;4854.902901941941;4893.926591101136;4930.359053323117;4966.255061740696;4990;10299.742506437398;10325.793484130276;10356.223283253787;10566.366018038942;10624.256302058937;5491.066518691832;5510.972445137749;5545.168224766237;5553.139263414178;5564.137558678412;4567.314902764612;4953.2570114483115;4966.056449274626;5033.949660503421;5050.393952725696;4211.663066954637;4222.915541689149;4230.196262710137;4237.427947227146;4319.870403887889;1373.5467295792364;1398.7971744097254;1403.4666826605824;1409.9506517271845;1410.4499245294344;912.9729054813336;915.914641150239;969.3941286695815;989.747614358334;998.1568687522637;6002.369751176151;6021.3677563856045;6071;6207.534434917399;6233.844153896189;405.2624223912451;462.1104374079883;478.3136199553582;478.849162513814;482.58497691985565;4022.396640503889;4100.794960251976;4107.712460127822;4115.609017143366;4117.75293482392;11002.174836887265;11338.603148889913;11389.829152562717;11390.772184556263;11427;10489;10778.19163562736;10827.025567698945;10881.075108615698;10905.236633435583;6408.647524386203;6798.490113241527;8332.16678332174;8541;8629.09394513567;6125.785597504066;6131.497783043004;6150.384961503813;6215.7529993293065;6239.594426362272;8405.159484051546;9928.305018648914;9975.052370024836;10218.540165692413;10257.487125643836;9948.154406875497;10156.847647285149;10201;10457.320274182153;10470.267081304235;7069.328413800681;7511.286427789351;7740.380769538536;7812.492188007761;7820.217978202134;7279.927200727925;8353.289970352345;8500.872486912825;8550.35872309567;8583.35624828128;6640.734370625314;7448.627568621656;7555.433342499225;7572.659230334538;7729.961350193213;6319.905201422074;7388.741394051182;7431;7455.0681164854395;7539.623018849145;7208.135023797178;7275.236100209613;7309.597972111585;7310.159331676925;7328.2305357938785;4975.452700203047;5025.9592946266985;5080.949190508048;5100.668456550312;5150.304708864255;5049.974750126225;5076.36545431821;5124.538791508782;5160.896782064413;5314.441983591667 15:20:32 INFO - PROCESS | 1666 | |4;prototype.html;286.8666070277321;325.66944551280454;345.43521342604834;347.29152528841155;348.4337950829901;6823.658817059152;6914.3777060064585;7019.192792828826;7053.118360204974;7077.256888026754;7011.89482157768;7081.61051142187;7093.219745827955;7103.33624890666;7111.146662400519;4184.769837658928;5159.793608255665;5199.402068762093;5200.32395788547;5219.295395121667;5210;5227.607929405291;5234.42421333653;5235.921461178085;5237.3453318335205;5155.304033955405;5162.767675454614;5169.896602067975;5175.482451754832;5177.145770947808;4807.975960120195;5020.397552293712;5049.899002019939;5161.66449180802;5163.3545806774155;4267.466566679165;4276.294411422143;4288.16380805743;4308.590683885026;4324.437823083011;6618.86762264752;6619.1395118634755;6642.5350225484235;6647.468202543785;6725.899111513331;4795.496472870368;4819.614430845559;5959.702014899238;6011;6031.24609423822;7480.334636028307;7492.7752167435065;7630.961845190794;7639.809004774869;7661.7701468955465;4585.564371384713;4757.976210118962;4762.52374762521;4771.093492236499;4804.4234691836855;6746.359095885932;7902.525848449054;7932.603369831543;7962.3630109591095;7973.122956474841;4842.661013729075;4863.902721945612;4866.46468888422;4884.3161957326;4892.094962431962;4970.15507363749;4979.4771548987555;4980.302757613937;4993.151164302005;5000.89998200034;5264.184051472028;5615.932972735167;6001.759929602855;6019.789307374221;6035.215421995113;6432.742690292336;7310.415166786751;7517.398608111447;7565.735199268;7586.317231449196;1708.3166733306778;1709.6067904382062;1724.4136993422046;1726.9309227630754;1736.0625262358437;1012.9594816207269;1066.589363095214;1067.631667074858;1070.1224995503612;1093.7156339351666;6949.861002779916;6951.7219311228;6955.6869940852785;6958.37374636275;6963.82590435233;5788.437121977043;6951.443884489228;7734.767956961187;7743.7676869694;7759.57322347265;6352.269489008823;6726.461883049345;7027.61348125858;7033.718651253893;7064.293570642894;4038.6163314485075;4083.1629515949157;4130.132672138824;4139.337705967031;4147.502299723992;7776;8292.917070829335;8311.750647480585;8327.66689332432;8341.45780524276;8179.550124743197;8194.631241594143;8198.508089514648;8205.753827385188;8226.42415030954;8299.502029878227;8316.833663326699;8326.916730832736;8329.292010179202;8330.333573314121;7519.135299440634;7784.727534536491;7789.64946577394;7811.453198276294;7904.051513818378;6179.62922224668;6240.875182496416;7649.120351159688;7771.067471903408;7797.41519386037;7175.354218120394;7762.680344341588;8257;8263.049749278909;8265.090840007715;4852.733099679552;4895.388076490439;4897.118518666674;4908.828191013297;4921.138800709933;7484.887726683995;7727.884081738666;7760.92239077602;7779.066512018594;7910.920890791019;6701.229358623821;7477.551346919202;7567.167611562613;7585.658645360862;7595.810104747426;7554.622268886644;7571.394288457021;7584.696612135453;7602.847943041219;7630.427717921067;5808.8091941151115;7153.284671533013;7255.129384473897;7378.631068446663;7425.183229844821;7540.245975402416;7570.16728159913;7591.240875912365;7592.734254301073;7644.197359277196;7087.818187269329;7256.818579535554;7434.36807871337;7468.253174682488;7479.439042071757 15:20:32 INFO - PROCESS | 1666 | |5;yui.html;705.6224919667973;735.1655870586883;760.9878861114721;789.9565523896183;798.6925033861964;15526.534203973884;15698.686026279482;15704.528864134094;15707.764383534257;15743.370265189407;14149.29253537324;14318.427262909498;14333.856661433383;14349;14364.425422983095;758.662395234121;910.4901255297021;933.4725879877864;959.9568019439107;960.0591420407969;782.5930516131608;917.4953775423523;919.296737995434;952.8665986761858;956.8899576548681;966.2028826218398;972.1639390124523;977.8386566216566;997.4813097189457;1003.1373019203479;965.859950307205;999.4902599674182;1001.749562609344;1004.7036124343336;1007.8992100789935;728.2389902551846;735.0921611809412;746.6192241956587;759.2255898983061;760.752755354512;15002.924985375112;15031.624209394853;15162.090274583561;15352.616184595363;15403.768943465857;10649.57401703931;12259.754804903852;12474.251544907334;12587.24476531411;12732.17240879349;10152.898471015269;10232.488375581266;10338.224633152544;10523;10578.312409693346;3556.875509357186;3570.285942811448;3579.266250418658;3621.2395396966667;3722.9627703722886;62060;68529.65735171342;75232.62383688102;82838.58580707057;84791.15208847895;8375.874361884577;9583.856242156373;9954.352967057119;9993.900060999442;10287.080148395318;22566.548669026528;22965.081396744277;22992.310230693107;23084.884575577016;23116.3065108047;7486.43851711113;7505.211952744992;7505.849883002309;7585.734499292498;7818;4073.7148399612333;4122.587741225853;4145.709800313949;4162.7502349859105;4174.791260437027;3669.6330366963625;3705.370087085202;3708.592054873962;3735.3089678409583;3758.3986562149926;568.2839622076214;643.9839004024936;657.1982181738272;664.4750646988824;672.6804767735287;574.0265425098032;592.8992071347881;612.1827360473728;613.3192156706052;615.7229246838851;8384.706535271236;8396.17523058617;8405.95797021011;8448.450850694686;8553.615087321086;518.3235877180316;538.6014349381429;548.958828087895;595.6188039654625;618.7432215630553;3722.274156539503;3782.3002744492355;3859.2474467479133;3960.326744453392;3968.920621587552;91167.54416227876;91404;91640;91751;95121.5243923776;63861.08416747505;74468.25531744721;77606;78100.99999999886;78213.60893195611;27837.860810695816;28027.299317517227;51033.97932041338;53268.66828329323;62825.685871571266;7447.92552074494;7622.5426474411715;7799.727009554638;7853.253940875607;8056.315213206943;34171;44711;45150.54849451463;46571.06857862756;46616;62911.37088629238;63737.36262637314;68026.9795953071;68470.65764671145;69222.30777692159;10173.08442240202;10218.846717299099;10344.637937672447;10527.947360263455;10702.73243168927;9341.766455838659;10438.63464778729;10627.787444251226;10808;11166.994970452699;8520.318374530147;9044.909550904407;10131.290809643255;10467.581296748349;10721.892781072089;9255.814883702153;9923.65267215644;10326.328788628714;10383.844242336521;10830.458477076272;8551.802747615215;9602.08780165883;9676.661316854157;9959.601615935282;10230.28388012832;8384;8718.17177368149;8802.60388282516;8985.910140898508;9020.278377729897;8422.157784221528;8751.212390884852;8788.472691638523;8789.648414063366;8979.685711000084 15:20:32 INFO - PROCESS | 1666 | __end_tp_report 15:20:32 INFO - PROCESS | 1666 | __start_cc_report 15:20:32 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,390 15:20:32 INFO - PROCESS | 1666 | __end_cc_report 15:20:32 INFO - PROCESS | 1666 | __startTimestamp1467238832690__endTimestamp 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:20:32 INFO - PROCESS | 1666 | Number of tests: 6 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5938.09 Median:6171.35 stddev:619.20 (10.0%) stddev-sans-first:592.94 15:20:32 INFO - PROCESS | 1666 | Values: 3585.0 3657.7 3682.5 3714.1 3731.7 4997.6 5455.7 5712.3 6072.2 6119.7 6049.4 6060.8 6074.7 6112.2 6119.5 5411.3 5477.2 5527.7 5549.0 5617.9 5234.5 5439.3 5467.9 5657.3 5679.1 5506.5 5512.0 5520.9 5530.1 5537.7 5499.8 5519.7 5521.8 5528.2 5531.3 5370.5 5434.7 5455.5 5470.6 5604.0 6048.6 6065.3 6096.5 6111.3 6133.0 6234.8 6236.6 6370.8 6371.7 6402.0 6259.3 6315.1 6339.4 6343.5 6348.6 6131.9 6144.4 6148.8 6151.2 6153.7 6376.8 6491.9 6536.0 6544.6 6545.7 6206.7 6311.2 6315.3 6319.2 6322.2 6426.7 6453.8 6466.6 6467.4 6473.3 6212.6 6260.9 6289.0 6340.5 6357.7 5297.2 6267.4 6280.7 6289.8 6293.4 5053.3 5132.2 5137.0 5143.1 5176.8 4512.2 4606.3 4654.7 4655.8 4665.6 6149.4 6171.4 6197.5 6199.6 6209.8 5332.8 5548.4 6422.7 6438.9 6443.6 5858.2 6035.0 6098.3 6125.6 6140.2 5550.9 5632.6 5637.8 5646.0 5671.9 6639.8 6641.2 6677.9 6700.3 6726.7 6248.8 6331.8 6359.0 6377.9 6420.5 6008.8 6250.5 6631.1 6658.7 6683.7 6232.9 6303.5 6331.1 6332.7 6340.3 6336.3 6405.2 6425.6 6435.0 6477.3 5204.4 6262.7 6272.2 6278.0 6279.8 6163.8 6192.0 6267.2 6268.6 6272.8 5303.0 5946.6 5979.6 6139.7 6227.9 6239.3 6245.7 6246.1 6270.1 6270.3 6158.4 6182.1 6182.3 6188.4 6217.4 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:56597.82 Median:37383.47 stddev:110940.97 (296.8%) stddev-sans-first:111172.31 15:20:32 INFO - PROCESS | 1666 | Values: 529.3 541.8 576.0 612.6 631.2 31398.1 40038.0 41238.0 41315.0 41406.2 42475.6 42814.7 43013.8 43021.0 43066.9 9658.3 9807.7 9810.7 9863.5 9889.4 9740.3 9798.5 9805.9 9894.9 9962.5 9882.2 9917.5 9938.7 9963.3 9969.4 9875.7 9908.0 9944.7 9955.3 10005.4 9699.3 9733.4 9791.2 9861.7 9864.8 40411.4 41278.0 41562.5 42030.6 42097.0 3234.6 3307.6 3380.6 3467.8 3493.7 29725.4 29830.4 29987.4 30001.2 30350.9 2805.1 2816.7 2837.4 2846.9 2853.7 353117.0 358223.0 358846.0 358929.0 359850.0 38166.4 66276.0 66682.0 66693.0 66706.3 36973.0 37373.1 37393.9 37458.8 37752.0 16566.3 16885.2 16892.2 16925.8 16927.4 13849.7 13926.1 14191.7 14336.5 14444.0 8219.0 8347.5 8360.1 8360.5 8612.5 3586.4 3597.9 3645.8 3660.8 3667.3 1096.8 1117.3 1153.4 1208.2 1209.8 31086.7 31293.2 31393.5 31454.8 31637.0 1164.7 1187.2 1223.7 1234.6 1338.5 15767.7 16041.9 16173.7 16261.0 16469.5 331121.0 333121.0 336821.0 336987.0 338117.0 64585.7 64660.7 65070.7 65074.7 65333.0 1543.0 1563.4 1573.8 1576.8 1581.4 30476.7 31021.8 31053.4 31433.9 31612.1 45985.5 55253.4 55620.7 55867.7 55880.7 428406.0 538892.0 558941.0 561440.0 563323.0 28178.4 44356.6 44412.3 46644.0 46644.0 44706.8 45076.5 45098.5 45743.5 46259.8 36549.3 38303.0 40153.6 40323.8 41926.0 36113.8 36355.3 36374.0 36760.4 36844.3 37771.2 37971.7 38778.0 38916.4 39742.4 32268.7 34043.3 34285.0 34386.8 34387.7 26197.6 26247.7 26261.3 26410.2 26682.2 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34294.30 Median:22122.06 stddev:97519.20 (440.8%) stddev-sans-first:97759.60 15:20:32 INFO - PROCESS | 1666 | Values: 418.1 420.6 422.8 429.5 430.2 20666.2 20690.5 21374.5 22608.1 22711.9 21740.5 21836.7 21947.2 22296.9 22561.2 5892.2 6154.3 6219.0 6219.9 6256.8 4485.7 5729.2 6099.1 6175.4 6718.2 6530.0 6656.0 6661.0 6697.9 6701.7 5355.9 6547.3 6705.1 6714.9 6721.8 5763.9 5858.4 5860.4 5891.3 5901.8 13451.6 14015.2 14191.0 14203.8 14206.2 2298.5 2357.9 2561.4 2580.5 2678.3 10094.9 10116.9 10481.7 11816.6 15629.9 1529.7 1578.5 1813.1 1856.0 1878.1 87648.6 108494.0 122009.9 123008.0 124626.8 12428.8 12527.1 12576.9 12633.2 12673.6 16015.4 16032.0 16053.4 16070.6 16089.4 9434.6 9438.0 9614.9 9618.9 9641.2 7653.6 9305.7 9573.7 9596.4 9634.4 4880.0 4891.9 4930.9 4953.5 4996.5 1856.4 2011.6 2072.9 2177.5 2178.0 859.8 996.0 1063.8 1078.5 1087.7 19808.1 20605.5 20839.4 20999.7 21219.9 1002.6 1040.3 1063.2 1074.1 1091.7 10539.0 11017.8 11325.3 11452.5 11487.1 529860.0 539375.0 540394.0 647868.0 650719.0 112406.4 122972.0 124046.4 131059.0 140133.0 930.7 1098.2 1107.0 1147.2 1156.3 18489.5 18868.2 18905.9 18920.8 19008.9 51633.5 51912.0 52300.4 52310.0 53585.1 74699.0 76113.6 77751.0 77769.0 78066.2 8250.0 8326.0 8379.0 8388.2 8474.7 9636.7 13173.8 13256.6 13461.0 13467.5 11056.3 11388.8 11649.2 11697.6 11753.9 10922.3 11198.4 11404.8 11415.0 11559.0 5965.8 8875.6 9189.3 9649.7 10557.0 7503.0 7803.3 8318.9 8400.5 8474.9 7934.6 10348.3 11491.5 11774.0 11810.9 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5866.53 Median:4959.66 stddev:3276.13 (66.1%) stddev-sans-first:3268.87 15:20:32 INFO - PROCESS | 1666 | Values: 1497.9 1629.4 1867.4 1950.9 2015.8 8270.8 9011.9 9124.1 9258.5 9300.0 8929.2 8951.1 9072.5 9087.4 9122.0 2484.9 2504.4 2505.8 2514.4 2515.3 2457.6 2514.5 2525.9 2530.6 2532.7 2469.7 2517.9 2550.4 2554.0 2561.2 2514.6 2536.8 2537.4 2549.8 2558.6 2508.1 2523.4 2526.7 2527.1 2560.4 9267.3 9269.2 9309.5 9313.7 9325.6 5577.0 5675.5 5707.5 5721.6 5727.9 3203.5 3365.5 3599.4 3687.2 3757.9 1052.8 1067.1 1072.2 1072.3 1075.0 11873.5 11955.5 12130.8 12313.3 12515.9 4854.9 4893.9 4930.4 4966.3 4990.0 10299.7 10325.8 10356.2 10566.4 10624.3 5491.1 5511.0 5545.2 5553.1 5564.1 4567.3 4953.3 4966.1 5033.9 5050.4 4211.7 4222.9 4230.2 4237.4 4319.9 1373.5 1398.8 1403.5 1410.0 1410.4 913.0 915.9 969.4 989.7 998.2 6002.4 6021.4 6071.0 6207.5 6233.8 405.3 462.1 478.3 478.8 482.6 4022.4 4100.8 4107.7 4115.6 4117.8 11002.2 11338.6 11389.8 11390.8 11427.0 10489.0 10778.2 10827.0 10881.1 10905.2 6408.6 6798.5 8332.2 8541.0 8629.1 6125.8 6131.5 6150.4 6215.8 6239.6 8405.2 9928.3 9975.1 10218.5 10257.5 9948.2 10156.8 10201.0 10457.3 10470.3 7069.3 7511.3 7740.4 7812.5 7820.2 7279.9 8353.3 8500.9 8550.4 8583.4 6640.7 7448.6 7555.4 7572.7 7730.0 6319.9 7388.7 7431.0 7455.1 7539.6 7208.1 7275.2 7309.6 7310.2 7328.2 4975.5 5026.0 5080.9 5100.7 5150.3 5050.0 5076.4 5124.5 5160.9 5314.4 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:6089.44 Median:6932.12 stddev:1987.58 (28.7%) stddev-sans-first:1944.85 15:20:32 INFO - PROCESS | 1666 | Values: 286.9 325.7 345.4 347.3 348.4 6823.7 6914.4 7019.2 7053.1 7077.3 7011.9 7081.6 7093.2 7103.3 7111.1 4184.8 5159.8 5199.4 5200.3 5219.3 5210.0 5227.6 5234.4 5235.9 5237.3 5155.3 5162.8 5169.9 5175.5 5177.1 4808.0 5020.4 5049.9 5161.7 5163.4 4267.5 4276.3 4288.2 4308.6 4324.4 6618.9 6619.1 6642.5 6647.5 6725.9 4795.5 4819.6 5959.7 6011.0 6031.2 7480.3 7492.8 7631.0 7639.8 7661.8 4585.6 4758.0 4762.5 4771.1 4804.4 6746.4 7902.5 7932.6 7962.4 7973.1 4842.7 4863.9 4866.5 4884.3 4892.1 4970.2 4979.5 4980.3 4993.2 5000.9 5264.2 5615.9 6001.8 6019.8 6035.2 6432.7 7310.4 7517.4 7565.7 7586.3 1708.3 1709.6 1724.4 1726.9 1736.1 1013.0 1066.6 1067.6 1070.1 1093.7 6949.9 6951.7 6955.7 6958.4 6963.8 5788.4 6951.4 7734.8 7743.8 7759.6 6352.3 6726.5 7027.6 7033.7 7064.3 4038.6 4083.2 4130.1 4139.3 4147.5 7776.0 8292.9 8311.8 8327.7 8341.5 8179.6 8194.6 8198.5 8205.8 8226.4 8299.5 8316.8 8326.9 8329.3 8330.3 7519.1 7784.7 7789.6 7811.5 7904.1 6179.6 6240.9 7649.1 7771.1 7797.4 7175.4 7762.7 8257.0 8263.0 8265.1 4852.7 4895.4 4897.1 4908.8 4921.1 7484.9 7727.9 7760.9 7779.1 7910.9 6701.2 7477.6 7567.2 7585.7 7595.8 7554.6 7571.4 7584.7 7602.8 7630.4 5808.8 7153.3 7255.1 7378.6 7425.2 7540.2 7570.2 7591.2 7592.7 7644.2 7087.8 7256.8 7434.4 7468.3 7479.4 15:20:32 INFO - PROCESS | 1666 | 15:20:32 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:16951.43 Median:31339.37 stddev:23862.35 (76.1%) stddev-sans-first:23898.11 15:20:32 INFO - PROCESS | 1666 | Values: 705.6 735.2 761.0 790.0 798.7 15526.5 15698.7 15704.5 15707.8 15743.4 14149.3 14318.4 14333.9 14349.0 14364.4 758.7 910.5 933.5 960.0 960.1 782.6 917.5 919.3 952.9 956.9 966.2 972.2 977.8 997.5 1003.1 965.9 999.5 1001.7 1004.7 1007.9 728.2 735.1 746.6 759.2 760.8 15002.9 15031.6 15162.1 15352.6 15403.8 10649.6 12259.8 12474.3 12587.2 12732.2 10152.9 10232.5 10338.2 10523.0 10578.3 3556.9 3570.3 3579.3 3621.2 3723.0 62060.0 68529.7 75232.6 82838.6 84791.2 8375.9 9583.9 9954.4 9993.9 10287.1 22566.5 22965.1 22992.3 23084.9 23116.3 7486.4 7505.2 7505.8 7585.7 7818.0 4073.7 4122.6 4145.7 4162.8 4174.8 3669.6 3705.4 3708.6 3735.3 3758.4 568.3 644.0 657.2 664.5 672.7 574.0 592.9 612.2 613.3 615.7 8384.7 8396.2 8406.0 8448.5 8553.6 518.3 538.6 549.0 595.6 618.7 3722.3 3782.3 3859.2 3960.3 3968.9 91167.5 91404.0 91640.0 91751.0 95121.5 63861.1 74468.3 77606.0 78101.0 78213.6 27837.9 28027.3 51034.0 53268.7 62825.7 7447.9 7622.5 7799.7 7853.3 8056.3 34171.0 44711.0 45150.5 46571.1 46616.0 62911.4 63737.4 68027.0 68470.7 69222.3 10173.1 10218.8 10344.6 10527.9 10702.7 9341.8 10438.6 10627.8 10808.0 11167.0 8520.3 9044.9 10131.3 10467.6 10721.9 9255.8 9923.7 10326.3 10383.8 10830.5 8551.8 9602.1 9676.7 9959.6 10230.3 8384.0 8718.2 8802.6 8985.9 9020.3 8422.2 8751.2 8788.5 8789.6 8979.7 15:20:32 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:20:32 INFO - PROCESS | 1666 | 15:20:33 INFO - TEST-INFO | 1666: exit 0 15:20:33 INFO - TEST-OK | dromaeo_css | took 1109937ms 15:20:33 INFO - TEST-START | kraken 15:20:33 INFO - operating with platform_type : mac_ 15:20:33 INFO - Initialising browser for kraken test... 15:20:33 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpf4026R/profile http://localhost:49271/getInfo.html) 15:20:35 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 15:20:35 INFO - PROCESS | 1674 | colorDepth:24 15:20:35 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 15:20:35 INFO - PROCESS | 1674 | __metrics 15:20:35 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:20:36 INFO - TEST-INFO | 1674: exit 0 15:20:36 INFO - Browser initialized. 15:20:36 INFO - Running cycle 1/1 for kraken test... 15:20:36 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpf4026R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:48 INFO - PROCESS | 1678 | RSS: Main: 170889216 15:20:48 INFO - PROCESS | 1678 | 15:20:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 15:20:51 INFO - PROCESS | 1678 | RSS: Main: 183590912 15:20:51 INFO - PROCESS | 1678 | 15:20:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 15:20:54 INFO - PROCESS | 1678 | RSS: Main: 192921600 15:20:54 INFO - PROCESS | 1678 | 15:20:58 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 15:20:58 INFO - PROCESS | 1678 | RSS: Main: 196341760 15:20:58 INFO - PROCESS | 1678 | 15:21:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 15:21:01 INFO - PROCESS | 1678 | RSS: Main: 197632000 15:21:01 INFO - PROCESS | 1678 | 15:21:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:21:04 INFO - PROCESS | 1678 | RSS: Main: 198131712 15:21:04 INFO - PROCESS | 1678 | 15:21:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 15:21:07 INFO - PROCESS | 1678 | RSS: Main: 198119424 15:21:07 INFO - PROCESS | 1678 | 15:21:10 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 15:21:10 INFO - PROCESS | 1678 | RSS: Main: 197922816 15:21:10 INFO - PROCESS | 1678 | 15:21:13 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 15:21:14 INFO - PROCESS | 1678 | RSS: Main: 198041600 15:21:14 INFO - PROCESS | 1678 | 15:21:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:21:16 INFO - PROCESS | 1678 | RSS: Main: 197775360 15:21:16 INFO - PROCESS | 1678 | 15:21:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:21:19 INFO - PROCESS | 1678 | RSS: Main: 197918720 15:21:19 INFO - PROCESS | 1678 | 15:21:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:21:22 INFO - PROCESS | 1678 | RSS: Main: 198041600 15:21:22 INFO - PROCESS | 1678 | 15:21:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:21:25 INFO - PROCESS | 1678 | RSS: Main: 198176768 15:21:25 INFO - PROCESS | 1678 | 15:21:28 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:21:29 INFO - PROCESS | 1678 | RSS: Main: 198053888 15:21:29 INFO - PROCESS | 1678 | 15:21:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 15:21:31 INFO - PROCESS | 1678 | RSS: Main: 199766016 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | __start_tp_report 15:21:31 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 15:21:31 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 15:21:31 INFO - PROCESS | 1678 | |i|pagename|runs| 15:21:31 INFO - PROCESS | 1678 | |0;ai-astar;118;112;116;118;117;132;115;116;116;117 15:21:31 INFO - PROCESS | 1678 | |1;audio-beat-detection;152;124;141;158;121;140;125;156;164;118 15:21:31 INFO - PROCESS | 1678 | |2;audio-dft;191;198;196;202;197;200;194;193;202;196 15:21:31 INFO - PROCESS | 1678 | |3;audio-fft;64;82;107;182;56;57;57;59;128;56 15:21:31 INFO - PROCESS | 1678 | |4;audio-oscillator;78;82;136;77;79;90;122;81;80;122 15:21:31 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;90;87;89;85;118;131;89;87;91;86 15:21:31 INFO - PROCESS | 1678 | |6;imaging-darkroom;137;97;94;96;95;96;96;95;94;103 15:21:31 INFO - PROCESS | 1678 | |7;imaging-desaturate;149;149;89;89;91;109;148;124;91;123 15:21:31 INFO - PROCESS | 1678 | |8;json-parse-financial;59;64;63;65;65;65;64;79;61;61 15:21:31 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;65;64;69;73;72;68;65;66;65;67 15:21:31 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;82;80;94;108;68;94;77;97;109 15:21:31 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;122;137;116;144;153;155;144;125;147;139 15:21:31 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;168;189;146;194;221;216;166;164;194;210 15:21:31 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;66;69;62;61;67;84;97;97;93;73 15:21:31 INFO - PROCESS | 1678 | __end_tp_report 15:21:31 INFO - PROCESS | 1678 | __start_cc_report 15:21:31 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1043 15:21:31 INFO - PROCESS | 1678 | __end_cc_report 15:21:31 INFO - PROCESS | 1678 | __startTimestamp1467238891110__endTimestamp 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | ------- Summary: start ------- 15:21:31 INFO - PROCESS | 1678 | Number of tests: 14 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:117.70 Median:117.00 stddev:5.31 (4.5%) stddev-sans-first:5.63 15:21:31 INFO - PROCESS | 1678 | Values: 118.0 112.0 116.0 118.0 117.0 132.0 115.0 116.0 116.0 117.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:146.50 stddev:17.08 (11.7%) stddev-sans-first:17.55 15:21:31 INFO - PROCESS | 1678 | Values: 152.0 124.0 141.0 158.0 121.0 140.0 125.0 156.0 164.0 118.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:196.90 Median:197.50 stddev:3.70 (1.9%) stddev-sans-first:3.24 15:21:31 INFO - PROCESS | 1678 | Values: 191.0 198.0 196.0 202.0 197.0 200.0 194.0 193.0 202.0 196.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:84.80 Median:57.00 stddev:42.32 (74.2%) stddev-sans-first:44.21 15:21:31 INFO - PROCESS | 1678 | Values: 64.0 82.0 107.0 182.0 56.0 57.0 57.0 59.0 128.0 56.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:79.50 stddev:22.66 (28.5%) stddev-sans-first:23.22 15:21:31 INFO - PROCESS | 1678 | Values: 78.0 82.0 136.0 77.0 79.0 90.0 122.0 81.0 80.0 122.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:88.00 stddev:15.80 (18.0%) stddev-sans-first:16.64 15:21:31 INFO - PROCESS | 1678 | Values: 90.0 87.0 89.0 85.0 118.0 131.0 89.0 87.0 91.0 86.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:95.50 stddev:13.15 (13.8%) stddev-sans-first:2.73 15:21:31 INFO - PROCESS | 1678 | Values: 137.0 97.0 94.0 96.0 95.0 96.0 96.0 95.0 94.0 103.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:116.20 Median:119.00 stddev:25.97 (21.8%) stddev-sans-first:24.69 15:21:31 INFO - PROCESS | 1678 | Values: 149.0 149.0 89.0 89.0 91.0 109.0 148.0 124.0 91.0 123.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.50 stddev:5.46 (8.5%) stddev-sans-first:5.40 15:21:31 INFO - PROCESS | 1678 | Values: 59.0 64.0 63.0 65.0 65.0 65.0 64.0 79.0 61.0 61.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:3.10 (4.6%) stddev-sans-first:3.16 15:21:31 INFO - PROCESS | 1678 | Values: 65.0 64.0 69.0 73.0 72.0 68.0 65.0 66.0 65.0 67.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:81.50 stddev:13.56 (16.6%) stddev-sans-first:14.07 15:21:31 INFO - PROCESS | 1678 | Values: 81.0 82.0 80.0 94.0 108.0 68.0 94.0 77.0 97.0 109.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:144.00 stddev:13.24 (9.2%) stddev-sans-first:12.68 15:21:31 INFO - PROCESS | 1678 | Values: 122.0 137.0 116.0 144.0 153.0 155.0 144.0 125.0 147.0 139.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:194.00 stddev:25.03 (12.9%) stddev-sans-first:25.61 15:21:31 INFO - PROCESS | 1678 | Values: 168.0 189.0 146.0 194.0 221.0 216.0 166.0 164.0 194.0 210.0 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:78.50 stddev:14.48 (18.4%) stddev-sans-first:14.81 15:21:31 INFO - PROCESS | 1678 | Values: 66.0 69.0 62.0 61.0 67.0 84.0 97.0 97.0 93.0 73.0 15:21:31 INFO - PROCESS | 1678 | -------- Summary: end -------- 15:21:31 INFO - PROCESS | 1678 | 15:21:31 INFO - TEST-INFO | 1678: exit 0 15:21:31 INFO - TEST-OK | kraken | took 58623ms 15:21:31 INFO - SUITE-END | took 1168s 15:21:32 INFO - Completed test suite (00:19:29) 15:21:32 INFO - javascript benchmark 15:21:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3585.0, 3657.7073834093276, 3682.539682539681, 3714.0529165062903, 3731.738778285521, 4997.550220480156, 5455.717906292023, 5712.285964254468, 6072.170716112531, 6119.7246123924415, 6049.425304596057, 6060.757569697218, 6074.726637301321, 6112.235970503687, 6119.479844213247, 5411.269478620395, 5477.233187353773, 5527.723613819313, 5548.9722551387185, 5617.915731264035, 5234.476552344773, 5439.265699130607, 5467.863303417426, 5657.264555607765, 5679.091345384739, 5506.504414602685, 5511.9724401378135, 5520.91718624221, 5530.115181570949, 5537.667739935597, 5499.752511136989, 5519.724013799294, 5521.779128834842, 5528.170774383874, 5531.3362396512675, 5370.462953704638, 5434.673919564839, 5455.454454554552, 5470.589705772083, 5604.019296623074, 6048.637081775064, 6065.332813390523, 6096.542759293071, 6111.2666480022235, 6132.969335153341, 6234.750609975596, 6236.6258024518675, 6370.808875733689, 6371.681415929185, 6401.967990160019, 6259.280182778994, 6315.147455093595, 6339.397757213057, 6343.492520598341, 6348.587341822767, 6131.9386806131815, 6144.41628045335, 6148.815535533941, 6151.231096112986, 6153.7230824613, 6376.840578985562, 6491.902621460587, 6536.019597060479, 6544.640044797487, 6545.672716364163, 6206.658633775095, 6311.211098612674, 6315.305316415191, 6319.178506794087, 6322.178116844872, 6426.678666066678, 6453.806385808433, 6466.579672321284, 6467.353264673495, 6473.28793832678, 6212.596181248205, 6260.906086408617, 6288.968555157287, 6340.461060809883, 6357.682115894187, 5297.231901374356, 6267.435930766253, 6280.748770049238, 6289.842753931097, 6293.402126797947, 5053.271233533924, 5132.204508301219, 5137.049645815536, 5143.074246635641, 5176.767045482962, 4512.2329204035395, 4606.332081848181, 4654.697444666086, 4655.790489427916, 4665.626749860003, 6149.354317796567, 6171.352008039091, 6197.5351848610635, 6199.628022318585, 6209.813705588839, 5332.813351532678, 5548.361938377138, 6422.678866056678, 6438.935610643927, 6443.581167224115, 5858.150568167592, 6035.0, 6098.32918378978, 6125.632462052291, 6140.232470941132, 5550.888982220333, 5632.60571759981, 5637.802676906289, 5645.9717701412055, 5671.914921276184, 6639.800805975829, 6641.169853768279, 6677.933220667828, 6700.296468870796, 6726.730930762715, 6248.812535623939, 6331.778387756406, 6359.0, 6377.904331435125, 6420.486361091008, 6008.819735407945, 6250.499960003098, 6631.104800851823, 6658.667066646552, 6683.699233534406, 6232.937670623236, 6303.46420554258, 6331.145295385064, 6332.683365831782, 6340.270868849957, 6336.334684858023, 6405.2313722351755, 6425.646589437626, 6434.967825160844, 6477.287498375269, 5204.375474943031, 6262.718177682106, 6272.247330320391, 6278.0269058296035, 6279.843003924939, 6163.7842675506145, 6191.9690401547705, 6267.2165979252595, 6268.561200715906, 6272.8118156455375, 5303.04545181871, 5946.583739138218, 5979.551533634908, 6139.706800529315, 6227.906581401373, 6239.251289845248, 6245.68771561411, 6246.063090536274, 6270.122182894307, 6270.310265870659, 6158.414950579688, 6182.072689096672, 6182.28903676083, 6188.412100850412, 6217.378262173927], "value": 5903.282817648676, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.2775361631371, 541.7535021565187, 575.9638983453408, 612.6171143035602, 631.2235949781768, 31398.05805825826, 40038.0, 41238.0, 41315.0, 41406.17187656249, 42475.57524424755, 42814.715558533215, 43013.7849310753, 43021.0, 43066.92332691677, 9658.275629327793, 9807.65673201438, 9810.70567882961, 9863.457509836957, 9889.357191782547, 9740.318177727562, 9798.510074496247, 9805.852912206323, 9894.90105098947, 9962.501874906262, 9882.160016398615, 9917.454540000297, 9938.701838944808, 9963.302568820185, 9969.401835889834, 9875.654352097677, 9908.0, 9944.651937182163, 9955.303128780988, 10005.399676019426, 9699.272554558436, 9733.367331123454, 9791.167750741195, 9861.654842080565, 9864.753381165456, 40411.39382909259, 41278.0, 41562.506249624814, 42030.579694203276, 42097.0, 3234.579504664402, 3307.6361600223954, 3380.5943286805496, 3467.7745946513396, 3493.7205023598303, 29725.42125415288, 29830.403391932257, 29987.400251995055, 30001.249968750737, 30350.937717179793, 2805.14443094856, 2816.690164081949, 2837.4466978939117, 2846.9430611387656, 2853.743163115309, 353117.0, 358223.0, 358846.0, 358928.9999999974, 359850.0, 38166.427503586914, 66276.0, 66682.0, 66692.99960500597, 66706.33293667098, 36973.0, 37373.065673357836, 37393.87818365399, 37458.812705936296, 37752.0, 16566.254518546695, 16885.24016419264, 16892.239849206817, 16925.830741692425, 16927.407540736014, 13849.723005539832, 13926.094803837717, 14191.71616567663, 14336.49822256216, 14443.988920775655, 8218.958905205554, 8347.499150051017, 8360.080391156967, 8360.540170290571, 8612.526311052952, 3586.4261718124976, 3597.9100522487147, 3645.799481028516, 3660.798656073888, 3667.3032123896373, 1096.7861267053008, 1117.273772048159, 1153.4348169396935, 1208.238809549978, 1209.7580483903255, 31086.68913310883, 31293.217669558442, 31393.52909706356, 31454.842725786682, 31637.0, 1164.67971307891, 1187.1776822577474, 1223.7246619510538, 1234.5925644764975, 1338.4646141543267, 15767.684646307009, 16041.87706860532, 16173.676526469404, 16261.024338539726, 16469.505914822334, 331121.0, 333121.0, 336821.0, 336987.0, 338117.0, 64585.677071614344, 64660.70678586496, 65070.67464662647, 65074.67462662656, 65333.02000469997, 1542.96142596436, 1563.3590228006462, 1573.8032745906762, 1576.8265490796002, 1581.430684953416, 30476.695233047387, 31021.844890776305, 31053.378932421678, 31433.899813506418, 31612.051638450885, 45985.540144598126, 55253.447465526435, 55620.721896390256, 55867.72066139643, 55880.72059639676, 428406.0, 538892.0, 558941.0, 561440.0, 563323.0, 28178.43643127167, 44356.556434436534, 44412.33381499345, 46644.0, 46644.0, 44706.77646611746, 45076.549234507234, 45098.54901450944, 45743.54256457393, 46259.76870115628, 36549.26901462009, 38303.04242393962, 40153.59846401498, 40323.790286291456, 41925.95185120274, 36113.81943090373, 36355.272894541435, 36374.0, 36760.44859327059, 36844.26311473809, 37771.244575108896, 37971.670991515166, 38778.030549235365, 38916.41625375565, 39742.40386394264, 32268.709251629673, 34043.31913361669, 34285.0, 34386.82806585951, 34387.65612343844, 26197.607035894856, 26247.737522624528, 26261.34346641349, 26410.207693769218, 26682.19953401401], "value": 19102.926898491434, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.1010826722546, 420.59412666776564, 422.76113995592505, 429.4696050377783, 430.15045285561, 20666.17335306588, 20690.482737931557, 21374.465638359048, 22608.095676172932, 22711.886440567814, 21740.45648858779, 21836.672449913265, 21947.231846885363, 22296.885155742228, 22561.210357637487, 5892.175095486636, 6154.292256390505, 6218.968905155468, 6219.937800622004, 6256.781012664557, 4485.7084289521235, 5729.197912292282, 6099.146119543268, 6175.41333573312, 6718.193816741998, 6530.0, 6655.966720166392, 6660.966695166518, 6697.8995315070315, 6701.731930722765, 5355.892882142355, 6547.345265473462, 6705.128333316687, 6714.932850671528, 6721.798346049602, 5763.855903602402, 5858.414158584107, 5860.384659610722, 5891.322497912752, 5901.7934372297195, 13451.596452106452, 14015.159090454605, 14191.0, 14203.78694319586, 14206.218657973808, 2298.471351589127, 2357.8938947747392, 2561.3852675357966, 2580.458103798205, 2678.263477543672, 10094.9495252524, 10116.94941525295, 10481.685549433529, 11816.586419475363, 15629.921850390789, 1529.7246495630782, 1578.5027716269337, 1813.1442977310787, 1856.0, 1878.079740926936, 87648.56175719081, 108494.0, 122009.8868154177, 123008.0, 124626.75373246243, 12428.813567796491, 12527.060470464752, 12576.87423125757, 12633.178843375152, 12673.556425525247, 16015.359385624677, 16032.0, 16053.43812966564, 16070.598235044217, 16089.436869709503, 9434.622615095457, 9438.0, 9614.9038509614, 9618.903810961941, 9641.228701703847, 7653.579053152019, 9305.720828375159, 9573.712788616353, 9596.376235544667, 9634.421934683942, 4879.975205206946, 4891.923776769105, 4930.926036109461, 4953.455119936804, 4996.475370086088, 1856.4152292027968, 2011.5675129847152, 2072.9067191976396, 2177.4556360909774, 2177.9782202177776, 859.750672305037, 996.0189213624574, 1063.8404239364002, 1078.4607696151925, 1087.7389426537575, 19808.108635111454, 20605.48486287825, 20839.374818755463, 20999.685004724943, 21219.8939005304, 1002.5789168549227, 1040.3445829127602, 1063.1654151491043, 1074.065562960214, 1091.7270682329417, 10539.0, 11017.779644407066, 11325.263857849213, 11452.484638191301, 11487.081033517466, 529860.0, 539375.0, 540394.0, 647868.0, 650719.0, 112406.43796780964, 122972.0, 124046.37976810058, 131059.0, 140133.0, 930.7254359963827, 1098.2092893117103, 1107.0, 1147.2485521983115, 1156.346664134761, 18489.53776155607, 18868.24527018904, 18905.90547047311, 18920.810791892454, 19008.904955475133, 51633.483665162865, 51912.0, 52300.38498075157, 52310.0, 53585.12452064159, 74699.0, 76113.61943190248, 77751.0, 77769.0, 78066.2193378059, 8249.958750206211, 8326.0, 8378.958105209435, 8388.245057944814, 8474.66101355939, 9636.71089867305, 13173.802392964304, 13256.602301930958, 13460.990425717915, 13467.528636497676, 11056.336619802838, 11388.829167562328, 11649.242799218024, 11697.590584329508, 11753.941230294136, 10922.344659320466, 11198.44007799623, 11404.82892756626, 11415.0, 11558.959693627614, 5965.761369545343, 8875.600597972978, 9189.264858811413, 9649.662261821084, 10557.049865511834, 7502.962485187539, 7803.258690424401, 8318.875216871873, 8400.537970411686, 8474.915250847413, 7934.642941067781, 10348.327358721659, 11491.540338386705, 11774.0, 11810.940945295219], "value": 9754.500239746436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.9325930333137, 1629.3645478263475, 1867.3650958674048, 1950.912208950598, 2015.758109026917, 8270.793230169249, 9011.864822027675, 9124.1332073453, 9258.490783006931, 9299.953500232506, 8929.241014513775, 8951.060138685425, 9072.455652660845, 9087.363884528086, 9121.996580376153, 2484.8757562121914, 2504.4239824840297, 2505.84964902106, 2514.4342522932284, 2515.2705715342527, 2457.6436416719566, 2514.509670614231, 2525.911593094242, 2530.607755797854, 2532.734062923385, 2469.7283298837115, 2517.874106294687, 2550.3879069023487, 2553.9744602553924, 2561.1804222648666, 2514.5599520084, 2536.8224224304213, 2537.416394229319, 2549.821512494126, 2558.5522533556646, 2508.059477695864, 2523.40699935515, 2526.6715327007555, 2527.1155095716535, 2560.398306397995, 9267.30495212855, 9269.165775080208, 9309.487978161198, 9313.674021409286, 9325.580348884316, 5576.972115139439, 5675.460831221028, 5707.486326230618, 5721.628094173865, 5727.9427205727825, 3203.4874420092674, 3365.478350855621, 3599.4420864766003, 3687.2441149564274, 3757.868474603376, 1052.8420736889452, 1067.124957534814, 1072.158355690779, 1072.2601405030582, 1074.9623763168252, 11873.465694043702, 11955.462004209832, 12130.757384852343, 12313.26120432777, 12515.937420312932, 4854.902901941941, 4893.926591101136, 4930.359053323117, 4966.255061740696, 4990.0, 10299.742506437398, 10325.793484130276, 10356.223283253787, 10566.366018038942, 10624.256302058937, 5491.066518691832, 5510.972445137749, 5545.168224766237, 5553.139263414178, 5564.137558678412, 4567.314902764612, 4953.2570114483115, 4966.056449274626, 5033.949660503421, 5050.393952725696, 4211.663066954637, 4222.915541689149, 4230.196262710137, 4237.427947227146, 4319.870403887889, 1373.5467295792364, 1398.7971744097254, 1403.4666826605824, 1409.9506517271845, 1410.4499245294344, 912.9729054813336, 915.914641150239, 969.3941286695815, 989.747614358334, 998.1568687522637, 6002.369751176151, 6021.3677563856045, 6071.0, 6207.534434917399, 6233.844153896189, 405.2624223912451, 462.1104374079883, 478.3136199553582, 478.849162513814, 482.58497691985565, 4022.396640503889, 4100.794960251976, 4107.712460127822, 4115.609017143366, 4117.75293482392, 11002.174836887265, 11338.603148889913, 11389.829152562717, 11390.772184556263, 11427.0, 10489.0, 10778.19163562736, 10827.025567698945, 10881.075108615698, 10905.236633435583, 6408.647524386203, 6798.490113241527, 8332.16678332174, 8541.0, 8629.09394513567, 6125.785597504066, 6131.497783043004, 6150.384961503813, 6215.7529993293065, 6239.594426362272, 8405.159484051546, 9928.305018648914, 9975.052370024836, 10218.540165692413, 10257.487125643836, 9948.154406875497, 10156.847647285149, 10201.0, 10457.320274182153, 10470.267081304235, 7069.328413800681, 7511.286427789351, 7740.380769538536, 7812.492188007761, 7820.217978202134, 7279.927200727925, 8353.289970352345, 8500.872486912825, 8550.35872309567, 8583.35624828128, 6640.734370625314, 7448.627568621656, 7555.433342499225, 7572.659230334538, 7729.961350193213, 6319.905201422074, 7388.741394051182, 7431.0, 7455.0681164854395, 7539.623018849145, 7208.135023797178, 7275.236100209613, 7309.597972111585, 7310.159331676925, 7328.2305357938785, 4975.452700203047, 5025.9592946266985, 5080.949190508048, 5100.668456550312, 5150.304708864255, 5049.974750126225, 5076.36545431821, 5124.538791508782, 5160.896782064413, 5314.441983591667], "value": 4667.785149642869, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.8666070277321, 325.66944551280454, 345.43521342604834, 347.29152528841155, 348.4337950829901, 6823.658817059152, 6914.3777060064585, 7019.192792828826, 7053.118360204974, 7077.256888026754, 7011.89482157768, 7081.61051142187, 7093.219745827955, 7103.33624890666, 7111.146662400519, 4184.769837658928, 5159.793608255665, 5199.402068762093, 5200.32395788547, 5219.295395121667, 5210.0, 5227.607929405291, 5234.42421333653, 5235.921461178085, 5237.3453318335205, 5155.304033955405, 5162.767675454614, 5169.896602067975, 5175.482451754832, 5177.145770947808, 4807.975960120195, 5020.397552293712, 5049.899002019939, 5161.66449180802, 5163.3545806774155, 4267.466566679165, 4276.294411422143, 4288.16380805743, 4308.590683885026, 4324.437823083011, 6618.86762264752, 6619.1395118634755, 6642.5350225484235, 6647.468202543785, 6725.899111513331, 4795.496472870368, 4819.614430845559, 5959.702014899238, 6011.0, 6031.24609423822, 7480.334636028307, 7492.7752167435065, 7630.961845190794, 7639.809004774869, 7661.7701468955465, 4585.564371384713, 4757.976210118962, 4762.52374762521, 4771.093492236499, 4804.4234691836855, 6746.359095885932, 7902.525848449054, 7932.603369831543, 7962.3630109591095, 7973.122956474841, 4842.661013729075, 4863.902721945612, 4866.46468888422, 4884.3161957326, 4892.094962431962, 4970.15507363749, 4979.4771548987555, 4980.302757613937, 4993.151164302005, 5000.89998200034, 5264.184051472028, 5615.932972735167, 6001.759929602855, 6019.789307374221, 6035.215421995113, 6432.742690292336, 7310.415166786751, 7517.398608111447, 7565.735199268, 7586.317231449196, 1708.3166733306778, 1709.6067904382062, 1724.4136993422046, 1726.9309227630754, 1736.0625262358437, 1012.9594816207269, 1066.589363095214, 1067.631667074858, 1070.1224995503612, 1093.7156339351666, 6949.861002779916, 6951.7219311228, 6955.6869940852785, 6958.37374636275, 6963.82590435233, 5788.437121977043, 6951.443884489228, 7734.767956961187, 7743.7676869694, 7759.57322347265, 6352.269489008823, 6726.461883049345, 7027.61348125858, 7033.718651253893, 7064.293570642894, 4038.6163314485075, 4083.1629515949157, 4130.132672138824, 4139.337705967031, 4147.502299723992, 7776.0, 8292.917070829335, 8311.750647480585, 8327.66689332432, 8341.45780524276, 8179.550124743197, 8194.631241594143, 8198.508089514648, 8205.753827385188, 8226.42415030954, 8299.502029878227, 8316.833663326699, 8326.916730832736, 8329.292010179202, 8330.333573314121, 7519.135299440634, 7784.727534536491, 7789.64946577394, 7811.453198276294, 7904.051513818378, 6179.62922224668, 6240.875182496416, 7649.120351159688, 7771.067471903408, 7797.41519386037, 7175.354218120394, 7762.680344341588, 8257.0, 8263.049749278909, 8265.090840007715, 4852.733099679552, 4895.388076490439, 4897.118518666674, 4908.828191013297, 4921.138800709933, 7484.887726683995, 7727.884081738666, 7760.92239077602, 7779.066512018594, 7910.920890791019, 6701.229358623821, 7477.551346919202, 7567.167611562613, 7585.658645360862, 7595.810104747426, 7554.622268886644, 7571.394288457021, 7584.696612135453, 7602.847943041219, 7630.427717921067, 5808.8091941151115, 7153.284671533013, 7255.129384473897, 7378.631068446663, 7425.183229844821, 7540.245975402416, 7570.16728159913, 7591.240875912365, 7592.734254301073, 7644.197359277196, 7087.818187269329, 7256.818579535554, 7434.36807871337, 7468.253174682488, 7479.439042071757], "value": 5417.381673103567, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.6224919667973, 735.1655870586883, 760.9878861114721, 789.9565523896183, 798.6925033861964, 15526.534203973884, 15698.686026279482, 15704.528864134094, 15707.764383534257, 15743.370265189407, 14149.29253537324, 14318.427262909498, 14333.856661433383, 14349.0, 14364.425422983095, 758.662395234121, 910.4901255297021, 933.4725879877864, 959.9568019439107, 960.0591420407969, 782.5930516131608, 917.4953775423523, 919.296737995434, 952.8665986761858, 956.8899576548681, 966.2028826218398, 972.1639390124523, 977.8386566216566, 997.4813097189457, 1003.1373019203479, 965.859950307205, 999.4902599674182, 1001.749562609344, 1004.7036124343336, 1007.8992100789935, 728.2389902551846, 735.0921611809412, 746.6192241956587, 759.2255898983061, 760.752755354512, 15002.924985375112, 15031.624209394853, 15162.090274583561, 15352.616184595363, 15403.768943465857, 10649.57401703931, 12259.754804903852, 12474.251544907334, 12587.24476531411, 12732.17240879349, 10152.898471015269, 10232.488375581266, 10338.224633152544, 10523.0, 10578.312409693346, 3556.875509357186, 3570.285942811448, 3579.266250418658, 3621.2395396966667, 3722.9627703722886, 62060.0, 68529.65735171342, 75232.62383688102, 82838.58580707057, 84791.15208847895, 8375.874361884577, 9583.856242156373, 9954.352967057119, 9993.900060999442, 10287.080148395318, 22566.548669026528, 22965.081396744277, 22992.310230693107, 23084.884575577016, 23116.3065108047, 7486.43851711113, 7505.211952744992, 7505.849883002309, 7585.734499292498, 7818.0, 4073.7148399612333, 4122.587741225853, 4145.709800313949, 4162.7502349859105, 4174.791260437027, 3669.6330366963625, 3705.370087085202, 3708.592054873962, 3735.3089678409583, 3758.3986562149926, 568.2839622076214, 643.9839004024936, 657.1982181738272, 664.4750646988824, 672.6804767735287, 574.0265425098032, 592.8992071347881, 612.1827360473728, 613.3192156706052, 615.7229246838851, 8384.706535271236, 8396.17523058617, 8405.95797021011, 8448.450850694686, 8553.615087321086, 518.3235877180316, 538.6014349381429, 548.958828087895, 595.6188039654625, 618.7432215630553, 3722.274156539503, 3782.3002744492355, 3859.2474467479133, 3960.326744453392, 3968.920621587552, 91167.54416227876, 91404.0, 91640.0, 91751.0, 95121.5243923776, 63861.08416747505, 74468.25531744721, 77606.0, 78100.99999999886, 78213.60893195611, 27837.860810695816, 28027.299317517227, 51033.97932041338, 53268.66828329323, 62825.685871571266, 7447.92552074494, 7622.5426474411715, 7799.727009554638, 7853.253940875607, 8056.315213206943, 34171.0, 44711.0, 45150.54849451463, 46571.06857862756, 46616.0, 62911.37088629238, 63737.36262637314, 68026.9795953071, 68470.65764671145, 69222.30777692159, 10173.08442240202, 10218.846717299099, 10344.637937672447, 10527.947360263455, 10702.73243168927, 9341.766455838659, 10438.63464778729, 10627.787444251226, 10808.0, 11166.994970452699, 8520.318374530147, 9044.909550904407, 10131.290809643255, 10467.581296748349, 10721.892781072089, 9255.814883702153, 9923.65267215644, 10326.328788628714, 10383.844242336521, 10830.458477076272, 8551.802747615215, 9602.08780165883, 9676.661316854157, 9959.601615935282, 10230.28388012832, 8384.0, 8718.17177368149, 8802.60388282516, 8985.910140898508, 9020.278377729897, 8422.157784221528, 8751.212390884852, 8788.472691638523, 8789.648414063366, 8979.685711000084], "value": 6693.107052384708, "unit": "score"}], "name": "dromaeo_css", "value": 7556.605990572509, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 116.0, 118.0, 117.0, 132.0, 115.0, 116.0, 116.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 141.0, 158.0, 121.0, 140.0, 125.0, 156.0, 164.0, 118.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 198.0, 196.0, 202.0, 197.0, 200.0, 194.0, 193.0, 202.0, 196.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 82.0, 107.0, 182.0, 56.0, 57.0, 57.0, 59.0, 128.0, 56.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 136.0, 77.0, 79.0, 90.0, 122.0, 81.0, 80.0, 122.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 85.0, 118.0, 131.0, 89.0, 87.0, 91.0, 86.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 97.0, 94.0, 96.0, 95.0, 96.0, 96.0, 95.0, 94.0, 103.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 89.0, 89.0, 91.0, 109.0, 148.0, 124.0, 91.0, 123.0], "value": 116.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 63.0, 65.0, 65.0, 65.0, 64.0, 79.0, 61.0, 61.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 69.0, 73.0, 72.0, 68.0, 65.0, 66.0, 65.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 94.0, 108.0, 68.0, 94.0, 77.0, 97.0, 109.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 137.0, 116.0, 144.0, 153.0, 155.0, 144.0, 125.0, 147.0, 139.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 189.0, 146.0, 194.0, 221.0, 216.0, 166.0, 164.0, 194.0, 210.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 62.0, 61.0, 67.0, 84.0, 97.0, 97.0, 93.0, 73.0], "value": 76.9, "unit": "score"}], "name": "kraken", "value": 1568.7, "alertThreshold": 2.0}]} 15:21:32 INFO - Return code: 0 15:21:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:21:32 ERROR - # TBPL SUCCESS # 15:21:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:21:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:21:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:21:32 INFO - 2016-06-29 15:21:32.456 screenresolution[1683:10349] starting screenresolution argv=screenresolution get 15:21:32 INFO - 2016-06-29 15:21:32.459 screenresolution[1683:10349] Display 0: 1600x1200x32@60 15:21:32 INFO - 2016-06-29 15:21:32.465 screenresolution[1684:10352] starting screenresolution argv=screenresolution list 15:21:32 INFO - Available Modes on Display 0 15:21:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:21:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:21:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:21:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:21:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:21:32 INFO - Intel Iris: 15:21:32 INFO - Chipset Model: Intel Iris 15:21:32 INFO - Type: GPU 15:21:32 INFO - Bus: Built-In 15:21:32 INFO - VRAM (Dynamic, Max): 1536 MB 15:21:32 INFO - Vendor: Intel (0x8086) 15:21:32 INFO - Device ID: 0x0a2e 15:21:32 INFO - Revision ID: 0x0009 15:21:32 INFO - Displays: 15:21:32 INFO - Display: 15:21:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:21:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:21:32 INFO - Display Serial Number: 1600x1200 60 15:21:32 INFO - Main Display: Yes 15:21:32 INFO - Mirror: Off 15:21:32 INFO - Online: Yes 15:21:32 INFO - Rotation: Supported 15:21:32 INFO - Return code: 0 15:21:32 INFO - Running post-action listener: _resource_record_post_action 15:21:32 INFO - Running post-run listener: _resource_record_post_run 15:21:33 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 36918784; Write bytes: 496812032; Read time: 241; Write time: 1569 15:21:33 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 200217088; Write bytes: 194686976; Read time: 17553; Write time: 449 15:21:33 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 29797888; Write bytes: 301277184; Read time: 182; Write time: 1118 15:21:33 INFO - Running post-run listener: _upload_blobber_files 15:21:33 INFO - Blob upload gear active. 15:21:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:21:33 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 15:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:21:34 INFO - (blobuploader) - INFO - Open directory for files ... 15:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:21:35 INFO - (blobuploader) - INFO - Done attempting. 15:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:21:36 INFO - (blobuploader) - INFO - Done attempting. 15:21:36 INFO - (blobuploader) - INFO - Iteration through files over. 15:21:36 INFO - Return code: 0 15:21:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:21:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:21:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c24031df7a24a1d1d4bc72b65dba6c82290230fe8d93a0efd4e47c4b8ee173583c1bf3fe1be0761105c8f81aa660a935bf965302624aa2c458643e75c51ae2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86c43303ec88a08d01198459eeee2f49131a967aa6a80f53eefa07f7bd1c395ca03ee138dcfa158fe458e30506ce7d7a5b7b1357e8b12daf3a6d3c806047e931"} 15:21:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:21:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:21:36 INFO - Contents: 15:21:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c24031df7a24a1d1d4bc72b65dba6c82290230fe8d93a0efd4e47c4b8ee173583c1bf3fe1be0761105c8f81aa660a935bf965302624aa2c458643e75c51ae2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86c43303ec88a08d01198459eeee2f49131a967aa6a80f53eefa07f7bd1c395ca03ee138dcfa158fe458e30506ce7d7a5b7b1357e8b12daf3a6d3c806047e931"} 15:21:36 INFO - Running post-run listener: copy_logs_to_upload_dir 15:21:36 INFO - Copying logs to upload dir... 15:21:36 INFO - mkdir: /builds/slave/test/build/upload/logs 15:21:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.226072 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 15:21:36.366626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 15:21:36.370102) ========= 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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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/Mozilla-Beta/sha512/1c24031df7a24a1d1d4bc72b65dba6c82290230fe8d93a0efd4e47c4b8ee173583c1bf3fe1be0761105c8f81aa660a935bf965302624aa2c458643e75c51ae2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86c43303ec88a08d01198459eeee2f49131a967aa6a80f53eefa07f7bd1c395ca03ee138dcfa158fe458e30506ce7d7a5b7b1357e8b12daf3a6d3c806047e931"} build_url:https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011045 build_url: 'https://queue.taskcluster.net/v1/task/ZbnTI0C9QCq8XXNWZt-Ocw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c24031df7a24a1d1d4bc72b65dba6c82290230fe8d93a0efd4e47c4b8ee173583c1bf3fe1be0761105c8f81aa660a935bf965302624aa2c458643e75c51ae2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86c43303ec88a08d01198459eeee2f49131a967aa6a80f53eefa07f7bd1c395ca03ee138dcfa158fe458e30506ce7d7a5b7b1357e8b12daf3a6d3c806047e931"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 15:21:36.402537) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:21:36.402897) ========= 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.f9kaIXvxgB/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.pjFWmjDjEJ/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005448 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:21:36.451509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 15:21:36.451844) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 15:21:36.452189) ========= ========= Total master_lag: 0.17 =========