builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0133 starttime: 1460981917.47 results: success (0) buildid: 20160418030305 builduid: 77bf842e77b14c10a37b789d99c2991a revision: 1f16d3da9280e40ada252acf8110b91ee1edbb08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 05:18:37.466580) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 05:18:37.467314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-18 05:18:37.467611) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-18 05:18:52.943670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 05:18:52.944015) ========= 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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-18 05:18:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.95M=0.002s 2016-04-18 05:18:54 (6.95 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.597863 ========= master_lag: 1.75 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 05:18:55.292166) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 05:18:55.292461) ========= 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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.019672 ========= master_lag: 1.55 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 05:18:56.865194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 05:18:56.865530) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f16d3da9280e40ada252acf8110b91ee1edbb08 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1f16d3da9280e40ada252acf8110b91ee1edbb08 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-18 05:18:56,897 truncating revision to first 12 chars 2016-04-18 05:18:56,897 Setting DEBUG logging. 2016-04-18 05:18:56,897 attempt 1/10 2016-04-18 05:18:56,897 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f16d3da9280?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 05:18:57,696 unpacking tar archive at: mozilla-central-1f16d3da9280/testing/mozharness/ program finished with exit code 0 elapsedTime=1.052560 ========= master_lag: 1.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 05:18:59.216021) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 05:18:59.216305) ========= script_repo_revision: 1f16d3da9280e40ada252acf8110b91ee1edbb08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 05:18:59.216670) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-18 05:18:59.219960) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-18 05:19:00.856341) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 05:19:00.856599) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:19:01 INFO - MultiFileLogger online at 20160418 05:19:01 in /builds/slave/test 05:19:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:19:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:01 INFO - {'append_to_log': False, 05:19:01 INFO - 'base_work_dir': '/builds/slave/test', 05:19:01 INFO - 'blob_upload_branch': 'Firefox', 05:19:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:01 INFO - 'branch': 'Firefox', 05:19:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:01 INFO - 'config_files': ('talos/mac_config.py',), 05:19:01 INFO - 'default_actions': ('clobber', 05:19:01 INFO - 'read-buildbot-config', 05:19:01 INFO - 'download-and-extract', 05:19:01 INFO - 'populate-webroot', 05:19:01 INFO - 'create-virtualenv', 05:19:01 INFO - 'install', 05:19:01 INFO - 'run-tests'), 05:19:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:01 INFO - 'download_minidump_stackwalk': True, 05:19:01 INFO - 'download_symbols': 'ondemand', 05:19:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:19:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:19:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:19:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:19:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:01 INFO - 'installer_path': 'installer.exe', 05:19:01 INFO - 'log_level': 'info', 05:19:01 INFO - 'log_name': 'talos', 05:19:01 INFO - 'log_to_console': True, 05:19:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:19:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:19:01 INFO - 'opt_config_files': (), 05:19:01 INFO - 'pip_index': False, 05:19:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:01 INFO - 'cmd': ('bash', 05:19:01 INFO - '-c', 05:19:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:01 INFO - 'enabled': True, 05:19:01 INFO - 'halt_on_failure': False, 05:19:01 INFO - 'name': 'check_screen_resolution'},), 05:19:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:01 INFO - 'cmd': ('bash', 05:19:01 INFO - '-c', 05:19:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:01 INFO - 'enabled': True, 05:19:01 INFO - 'halt_on_failure': False, 05:19:01 INFO - 'name': 'check_screen_resolution'},), 05:19:01 INFO - 'run_cmd_checks_enabled': True, 05:19:01 INFO - 'sps_profile': False, 05:19:01 INFO - 'sps_profile_interval': 0, 05:19:01 INFO - 'suite': 'dromaeojs', 05:19:01 INFO - 'system_bits': '32', 05:19:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:01 INFO - 'title': 't-yosemite-r7-0133', 05:19:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:01 INFO - 'use_talos_json': True, 05:19:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:01 INFO - 'work_dir': 'build'} 05:19:01 INFO - ##### 05:19:01 INFO - ##### Running clobber step. 05:19:01 INFO - ##### 05:19:01 INFO - Running pre-action listener: _resource_record_pre_action 05:19:01 INFO - Running main action method: clobber 05:19:01 INFO - rmtree: /builds/slave/test/build 05:19:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:01 INFO - Running post-action listener: _resource_record_post_action 05:19:01 INFO - ##### 05:19:01 INFO - ##### Running read-buildbot-config step. 05:19:01 INFO - ##### 05:19:01 INFO - Running pre-action listener: _resource_record_pre_action 05:19:01 INFO - Running main action method: read_buildbot_config 05:19:01 INFO - Using buildbot properties: 05:19:01 INFO - { 05:19:01 INFO - "project": "", 05:19:01 INFO - "product": "firefox", 05:19:01 INFO - "script_repo_revision": "production", 05:19:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:19:01 INFO - "repository": "", 05:19:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:19:01 INFO - "buildid": "20160418030305", 05:19:01 INFO - "stage_platform": "macosx64", 05:19:01 INFO - "basedir": "/builds/slave/test", 05:19:01 INFO - "nightly_build": "True", 05:19:01 INFO - "buildnumber": 212, 05:19:01 INFO - "slavename": "t-yosemite-r7-0133", 05:19:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:19:01 INFO - "platform": "yosemite_r7", 05:19:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:19:01 INFO - "branch": "mozilla-central", 05:19:01 INFO - "repo_path": "mozilla-central", 05:19:01 INFO - "revision": "1f16d3da9280e40ada252acf8110b91ee1edbb08", 05:19:01 INFO - "pgo_build": "False", 05:19:01 INFO - "builduid": "77bf842e77b14c10a37b789d99c2991a", 05:19:01 INFO - "slavebuilddir": "test" 05:19:01 INFO - } 05:19:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:19:01 INFO - Running post-action listener: _resource_record_post_action 05:19:01 INFO - ##### 05:19:01 INFO - ##### Running download-and-extract step. 05:19:01 INFO - ##### 05:19:01 INFO - Running pre-action listener: _resource_record_pre_action 05:19:01 INFO - Running main action method: download_and_extract 05:19:01 INFO - mkdir: /builds/slave/test/build/tests 05:19: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')]} 05:19:01 INFO - https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:19:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:19:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:19:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:19:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:19:02 INFO - Downloaded 1199 bytes. 05:19:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:19:02 INFO - Using the following test package requirements: 05:19:02 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:19:02 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:19:02 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:19:02 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'jsshell-mac.zip'], 05:19:02 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:19:02 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:19:02 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:19:02 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:19:02 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:19:02 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:19:02 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:19:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:02 INFO - https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:19:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:19:03 INFO - Downloaded 18032156 bytes. 05:19:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:19:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:19:07 INFO - Return code: 0 05:19:07 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:07 INFO - https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:19:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:19:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:19:08 INFO - Downloaded 18032156 bytes. 05:19:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:19:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:19:11 INFO - Return code: 0 05:19: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')]} 05:19:11 INFO - https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:19:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:19:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:19:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:19:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:19:12 INFO - Downloaded 10841076 bytes. 05:19:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:19:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:19:13 INFO - Return code: 0 05:19: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')]} 05:19:13 INFO - https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:19:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:19:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:19:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:19:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:19:17 INFO - Downloaded 109985954 bytes. 05:19:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:19:17 INFO - mkdir: /builds/slave/test/properties 05:19:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:17 INFO - Contents: 05:19:17 INFO - build_url:https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:19:18 INFO - Running post-action listener: _resource_record_post_action 05:19:18 INFO - Running post-action listener: set_extra_try_arguments 05:19:18 INFO - ##### 05:19:18 INFO - ##### Running populate-webroot step. 05:19:18 INFO - ##### 05:19:18 INFO - Running pre-action listener: _resource_record_pre_action 05:19:18 INFO - Running main action method: populate_webroot 05:19:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:18 INFO - '--tppagecycles', 05:19:18 INFO - '7'], 05:19:18 INFO - 'tests': ['tsvgm']}}, 05:19:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['damp', 'tps']}, 05:19:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'tests': ['damp', 'tps']}, 05:19:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['dromaeo_dom']}, 05:19:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['a11yr', 05:19:18 INFO - 'ts_paint', 05:19:18 INFO - 'tpaint', 05:19:18 INFO - 'sessionrestore', 05:19:18 INFO - 'sessionrestore_no_auto_restore', 05:19:18 INFO - 'tabpaint']}, 05:19:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['tsvgx', 05:19:18 INFO - 'tsvgr_opacity', 05:19:18 INFO - 'tart', 05:19:18 INFO - 'tscrollx', 05:19:18 INFO - 'cart']}, 05:19:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:18 INFO - 'tsvgr_opacity', 05:19:18 INFO - 'tart', 05:19:18 INFO - 'tscrollx', 05:19:18 INFO - 'cart']}, 05:19:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'talos_options': ['--disable-e10s'], 05:19:18 INFO - 'tests': ['tp5o']}, 05:19:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'tests': ['tp5o']}, 05:19:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'talos_options': ['--disable-e10s', 05:19:18 INFO - '--xperf_path', 05:19:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:18 INFO - 'tests': ['tp5n']}, 05:19:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:18 INFO - 'talos_options': ['--xperf_path', 05:19:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:18 INFO - 'tests': ['tp5n']}}, 05:19:18 INFO - 'talos.zip': {'path': '', 05:19:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:18 INFO - Running post-action listener: _resource_record_post_action 05:19:18 INFO - ##### 05:19:18 INFO - ##### Running create-virtualenv step. 05:19:18 INFO - ##### 05:19:18 INFO - Running pre-action listener: _resource_record_pre_action 05:19:18 INFO - Running main action method: create_virtualenv 05:19:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:19:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:18 INFO - Using real prefix '/tools/python27' 05:19:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:22 INFO - Installing pip.................done. 05:19:22 INFO - Return code: 0 05:19:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:22 INFO - 'HOME': '/Users/cltbld', 05:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:22 INFO - 'LOGNAME': 'cltbld', 05:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:22 INFO - 'MOZ_NO_REMOTE': '1', 05:19:22 INFO - 'NO_EM_RESTART': '1', 05:19:22 INFO - 'PAGER': '/bin/cat', 05:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:22 INFO - 'PWD': '/builds/slave/test', 05:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:22 INFO - 'SHELL': '/bin/bash', 05:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:22 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:22 INFO - 'USER': 'cltbld', 05:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:22 INFO - 'XPC_FLAGS': '0x0', 05:19:22 INFO - 'XPC_SERVICE_NAME': '0', 05:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:19:22 INFO - Cleaning up... 05:19:22 INFO - Return code: 0 05:19:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:22 INFO - 'HOME': '/Users/cltbld', 05:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:22 INFO - 'LOGNAME': 'cltbld', 05:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:22 INFO - 'MOZ_NO_REMOTE': '1', 05:19:22 INFO - 'NO_EM_RESTART': '1', 05:19:22 INFO - 'PAGER': '/bin/cat', 05:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:22 INFO - 'PWD': '/builds/slave/test', 05:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:22 INFO - 'SHELL': '/bin/bash', 05:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:22 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:22 INFO - 'USER': 'cltbld', 05:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:22 INFO - 'XPC_FLAGS': '0x0', 05:19:22 INFO - 'XPC_SERVICE_NAME': '0', 05:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:22 INFO - Downloading/unpacking psutil>=0.7.1 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:25 INFO - Installing collected packages: psutil 05:19:25 INFO - Running setup.py install for psutil 05:19:26 INFO - building 'psutil._psutil_osx' extension 05:19:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:19:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:19:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:19:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:19:26 INFO - building 'psutil._psutil_posix' extension 05:19:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:19:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:19:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:19:26 INFO - ^ 05:19:26 INFO - 1 warning generated. 05:19:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:19:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:26 INFO - Successfully installed psutil 05:19:26 INFO - Cleaning up... 05:19:26 INFO - Return code: 0 05:19:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:26 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:26 INFO - 'HOME': '/Users/cltbld', 05:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:26 INFO - 'LOGNAME': 'cltbld', 05:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:26 INFO - 'MOZ_NO_REMOTE': '1', 05:19:26 INFO - 'NO_EM_RESTART': '1', 05:19:26 INFO - 'PAGER': '/bin/cat', 05:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:26 INFO - 'PWD': '/builds/slave/test', 05:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:26 INFO - 'SHELL': '/bin/bash', 05:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:26 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:26 INFO - 'USER': 'cltbld', 05:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:26 INFO - 'XPC_FLAGS': '0x0', 05:19:26 INFO - 'XPC_SERVICE_NAME': '0', 05:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:19:29 INFO - Installing collected packages: mozsystemmonitor 05:19:29 INFO - Running setup.py install for mozsystemmonitor 05:19:29 INFO - Successfully installed mozsystemmonitor 05:19:29 INFO - Cleaning up... 05:19:29 INFO - Return code: 0 05:19:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:29 INFO - 'HOME': '/Users/cltbld', 05:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:29 INFO - 'LOGNAME': 'cltbld', 05:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:29 INFO - 'MOZ_NO_REMOTE': '1', 05:19:29 INFO - 'NO_EM_RESTART': '1', 05:19:29 INFO - 'PAGER': '/bin/cat', 05:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:29 INFO - 'PWD': '/builds/slave/test', 05:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:29 INFO - 'SHELL': '/bin/bash', 05:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:29 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:29 INFO - 'USER': 'cltbld', 05:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:29 INFO - 'XPC_FLAGS': '0x0', 05:19:29 INFO - 'XPC_SERVICE_NAME': '0', 05:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:33 INFO - Downloading docopt-0.6.1.tar.gz 05:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:33 INFO - Running setup.py install for blobuploader 05:19:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:34 INFO - Running setup.py install for requests 05:19:34 INFO - Running setup.py install for docopt 05:19:34 INFO - Successfully installed blobuploader requests docopt 05:19:34 INFO - Cleaning up... 05:19:34 INFO - Return code: 0 05:19:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:34 INFO - 'HOME': '/Users/cltbld', 05:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:34 INFO - 'LOGNAME': 'cltbld', 05:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:34 INFO - 'MOZ_NO_REMOTE': '1', 05:19:34 INFO - 'NO_EM_RESTART': '1', 05:19:34 INFO - 'PAGER': '/bin/cat', 05:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:34 INFO - 'PWD': '/builds/slave/test', 05:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:34 INFO - 'SHELL': '/bin/bash', 05:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:34 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:34 INFO - 'USER': 'cltbld', 05:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:34 INFO - 'XPC_FLAGS': '0x0', 05:19:34 INFO - 'XPC_SERVICE_NAME': '0', 05:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-hO4aDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-bqJTKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Ycl46I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Pa1aUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-38dHg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1dowEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-G8M1sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-H7qEBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sgu4gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:35 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-r_mnOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TUcnFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-DKTygT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-gPOkX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-O8ixFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-QyQt_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rbo16j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:36 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-BF_LKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:36 INFO - Running setup.py install for manifestparser 05:19:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:36 INFO - Running setup.py install for mozcrash 05:19:37 INFO - Running setup.py install for mozdebug 05:19:37 INFO - Running setup.py install for mozdevice 05:19:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Running setup.py install for mozfile 05:19:37 INFO - Running setup.py install for mozhttpd 05:19:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Running setup.py install for mozinfo 05:19:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Running setup.py install for mozInstall 05:19:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:37 INFO - Running setup.py install for mozleak 05:19:38 INFO - Running setup.py install for mozlog 05:19:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for moznetwork 05:19:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozprocess 05:19:38 INFO - Running setup.py install for mozprofile 05:19:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozrunner 05:19:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:38 INFO - Running setup.py install for mozscreenshot 05:19:39 INFO - Running setup.py install for moztest 05:19:39 INFO - Running setup.py install for mozversion 05:19:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:39 INFO - Cleaning up... 05:19:39 INFO - Return code: 0 05:19:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:39 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:39 INFO - 'HOME': '/Users/cltbld', 05:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:39 INFO - 'LOGNAME': 'cltbld', 05:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:39 INFO - 'MOZ_NO_REMOTE': '1', 05:19:39 INFO - 'NO_EM_RESTART': '1', 05:19:39 INFO - 'PAGER': '/bin/cat', 05:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:39 INFO - 'PWD': '/builds/slave/test', 05:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:39 INFO - 'SHELL': '/bin/bash', 05:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:39 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:39 INFO - 'USER': 'cltbld', 05:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:39 INFO - 'XPC_FLAGS': '0x0', 05:19:39 INFO - 'XPC_SERVICE_NAME': '0', 05:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-GCedQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-2G8qB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-yNu01h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Mbbht9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-NaDReu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-gAJsxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-nFOYrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-r7mUym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-hCj9rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-V8Gs9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-jzK7CV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-BrBXcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-55Jy_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-u1U1PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-hTqihl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-KwNClD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-lxjC6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:43 INFO - Downloading blessings-1.6.tar.gz 05:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:44 INFO - Installing collected packages: blessings 05:19:44 INFO - Running setup.py install for blessings 05:19:44 INFO - Successfully installed blessings 05:19:44 INFO - Cleaning up... 05:19:44 INFO - Return code: 0 05:19:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:44 INFO - Reading from file tmpfile_stdout 05:19:44 INFO - Current package versions: 05:19:44 INFO - blessings == 1.6 05:19:44 INFO - blobuploader == 1.2.4 05:19:44 INFO - docopt == 0.6.1 05:19:44 INFO - manifestparser == 1.1 05:19:44 INFO - mozInstall == 1.12 05:19:44 INFO - mozcrash == 0.17 05:19:44 INFO - mozdebug == 0.1 05:19:44 INFO - mozdevice == 0.48 05:19:44 INFO - mozfile == 1.2 05:19:44 INFO - mozhttpd == 0.7 05:19:44 INFO - mozinfo == 0.9 05:19:44 INFO - mozleak == 0.1 05:19:44 INFO - mozlog == 3.1 05:19:44 INFO - moznetwork == 0.27 05:19:44 INFO - mozprocess == 0.22 05:19:44 INFO - mozprofile == 0.28 05:19:44 INFO - mozrunner == 6.11 05:19:44 INFO - mozscreenshot == 0.1 05:19:44 INFO - mozsystemmonitor == 0.0 05:19:44 INFO - moztest == 0.7 05:19:44 INFO - mozversion == 1.4 05:19:44 INFO - psutil == 3.1.1 05:19:44 INFO - requests == 1.2.3 05:19:44 INFO - wsgiref == 0.1.2 05:19:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:44 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:44 INFO - 'HOME': '/Users/cltbld', 05:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:44 INFO - 'LOGNAME': 'cltbld', 05:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:44 INFO - 'MOZ_NO_REMOTE': '1', 05:19:44 INFO - 'NO_EM_RESTART': '1', 05:19:44 INFO - 'PAGER': '/bin/cat', 05:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:44 INFO - 'PWD': '/builds/slave/test', 05:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:44 INFO - 'SHELL': '/bin/bash', 05:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:44 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:44 INFO - 'USER': 'cltbld', 05:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:44 INFO - 'XPC_FLAGS': '0x0', 05:19:44 INFO - 'XPC_SERVICE_NAME': '0', 05:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:44 INFO - Cleaning up... 05:19:44 INFO - Return code: 0 05:19:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:44 INFO - 'HOME': '/Users/cltbld', 05:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:44 INFO - 'LOGNAME': 'cltbld', 05:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:44 INFO - 'MOZ_NO_REMOTE': '1', 05:19:44 INFO - 'NO_EM_RESTART': '1', 05:19:44 INFO - 'PAGER': '/bin/cat', 05:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:44 INFO - 'PWD': '/builds/slave/test', 05:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:44 INFO - 'SHELL': '/bin/bash', 05:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:44 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:44 INFO - 'USER': 'cltbld', 05:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:44 INFO - 'XPC_FLAGS': '0x0', 05:19:44 INFO - 'XPC_SERVICE_NAME': '0', 05:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:45 INFO - Downloading/unpacking jsonschema 05:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:47 INFO - Downloading/unpacking functools32 (from jsonschema) 05:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:48 INFO - warning: no files found matching '*.txt' 05:19:48 INFO - no previously-included directories found matching 'build' 05:19:48 INFO - no previously-included directories found matching 'dist' 05:19:48 INFO - no previously-included directories found matching '.git*' 05:19:48 INFO - Installing collected packages: jsonschema, functools32 05:19:48 INFO - Running setup.py install for functools32 05:19:48 INFO - warning: no files found matching '*.txt' 05:19:48 INFO - no previously-included directories found matching 'build' 05:19:48 INFO - no previously-included directories found matching 'dist' 05:19:48 INFO - no previously-included directories found matching '.git*' 05:19:48 INFO - Successfully installed jsonschema functools32 05:19:48 INFO - Cleaning up... 05:19:48 INFO - Return code: 0 05:19:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:48 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 0x10c3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe97ad94270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, '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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:19:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:19:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:48 INFO - 'HOME': '/Users/cltbld', 05:19:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:48 INFO - 'LOGNAME': 'cltbld', 05:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:48 INFO - 'MOZ_NO_REMOTE': '1', 05:19:48 INFO - 'NO_EM_RESTART': '1', 05:19:48 INFO - 'PAGER': '/bin/cat', 05:19:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:48 INFO - 'PWD': '/builds/slave/test', 05:19:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:48 INFO - 'SHELL': '/bin/bash', 05:19:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:19:48 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:19:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:48 INFO - 'USER': 'cltbld', 05:19:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:48 INFO - 'XPC_FLAGS': '0x0', 05:19:48 INFO - 'XPC_SERVICE_NAME': '0', 05:19:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:48 INFO - Downloading/unpacking flake8 05:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:19:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:19:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:19:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:52 INFO - no previously-included directories found matching 'docs/_build' 05:19:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:19:52 INFO - Running setup.py install for pep8 05:19:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:52 INFO - no previously-included directories found matching 'docs/_build' 05:19:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:19:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:19:52 INFO - Cleaning up... 05:19:52 INFO - Return code: 0 05:19:52 INFO - Running post-action listener: _resource_record_post_action 05:19:52 INFO - Running post-action listener: _start_resource_monitoring 05:19:52 INFO - Starting resource monitoring. 05:19:52 INFO - ##### 05:19:52 INFO - ##### Running install step. 05:19:52 INFO - ##### 05:19:52 INFO - Running pre-action listener: _resource_record_pre_action 05:19:52 INFO - Running main action method: install 05:19:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:52 INFO - Reading from file tmpfile_stdout 05:19:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:53 INFO - Reading from file tmpfile_stdout 05:19:53 INFO - Output received: 05:19:53 INFO - Usage: mozinstall [options] installer 05:19:53 INFO - Options: 05:19:53 INFO - -h, --help show this help message and exit 05:19:53 INFO - -d DEST, --destination=DEST 05:19:53 INFO - Directory to install application into. [default: 05:19:53 INFO - "/builds/slave/test"] 05:19:53 INFO - --app=APP Application being installed. [default: firefox] 05:19:53 INFO - mkdir: /builds/slave/test/build/application 05:19:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:20:26 INFO - Reading from file tmpfile_stdout 05:20:26 INFO - Output received: 05:20:26 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:20:26 INFO - Running post-action listener: _resource_record_post_action 05:20:26 INFO - ##### 05:20:26 INFO - ##### Running run-tests step. 05:20:26 INFO - ##### 05:20:26 INFO - Running pre-action listener: _resource_record_pre_action 05:20:26 INFO - Running main action method: run_tests 05:20:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:26 INFO - 2016-04-18 05:20:26.674 screenresolution[6240:102379] starting screenresolution argv=screenresolution get 05:20:26 INFO - 2016-04-18 05:20:26.676 screenresolution[6240:102379] Display 0: 1600x1200x32@60 05:20:26 INFO - 2016-04-18 05:20:26.682 screenresolution[6241:102381] starting screenresolution argv=screenresolution list 05:20:26 INFO - Available Modes on Display 0 05:20:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:26 INFO - Intel Iris: 05:20:26 INFO - Chipset Model: Intel Iris 05:20:26 INFO - Type: GPU 05:20:26 INFO - Bus: Built-In 05:20:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:26 INFO - Vendor: Intel (0x8086) 05:20:26 INFO - Device ID: 0x0a2e 05:20:26 INFO - Revision ID: 0x0009 05:20:26 INFO - Displays: 05:20:26 INFO - Display: 05:20:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:26 INFO - Display Serial Number: 1600x1200 60 05:20:26 INFO - Main Display: Yes 05:20:26 INFO - Mirror: Off 05:20:26 INFO - Online: Yes 05:20:26 INFO - Rotation: Supported 05:20:26 INFO - Return code: 0 05:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:26 INFO - Python 2.7.3 05:20:26 INFO - Return code: 0 05:20:26 INFO - grabbing minidump binary from tooltool 05:20: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')]} 05:20:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c269e00>, '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 0x10c092db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c264b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:20:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:20:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:27 INFO - Return code: 0 05:20:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:20:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:20:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:20:28 INFO - Return code: 0 05:20:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cSW9Ruzn7E/Render', 05:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:28 INFO - 'HOME': '/Users/cltbld', 05:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:28 INFO - 'LOGNAME': 'cltbld', 05:20:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:28 INFO - 'MOZ_NO_REMOTE': '1', 05:20:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:28 INFO - 'NO_EM_RESTART': '1', 05:20:28 INFO - 'PAGER': '/bin/cat', 05:20:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:28 INFO - 'PWD': '/builds/slave/test', 05:20:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:28 INFO - 'SHELL': '/bin/bash', 05:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUHZFTqtPj/Listeners', 05:20:28 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 05:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:28 INFO - 'USER': 'cltbld', 05:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:28 INFO - 'XPC_FLAGS': '0x0', 05:20:28 INFO - 'XPC_SERVICE_NAME': '0', 05:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:20:28 INFO - mozversion application_buildid: 20160418030305 05:20:28 INFO - mozversion application_changeset: 1f16d3da9280e40ada252acf8110b91ee1edbb08 05:20:28 INFO - mozversion application_display_name: Nightly 05:20:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:28 INFO - mozversion application_name: Firefox 05:20:28 INFO - mozversion application_remotingname: firefox 05:20:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:20:28 INFO - mozversion application_vendor: Mozilla 05:20:28 INFO - mozversion application_version: 48.0a1 05:20:28 INFO - mozversion platform_buildid: 20160418030305 05:20:28 INFO - mozversion platform_changeset: 1f16d3da9280e40ada252acf8110b91ee1edbb08 05:20:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:20:28 INFO - mozversion platform_version: 48.0a1 05:20:28 INFO - using testdate: 1460982028 05:20:28 INFO - actual date: 1460982028 05:20:28 INFO - starting webserver on 'localhost:56974' 05:20:28 INFO - SUITE-START | Running 2 tests 05:20:28 INFO - TEST-START | dromaeo_css 05:20:28 INFO - operating with platform_type : mac_ 05:20:28 INFO - Initialising browser for dromaeo_css test... 05:20:28 INFO - TEST-INFO | started process 6259 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmp6r_zS7/profile http://localhost:56974/getInfo.html) 05:20:30 INFO - PROCESS | 6259 | __metrics Screen width/height:1600/1200 05:20:30 INFO - PROCESS | 6259 | colorDepth:24 05:20:30 INFO - PROCESS | 6259 | Browser inner width/height: 1024/689 05:20:30 INFO - PROCESS | 6259 | __metrics 05:20:30 INFO - PROCESS | 6259 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:20:30 ERROR - PROCESS | 6259 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:20:31 INFO - TEST-INFO | 6259: exit 0 05:20:31 INFO - Browser initialized. 05:20:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:31 INFO - TEST-INFO | started process 6263 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmp6r_zS7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:43 INFO - PROCESS | 6263 | RSS: Main: 237395968 05:20:43 INFO - PROCESS | 6263 | 05:21:35 INFO - PROCESS | 6263 | 1460982095282 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:23:45 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56974/tests/dromaeo/cssquery-ext.html) 05:23:47 INFO - PROCESS | 6263 | RSS: Main: 420065280 05:23:47 INFO - PROCESS | 6263 | 05:26:50 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-ext.html (next: http://localhost:56974/tests/dromaeo/cssquery-jquery.html) 05:26:51 INFO - PROCESS | 6263 | RSS: Main: 376053760 05:26:51 INFO - PROCESS | 6263 | 05:29:53 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56974/tests/dromaeo/cssquery-mootools.html) 05:29:54 INFO - PROCESS | 6263 | RSS: Main: 374726656 05:29:54 INFO - PROCESS | 6263 | 05:32:56 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56974/tests/dromaeo/cssquery-prototype.html) 05:32:56 INFO - PROCESS | 6263 | RSS: Main: 376406016 05:32:56 INFO - PROCESS | 6263 | 05:35:59 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56974/tests/dromaeo/cssquery-yui.html) 05:36:00 INFO - PROCESS | 6263 | RSS: Main: 345919488 05:36:00 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | Cycle 1(1): loaded http://localhost:56974/tests/dromaeo/cssquery-yui.html (next: http://localhost:56974/tests/dromaeo/cssquery-dojo.html) 05:39:03 INFO - PROCESS | 6263 | RSS: Main: 382369792 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | __start_tp_report 05:39:03 INFO - PROCESS | 6263 | _x_x_mozilla_page_load 05:39:03 INFO - PROCESS | 6263 | _x_x_mozilla_page_load_details 05:39:03 INFO - PROCESS | 6263 | |i|pagename|runs| 05:39:03 INFO - PROCESS | 6263 | |0;dojo.html;2863.212616530454;3349.296647703982;3425.5033020212077;3425.5718035245595;3458.5330980317667;5490.725463726818;5595.58033147514;5601.691906945127;5603.103503439445;5621.494065534096;5571.777128914849;5627.409122042188;5645.1814486899375;5645.491905728483;5645.689487078209;5132.717700526469;5161.7161056141895;5175.327207463025;5202.557782588485;5244.475552444763;5242.973785131069;5244.475552444763;5252.264682944372;5252.658577192489;5255.448177941318;4444.97777511112;5178.948210517903;5206.817761378353;5207.661502002376;5284.313039304885;5187.273781670569;5229.450907654679;5234.371875374942;5238.188080847474;5290.127129023706;5097.209932460474;5102.668326558762;5146.716930568855;5160.40655324635;5160.92258616121;5475.753591088411;5501.779928802843;5507.586930980193;5531.7234138293325;5544.16837474378;6158.445739883387;6159.661218632973;6176.66028368444;6183.072539119124;6191.133241346215;5267.815626453055;6112.541559383064;6132.233136608343;6212.347703491158;6235.688215589248;5682.829515114553;5695.572832037614;5719.7712091516705;5754.74103665336;5780.682062486561;6330.52521060913;6347.746090156342;6350.682465876687;6359.841003974845;6374.553781235269;5584.6090773646265;5849.1226316052935;5951.1073338999495;6040.063790112452;6055.273367195965;5724.141378793214;6154.353792851686;6162.722677479434;6165.290991535941;6170.981788005019;5979.312379076375;6035.124906888476;6062.393760623991;6076.210092687922;6113.144159817629;5996.98051331266;6109.75560977556;6109.84725381869;6151.5386346024225;6159.5072394208355;4823.469418363977;4852.320675105488;4868.756562171877;4871.634627402959;4881.145799485147;4348.673849461303;4369.12617476506;4429.025614364835;4431.911361772746;4469.284914413678;5232.1628539433505;5582.49757521825;5598.832035038955;5612.719364031782;5625.071863142617;4758.595519380891;6039.758409663565;6235.407636274546;6237.563370564108;6245.594036387621;5540.501354878;5570.832875013756;5613.270274864241;5615.9157612635845;5655.4910058094965;5264.526192642604;5332.813351532678;5336.359636843526;5337.2794672719465;5343.465653434625;6273.1844860167885;6289.7484100635465;6323.683815809191;6361.554691171574;6367.617942923347;5321.733913304397;6166.321704612579;6180.289266734383;6290.182276304235;6290.591111577733;6286.308506064421;6308.842278943063;6321.683915804283;6323.9683801580695;6331.525135614939;5961.314448838266;6046.3046749624355;6132.938670613236;6142.877142457215;6187.412195841388;6107.977837958671;6193.876122477435;6213.565050446426;6220.813375598558;6226;5914.674692891932;5925.022371308858;5943.257092863392;5949.25634295713;5989.7304621293015;5829;5898.3216930053595;5919.112133179885;5971.074483454898;5974.2830860297045;4771.308160316804;5803.216565763568;5806.593538452268;5807.8548036299435;5812.041013232769;5782.161586570008;5921.259842519685;5921.467067963904;5931.940680593311;5941.31674857397;5735.340435849931;5882.823515294548;5900.616459930091;5919.496842768241;5924.644521328734 05:39:03 INFO - PROCESS | 6263 | |1;ext.html;481.54975098283086;513.5070332480818;536.0966770990881;579.5103137848517;608.9647599081562;22703.659445108315;31071.378572428566;38304.23391532171;39339.40990885139;39724.40413393801;34534.13664658385;41489.7925510372;42015.57984420155;42064.94837629061;42215.78892105543;9457.148856602922;9717.902820971805;9719.416834989923;9728.90271097287;9764.755881102994;8552.401331906769;8871.379003469727;11684.240524365932;11961.700957476089;11994.880051199463;11537.077033837273;11652.941735291353;11653.067754579655;11654.24247423919;11656.592019279327;8577.39958202926;11017.614383496539;11500.42497875107;11639;11655.475503602274;11003.284786488852;11103.55585776568;11114.610988615359;11120.944395277971;11124.721881952935;38964.22071558553;39559.8022009891;39599;39613.009674758076;39753.80123099395;3431.725461963037;3743.026813028605;3763.6989040876665;3770.26479836432;4035.1727895781296;28525;29094;29471.70528294711;30045.549316760265;30056.248593785112;2643.973560264392;2686.570148776186;2708.4718479896233;2719.3745438549245;2772.50094982903;459842;460816;461803;461993;463471;34332.82833585816;51043.489565103875;62481.68759156175;62487.68756156281;62581.374186258465;34809.82595087059;35073;35132.473012904826;35144.76993305222;35175.4723679145;14749.926250368679;15102.773458398133;15119.773203401957;15124.621884452758;15133;11844.585439509576;12027.03783697302;12766.680832979064;12867.485300587872;12964.157329773534;8010.198980101944;8039.437239393186;8047.235512626291;8117.594120293962;8128.796780080545;3367.71374433171;3405.4721518164724;3407.6592340765724;3408.2161102945965;3433.8454769535433;858.6050416808213;999.605155963405;1003.9548220330103;1084.7233955341337;1088;29657.25856853596;29790.40419191604;30273.848630756704;30356.696433035828;30416.087517374515;1069.7646517766234;1106.6126855600476;1111.2276967507505;1142.2404101272562;1269.9365031748375;13455.596332109857;15327.923360383349;15451.690966180613;15579.643211990602;15594.532164035098;605133;613603;613813;615259;615520;60968.695156523936;62143.6892815533;62189;62191.37808621856;62385;1344.8184495093233;1344.9663758406118;1402.214759734552;1482.4440834686993;1490.180400779567;29555.965541205955;30160.24599385033;30451.2387190322;30601.928932488147;30734.231644209074;52453.21320180277;53080;53181.734091329294;53316.73341633267;53419.6645083876;390847;403870;407303;407375;408788;23718.762812371657;24297.75702242955;26316.4736705261;26945;27425.725742742317;42336.36495452509;42841.357379638706;44170.77914610406;44451;44465.33302000408;35211.82394088013;36714.8164259177;37451.625483744814;37965;38245.617543824206;35701.82149089238;35904.461433078;35953.82023089973;36871.26257474889;36971.89084327474;35189;35594.822025889705;37345.626543734215;37935.43096853494;37941.81029094837;30128.698713013466;32473.35053298968;32533.18667033343;32563.674363256065;32580.837095814368;24160.758392416556;24403.355298984487;24582.608825625073;24766.25701228966;25122.995080196586 05:39:03 INFO - PROCESS | 6263 | |2;jquery.html;384.1491097219659;384.45983393332375;386.4493097336295;395.38320220456075;404.1351507773364;18755.624887502257;20048.89731064791;20504.69242961357;20621.793782062192;20628.38114856555;19968.301109461172;20251.987400629983;20847.687284690743;21089.789102108974;21126.788732112673;5017.9247311290355;5278.445763194867;5313.043652142623;5545.473180047909;5556.638818476807;5358.732063396834;5511.917321240185;5539.750711217985;5563.610547261694;5564.1931919871595;5433.510984011438;5497.092979658351;5509.118541033436;5513.7794488220625;5546.86132846678;5360.571154307646;5508.614396992212;5514.724263786815;5527.2538207341895;5543.6396634218645;5067.0879241736475;5374;5383.488568585978;5385.5960802939935;5394.487523685244;13187.472501099945;13384.397702103428;14405.639859003504;23543.529129417486;23980.920858561407;2121.11973530986;2243.876586787726;2342.4846533762543;2410.1443987384578;2498.71264804548;9925.801483970352;9997.950010249902;10022.901523449213;10208.336458130272;10288.43413612251;1735.5314065202451;1742.2421246757701;1758.5955230296975;1796.380248814157;1863.1615772902248;88441;105470.47264763704;109693.45153274182;110018.54823791728;112089;11287.887121128848;11605.95546400828;11728.765424691459;11814.763704725858;11819.409029548488;14912.552623421314;14990;15006.174660393564;15012.324445399983;15072.849271507364;8664.350173736995;8710.608022638997;8711.950817213925;8731.869021964676;8838.337124715672;8699.130086991378;8699.565021748887;8717.782055448664;8727.170918762708;8851.911480885237;3768.2840260350445;4644.698094623838;4651.37206477128;4652.511486293891;4716.316134160527;1656.8425999530025;1685.233218885404;1756.2535922233;1910.3409323783274;1965.9213631454868;743.9962800186073;871.9258862996717;944.1078181118777;947.7535840681472;1018.5721996761378;19121;20534.38396848067;20752.896235518725;20923.68614470784;20933.16267349289;957.3011701457974;1005.527402121002;1006.7885743993843;1014.8224060789332;1048.638219814163;10516.053555180071;11023;11064.834027489595;11074.778504429867;11081.612143575097;419808;605319;614529;623032;626076.869615649;73953.26046739571;117544;118110.8188918117;126113;131375;968.5205823117602;971.533663841346;1001.9599216031423;1003.2926786615565;1003.3929472669076;18258.36095736643;18514.722279166093;18607.020329892843;18611.534711632317;19033.143508542427;48634.513654862996;49157.2626410597;49945;51101.744491277306;51862.740686296325;73113.6344318275;74811;75105;75191.24808752061;76919;7878.975733154883;8075.676972921252;8143.877841832259;8144.796380090545;8437.325013998743;12283.631491055283;12289.815652765394;12548.811767823307;12609.117361784587;12807.61577152687;10802.08182304513;10919.781604368027;11147.888521114684;11157.944210278896;11171.441427928734;10597.364158150536;10805.567777289134;10900.890991089987;10996.505157267782;11222.438878056228;10865.837012444661;11169.38568378747;11192.720181995515;11469.139814513777;11569.88430115688;6948.235694073547;7339.229380915141;7429.628518573942;7541.962290188514;8170.714025009096;9731.172850307803;11235.66293011211;11373.601923932954;11392.715182120513;11506.884931150917 05:39:03 INFO - PROCESS | 6263 | |3;mootools.html;1446.9204193769351;1639.6064944413338;1643.9424620138293;1923.81723736245;2021.1612180944908;7719.382449404048;8511.1914368135;8692.087330830267;8699.217070463657;8711.782205444868;8337;8577.52823594702;8579;8580.184882436175;8587;2370.6207006878903;2385.129427758864;2397.9760202398015;2399.2682231919257;2399.9040038398443;2369.8104151667912;2393.940151496218;2407.554602398553;2412.8069754419694;2413.2760171948435;2373.52529494101;2380.333506618141;2394.3295877154337;2403.399150212447;2406.0736616402637;2331.2423462374627;2370.668106465096;2373.050779688121;2379.8096152307776;2389.438481956739;2275.9317220483413;2366.9171578994824;2368.7039120109985;2371.3715865295712;2372.6678265042906;7706.614669266514;7953.40349473786;8592.183742544448;8686.826263474759;8687.087855775178;5135.794568217267;5277;5303.522682958553;5313.043652142613;5347.6256662033675;3455.481677748333;3479.1128262292955;3563.8574457021687;3572.5534308211472;3584.6056933737527;1003.9698809035667;1021.7701017271129;1023.8515415264819;1027.9640212592524;1028.0644613401769;11131.944340278327;11235.550577976872;11286.379249141293;11415;11438.313701177956;4625.121226966866;4645.9303110453375;4864.610831133501;4865.245886887538;4865.829695960624;9849.310548261696;9862.605495780232;9863.654772082942;9872.45701486425;9875.308728388944;5330.200469929464;5345.812896548602;5358.062339090644;5359;5383.488568585978;4782;4790.113828941657;4856.708597484162;4893.02139572087;4925.901481970341;3755.455458958436;3771.830267637963;3829.9617003830163;3858.839050131935;3885.980570097188;1280.5582074184392;1288.4137717338588;1289.0589869395205;1302.2446980751142;1302.3915464401457;795.7135431244748;822.3791037766448;879.9340049496313;884.752269364579;932.3613324872484;5292.364916210079;5394.757235924393;5577.9163312550345;5597.471400965695;5704.401037891044;870.9477431354139;902.472053848506;930.9627614895463;951.757301888028;1002.5238011944268;3396.7432050141606;3511.2801875615437;3611.7832930024283;3652.0504668786307;3689.90775230616;9826.65606703762;10619.09737672291;10708.357498550113;10716.981886720749;10719.89280107189;10290.897091029143;10447.111995480469;10501.264911456125;10625.362478251329;10697.358158510358;7991.200879911962;8076.636551355203;8098;8167.591620418955;8205.630746616416;5309.495597918191;5391.676499410048;5457.69982650958;5512.090505066619;5640.464155905182;9196.310276729406;9494.572744226665;9548.045195480396;9550.761230969281;9661.806763864824;9280.58237379306;9769.658061967797;9799.608015679578;9966.202703783534;9968.950155249177;6452.612843229421;7124.002639630559;7360.411167106726;7373.5575865448245;7378.040854688856;7861.095973962839;7902.723404680809;8011.278984891388;8094.231048050426;8095.55474448911;6275.403836635513;7068.752593659197;7291.927080729125;7335.229800870832;7422.703091876265;7054.682539285847;7119.394851437686;7127.536710113826;7270.272972702687;7295.088113985752;6707.29573394806;6725.9663701681175;6729.596224226562;6767.627780472121;6777.661116944035;2786.818856774303;4312.159128969821;4509.7519636419;4597.103564804965;4746.667733258777;4655.208614535605;4704.059188162313;4764.857054288377;4777.901082750917;4816.759162041813 05:39:03 INFO - PROCESS | 6263 | |4;prototype.html;311.5358116406555;315.2827317851887;323.8105708160726;328.64998776303236;330.550451386115;5922.703864806758;5931.139984702216;5934.376890426508;5936.228290322263;6010.939890601093;4651.836461527701;4993.126202914485;4993.675411098276;5487.451254874511;5700.8859822803515;3713.925721485575;4404.603585677289;4446.421965144526;4456.844010459635;4512.481064677555;4459.44256967879;4466.285394336906;4475.127350166719;4485.842995495158;4505.144022635705;4153.522344930341;4199.905092534785;4399.714018588781;4403.933940990888;4412.47050353958;3838.692904567642;4201.558836322203;4340.848070317524;4362.45469316337;4380.386745855582;3708.3510385682666;3773.7735735855936;3774.6225377462033;3792.715546334008;3792.924141517182;4999.375078115236;5457.781688732485;5529.557635389159;5641.12562552805;5659.490645841853;3991.3613821788663;4248.320268756984;4602.826279298791;4659.88350291242;5011;6101.969490152565;6110.29731580865;6173.2900716417735;6174.96912515439;6179.5056395488255;3836.3094642964256;4089.7750623715683;4118.176364727036;4137.172565486884;4149.751014939114;5457.781688732446;5468.6171967962255;6223.471004964584;6462.612243265419;6514;3559.537260156163;4203.852865149766;4210.810513526899;4212.578742125825;4219.936700949488;4098.180363927227;4212.945614429514;4236.4704411948505;4259.893502662396;4266.338717498793;4020.6582440492302;4231.090315582164;4695.95304046962;4953.851384458472;4975.303457515951;5214.374275087014;6160.907586386208;6256.436920677161;6288.496920246369;6299.6535190564955;1083.2417307884355;1174.8193065968817;1452.1069542231662;1461.9049761765664;1475.2697414779752;934.8878134623889;941.1717688434134;947.1002547579827;954.3271993244746;956.9234461243084;5686.402927692616;5701.515371193495;5702.942970570324;5772.2207501986695;5790.681512516768;5955.392044148142;6343.953247714167;6365.904511432333;6378.330275321117;6384.52116091295;4936.975315123402;5544.8355845272135;5600.4399560044485;5723.5707321950185;5776.277965254343;2754.297654098232;2795.636567246245;3340.799552026886;3387.932241355159;3400.268942177444;6150.370151909748;6454.741810327629;6592.703328350236;6614.735410583523;6669.133012708414;6071.908921366183;6504.544387094855;6529.412352888263;6546.803595892131;6583.177102862142;5477.904419116193;5960.7019649017375;6183.690815459299;6631.23740769808;6655.46756259498;5969.820905372846;6259.056794219612;6264.655443950444;6301.842453938505;6332;5190.480951904779;5193.68837869729;5821;6197.5351848610635;6219.129321895029;5793.594448388572;6256.186695729526;6623.867522649423;6632.6556105750815;6643.468522518284;4147.4815648044;4159.355299928516;4168.666506679519;4183.058811767328;4195.014171669594;5545;5633.0705433604635;6269.247690277196;6329.208848893888;6357.965451055617;5026.723530205728;6108.57239993214;6314.210723659543;6331.27190373113;6345.555811093179;5330.360356757214;6223.4879712208285;6256.718447669957;6279.7392938543;6326.588771729823;5126.359205099363;5286.603504737237;5294.426262272873;6141.692915354125;6192;5221.198686453167;6180.6909654517995;6225.564210505221;6229.688515574294;6242.2509298882605;5146.485351464824;5859.00396932514;5955.940440595712;6175.104609831459;6180.505559555135 05:39:03 INFO - PROCESS | 6263 | |5;yui.html;656.9868602627947;713.4862898712928;749.5465243527667;749.9100107987042;784.0238902566306;14484.855151448495;14841.109533428002;14849.40602375905;14850.628734281634;14857.03429277099;10746.677599672003;11512.618485781697;12227;13654;13730.725385492284;713.9464540159482;906.1255888068013;911.7036962987056;918.2149262380668;919.5586118663045;886.9068747781487;901.4546199549277;902.1249388093539;904.5748498205833;907.5235501361805;750.7447467860926;937.8593211018334;964.6430820596354;967.4243824924159;967.5307475874196;948.7865230323157;953.1040821627648;953.7901661634429;954.5513608603945;955.1356022799378;713.4114355656552;714.5712572456537;715.4383808710138;715.4812760748467;717.7057406463377;15051;15095.245237738069;15127.773083403757;15134.016288941184;15149.621259468491;12619.873801262054;12868.212439602697;12919.289439080847;12998.805017924738;13089.607311780566;9419.826359448844;9848.901510984942;10140.340877842991;10142.239332050052;10268.794624107551;3508.3684936711384;3509.7718648288037;3513.631068737797;3580.2481478889463;3590.622984586607;69997;72009.63995180043;72298.37137458414;80023.5998820008;81693.59153204256;9543.188828949616;9721.70834874955;9750;9800.509974501247;9823.459709715891;21763.455913602287;21827.890860545594;21840;21864.781352186594;21888;7153.570785752871;7364.410847132217;7476.813079673052;7741.793588827157;7757.961210193913;3955.881323560298;3958.0698535844053;3974.8201438848805;3995.220931918248;4019.055521952339;3077.876884924578;3534.6818786309354;3630.6006339302658;3638.8180590970874;3646.9635303646623;631.2803404119308;649.1042361541043;652.954293199481;657.0636842499515;657.5101549345768;477.89725209080217;513.6789506558401;539.5521716974902;547.1601092323265;591.2934043817596;6998.825029374307;7548.811279718051;7605.619719014027;7964.840703185904;8056.691196511265;457.636179237507;535.2052202479315;578.1010528628024;581.7992792486585;592.5703864698146;3244.383567122239;3678.7240956927803;3698.278835627027;3753.9061523462133;3935.665468435158;88538.55730721305;92691;92890.53554732318;93336;93528;62476;75214;75426.62286688531;75556.62221688966;77465.6126719374;50159.74920125376;68029.63940721059;68719.3128068713;69204;69585;7723.497972631761;7840.255175758294;7919.08930473003;8029.291363618512;8130;43239.56760432481;43522.564774353115;44446.11107777891;45736;45970.54029459663;61904;62049.689751550955;63697;63715.6814215926;65132.67433662801;11075.83386249223;11097.33415995043;11306.547738090385;11306.94346528262;11685.415729213675;11853.466594003117;11935;12124;12253.336588136372;12553.560625378068;10702.518386672462;10770.13838892871;10772.24594278393;10779.299345542515;10978.451077445936;10322.483875806029;11373.715657108638;11382.14633902444;11401.429928503376;11407.372594507382;10501.684949451528;10546.736331591772;10631.415272159797;10668.519916603616;11583.76832463363;9280.211182049601;9280.396774209656;9321;9453.196478299422;9774.462404567817;7215.314545118205;9039.548022598976;9108.635654574007;9207.17135457812;9316.44101353921 05:39:03 INFO - PROCESS | 6263 | __end_tp_report 05:39:03 INFO - PROCESS | 6263 | __start_cc_report 05:39:03 INFO - PROCESS | 6263 | _x_x_mozilla_cycle_collect,1780 05:39:03 INFO - PROCESS | 6263 | __end_cc_report 05:39:03 INFO - PROCESS | 6263 | __startTimestamp1460983143868__endTimestamp 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | ------- Summary: start ------- 05:39:03 INFO - PROCESS | 6263 | Number of tests: 6 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#0] dojo.html Cycles:165 Average:5639.48 Median:5782.16 stddev:639.16 (11.1%) stddev-sans-first:602.87 05:39:03 INFO - PROCESS | 6263 | Values: 2863.2 3349.3 3425.5 3425.6 3458.5 5490.7 5595.6 5601.7 5603.1 5621.5 5571.8 5627.4 5645.2 5645.5 5645.7 5132.7 5161.7 5175.3 5202.6 5244.5 5243.0 5244.5 5252.3 5252.7 5255.4 4445.0 5178.9 5206.8 5207.7 5284.3 5187.3 5229.5 5234.4 5238.2 5290.1 5097.2 5102.7 5146.7 5160.4 5160.9 5475.8 5501.8 5507.6 5531.7 5544.2 6158.4 6159.7 6176.7 6183.1 6191.1 5267.8 6112.5 6132.2 6212.3 6235.7 5682.8 5695.6 5719.8 5754.7 5780.7 6330.5 6347.7 6350.7 6359.8 6374.6 5584.6 5849.1 5951.1 6040.1 6055.3 5724.1 6154.4 6162.7 6165.3 6171.0 5979.3 6035.1 6062.4 6076.2 6113.1 5997.0 6109.8 6109.8 6151.5 6159.5 4823.5 4852.3 4868.8 4871.6 4881.1 4348.7 4369.1 4429.0 4431.9 4469.3 5232.2 5582.5 5598.8 5612.7 5625.1 4758.6 6039.8 6235.4 6237.6 6245.6 5540.5 5570.8 5613.3 5615.9 5655.5 5264.5 5332.8 5336.4 5337.3 5343.5 6273.2 6289.7 6323.7 6361.6 6367.6 5321.7 6166.3 6180.3 6290.2 6290.6 6286.3 6308.8 6321.7 6324.0 6331.5 5961.3 6046.3 6132.9 6142.9 6187.4 6108.0 6193.9 6213.6 6220.8 6226.0 5914.7 5925.0 5943.3 5949.3 5989.7 5829.0 5898.3 5919.1 5971.1 5974.3 4771.3 5803.2 5806.6 5807.9 5812.0 5782.2 5921.3 5921.5 5931.9 5941.3 5735.3 5882.8 5900.6 5919.5 5924.6 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#1] ext.html Cycles:180 Average:62262.15 Median:34941.41 stddev:133653.66 (382.5%) stddev-sans-first:133948.10 05:39:03 INFO - PROCESS | 6263 | Values: 481.5 513.5 536.1 579.5 609.0 22703.7 31071.4 38304.2 39339.4 39724.4 34534.1 41489.8 42015.6 42064.9 42215.8 9457.1 9717.9 9719.4 9728.9 9764.8 8552.4 8871.4 11684.2 11961.7 11994.9 11537.1 11652.9 11653.1 11654.2 11656.6 8577.4 11017.6 11500.4 11639.0 11655.5 11003.3 11103.6 11114.6 11120.9 11124.7 38964.2 39559.8 39599.0 39613.0 39753.8 3431.7 3743.0 3763.7 3770.3 4035.2 28525.0 29094.0 29471.7 30045.5 30056.2 2644.0 2686.6 2708.5 2719.4 2772.5 459842.0 460816.0 461803.0 461993.0 463471.0 34332.8 51043.5 62481.7 62487.7 62581.4 34809.8 35073.0 35132.5 35144.8 35175.5 14749.9 15102.8 15119.8 15124.6 15133.0 11844.6 12027.0 12766.7 12867.5 12964.2 8010.2 8039.4 8047.2 8117.6 8128.8 3367.7 3405.5 3407.7 3408.2 3433.8 858.6 999.6 1004.0 1084.7 1088.0 29657.3 29790.4 30273.8 30356.7 30416.1 1069.8 1106.6 1111.2 1142.2 1269.9 13455.6 15327.9 15451.7 15579.6 15594.5 605133.0 613603.0 613813.0 615259.0 615520.0 60968.7 62143.7 62189.0 62191.4 62385.0 1344.8 1345.0 1402.2 1482.4 1490.2 29556.0 30160.2 30451.2 30601.9 30734.2 52453.2 53080.0 53181.7 53316.7 53419.7 390847.0 403870.0 407303.0 407375.0 408788.0 23718.8 24297.8 26316.5 26945.0 27425.7 42336.4 42841.4 44170.8 44451.0 44465.3 35211.8 36714.8 37451.6 37965.0 38245.6 35701.8 35904.5 35953.8 36871.3 36971.9 35189.0 35594.8 37345.6 37935.4 37941.8 30128.7 32473.4 32533.2 32563.7 32580.8 24160.8 24403.4 24582.6 24766.3 25123.0 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#2] jquery.html Cycles:180 Average:33260.71 Median:11623.95 stddev:96941.87 (834.0%) stddev-sans-first:97182.39 05:39:03 INFO - PROCESS | 6263 | Values: 384.1 384.5 386.4 395.4 404.1 18755.6 20048.9 20504.7 20621.8 20628.4 19968.3 20252.0 20847.7 21089.8 21126.8 5017.9 5278.4 5313.0 5545.5 5556.6 5358.7 5511.9 5539.8 5563.6 5564.2 5433.5 5497.1 5509.1 5513.8 5546.9 5360.6 5508.6 5514.7 5527.3 5543.6 5067.1 5374.0 5383.5 5385.6 5394.5 13187.5 13384.4 14405.6 23543.5 23980.9 2121.1 2243.9 2342.5 2410.1 2498.7 9925.8 9998.0 10022.9 10208.3 10288.4 1735.5 1742.2 1758.6 1796.4 1863.2 88441.0 105470.5 109693.5 110018.5 112089.0 11287.9 11606.0 11728.8 11814.8 11819.4 14912.6 14990.0 15006.2 15012.3 15072.8 8664.4 8710.6 8712.0 8731.9 8838.3 8699.1 8699.6 8717.8 8727.2 8851.9 3768.3 4644.7 4651.4 4652.5 4716.3 1656.8 1685.2 1756.3 1910.3 1965.9 744.0 871.9 944.1 947.8 1018.6 19121.0 20534.4 20752.9 20923.7 20933.2 957.3 1005.5 1006.8 1014.8 1048.6 10516.1 11023.0 11064.8 11074.8 11081.6 419808.0 605319.0 614529.0 623032.0 626076.9 73953.3 117544.0 118110.8 126113.0 131375.0 968.5 971.5 1002.0 1003.3 1003.4 18258.4 18514.7 18607.0 18611.5 19033.1 48634.5 49157.3 49945.0 51101.7 51862.7 73113.6 74811.0 75105.0 75191.2 76919.0 7879.0 8075.7 8143.9 8144.8 8437.3 12283.6 12289.8 12548.8 12609.1 12807.6 10802.1 10919.8 11147.9 11157.9 11171.4 10597.4 10805.6 10900.9 10996.5 11222.4 10865.8 11169.4 11192.7 11469.1 11569.9 6948.2 7339.2 7429.6 7542.0 8170.7 9731.2 11235.7 11373.6 11392.7 11506.9 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#3] mootools.html Cycles:180 Average:5550.91 Median:5206.40 stddev:3098.49 (59.5%) stddev-sans-first:3091.83 05:39:03 INFO - PROCESS | 6263 | Values: 1446.9 1639.6 1643.9 1923.8 2021.2 7719.4 8511.2 8692.1 8699.2 8711.8 8337.0 8577.5 8579.0 8580.2 8587.0 2370.6 2385.1 2398.0 2399.3 2399.9 2369.8 2393.9 2407.6 2412.8 2413.3 2373.5 2380.3 2394.3 2403.4 2406.1 2331.2 2370.7 2373.1 2379.8 2389.4 2275.9 2366.9 2368.7 2371.4 2372.7 7706.6 7953.4 8592.2 8686.8 8687.1 5135.8 5277.0 5303.5 5313.0 5347.6 3455.5 3479.1 3563.9 3572.6 3584.6 1004.0 1021.8 1023.9 1028.0 1028.1 11131.9 11235.6 11286.4 11415.0 11438.3 4625.1 4645.9 4864.6 4865.2 4865.8 9849.3 9862.6 9863.7 9872.5 9875.3 5330.2 5345.8 5358.1 5359.0 5383.5 4782.0 4790.1 4856.7 4893.0 4925.9 3755.5 3771.8 3830.0 3858.8 3886.0 1280.6 1288.4 1289.1 1302.2 1302.4 795.7 822.4 879.9 884.8 932.4 5292.4 5394.8 5577.9 5597.5 5704.4 870.9 902.5 931.0 951.8 1002.5 3396.7 3511.3 3611.8 3652.1 3689.9 9826.7 10619.1 10708.4 10717.0 10719.9 10290.9 10447.1 10501.3 10625.4 10697.4 7991.2 8076.6 8098.0 8167.6 8205.6 5309.5 5391.7 5457.7 5512.1 5640.5 9196.3 9494.6 9548.0 9550.8 9661.8 9280.6 9769.7 9799.6 9966.2 9969.0 6452.6 7124.0 7360.4 7373.6 7378.0 7861.1 7902.7 8011.3 8094.2 8095.6 6275.4 7068.8 7291.9 7335.2 7422.7 7054.7 7119.4 7127.5 7270.3 7295.1 6707.3 6726.0 6729.6 6767.6 6777.7 2786.8 4312.2 4509.8 4597.1 4746.7 4655.2 4704.1 4764.9 4777.9 4816.8 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#4] prototype.html Cycles:180 Average:4940.27 Median:5537.20 stddev:1559.54 (28.2%) stddev-sans-first:1524.72 05:39:03 INFO - PROCESS | 6263 | Values: 311.5 315.3 323.8 328.6 330.6 5922.7 5931.1 5934.4 5936.2 6010.9 4651.8 4993.1 4993.7 5487.5 5700.9 3713.9 4404.6 4446.4 4456.8 4512.5 4459.4 4466.3 4475.1 4485.8 4505.1 4153.5 4199.9 4399.7 4403.9 4412.5 3838.7 4201.6 4340.8 4362.5 4380.4 3708.4 3773.8 3774.6 3792.7 3792.9 4999.4 5457.8 5529.6 5641.1 5659.5 3991.4 4248.3 4602.8 4659.9 5011.0 6102.0 6110.3 6173.3 6175.0 6179.5 3836.3 4089.8 4118.2 4137.2 4149.8 5457.8 5468.6 6223.5 6462.6 6514.0 3559.5 4203.9 4210.8 4212.6 4219.9 4098.2 4212.9 4236.5 4259.9 4266.3 4020.7 4231.1 4696.0 4953.9 4975.3 5214.4 6160.9 6256.4 6288.5 6299.7 1083.2 1174.8 1452.1 1461.9 1475.3 934.9 941.2 947.1 954.3 956.9 5686.4 5701.5 5702.9 5772.2 5790.7 5955.4 6344.0 6365.9 6378.3 6384.5 4937.0 5544.8 5600.4 5723.6 5776.3 2754.3 2795.6 3340.8 3387.9 3400.3 6150.4 6454.7 6592.7 6614.7 6669.1 6071.9 6504.5 6529.4 6546.8 6583.2 5477.9 5960.7 6183.7 6631.2 6655.5 5969.8 6259.1 6264.7 6301.8 6332.0 5190.5 5193.7 5821.0 6197.5 6219.1 5793.6 6256.2 6623.9 6632.7 6643.5 4147.5 4159.4 4168.7 4183.1 4195.0 5545.0 5633.1 6269.2 6329.2 6358.0 5026.7 6108.6 6314.2 6331.3 6345.6 5330.4 6223.5 6256.7 6279.7 6326.6 5126.4 5286.6 5294.4 6141.7 6192.0 5221.2 6180.7 6225.6 6229.7 6242.3 5146.5 5859.0 5955.9 6175.1 6180.5 05:39:03 INFO - PROCESS | 6263 | 05:39:03 INFO - PROCESS | 6263 | [#5] yui.html Cycles:180 Average:17481.07 Median:62262.84 stddev:24417.58 (39.2%) stddev-sans-first:24453.40 05:39:03 INFO - PROCESS | 6263 | Values: 657.0 713.5 749.5 749.9 784.0 14484.9 14841.1 14849.4 14850.6 14857.0 10746.7 11512.6 12227.0 13654.0 13730.7 713.9 906.1 911.7 918.2 919.6 886.9 901.5 902.1 904.6 907.5 750.7 937.9 964.6 967.4 967.5 948.8 953.1 953.8 954.6 955.1 713.4 714.6 715.4 715.5 717.7 15051.0 15095.2 15127.8 15134.0 15149.6 12619.9 12868.2 12919.3 12998.8 13089.6 9419.8 9848.9 10140.3 10142.2 10268.8 3508.4 3509.8 3513.6 3580.2 3590.6 69997.0 72009.6 72298.4 80023.6 81693.6 9543.2 9721.7 9750.0 9800.5 9823.5 21763.5 21827.9 21840.0 21864.8 21888.0 7153.6 7364.4 7476.8 7741.8 7758.0 3955.9 3958.1 3974.8 3995.2 4019.1 3077.9 3534.7 3630.6 3638.8 3647.0 631.3 649.1 653.0 657.1 657.5 477.9 513.7 539.6 547.2 591.3 6998.8 7548.8 7605.6 7964.8 8056.7 457.6 535.2 578.1 581.8 592.6 3244.4 3678.7 3698.3 3753.9 3935.7 88538.6 92691.0 92890.5 93336.0 93528.0 62476.0 75214.0 75426.6 75556.6 77465.6 50159.7 68029.6 68719.3 69204.0 69585.0 7723.5 7840.3 7919.1 8029.3 8130.0 43239.6 43522.6 44446.1 45736.0 45970.5 61904.0 62049.7 63697.0 63715.7 65132.7 11075.8 11097.3 11306.5 11306.9 11685.4 11853.5 11935.0 12124.0 12253.3 12553.6 10702.5 10770.1 10772.2 10779.3 10978.5 10322.5 11373.7 11382.1 11401.4 11407.4 10501.7 10546.7 10631.4 10668.5 11583.8 9280.2 9280.4 9321.0 9453.2 9774.5 7215.3 9039.5 9108.6 9207.2 9316.4 05:39:03 INFO - PROCESS | 6263 | -------- Summary: end -------- 05:39:03 INFO - PROCESS | 6263 | 05:39:04 INFO - TEST-INFO | 6263: exit 0 05:39:04 INFO - TEST-OK | dromaeo_css | took 1116151ms 05:39:04 INFO - TEST-START | kraken 05:39:04 INFO - operating with platform_type : mac_ 05:39:04 INFO - Initialising browser for kraken test... 05:39:04 INFO - TEST-INFO | started process 6266 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpAB6RmG/profile http://localhost:56974/getInfo.html) 05:39:06 INFO - PROCESS | 6266 | __metrics Screen width/height:1600/1200 05:39:06 INFO - PROCESS | 6266 | colorDepth:24 05:39:06 INFO - PROCESS | 6266 | Browser inner width/height: 1024/689 05:39:06 INFO - PROCESS | 6266 | __metrics 05:39:06 INFO - PROCESS | 6266 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:39:06 ERROR - PROCESS | 6266 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:39:07 INFO - TEST-INFO | 6266: exit 0 05:39:07 INFO - Browser initialized. 05:39:07 INFO - Running cycle 1/1 for kraken test... 05:39:07 INFO - TEST-INFO | started process 6269 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpAB6RmG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:19 INFO - PROCESS | 6269 | RSS: Main: 237514752 05:39:19 INFO - PROCESS | 6269 | 05:39:21 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56974/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:22 INFO - PROCESS | 6269 | RSS: Main: 286449664 05:39:22 INFO - PROCESS | 6269 | 05:39:25 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56974/tests/kraken/driver.html?testName=audio-dft) 05:39:26 INFO - PROCESS | 6269 | RSS: Main: 298815488 05:39:26 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56974/tests/kraken/driver.html?testName=audio-fft) 05:39:30 INFO - PROCESS | 6269 | RSS: Main: 385712128 05:39:30 INFO - PROCESS | 6269 | 05:39:32 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56974/tests/kraken/driver.html?testName=audio-oscillator) 05:39:33 INFO - PROCESS | 6269 | RSS: Main: 286212096 05:39:33 INFO - PROCESS | 6269 | 05:39:35 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56974/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:35 INFO - PROCESS | 6269 | RSS: Main: 290136064 05:39:35 INFO - PROCESS | 6269 | 05:39:38 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56974/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:39 INFO - PROCESS | 6269 | RSS: Main: 327589888 05:39:39 INFO - PROCESS | 6269 | 05:39:42 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56974/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:42 INFO - PROCESS | 6269 | RSS: Main: 330563584 05:39:42 INFO - PROCESS | 6269 | 05:39:45 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56974/tests/kraken/driver.html?testName=json-parse-financial) 05:39:46 INFO - PROCESS | 6269 | RSS: Main: 329416704 05:39:46 INFO - PROCESS | 6269 | 05:39:47 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56974/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:48 INFO - PROCESS | 6269 | RSS: Main: 319758336 05:39:48 INFO - PROCESS | 6269 | 05:39:50 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:50 INFO - PROCESS | 6269 | RSS: Main: 326512640 05:39:50 INFO - PROCESS | 6269 | 05:39:53 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:53 INFO - PROCESS | 6269 | RSS: Main: 329441280 05:39:53 INFO - PROCESS | 6269 | 05:39:56 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:57 INFO - PROCESS | 6269 | RSS: Main: 334405632 05:39:57 INFO - PROCESS | 6269 | 05:40:00 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:00 INFO - PROCESS | 6269 | RSS: Main: 331845632 05:40:00 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56974/tests/kraken/driver.html?testName=ai-astar) 05:40:02 INFO - PROCESS | 6269 | RSS: Main: 396746752 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | __start_tp_report 05:40:02 INFO - PROCESS | 6269 | _x_x_mozilla_page_load 05:40:02 INFO - PROCESS | 6269 | _x_x_mozilla_page_load_details 05:40:02 INFO - PROCESS | 6269 | |i|pagename|runs| 05:40:02 INFO - PROCESS | 6269 | |0;ai-astar;112;111;112;113;113;113;117;112;112;112 05:40:02 INFO - PROCESS | 6269 | |1;audio-beat-detection;171;153;185;176;122;144;142;124;175;122 05:40:02 INFO - PROCESS | 6269 | |2;audio-dft;187;187;188;184;186;187;181;194;263;193 05:40:02 INFO - PROCESS | 6269 | |3;audio-fft;78;72;91;92;56;60;58;80;86;55 05:40:02 INFO - PROCESS | 6269 | |4;audio-oscillator;90;103;141;78;77;78;128;81;78;132 05:40:02 INFO - PROCESS | 6269 | |5;imaging-gaussian-blur;88;86;88;84;87;85;89;86;89;85 05:40:02 INFO - PROCESS | 6269 | |6;imaging-darkroom;94;93;94;94;93;93;101;132;94;93 05:40:02 INFO - PROCESS | 6269 | |7;imaging-desaturate;84;83;83;83;84;85;84;85;84;85 05:40:02 INFO - PROCESS | 6269 | |8;json-parse-financial;59;60;56;58;52;60;58;77;60;58 05:40:02 INFO - PROCESS | 6269 | |9;json-stringify-tinderbox;65;64;75;75;74;70;68;68;69;73 05:40:02 INFO - PROCESS | 6269 | |10;stanford-crypto-aes;83;83;81;83;81;82;81;81;84;84 05:40:02 INFO - PROCESS | 6269 | |11;stanford-crypto-ccm;154;154;160;156;152;136;136;138;172;142 05:40:02 INFO - PROCESS | 6269 | |12;stanford-crypto-pbkdf2;173;172;170;171;169;160;171;170;167;168 05:40:02 INFO - PROCESS | 6269 | |13;stanford-crypto-sha256-iterative;70;68;70;71;68;68;68;69;68;66 05:40:02 INFO - PROCESS | 6269 | __end_tp_report 05:40:02 INFO - PROCESS | 6269 | __start_cc_report 05:40:02 INFO - PROCESS | 6269 | _x_x_mozilla_cycle_collect,2005 05:40:02 INFO - PROCESS | 6269 | __end_cc_report 05:40:02 INFO - PROCESS | 6269 | __startTimestamp1460983202817__endTimestamp 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | ------- Summary: start ------- 05:40:02 INFO - PROCESS | 6269 | Number of tests: 14 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.50 stddev:1.64 (1.5%) stddev-sans-first:1.72 05:40:02 INFO - PROCESS | 6269 | Values: 112.0 111.0 112.0 113.0 113.0 113.0 117.0 112.0 112.0 112.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:162.00 stddev:24.27 (15.0%) stddev-sans-first:24.68 05:40:02 INFO - PROCESS | 6269 | Values: 171.0 153.0 185.0 176.0 122.0 144.0 142.0 124.0 175.0 122.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#2] audio-dft Cycles:10 Average:195.00 Median:187.50 stddev:24.19 (12.9%) stddev-sans-first:25.49 05:40:02 INFO - PROCESS | 6269 | Values: 187.0 187.0 188.0 184.0 186.0 187.0 181.0 194.0 263.0 193.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#3] audio-fft Cycles:10 Average:72.80 Median:79.00 stddev:14.67 (18.6%) stddev-sans-first:15.43 05:40:02 INFO - PROCESS | 6269 | Values: 78.0 72.0 91.0 92.0 56.0 60.0 58.0 80.0 86.0 55.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:78.00 stddev:25.65 (32.9%) stddev-sans-first:27.01 05:40:02 INFO - PROCESS | 6269 | Values: 90.0 103.0 141.0 78.0 77.0 78.0 128.0 81.0 78.0 132.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.77 (2.0%) stddev-sans-first:1.81 05:40:02 INFO - PROCESS | 6269 | Values: 88.0 86.0 88.0 84.0 87.0 85.0 89.0 86.0 89.0 85.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:93.50 stddev:12.15 (13.0%) stddev-sans-first:12.80 05:40:02 INFO - PROCESS | 6269 | Values: 94.0 93.0 94.0 94.0 93.0 93.0 101.0 132.0 94.0 93.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 05:40:02 INFO - PROCESS | 6269 | Values: 84.0 83.0 83.0 83.0 84.0 85.0 84.0 85.0 84.0 85.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:59.50 stddev:6.51 (10.9%) stddev-sans-first:6.90 05:40:02 INFO - PROCESS | 6269 | Values: 59.0 60.0 56.0 58.0 52.0 60.0 58.0 77.0 60.0 58.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.50 stddev:4.01 (5.6%) stddev-sans-first:3.81 05:40:02 INFO - PROCESS | 6269 | Values: 65.0 64.0 75.0 75.0 74.0 70.0 68.0 68.0 69.0 73.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 05:40:02 INFO - PROCESS | 6269 | Values: 83.0 83.0 81.0 83.0 81.0 82.0 81.0 81.0 84.0 84.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:154.00 stddev:11.81 (7.7%) stddev-sans-first:12.44 05:40:02 INFO - PROCESS | 6269 | Values: 154.0 154.0 160.0 156.0 152.0 136.0 136.0 138.0 172.0 142.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:170.50 stddev:3.67 (2.1%) stddev-sans-first:3.61 05:40:02 INFO - PROCESS | 6269 | Values: 173.0 172.0 170.0 171.0 169.0 160.0 171.0 170.0 167.0 168.0 05:40:02 INFO - PROCESS | 6269 | 05:40:02 INFO - PROCESS | 6269 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.50 stddev:1.43 (2.1%) stddev-sans-first:1.42 05:40:02 INFO - PROCESS | 6269 | Values: 70.0 68.0 70.0 71.0 68.0 68.0 68.0 69.0 68.0 66.0 05:40:02 INFO - PROCESS | 6269 | -------- Summary: end -------- 05:40:02 INFO - PROCESS | 6269 | 05:40:03 INFO - TEST-INFO | 6269: exit 0 05:40:03 INFO - TEST-OK | kraken | took 59070ms 05:40:03 INFO - SUITE-END | took 1175s 05:40:03 INFO - Completed test suite (00:19:35) 05:40:03 INFO - javascript benchmark 05:40:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2863.212616530454, 3349.296647703982, 3425.5033020212077, 3425.5718035245595, 3458.5330980317667, 5490.725463726818, 5595.58033147514, 5601.691906945127, 5603.103503439445, 5621.494065534096, 5571.777128914849, 5627.409122042188, 5645.1814486899375, 5645.491905728483, 5645.689487078209, 5132.717700526469, 5161.7161056141895, 5175.327207463025, 5202.557782588485, 5244.475552444763, 5242.973785131069, 5244.475552444763, 5252.264682944372, 5252.658577192489, 5255.448177941318, 4444.97777511112, 5178.948210517903, 5206.817761378353, 5207.661502002376, 5284.313039304885, 5187.273781670569, 5229.450907654679, 5234.371875374942, 5238.188080847474, 5290.127129023706, 5097.209932460474, 5102.668326558762, 5146.716930568855, 5160.40655324635, 5160.92258616121, 5475.753591088411, 5501.779928802843, 5507.586930980193, 5531.7234138293325, 5544.16837474378, 6158.445739883387, 6159.661218632973, 6176.66028368444, 6183.072539119124, 6191.133241346215, 5267.815626453055, 6112.541559383064, 6132.233136608343, 6212.347703491158, 6235.688215589248, 5682.829515114553, 5695.572832037614, 5719.7712091516705, 5754.74103665336, 5780.682062486561, 6330.52521060913, 6347.746090156342, 6350.682465876687, 6359.841003974845, 6374.553781235269, 5584.6090773646265, 5849.1226316052935, 5951.1073338999495, 6040.063790112452, 6055.273367195965, 5724.141378793214, 6154.353792851686, 6162.722677479434, 6165.290991535941, 6170.981788005019, 5979.312379076375, 6035.124906888476, 6062.393760623991, 6076.210092687922, 6113.144159817629, 5996.98051331266, 6109.75560977556, 6109.84725381869, 6151.5386346024225, 6159.5072394208355, 4823.469418363977, 4852.320675105488, 4868.756562171877, 4871.634627402959, 4881.145799485147, 4348.673849461303, 4369.12617476506, 4429.025614364835, 4431.911361772746, 4469.284914413678, 5232.1628539433505, 5582.49757521825, 5598.832035038955, 5612.719364031782, 5625.071863142617, 4758.595519380891, 6039.758409663565, 6235.407636274546, 6237.563370564108, 6245.594036387621, 5540.501354878, 5570.832875013756, 5613.270274864241, 5615.9157612635845, 5655.4910058094965, 5264.526192642604, 5332.813351532678, 5336.359636843526, 5337.2794672719465, 5343.465653434625, 6273.1844860167885, 6289.7484100635465, 6323.683815809191, 6361.554691171574, 6367.617942923347, 5321.733913304397, 6166.321704612579, 6180.289266734383, 6290.182276304235, 6290.591111577733, 6286.308506064421, 6308.842278943063, 6321.683915804283, 6323.9683801580695, 6331.525135614939, 5961.314448838266, 6046.3046749624355, 6132.938670613236, 6142.877142457215, 6187.412195841388, 6107.977837958671, 6193.876122477435, 6213.565050446426, 6220.813375598558, 6226.0, 5914.674692891932, 5925.022371308858, 5943.257092863392, 5949.25634295713, 5989.7304621293015, 5829.0, 5898.3216930053595, 5919.112133179885, 5971.074483454898, 5974.2830860297045, 4771.308160316804, 5803.216565763568, 5806.593538452268, 5807.8548036299435, 5812.041013232769, 5782.161586570008, 5921.259842519685, 5921.467067963904, 5931.940680593311, 5941.31674857397, 5735.340435849931, 5882.823515294548, 5900.616459930091, 5919.496842768241, 5924.644521328734], "value": 5599.980998809462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.54975098283086, 513.5070332480818, 536.0966770990881, 579.5103137848517, 608.9647599081562, 22703.659445108315, 31071.378572428566, 38304.23391532171, 39339.40990885139, 39724.40413393801, 34534.13664658385, 41489.7925510372, 42015.57984420155, 42064.94837629061, 42215.78892105543, 9457.148856602922, 9717.902820971805, 9719.416834989923, 9728.90271097287, 9764.755881102994, 8552.401331906769, 8871.379003469727, 11684.240524365932, 11961.700957476089, 11994.880051199463, 11537.077033837273, 11652.941735291353, 11653.067754579655, 11654.24247423919, 11656.592019279327, 8577.39958202926, 11017.614383496539, 11500.42497875107, 11639.0, 11655.475503602274, 11003.284786488852, 11103.55585776568, 11114.610988615359, 11120.944395277971, 11124.721881952935, 38964.22071558553, 39559.8022009891, 39599.0, 39613.009674758076, 39753.80123099395, 3431.725461963037, 3743.026813028605, 3763.6989040876665, 3770.26479836432, 4035.1727895781296, 28525.0, 29094.0, 29471.70528294711, 30045.549316760265, 30056.248593785112, 2643.973560264392, 2686.570148776186, 2708.4718479896233, 2719.3745438549245, 2772.50094982903, 459842.0, 460816.0, 461803.0, 461993.0, 463471.0, 34332.82833585816, 51043.489565103875, 62481.68759156175, 62487.68756156281, 62581.374186258465, 34809.82595087059, 35073.0, 35132.473012904826, 35144.76993305222, 35175.4723679145, 14749.926250368679, 15102.773458398133, 15119.773203401957, 15124.621884452758, 15133.0, 11844.585439509576, 12027.03783697302, 12766.680832979064, 12867.485300587872, 12964.157329773534, 8010.198980101944, 8039.437239393186, 8047.235512626291, 8117.594120293962, 8128.796780080545, 3367.71374433171, 3405.4721518164724, 3407.6592340765724, 3408.2161102945965, 3433.8454769535433, 858.6050416808213, 999.605155963405, 1003.9548220330103, 1084.7233955341337, 1088.0, 29657.25856853596, 29790.40419191604, 30273.848630756704, 30356.696433035828, 30416.087517374515, 1069.7646517766234, 1106.6126855600476, 1111.2276967507505, 1142.2404101272562, 1269.9365031748375, 13455.596332109857, 15327.923360383349, 15451.690966180613, 15579.643211990602, 15594.532164035098, 605133.0, 613603.0, 613813.0, 615259.0, 615520.0, 60968.695156523936, 62143.6892815533, 62189.0, 62191.37808621856, 62385.0, 1344.8184495093233, 1344.9663758406118, 1402.214759734552, 1482.4440834686993, 1490.180400779567, 29555.965541205955, 30160.24599385033, 30451.2387190322, 30601.928932488147, 30734.231644209074, 52453.21320180277, 53080.0, 53181.734091329294, 53316.73341633267, 53419.6645083876, 390847.0, 403870.0, 407303.0, 407375.0, 408788.0, 23718.762812371657, 24297.75702242955, 26316.4736705261, 26945.0, 27425.725742742317, 42336.36495452509, 42841.357379638706, 44170.77914610406, 44451.0, 44465.33302000408, 35211.82394088013, 36714.8164259177, 37451.625483744814, 37965.0, 38245.617543824206, 35701.82149089238, 35904.461433078, 35953.82023089973, 36871.26257474889, 36971.89084327474, 35189.0, 35594.822025889705, 37345.626543734215, 37935.43096853494, 37941.81029094837, 30128.698713013466, 32473.35053298968, 32533.18667033343, 32563.674363256065, 32580.837095814368, 24160.758392416556, 24403.355298984487, 24582.608825625073, 24766.25701228966, 25122.995080196586], "value": 18561.98007582833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.1491097219659, 384.45983393332375, 386.4493097336295, 395.38320220456075, 404.1351507773364, 18755.624887502257, 20048.89731064791, 20504.69242961357, 20621.793782062192, 20628.38114856555, 19968.301109461172, 20251.987400629983, 20847.687284690743, 21089.789102108974, 21126.788732112673, 5017.9247311290355, 5278.445763194867, 5313.043652142623, 5545.473180047909, 5556.638818476807, 5358.732063396834, 5511.917321240185, 5539.750711217985, 5563.610547261694, 5564.1931919871595, 5433.510984011438, 5497.092979658351, 5509.118541033436, 5513.7794488220625, 5546.86132846678, 5360.571154307646, 5508.614396992212, 5514.724263786815, 5527.2538207341895, 5543.6396634218645, 5067.0879241736475, 5374.0, 5383.488568585978, 5385.5960802939935, 5394.487523685244, 13187.472501099945, 13384.397702103428, 14405.639859003504, 23543.529129417486, 23980.920858561407, 2121.11973530986, 2243.876586787726, 2342.4846533762543, 2410.1443987384578, 2498.71264804548, 9925.801483970352, 9997.950010249902, 10022.901523449213, 10208.336458130272, 10288.43413612251, 1735.5314065202451, 1742.2421246757701, 1758.5955230296975, 1796.380248814157, 1863.1615772902248, 88441.0, 105470.47264763704, 109693.45153274182, 110018.54823791728, 112089.0, 11287.887121128848, 11605.95546400828, 11728.765424691459, 11814.763704725858, 11819.409029548488, 14912.552623421314, 14990.0, 15006.174660393564, 15012.324445399983, 15072.849271507364, 8664.350173736995, 8710.608022638997, 8711.950817213925, 8731.869021964676, 8838.337124715672, 8699.130086991378, 8699.565021748887, 8717.782055448664, 8727.170918762708, 8851.911480885237, 3768.2840260350445, 4644.698094623838, 4651.37206477128, 4652.511486293891, 4716.316134160527, 1656.8425999530025, 1685.233218885404, 1756.2535922233, 1910.3409323783274, 1965.9213631454868, 743.9962800186073, 871.9258862996717, 944.1078181118777, 947.7535840681472, 1018.5721996761378, 19121.0, 20534.38396848067, 20752.896235518725, 20923.68614470784, 20933.16267349289, 957.3011701457974, 1005.527402121002, 1006.7885743993843, 1014.8224060789332, 1048.638219814163, 10516.053555180071, 11023.0, 11064.834027489595, 11074.778504429867, 11081.612143575097, 419808.0, 605319.0, 614529.0, 623032.0, 626076.869615649, 73953.26046739571, 117544.0, 118110.8188918117, 126113.0, 131375.0, 968.5205823117602, 971.533663841346, 1001.9599216031423, 1003.2926786615565, 1003.3929472669076, 18258.36095736643, 18514.722279166093, 18607.020329892843, 18611.534711632317, 19033.143508542427, 48634.513654862996, 49157.2626410597, 49945.0, 51101.744491277306, 51862.740686296325, 73113.6344318275, 74811.0, 75105.0, 75191.24808752061, 76919.0, 7878.975733154883, 8075.676972921252, 8143.877841832259, 8144.796380090545, 8437.325013998743, 12283.631491055283, 12289.815652765394, 12548.811767823307, 12609.117361784587, 12807.61577152687, 10802.08182304513, 10919.781604368027, 11147.888521114684, 11157.944210278896, 11171.441427928734, 10597.364158150536, 10805.567777289134, 10900.890991089987, 10996.505157267782, 11222.438878056228, 10865.837012444661, 11169.38568378747, 11192.720181995515, 11469.139814513777, 11569.88430115688, 6948.235694073547, 7339.229380915141, 7429.628518573942, 7541.962290188514, 8170.714025009096, 9731.172850307803, 11235.66293011211, 11373.601923932954, 11392.715182120513, 11506.884931150917], "value": 9280.555272757982, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.9204193769351, 1639.6064944413338, 1643.9424620138293, 1923.81723736245, 2021.1612180944908, 7719.382449404048, 8511.1914368135, 8692.087330830267, 8699.217070463657, 8711.782205444868, 8337.0, 8577.52823594702, 8579.0, 8580.184882436175, 8587.0, 2370.6207006878903, 2385.129427758864, 2397.9760202398015, 2399.2682231919257, 2399.9040038398443, 2369.8104151667912, 2393.940151496218, 2407.554602398553, 2412.8069754419694, 2413.2760171948435, 2373.52529494101, 2380.333506618141, 2394.3295877154337, 2403.399150212447, 2406.0736616402637, 2331.2423462374627, 2370.668106465096, 2373.050779688121, 2379.8096152307776, 2389.438481956739, 2275.9317220483413, 2366.9171578994824, 2368.7039120109985, 2371.3715865295712, 2372.6678265042906, 7706.614669266514, 7953.40349473786, 8592.183742544448, 8686.826263474759, 8687.087855775178, 5135.794568217267, 5277.0, 5303.522682958553, 5313.043652142613, 5347.6256662033675, 3455.481677748333, 3479.1128262292955, 3563.8574457021687, 3572.5534308211472, 3584.6056933737527, 1003.9698809035667, 1021.7701017271129, 1023.8515415264819, 1027.9640212592524, 1028.0644613401769, 11131.944340278327, 11235.550577976872, 11286.379249141293, 11415.0, 11438.313701177956, 4625.121226966866, 4645.9303110453375, 4864.610831133501, 4865.245886887538, 4865.829695960624, 9849.310548261696, 9862.605495780232, 9863.654772082942, 9872.45701486425, 9875.308728388944, 5330.200469929464, 5345.812896548602, 5358.062339090644, 5359.0, 5383.488568585978, 4782.0, 4790.113828941657, 4856.708597484162, 4893.02139572087, 4925.901481970341, 3755.455458958436, 3771.830267637963, 3829.9617003830163, 3858.839050131935, 3885.980570097188, 1280.5582074184392, 1288.4137717338588, 1289.0589869395205, 1302.2446980751142, 1302.3915464401457, 795.7135431244748, 822.3791037766448, 879.9340049496313, 884.752269364579, 932.3613324872484, 5292.364916210079, 5394.757235924393, 5577.9163312550345, 5597.471400965695, 5704.401037891044, 870.9477431354139, 902.472053848506, 930.9627614895463, 951.757301888028, 1002.5238011944268, 3396.7432050141606, 3511.2801875615437, 3611.7832930024283, 3652.0504668786307, 3689.90775230616, 9826.65606703762, 10619.09737672291, 10708.357498550113, 10716.981886720749, 10719.89280107189, 10290.897091029143, 10447.111995480469, 10501.264911456125, 10625.362478251329, 10697.358158510358, 7991.200879911962, 8076.636551355203, 8098.0, 8167.591620418955, 8205.630746616416, 5309.495597918191, 5391.676499410048, 5457.69982650958, 5512.090505066619, 5640.464155905182, 9196.310276729406, 9494.572744226665, 9548.045195480396, 9550.761230969281, 9661.806763864824, 9280.58237379306, 9769.658061967797, 9799.608015679578, 9966.202703783534, 9968.950155249177, 6452.612843229421, 7124.002639630559, 7360.411167106726, 7373.5575865448245, 7378.040854688856, 7861.095973962839, 7902.723404680809, 8011.278984891388, 8094.231048050426, 8095.55474448911, 6275.403836635513, 7068.752593659197, 7291.927080729125, 7335.229800870832, 7422.703091876265, 7054.682539285847, 7119.394851437686, 7127.536710113826, 7270.272972702687, 7295.088113985752, 6707.29573394806, 6725.9663701681175, 6729.596224226562, 6767.627780472121, 6777.661116944035, 2786.818856774303, 4312.159128969821, 4509.7519636419, 4597.103564804965, 4746.667733258777, 4655.208614535605, 4704.059188162313, 4764.857054288377, 4777.901082750917, 4816.759162041813], "value": 4484.430016614269, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.5358116406555, 315.2827317851887, 323.8105708160726, 328.64998776303236, 330.550451386115, 5922.703864806758, 5931.139984702216, 5934.376890426508, 5936.228290322263, 6010.939890601093, 4651.836461527701, 4993.126202914485, 4993.675411098276, 5487.451254874511, 5700.8859822803515, 3713.925721485575, 4404.603585677289, 4446.421965144526, 4456.844010459635, 4512.481064677555, 4459.44256967879, 4466.285394336906, 4475.127350166719, 4485.842995495158, 4505.144022635705, 4153.522344930341, 4199.905092534785, 4399.714018588781, 4403.933940990888, 4412.47050353958, 3838.692904567642, 4201.558836322203, 4340.848070317524, 4362.45469316337, 4380.386745855582, 3708.3510385682666, 3773.7735735855936, 3774.6225377462033, 3792.715546334008, 3792.924141517182, 4999.375078115236, 5457.781688732485, 5529.557635389159, 5641.12562552805, 5659.490645841853, 3991.3613821788663, 4248.320268756984, 4602.826279298791, 4659.88350291242, 5011.0, 6101.969490152565, 6110.29731580865, 6173.2900716417735, 6174.96912515439, 6179.5056395488255, 3836.3094642964256, 4089.7750623715683, 4118.176364727036, 4137.172565486884, 4149.751014939114, 5457.781688732446, 5468.6171967962255, 6223.471004964584, 6462.612243265419, 6514.0, 3559.537260156163, 4203.852865149766, 4210.810513526899, 4212.578742125825, 4219.936700949488, 4098.180363927227, 4212.945614429514, 4236.4704411948505, 4259.893502662396, 4266.338717498793, 4020.6582440492302, 4231.090315582164, 4695.95304046962, 4953.851384458472, 4975.303457515951, 5214.374275087014, 6160.907586386208, 6256.436920677161, 6288.496920246369, 6299.6535190564955, 1083.2417307884355, 1174.8193065968817, 1452.1069542231662, 1461.9049761765664, 1475.2697414779752, 934.8878134623889, 941.1717688434134, 947.1002547579827, 954.3271993244746, 956.9234461243084, 5686.402927692616, 5701.515371193495, 5702.942970570324, 5772.2207501986695, 5790.681512516768, 5955.392044148142, 6343.953247714167, 6365.904511432333, 6378.330275321117, 6384.52116091295, 4936.975315123402, 5544.8355845272135, 5600.4399560044485, 5723.5707321950185, 5776.277965254343, 2754.297654098232, 2795.636567246245, 3340.799552026886, 3387.932241355159, 3400.268942177444, 6150.370151909748, 6454.741810327629, 6592.703328350236, 6614.735410583523, 6669.133012708414, 6071.908921366183, 6504.544387094855, 6529.412352888263, 6546.803595892131, 6583.177102862142, 5477.904419116193, 5960.7019649017375, 6183.690815459299, 6631.23740769808, 6655.46756259498, 5969.820905372846, 6259.056794219612, 6264.655443950444, 6301.842453938505, 6332.0, 5190.480951904779, 5193.68837869729, 5821.0, 6197.5351848610635, 6219.129321895029, 5793.594448388572, 6256.186695729526, 6623.867522649423, 6632.6556105750815, 6643.468522518284, 4147.4815648044, 4159.355299928516, 4168.666506679519, 4183.058811767328, 4195.014171669594, 5545.0, 5633.0705433604635, 6269.247690277196, 6329.208848893888, 6357.965451055617, 5026.723530205728, 6108.57239993214, 6314.210723659543, 6331.27190373113, 6345.555811093179, 5330.360356757214, 6223.4879712208285, 6256.718447669957, 6279.7392938543, 6326.588771729823, 5126.359205099363, 5286.603504737237, 5294.426262272873, 6141.692915354125, 6192.0, 5221.198686453167, 6180.6909654517995, 6225.564210505221, 6229.688515574294, 6242.2509298882605, 5146.485351464824, 5859.00396932514, 5955.940440595712, 6175.104609831459, 6180.505559555135], "value": 4435.66482990455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.9868602627947, 713.4862898712928, 749.5465243527667, 749.9100107987042, 784.0238902566306, 14484.855151448495, 14841.109533428002, 14849.40602375905, 14850.628734281634, 14857.03429277099, 10746.677599672003, 11512.618485781697, 12227.0, 13654.0, 13730.725385492284, 713.9464540159482, 906.1255888068013, 911.7036962987056, 918.2149262380668, 919.5586118663045, 886.9068747781487, 901.4546199549277, 902.1249388093539, 904.5748498205833, 907.5235501361805, 750.7447467860926, 937.8593211018334, 964.6430820596354, 967.4243824924159, 967.5307475874196, 948.7865230323157, 953.1040821627648, 953.7901661634429, 954.5513608603945, 955.1356022799378, 713.4114355656552, 714.5712572456537, 715.4383808710138, 715.4812760748467, 717.7057406463377, 15051.0, 15095.245237738069, 15127.773083403757, 15134.016288941184, 15149.621259468491, 12619.873801262054, 12868.212439602697, 12919.289439080847, 12998.805017924738, 13089.607311780566, 9419.826359448844, 9848.901510984942, 10140.340877842991, 10142.239332050052, 10268.794624107551, 3508.3684936711384, 3509.7718648288037, 3513.631068737797, 3580.2481478889463, 3590.622984586607, 69997.0, 72009.63995180043, 72298.37137458414, 80023.5998820008, 81693.59153204256, 9543.188828949616, 9721.70834874955, 9750.0, 9800.509974501247, 9823.459709715891, 21763.455913602287, 21827.890860545594, 21840.0, 21864.781352186594, 21888.0, 7153.570785752871, 7364.410847132217, 7476.813079673052, 7741.793588827157, 7757.961210193913, 3955.881323560298, 3958.0698535844053, 3974.8201438848805, 3995.220931918248, 4019.055521952339, 3077.876884924578, 3534.6818786309354, 3630.6006339302658, 3638.8180590970874, 3646.9635303646623, 631.2803404119308, 649.1042361541043, 652.954293199481, 657.0636842499515, 657.5101549345768, 477.89725209080217, 513.6789506558401, 539.5521716974902, 547.1601092323265, 591.2934043817596, 6998.825029374307, 7548.811279718051, 7605.619719014027, 7964.840703185904, 8056.691196511265, 457.636179237507, 535.2052202479315, 578.1010528628024, 581.7992792486585, 592.5703864698146, 3244.383567122239, 3678.7240956927803, 3698.278835627027, 3753.9061523462133, 3935.665468435158, 88538.55730721305, 92691.0, 92890.53554732318, 93336.0, 93528.0, 62476.0, 75214.0, 75426.62286688531, 75556.62221688966, 77465.6126719374, 50159.74920125376, 68029.63940721059, 68719.3128068713, 69204.0, 69585.0, 7723.497972631761, 7840.255175758294, 7919.08930473003, 8029.291363618512, 8130.0, 43239.56760432481, 43522.564774353115, 44446.11107777891, 45736.0, 45970.54029459663, 61904.0, 62049.689751550955, 63697.0, 63715.6814215926, 65132.67433662801, 11075.83386249223, 11097.33415995043, 11306.547738090385, 11306.94346528262, 11685.415729213675, 11853.466594003117, 11935.0, 12124.0, 12253.336588136372, 12553.560625378068, 10702.518386672462, 10770.13838892871, 10772.24594278393, 10779.299345542515, 10978.451077445936, 10322.483875806029, 11373.715657108638, 11382.14633902444, 11401.429928503376, 11407.372594507382, 10501.684949451528, 10546.736331591772, 10631.415272159797, 10668.519916603616, 11583.76832463363, 9280.211182049601, 9280.396774209656, 9321.0, 9453.196478299422, 9774.462404567817, 7215.314545118205, 9039.548022598976, 9108.635654574007, 9207.17135457812, 9316.44101353921], "value": 6714.123006874588, "unit": "score"}], "name": "dromaeo_css", "value": 7106.898299746355, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 113.0, 113.0, 113.0, 117.0, 112.0, 112.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 153.0, 185.0, 176.0, 122.0, 144.0, 142.0, 124.0, 175.0, 122.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 188.0, 184.0, 186.0, 187.0, 181.0, 194.0, 263.0, 193.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 91.0, 92.0, 56.0, 60.0, 58.0, 80.0, 86.0, 55.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 103.0, 141.0, 78.0, 77.0, 78.0, 128.0, 81.0, 78.0, 132.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 84.0, 87.0, 85.0, 89.0, 86.0, 89.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 101.0, 132.0, 94.0, 93.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 83.0, 84.0, 85.0, 84.0, 85.0, 84.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 56.0, 58.0, 52.0, 60.0, 58.0, 77.0, 60.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 75.0, 75.0, 74.0, 70.0, 68.0, 68.0, 69.0, 73.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 83.0, 81.0, 82.0, 81.0, 81.0, 84.0, 84.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 160.0, 156.0, 152.0, 136.0, 136.0, 138.0, 172.0, 142.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 170.0, 171.0, 169.0, 160.0, 171.0, 170.0, 167.0, 168.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 71.0, 68.0, 68.0, 68.0, 69.0, 68.0, 66.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1499.1999999999998, "alertThreshold": 2.0}]} 05:40:04 INFO - Return code: 0 05:40:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:40:04 ERROR - # TBPL SUCCESS # 05:40:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:40:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:40:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:40:04 INFO - 2016-04-18 05:40:04.150 screenresolution[6273:104442] starting screenresolution argv=screenresolution get 05:40:04 INFO - 2016-04-18 05:40:04.153 screenresolution[6273:104442] Display 0: 1600x1200x32@60 05:40:04 INFO - 2016-04-18 05:40:04.159 screenresolution[6274:104445] starting screenresolution argv=screenresolution list 05:40:04 INFO - Available Modes on Display 0 05:40:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:40:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:40:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:40:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:40:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:40:04 INFO - Intel Iris: 05:40:04 INFO - Chipset Model: Intel Iris 05:40:04 INFO - Type: GPU 05:40:04 INFO - Bus: Built-In 05:40:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:40:04 INFO - Vendor: Intel (0x8086) 05:40:04 INFO - Device ID: 0x0a2e 05:40:04 INFO - Revision ID: 0x0009 05:40:04 INFO - Displays: 05:40:04 INFO - Display: 05:40:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:40:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:40:04 INFO - Display Serial Number: 1600x1200 60 05:40:04 INFO - Main Display: Yes 05:40:04 INFO - Mirror: Off 05:40:04 INFO - Online: Yes 05:40:04 INFO - Rotation: Supported 05:40:04 INFO - Return code: 0 05:40:04 INFO - Running post-action listener: _resource_record_post_action 05:40:04 INFO - Running post-run listener: _resource_record_post_run 05:40:05 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 1515008; Write bytes: 693387264; Read time: 24; Write time: 2173 05:40:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373035520; Write bytes: 367904768; Read time: 27979; Write time: 764 05:40:05 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 1515008; Write bytes: 296146944; Read time: 24; Write time: 1353 05:40:05 INFO - Running post-run listener: _upload_blobber_files 05:40:05 INFO - Blob upload gear active. 05:40:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:40:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:40:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:06 INFO - (blobuploader) - INFO - Done attempting. 05:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:40:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:08 INFO - (blobuploader) - INFO - Done attempting. 05:40:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:08 INFO - Return code: 0 05:40:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609ecca1fc9a861714c091db88ec9e3492423a44e69dbbf236d585678225f7c37f6da7b17cdd12630f759ec6531b016b82c369e88d83d0a92729cb595c65cf37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f7df1fd2bdde31db99984b4d9423cc3a587b2514608dcfe3d4c1e4172628131e50d8bc73a0982b96cd0e9ecc3070584e2e3c46fd208dd289568701d57c4852"} 05:40:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:08 INFO - Contents: 05:40:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609ecca1fc9a861714c091db88ec9e3492423a44e69dbbf236d585678225f7c37f6da7b17cdd12630f759ec6531b016b82c369e88d83d0a92729cb595c65cf37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f7df1fd2bdde31db99984b4d9423cc3a587b2514608dcfe3d4c1e4172628131e50d8bc73a0982b96cd0e9ecc3070584e2e3c46fd208dd289568701d57c4852"} 05:40:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:08 INFO - Copying logs to upload dir... 05:40:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.695677 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 05:40:08.688168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 05:40:08.691267) ========= 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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609ecca1fc9a861714c091db88ec9e3492423a44e69dbbf236d585678225f7c37f6da7b17cdd12630f759ec6531b016b82c369e88d83d0a92729cb595c65cf37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f7df1fd2bdde31db99984b4d9423cc3a587b2514608dcfe3d4c1e4172628131e50d8bc73a0982b96cd0e9ecc3070584e2e3c46fd208dd289568701d57c4852"} build_url:https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/EXali4grQQek1mvKndJ9JA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609ecca1fc9a861714c091db88ec9e3492423a44e69dbbf236d585678225f7c37f6da7b17cdd12630f759ec6531b016b82c369e88d83d0a92729cb595c65cf37", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f7df1fd2bdde31db99984b4d9423cc3a587b2514608dcfe3d4c1e4172628131e50d8bc73a0982b96cd0e9ecc3070584e2e3c46fd208dd289568701d57c4852"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 05:40:08.725980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 05:40:08.726310) ========= 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.cSW9Ruzn7E/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.IUHZFTqtPj/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005071 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 05:40:08.775436) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 05:40:08.775739) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 05:40:08.779118) ========= ========= Total master_lag: 4.80 =========