builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0064 starttime: 1461045339.39 results: success (0) buildid: 20160418205131 builduid: d5db07eff05d47449dc0edb10d63b704 revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.388236) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.388926) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.389197) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.563851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.564282) ========= 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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:55:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.97M=0.001s 2016-04-18 22:55:40 (9.97 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.447737 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.212208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.212588) ========= 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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.019682 ========= master_lag: 0.29 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.519743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 22:55:40.520053) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb65ec85049efa44b18e2e8d06af26768c3bfb94 --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 cb65ec85049efa44b18e2e8d06af26768c3bfb94 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:55:40,619 truncating revision to first 12 chars 2016-04-18 22:55:40,619 Setting DEBUG logging. 2016-04-18 22:55:40,619 attempt 1/10 2016-04-18 22:55:40,619 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb65ec85049e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 22:55:41,604 unpacking tar archive at: mozilla-central-cb65ec85049e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.242325 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 22:55:41.810619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:41.810940) ========= script_repo_revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:41.811320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:41.811583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:41.825360) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-04-18 22:55:41.825777) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:55:41 INFO - MultiFileLogger online at 20160418 22:55:41 in /builds/slave/test 22:55:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:55:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:55:42 INFO - {'append_to_log': False, 22:55:42 INFO - 'base_work_dir': '/builds/slave/test', 22:55:42 INFO - 'blob_upload_branch': 'Firefox', 22:55:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:55:42 INFO - 'branch': 'Firefox', 22:55:42 INFO - 'buildbot_json_path': 'buildprops.json', 22:55:42 INFO - 'config_files': ('talos/mac_config.py',), 22:55:42 INFO - 'default_actions': ('clobber', 22:55:42 INFO - 'read-buildbot-config', 22:55:42 INFO - 'download-and-extract', 22:55:42 INFO - 'populate-webroot', 22:55:42 INFO - 'create-virtualenv', 22:55:42 INFO - 'install', 22:55:42 INFO - 'run-tests'), 22:55:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:55:42 INFO - 'download_minidump_stackwalk': True, 22:55:42 INFO - 'download_symbols': 'ondemand', 22:55:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:55:42 INFO - 'tooltool.py': '/tools/tooltool.py', 22:55:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:55:42 INFO - '/tools/misc-python/virtualenv.py')}, 22:55:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:55:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:55:42 INFO - 'installer_path': 'installer.exe', 22:55:42 INFO - 'log_level': 'info', 22:55:42 INFO - 'log_name': 'talos', 22:55:42 INFO - 'log_to_console': True, 22:55:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:55:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:55:42 INFO - 'opt_config_files': (), 22:55:42 INFO - 'pip_index': False, 22:55:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:42 INFO - 'cmd': ('bash', 22:55:42 INFO - '-c', 22:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:42 INFO - 'enabled': True, 22:55:42 INFO - 'halt_on_failure': False, 22:55:42 INFO - 'name': 'check_screen_resolution'},), 22:55:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:42 INFO - 'cmd': ('bash', 22:55:42 INFO - '-c', 22:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:42 INFO - 'enabled': True, 22:55:42 INFO - 'halt_on_failure': False, 22:55:42 INFO - 'name': 'check_screen_resolution'},), 22:55:42 INFO - 'run_cmd_checks_enabled': True, 22:55:42 INFO - 'sps_profile': False, 22:55:42 INFO - 'sps_profile_interval': 0, 22:55:42 INFO - 'suite': 'dromaeojs-e10s', 22:55:42 INFO - 'system_bits': '32', 22:55:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:55:42 INFO - 'title': 't-yosemite-r7-0064', 22:55:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:55:42 INFO - 'use_talos_json': True, 22:55:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:55:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:55:42 INFO - 'work_dir': 'build'} 22:55:42 INFO - ##### 22:55:42 INFO - ##### Running clobber step. 22:55:42 INFO - ##### 22:55:42 INFO - Running pre-action listener: _resource_record_pre_action 22:55:42 INFO - Running main action method: clobber 22:55:42 INFO - rmtree: /builds/slave/test/build 22:55:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:55:43 INFO - Running post-action listener: _resource_record_post_action 22:55:43 INFO - ##### 22:55:43 INFO - ##### Running read-buildbot-config step. 22:55:43 INFO - ##### 22:55:43 INFO - Running pre-action listener: _resource_record_pre_action 22:55:43 INFO - Running main action method: read_buildbot_config 22:55:43 INFO - Using buildbot properties: 22:55:43 INFO - { 22:55:43 INFO - "project": "", 22:55:43 INFO - "product": "firefox", 22:55:43 INFO - "script_repo_revision": "production", 22:55:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:55:43 INFO - "repository": "", 22:55:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:55:43 INFO - "buildid": "20160418205131", 22:55:43 INFO - "pgo_build": "False", 22:55:43 INFO - "basedir": "/builds/slave/test", 22:55:43 INFO - "buildnumber": 198, 22:55:43 INFO - "slavename": "t-yosemite-r7-0064", 22:55:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:55:43 INFO - "platform": "yosemite_r7", 22:55:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:55:43 INFO - "branch": "mozilla-central", 22:55:43 INFO - "repo_path": "mozilla-central", 22:55:43 INFO - "revision": "cb65ec85049efa44b18e2e8d06af26768c3bfb94", 22:55:43 INFO - "stage_platform": "macosx64", 22:55:43 INFO - "builduid": "d5db07eff05d47449dc0edb10d63b704", 22:55:43 INFO - "slavebuilddir": "test" 22:55:43 INFO - } 22:55:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:55:43 INFO - Running post-action listener: _resource_record_post_action 22:55:43 INFO - ##### 22:55:43 INFO - ##### Running download-and-extract step. 22:55:43 INFO - ##### 22:55:43 INFO - Running pre-action listener: _resource_record_pre_action 22:55:43 INFO - Running main action method: download_and_extract 22:55:43 INFO - mkdir: /builds/slave/test/build/tests 22:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:43 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:55:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:55:55 INFO - Downloaded 1199 bytes. 22:55:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:55 INFO - Using the following test package requirements: 22:55:55 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:55:55 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:55:55 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:55:55 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'jsshell-mac.zip'], 22:55:55 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:55:55 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:55:55 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:55:55 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:55:55 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:55:55 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:55:55 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:55:55 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:55:55 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')]} 22:55:55 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:55:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:55:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:55:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:55:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:55:57 INFO - Downloaded 18037923 bytes. 22:55:57 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'] 22:55:57 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 22:56:00 INFO - Return code: 0 22:56:00 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 22:56:00 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')]} 22:56:00 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:56:02 INFO - Downloaded 18037923 bytes. 22:56:02 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'] 22:56:02 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 22:56:05 INFO - Return code: 0 22:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:05 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:56:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:56:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:56:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:56:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:56:14 INFO - Downloaded 10845952 bytes. 22:56:14 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'] 22:56:14 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 22:56:15 INFO - Return code: 0 22:56:15 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')]} 22:56:15 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:56:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:56:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/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 22:56:18 INFO - Downloaded 109970044 bytes. 22:56:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:18 INFO - mkdir: /builds/slave/test/properties 22:56:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:56:18 INFO - Writing to file /builds/slave/test/properties/build_url 22:56:18 INFO - Contents: 22:56:18 INFO - build_url:https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:19 INFO - Running post-action listener: _resource_record_post_action 22:56:19 INFO - Running post-action listener: set_extra_try_arguments 22:56:19 INFO - ##### 22:56:19 INFO - ##### Running populate-webroot step. 22:56:19 INFO - ##### 22:56:19 INFO - Running pre-action listener: _resource_record_pre_action 22:56:19 INFO - Running main action method: populate_webroot 22:56:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:56:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:56:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:56:19 INFO - '--tppagecycles', 22:56:19 INFO - '7'], 22:56:19 INFO - 'tests': ['tsvgm']}}, 22:56:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:56:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:56:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:56:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:56:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['damp', 'tps']}, 22:56:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'tests': ['damp', 'tps']}, 22:56:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['dromaeo_dom']}, 22:56:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:56:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'other-e10s': {'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['a11yr', 22:56:19 INFO - 'ts_paint', 22:56:19 INFO - 'tpaint', 22:56:19 INFO - 'sessionrestore', 22:56:19 INFO - 'sessionrestore_no_auto_restore', 22:56:19 INFO - 'tabpaint']}, 22:56:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['tsvgx', 22:56:19 INFO - 'tsvgr_opacity', 22:56:19 INFO - 'tart', 22:56:19 INFO - 'tscrollx', 22:56:19 INFO - 'cart']}, 22:56:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:56:19 INFO - 'tsvgr_opacity', 22:56:19 INFO - 'tart', 22:56:19 INFO - 'tscrollx', 22:56:19 INFO - 'cart']}, 22:56:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'talos_options': ['--disable-e10s'], 22:56:19 INFO - 'tests': ['tp5o']}, 22:56:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'tests': ['tp5o']}, 22:56:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'talos_options': ['--disable-e10s', 22:56:19 INFO - '--xperf_path', 22:56:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:19 INFO - 'tests': ['tp5n']}, 22:56:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:19 INFO - 'talos_options': ['--xperf_path', 22:56:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:19 INFO - 'tests': ['tp5n']}}, 22:56:19 INFO - 'talos.zip': {'path': '', 22:56:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:56:19 INFO - Running post-action listener: _resource_record_post_action 22:56:19 INFO - ##### 22:56:19 INFO - ##### Running create-virtualenv step. 22:56:19 INFO - ##### 22:56:19 INFO - Running pre-action listener: _resource_record_pre_action 22:56:19 INFO - Running main action method: create_virtualenv 22:56:19 INFO - Creating virtualenv /builds/slave/test/build/venv 22:56:19 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 22:56:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:56:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:56:19 INFO - Using real prefix '/tools/python27' 22:56:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:56:20 INFO - Installing distribute.............................................................................................................................................................................................done. 22:56:23 INFO - Installing pip.................done. 22:56:23 INFO - Return code: 0 22:56:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:23 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 22:56:23 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 22:56:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:23 INFO - 'HOME': '/Users/cltbld', 22:56:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:23 INFO - 'LOGNAME': 'cltbld', 22:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:23 INFO - 'MOZ_NO_REMOTE': '1', 22:56:23 INFO - 'NO_EM_RESTART': '1', 22:56:23 INFO - 'PAGER': '/bin/cat', 22:56:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:23 INFO - 'PWD': '/builds/slave/test', 22:56:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:23 INFO - 'SHELL': '/bin/bash', 22:56:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:23 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:23 INFO - 'USER': 'cltbld', 22:56:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:23 INFO - 'XPC_FLAGS': '0x0', 22:56:23 INFO - 'XPC_SERVICE_NAME': '0', 22:56:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:23 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 22:56:23 INFO - Cleaning up... 22:56:23 INFO - Return code: 0 22:56:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:23 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 22:56:23 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 22:56:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:23 INFO - 'HOME': '/Users/cltbld', 22:56:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:23 INFO - 'LOGNAME': 'cltbld', 22:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:23 INFO - 'MOZ_NO_REMOTE': '1', 22:56:23 INFO - 'NO_EM_RESTART': '1', 22:56:23 INFO - 'PAGER': '/bin/cat', 22:56:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:23 INFO - 'PWD': '/builds/slave/test', 22:56:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:23 INFO - 'SHELL': '/bin/bash', 22:56:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:23 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:23 INFO - 'USER': 'cltbld', 22:56:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:23 INFO - 'XPC_FLAGS': '0x0', 22:56:23 INFO - 'XPC_SERVICE_NAME': '0', 22:56:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:23 INFO - Downloading/unpacking psutil>=0.7.1 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:56:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:26 INFO - Installing collected packages: psutil 22:56:26 INFO - Running setup.py install for psutil 22:56:26 INFO - building 'psutil._psutil_osx' extension 22:56: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 22:56:27 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 22:56:27 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 22:56:27 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 22:56:27 INFO - building 'psutil._psutil_posix' extension 22:56:27 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 22:56:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:56:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:56:27 INFO - ^ 22:56:27 INFO - 1 warning generated. 22:56:27 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 22:56:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:27 INFO - Successfully installed psutil 22:56:27 INFO - Cleaning up... 22:56:27 INFO - Return code: 0 22:56:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:27 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:27 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 22:56:27 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 22:56:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:27 INFO - 'HOME': '/Users/cltbld', 22:56:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:27 INFO - 'LOGNAME': 'cltbld', 22:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:27 INFO - 'MOZ_NO_REMOTE': '1', 22:56:27 INFO - 'NO_EM_RESTART': '1', 22:56:27 INFO - 'PAGER': '/bin/cat', 22:56:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:27 INFO - 'PWD': '/builds/slave/test', 22:56:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:27 INFO - 'SHELL': '/bin/bash', 22:56:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:27 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:27 INFO - 'USER': 'cltbld', 22:56:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:27 INFO - 'XPC_FLAGS': '0x0', 22:56:27 INFO - 'XPC_SERVICE_NAME': '0', 22:56:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:56:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:56:30 INFO - Installing collected packages: mozsystemmonitor 22:56:30 INFO - Running setup.py install for mozsystemmonitor 22:56:30 INFO - Successfully installed mozsystemmonitor 22:56:30 INFO - Cleaning up... 22:56:30 INFO - Return code: 0 22:56:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:56:30 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')]} 22:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:30 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')]} 22:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:30 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:30 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 22:56:30 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 22:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:30 INFO - 'HOME': '/Users/cltbld', 22:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:30 INFO - 'LOGNAME': 'cltbld', 22:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:30 INFO - 'MOZ_NO_REMOTE': '1', 22:56:30 INFO - 'NO_EM_RESTART': '1', 22:56:30 INFO - 'PAGER': '/bin/cat', 22:56:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:30 INFO - 'PWD': '/builds/slave/test', 22:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:30 INFO - 'SHELL': '/bin/bash', 22:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:30 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:30 INFO - 'USER': 'cltbld', 22:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:30 INFO - 'XPC_FLAGS': '0x0', 22:56:30 INFO - 'XPC_SERVICE_NAME': '0', 22:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:30 INFO - Downloading/unpacking blobuploader==1.2.4 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56:33 INFO - Downloading blobuploader-1.2.4.tar.gz 22:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:56:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:56:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:34 INFO - Downloading docopt-0.6.1.tar.gz 22:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:56:34 INFO - Installing collected packages: blobuploader, requests, docopt 22:56:34 INFO - Running setup.py install for blobuploader 22:56:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:56:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:56:34 INFO - Running setup.py install for requests 22:56:35 INFO - Running setup.py install for docopt 22:56:35 INFO - Successfully installed blobuploader requests docopt 22:56:35 INFO - Cleaning up... 22:56:35 INFO - Return code: 0 22:56:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:35 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:35 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 22:56:35 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 22:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:35 INFO - 'HOME': '/Users/cltbld', 22:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:35 INFO - 'LOGNAME': 'cltbld', 22:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:35 INFO - 'MOZ_NO_REMOTE': '1', 22:56:35 INFO - 'NO_EM_RESTART': '1', 22:56:35 INFO - 'PAGER': '/bin/cat', 22:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:35 INFO - 'PWD': '/builds/slave/test', 22:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:35 INFO - 'SHELL': '/bin/bash', 22:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:35 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:35 INFO - 'USER': 'cltbld', 22:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:35 INFO - 'XPC_FLAGS': '0x0', 22:56:35 INFO - 'XPC_SERVICE_NAME': '0', 22:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:56:35 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-JSfh_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:56:35 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-b1FwGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-R6xPli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-9eX3rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QvKOIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-SM7n5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-PCnLFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-WQllL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-iJ9VMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-9pbN8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-h4TpkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:56:36 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-H_QIeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:56:37 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-mVYjg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:56:37 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-vRyPpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:37 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-xAQ2EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:56:37 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Xm84G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:56:37 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-SC4acA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:56:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:56:37 INFO - Running setup.py install for manifestparser 22:56:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:56:37 INFO - Running setup.py install for mozcrash 22:56:37 INFO - Running setup.py install for mozdebug 22:56:37 INFO - Running setup.py install for mozdevice 22:56:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Running setup.py install for mozfile 22:56:38 INFO - Running setup.py install for mozhttpd 22:56:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Running setup.py install for mozinfo 22:56:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Running setup.py install for mozInstall 22:56:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:56:38 INFO - Running setup.py install for mozleak 22:56:38 INFO - Running setup.py install for mozlog 22:56:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Running setup.py install for moznetwork 22:56:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Running setup.py install for mozprocess 22:56:39 INFO - Running setup.py install for mozprofile 22:56:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Running setup.py install for mozrunner 22:56:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:56:39 INFO - Running setup.py install for mozscreenshot 22:56:39 INFO - Running setup.py install for moztest 22:56:39 INFO - Running setup.py install for mozversion 22:56:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:56:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:56:40 INFO - Cleaning up... 22:56:40 INFO - Return code: 0 22:56:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:40 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:40 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 22:56:40 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 22:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:40 INFO - 'HOME': '/Users/cltbld', 22:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:40 INFO - 'LOGNAME': 'cltbld', 22:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:40 INFO - 'MOZ_NO_REMOTE': '1', 22:56:40 INFO - 'NO_EM_RESTART': '1', 22:56:40 INFO - 'PAGER': '/bin/cat', 22:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:40 INFO - 'PWD': '/builds/slave/test', 22:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:40 INFO - 'SHELL': '/bin/bash', 22:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:40 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:40 INFO - 'USER': 'cltbld', 22:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:40 INFO - 'XPC_FLAGS': '0x0', 22:56:40 INFO - 'XPC_SERVICE_NAME': '0', 22:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-MU0Bs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:56:40 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)) 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-kEUaR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:56:40 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)) 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-hYRZut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:56: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)) 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-zUGTdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:56: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)) 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-hjAvBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:56: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)) 22:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:56:40 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-JeeWYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ZGX215-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Q_pu5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Tiz6ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-_fJh_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-3BiOwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-VzInRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:56:41 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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-45l4p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:56: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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-M10gNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:56: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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-X3j2Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:56: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)) 22:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:56:41 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ATda1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:56:42 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)) 22:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:56:42 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-BG9uY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:56:42 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)) 22:56:42 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)) 22:56:42 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)) 22:56:42 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)) 22:56:42 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)) 22:56:42 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)) 22:56:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:45 INFO - Downloading blessings-1.6.tar.gz 22:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:56:45 INFO - Installing collected packages: blessings 22:56:45 INFO - Running setup.py install for blessings 22:56:45 INFO - Successfully installed blessings 22:56:45 INFO - Cleaning up... 22:56:45 INFO - Return code: 0 22:56:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:56:45 INFO - Reading from file tmpfile_stdout 22:56:45 INFO - Current package versions: 22:56:45 INFO - blessings == 1.6 22:56:45 INFO - blobuploader == 1.2.4 22:56:45 INFO - docopt == 0.6.1 22:56:45 INFO - manifestparser == 1.1 22:56:45 INFO - mozInstall == 1.12 22:56:45 INFO - mozcrash == 0.17 22:56:45 INFO - mozdebug == 0.1 22:56:45 INFO - mozdevice == 0.48 22:56:45 INFO - mozfile == 1.2 22:56:45 INFO - mozhttpd == 0.7 22:56:45 INFO - mozinfo == 0.9 22:56:45 INFO - mozleak == 0.1 22:56:45 INFO - mozlog == 3.1 22:56:45 INFO - moznetwork == 0.27 22:56:45 INFO - mozprocess == 0.22 22:56:45 INFO - mozprofile == 0.28 22:56:45 INFO - mozrunner == 6.11 22:56:45 INFO - mozscreenshot == 0.1 22:56:45 INFO - mozsystemmonitor == 0.0 22:56:45 INFO - moztest == 0.7 22:56:45 INFO - mozversion == 1.4 22:56:45 INFO - psutil == 3.1.1 22:56:45 INFO - requests == 1.2.3 22:56:45 INFO - wsgiref == 0.1.2 22:56:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:45 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')]} 22:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:46 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')]} 22:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:46 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:46 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 22:56:46 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 22:56:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:46 INFO - 'HOME': '/Users/cltbld', 22:56:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:46 INFO - 'LOGNAME': 'cltbld', 22:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:46 INFO - 'MOZ_NO_REMOTE': '1', 22:56:46 INFO - 'NO_EM_RESTART': '1', 22:56:46 INFO - 'PAGER': '/bin/cat', 22:56:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:46 INFO - 'PWD': '/builds/slave/test', 22:56:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:46 INFO - 'SHELL': '/bin/bash', 22:56:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:46 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:46 INFO - 'USER': 'cltbld', 22:56:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:46 INFO - 'XPC_FLAGS': '0x0', 22:56:46 INFO - 'XPC_SERVICE_NAME': '0', 22:56:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 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)) 22:56:46 INFO - Cleaning up... 22:56:46 INFO - Return code: 0 22:56:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:56:46 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')]} 22:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:46 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')]} 22:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:46 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:46 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 22:56:46 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 22:56:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:46 INFO - 'HOME': '/Users/cltbld', 22:56:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:46 INFO - 'LOGNAME': 'cltbld', 22:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:46 INFO - 'MOZ_NO_REMOTE': '1', 22:56:46 INFO - 'NO_EM_RESTART': '1', 22:56:46 INFO - 'PAGER': '/bin/cat', 22:56:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:46 INFO - 'PWD': '/builds/slave/test', 22:56:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:46 INFO - 'SHELL': '/bin/bash', 22:56:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:46 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:46 INFO - 'USER': 'cltbld', 22:56:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:46 INFO - 'XPC_FLAGS': '0x0', 22:56:46 INFO - 'XPC_SERVICE_NAME': '0', 22:56:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:46 INFO - Downloading/unpacking jsonschema 22:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:56:49 INFO - Downloading/unpacking functools32 (from jsonschema) 22:56:49 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 22:56:49 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 22:56:49 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 22:56:49 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 22:56:49 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 22:56:49 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 22:56:49 INFO - Downloading functools32-3.2.3-2.tar.gz 22:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:56:49 INFO - warning: no files found matching '*.txt' 22:56:49 INFO - no previously-included directories found matching 'build' 22:56:49 INFO - no previously-included directories found matching 'dist' 22:56:49 INFO - no previously-included directories found matching '.git*' 22:56:49 INFO - Installing collected packages: jsonschema, functools32 22:56:49 INFO - Running setup.py install for functools32 22:56:49 INFO - warning: no files found matching '*.txt' 22:56:49 INFO - no previously-included directories found matching 'build' 22:56:49 INFO - no previously-included directories found matching 'dist' 22:56:49 INFO - no previously-included directories found matching '.git*' 22:56:49 INFO - Successfully installed jsonschema functools32 22:56:49 INFO - Cleaning up... 22:56:49 INFO - Return code: 0 22:56:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:56:49 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')]} 22:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:49 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')]} 22:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:49 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 0x1010b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe953d972c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, '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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:56:49 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 22:56:49 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 22:56:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:56:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:49 INFO - 'HOME': '/Users/cltbld', 22:56:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:49 INFO - 'LOGNAME': 'cltbld', 22:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:49 INFO - 'MOZ_NO_REMOTE': '1', 22:56:49 INFO - 'NO_EM_RESTART': '1', 22:56:49 INFO - 'PAGER': '/bin/cat', 22:56:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:49 INFO - 'PWD': '/builds/slave/test', 22:56:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:49 INFO - 'SHELL': '/bin/bash', 22:56:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:56:49 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:56:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:49 INFO - 'USER': 'cltbld', 22:56:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:49 INFO - 'XPC_FLAGS': '0x0', 22:56:49 INFO - 'XPC_SERVICE_NAME': '0', 22:56:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:50 INFO - Downloading/unpacking flake8 22:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:56:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:56:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:52 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 22:56:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:56:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:56:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:56:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:56:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:56:53 INFO - no previously-included directories found matching 'docs/_build' 22:56:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:56:53 INFO - Running setup.py install for pep8 22:56:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:56:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:56:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:56:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:56:53 INFO - no previously-included directories found matching 'docs/_build' 22:56:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:56:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:56:53 INFO - Cleaning up... 22:56:54 INFO - Return code: 0 22:56:54 INFO - Running post-action listener: _resource_record_post_action 22:56:54 INFO - Running post-action listener: _start_resource_monitoring 22:56:54 INFO - Starting resource monitoring. 22:56:54 INFO - ##### 22:56:54 INFO - ##### Running install step. 22:56:54 INFO - ##### 22:56:54 INFO - Running pre-action listener: _resource_record_pre_action 22:56:54 INFO - Running main action method: install 22:56:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:56:54 INFO - Reading from file tmpfile_stdout 22:56:54 INFO - Detecting whether we're running mozinstall >=1.0... 22:56:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:56:54 INFO - Reading from file tmpfile_stdout 22:56:54 INFO - Output received: 22:56:54 INFO - Usage: mozinstall [options] installer 22:56:54 INFO - Options: 22:56:54 INFO - -h, --help show this help message and exit 22:56:54 INFO - -d DEST, --destination=DEST 22:56:54 INFO - Directory to install application into. [default: 22:56:54 INFO - "/builds/slave/test"] 22:56:54 INFO - --app=APP Application being installed. [default: firefox] 22:56:54 INFO - mkdir: /builds/slave/test/build/application 22:56:54 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'] 22:56:54 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 22:57:28 INFO - Reading from file tmpfile_stdout 22:57:28 INFO - Output received: 22:57:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:57:28 INFO - Running post-action listener: _resource_record_post_action 22:57:28 INFO - ##### 22:57:28 INFO - ##### Running run-tests step. 22:57:28 INFO - ##### 22:57:28 INFO - Running pre-action listener: _resource_record_pre_action 22:57:28 INFO - Running main action method: run_tests 22:57:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:57:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:57:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:57:28 INFO - 2016-04-18 22:57:28.731 screenresolution[1926:16886] starting screenresolution argv=screenresolution get 22:57:28 INFO - 2016-04-18 22:57:28.733 screenresolution[1926:16886] Display 0: 1600x1200x32@60 22:57:28 INFO - 2016-04-18 22:57:28.739 screenresolution[1927:16888] starting screenresolution argv=screenresolution list 22:57:28 INFO - Available Modes on Display 0 22:57:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:57:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:57:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:57:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:57:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:57:28 INFO - Intel Iris: 22:57:28 INFO - Chipset Model: Intel Iris 22:57:28 INFO - Type: GPU 22:57:28 INFO - Bus: Built-In 22:57:28 INFO - VRAM (Dynamic, Max): 1536 MB 22:57:28 INFO - Vendor: Intel (0x8086) 22:57:28 INFO - Device ID: 0x0a2e 22:57:28 INFO - Revision ID: 0x0009 22:57:28 INFO - Displays: 22:57:28 INFO - Display: 22:57:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:57:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:57:28 INFO - Display Serial Number: 1600x1200 60 22:57:28 INFO - Main Display: Yes 22:57:28 INFO - Mirror: Off 22:57:28 INFO - Online: Yes 22:57:28 INFO - Rotation: Supported 22:57:28 INFO - Return code: 0 22:57:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:57:28 INFO - Python 2.7.3 22:57:28 INFO - Return code: 0 22:57:28 INFO - grabbing minidump binary from tooltool 22:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:28 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 0x100f53e00>, '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 0x100dbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f4eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:57:28 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 22:57:28 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 22:57:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:57:29 INFO - Return code: 0 22:57:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:57:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:57:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:57:30 INFO - Return code: 0 22:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uN7xuIa9o6/Render', 22:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:30 INFO - 'HOME': '/Users/cltbld', 22:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:30 INFO - 'LOGNAME': 'cltbld', 22:57:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:57:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:30 INFO - 'MOZ_NO_REMOTE': '1', 22:57:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:57:30 INFO - 'NO_EM_RESTART': '1', 22:57:30 INFO - 'PAGER': '/bin/cat', 22:57:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:30 INFO - 'PWD': '/builds/slave/test', 22:57:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:30 INFO - 'SHELL': '/bin/bash', 22:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hJw3HBmuv/Listeners', 22:57:30 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:30 INFO - 'USER': 'cltbld', 22:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:30 INFO - 'XPC_FLAGS': '0x0', 22:57:30 INFO - 'XPC_SERVICE_NAME': '0', 22:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:57:30 INFO - mozversion application_buildid: 20160418205131 22:57:30 INFO - mozversion application_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:57:30 INFO - mozversion application_display_name: Nightly 22:57:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:57:30 INFO - mozversion application_name: Firefox 22:57:30 INFO - mozversion application_remotingname: firefox 22:57:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:57:30 INFO - mozversion application_vendor: Mozilla 22:57:30 INFO - mozversion application_version: 48.0a1 22:57:30 INFO - mozversion platform_buildid: 20160418205131 22:57:30 INFO - mozversion platform_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:57:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:57:30 INFO - mozversion platform_version: 48.0a1 22:57:30 INFO - using testdate: 1461045450 22:57:30 INFO - actual date: 1461045450 22:57:30 INFO - starting webserver on 'localhost:52301' 22:57:30 INFO - SUITE-START | Running 2 tests 22:57:30 INFO - TEST-START | dromaeo_css 22:57:30 INFO - operating with platform_type : mac_ 22:57:30 INFO - Initialising browser for dromaeo_css test... 22:57:30 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpXz6wu4/profile http://localhost:52301/getInfo.html) 22:57:33 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 22:57:33 INFO - PROCESS | 1945 | colorDepth:24 22:57:33 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 22:57:33 INFO - PROCESS | 1945 | __metrics 22:57:33 ERROR - PROCESS | 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:57:34 INFO - TEST-INFO | 1945: exit 0 22:57:34 INFO - Browser initialized. 22:57:34 INFO - Running cycle 1/1 for dromaeo_css test... 22:57:34 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpXz6wu4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:46 INFO - PROCESS | 1948 | RSS: Main: 247615488 22:57:46 INFO - PROCESS | 1948 | 22:58:35 INFO - PROCESS | 1948 | 1461045515748 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] 23:00:46 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52301/tests/dromaeo/cssquery-ext.html) 23:00:47 INFO - PROCESS | 1948 | RSS: Main: 262782976 23:00:47 INFO - PROCESS | 1948 | 23:03:50 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-ext.html (next: http://localhost:52301/tests/dromaeo/cssquery-jquery.html) 23:03:50 INFO - PROCESS | 1948 | RSS: Main: 273367040 23:03:50 INFO - PROCESS | 1948 | 23:06:52 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52301/tests/dromaeo/cssquery-mootools.html) 23:06:52 INFO - PROCESS | 1948 | RSS: Main: 275177472 23:06:52 INFO - PROCESS | 1948 | 23:09:54 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52301/tests/dromaeo/cssquery-prototype.html) 23:09:55 INFO - PROCESS | 1948 | RSS: Main: 276688896 23:09:55 INFO - PROCESS | 1948 | 23:12:57 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52301/tests/dromaeo/cssquery-yui.html) 23:12:58 INFO - PROCESS | 1948 | RSS: Main: 279810048 23:12:58 INFO - PROCESS | 1948 | 23:16:00 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52301/tests/dromaeo/cssquery-yui.html (next: http://localhost:52301/tests/dromaeo/cssquery-dojo.html) 23:16:01 INFO - PROCESS | 1948 | RSS: Main: 280915968 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | __start_tp_report 23:16:01 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 23:16:01 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 23:16:01 INFO - PROCESS | 1948 | |i|pagename|runs| 23:16:01 INFO - PROCESS | 1948 | |0;dojo.html;3499.4925735768315;3504.1590018395586;3511.8068506232157;3558.6797188253054;3564.679178873901;5716.657000579969;5931.970340148293;5955.315138759044;5982.312034116078;5987.221661184046;5833.3874943130995;5920.171176035358;5994.700264986755;5999.460048595625;6070.908936365947;5317.361916570007;5363.436839131893;5374.731263436832;5410.8917821643545;5472.1244600863865;107.01072093216143;4271.273883439822;5317.335333083364;5335.439778823226;5381.569474442035;5338.546223571001;5344.732763361836;5369.301990741179;5397.541208997221;5399.919001214985;5383.919241211385;5391.78432862685;5410.891782164374;5417.59368047394;5450.890982180374;5268.0517506848755;5269.420363759983;5301.469853014706;5330.093884039723;5361.705106219157;5679.716014199274;5800.564957628195;5806.361300256968;5846.298444186682;5889.85275368115;6166.660833654148;6224.564280500367;6281.560290779647;6285.937140628581;6309.400606942333;6221.4089661482085;6277.4350308472;6310.211223597051;6329.430351268408;6338.809835704937;4925.261210818406;5247.845473995753;5999.430054144849;6037.54718396122;6064.666443345614;5987.311459182162;6250.0937364082865;6498.480121590262;6502.089707440962;6529.444997175198;5789.507891829157;6267.874642507124;6271.905921411182;6291.591046581958;6292.402221788922;5236.29310043147;6269;6298.0552917062805;6303.212098487689;6356.872862542723;6161.137440758297;6206.782762603287;6255.186825712628;6262.749490020439;6265.27949285838;6217.6891155442045;6254.3745625437095;6270.7805226816845;6290.779822706275;6293.685315734195;5014.6740461870595;5030.798768049311;5052.873678158076;5056;5065.594752419797;4429.9778501107285;4454.977725111418;4489.326601009875;4566.086782643484;4593.816247350069;5936.643801371932;5979.850503737442;5994.340622531518;6027.126066720301;6080.847978800565;6266.404691554295;6324.841878952971;6364.1408409865;6389.648569328639;6391.776287829996;5855.912161317584;5923.229980102559;5986.401359864066;6020.217371741734;6035.2154219952;5479.397266300703;5494.230807686927;5524.447555244444;5529.529989950819;5539.889202215933;6523.739050437929;6550.2794692583775;6553.705083271264;6582.703778329987;6592.967035164794;6143.539234557336;6215.533834962305;6267.4986001120715;6291.213598300213;6330.113784070142;6543.934560654523;6574;6583.374579414948;6585.5719378240265;6597.901031484435;6266.467350275278;6276.811695649138;6301.558890877594;6313.2424109107205;6335.4931605472375;6149.2620885494025;6172.629642221481;6196.287426945959;6306.84232894163;6309.400606942333;6121.3572574879;6254.155688982111;6265.404786545453;6268.341824108586;6268.937310626835;6228.377162283917;6268.279147897868;6283.214598175228;6285.057241413824;6326.145970293951;6018.037114061729;6097.766665674493;6126.417990290915;6144.740328232612;6174.9073763892675;4884.852059765881;6252.280987686474;6272.4982001440685;6284.811455656338;6293.96853015732;6061.454469097803;6165.136880836776;6215.912215362384;6223.657698826608;6234.501239900706 23:16:01 INFO - PROCESS | 1948 | |1;ext.html;498.2824732385362;538.1980848535682;556.0907915558064;567.821136342052;577.5495113811228;33552;38173.23653526928;38212;38273.04317392067;38302.425463618034;37540.62459375405;38309;38663;39304.80347598258;39376;9394.154526092667;9480.146786789188;9513.429194248332;9561.187299079587;9625.37435066722;8398.118197589256;8825.33809964252;9459.858102128474;9637.18083962864;9695.951520242388;9344.906550934506;9600.32797704161;9685.709428717115;9690.854637180448;9751.317407781458;8390.790230244262;9706.466144362093;9711.951440242754;9719.271054670928;9724.951375243149;9445.858312125254;9503.952480237624;9521.381110227812;9625.90374096257;9667.468289244089;37467.81266093679;38100;38510.42234366487;38619.806900965596;39073.218535629414;2502.3243724194367;2667.9328268692484;2992.9850350748325;3110.7977981431136;3141.8114913105287;26240.9503619855;26243.343916402053;26423.867880660666;26582;26701.866490667617;2581.238534632288;2613.7647611714847;2631.302704783234;2778.0693467688347;2824.209221418006;283352;327384;332249;333250.00124998146;335194.32402838074;58577.70711146417;59091.70454147702;59138;59169;59203.407965920655;66920.33079669141;68050;68723.65638171777;68746.96879546811;68836.311636883;15938.760918586231;16229.431969880998;16414.67170656604;16457.588560286087;16462.75305870413;12161.331126787934;12220.388980551115;12668.683282918;12696;12727.981761459061;18827.34104306343;19171.904140479488;19246.615067698567;19385.739926904705;19456.319028833925;7056.012158297843;7249.927500724925;8535.743927682179;8617.35369847264;8645.610947507377;914.9660883201939;976.2190247801731;1023.2581378500528;1084.3710647824244;1105.42517890698;26662;28941.42117157645;29546.40907181844;29574.852125739235;30176.69823301739;1090.5419723716059;1112.799696054718;1121.7195701074731;1156.8611766587894;1239.3555351217317;14300.356483958247;15354.309056092503;15408.481377067685;15539.45611903578;16001.519954401387;642021;643405;645040.8739781543;650499;662727;60151.699241504386;60668.39331606627;60994.69502652458;61021.69489152526;61137.69431152905;1440.229477229677;1468.5814542855449;1473.6463248820205;1482.9925850370894;1484.606579256498;28991.42017159603;29176.854115730133;29219.123426297247;29489.410211795213;29673;51919.74040129775;52065.7396713014;52314;52489;52766.20850687166;570598;587449;592768;593768;594888.0511195005;24775.876120619283;25319;25634;26668.733312667402;27353.316167095185;45203.77398112988;45559.31661025021;45640.54359456363;46509.6047118587;46519.534804652874;37343;37472.81263593664;37833;38126;38130;28001.859990699915;37257.44113838349;37295;37511;38094;32275.35449290954;36439.08902277464;36656;38028.80985595165;38189;24118.155864544657;31576.210594734395;33241.667583323855;33397.666023340425;33616.83191584027;24758.25725228246;25130.497390052464;25233.36916577027;25315.74684253134;25337.23988280281 23:16:01 INFO - PROCESS | 1948 | |2;jquery.html;336.8652538984404;378.21709062241155;397.79314756326704;409.52904160215746;411.96086371794667;15482.84517154828;18344.266229350826;20310.984450777458;20443.18227270911;20495.590088198245;19083.854968701853;19488.317908873192;19560.217591296365;19936.50158746032;20336.288229911956;5618.269624948751;5676.971615141919;5869.735861886225;5871.412858714116;5951.553633477485;5697.572682048843;5712.314522257335;5841.678707671076;5854.20968169296;5880.882632299849;5641.774329026834;5690.374058853544;5720.1991721159075;5790.10253410722;5840.766369345221;5636.098224284095;5754.165645981309;5763.077907534816;5808.303003639569;5833.854153646172;4869.196582563873;5078.720670363128;5212.322398088262;5212.58299336056;5219.243209734567;12663.366831658472;12915.354232288348;15668.764968525482;23176.420589485228;23728.932198050956;1996.1236437748614;2125.4473836802545;2137.4549489880137;2185.1805572910157;2220.2562141682556;9548.045195480467;9614.807703845954;9661.951690241574;9674.129328360412;9846.212303015813;1653.1403670091606;1656.8508834204858;1658.8921720088276;1738.97391539127;1738.9913050434793;113349.23182297668;115844;116643;119532.40233798862;120125;11320;11487.712807179887;11569;11760.824247514238;12037.217580857216;14341.92829035848;14420.278986050866;14512.056716313406;14693.77959330611;14703.705925881422;8282.420230583923;8450.788730281793;8454.28138608213;8465.28045116172;8476.449030812977;7692.384609231248;8284.917150828534;8358.414910956297;8361.289290410383;8507.404481686222;3982.287616325008;4478.798454069574;4495.415595972503;4544.386507821468;4615.561521655437;1536.4852774320727;1973.289615738333;1983.7700625611924;1987.8012198780007;1990.5222746540726;870.4385671242013;895.4135041547796;960.2558017536326;971.7959228562081;1008.8789345278614;16478;18223.908880455514;18438.81561184371;19789.109490072693;19990.000499974944;862.1550880137501;939.9718008459757;961.8605302231277;961.8701475300885;974.8537719342155;8597.527136007455;10382.3251488653;10411.167106631603;10453.84319235212;10458.320209186379;536680;537284;557829;616478;619390;114608;122047.38976305061;125623;126647;128470;999.645125980281;1007.4459047523833;1018.5620183321244;1021.8620486234263;1028.2493779540978;17450.47648570545;17475;17632.382866599608;17727.468175954742;18004.54988625295;40481.5695896986;42494.51269205563;49506;49748.50251497584;50857.74571127121;59697;69883.65058174677;73501.52996940138;73829;74027;6890.10428644273;7016.5088443808445;7385.559815835861;7416.7774966751085;8240.958795205986;10705.250632455653;12193.451294691582;12245.020398367931;12508.812367814671;12594.3073130975;5423.132298832168;7567;8867;10494.530765692962;10704.839427408751;9646.697695811257;10138.290319677551;10820.458977051274;10908.181886358396;11322.150838687296;11049.674039115358;11185.608503702331;11439.885601144215;11463.484143213742;11591.88408115908;7399.1299836373155;7406.037215161994;7448.925510744823;7504.699812007459;7534.321911028033;9506;10634.627788027381;10968.890311096788;11293.661190164308;11420.543178272776 23:16:01 INFO - PROCESS | 1948 | |3;mootools.html;1398.1331574423855;1483.7032593481304;1786.6158775863187;1953.2089503750976;1967.144292232878;7998.200179981999;8531.146885311482;8593.871091933626;8823.426477278972;8854.513001784908;8581.52801595912;8591.957040214791;8682.86975695365;8715.477071375706;8722.433041852275;2398.8080953523718;2407.879606019701;2426.696662917135;2430.2101816909485;2438.0247900839718;2140.86084404514;2271.7273927128767;2425.3815277104313;2437.792787613055;2444.315591634336;2413.987930060347;2429.720582133051;2431.0761910473993;2432.8540287582714;2459.950800983979;2392.4497365605835;2409.4458274596855;2425.151197081025;2439.4755127647463;2441.829071964963;2400.663907053014;2420.92737217882;2427.927162185137;2428.8178386621075;2451.5587194305017;7840.176781437924;8514.318854491627;8672.913270867337;8717.912820871774;8799.120087991214;5194.71429071401;5277.683338999634;5278.155495120801;5319.96260729158;5390;2802.7897907657007;3440.415129428003;3469.4795780632853;3482.320947415255;3512.9297414051575;991.657878032085;1003.6386900715737;1019.3119644240109;1022.0954455307033;1024.5799222318888;9815.460149691835;11185.384803835786;11225.38260395678;11374.772504549863;11426.028787553068;4481.170983368087;4496.797644106023;4611.224678498802;4613.815447382134;4645.674802763841;8848.11518848123;8991.415557988841;8994.415363001384;9005.504697241584;9023.954880225558;5120.5391514763105;5169.991851588979;5184.818531351385;5211.5049070338255;5230.08473517133;4404.4494438195225;4762.095201911626;4840.951590484121;4903.190973489334;4906.1414252505665;3832.9425058624142;3870.53553573573;3900.8439662413184;3942.3692209246383;3996.1208534122447;1205.9879401206051;1216.8904798568171;1221.071985291185;1225.5342969671467;1236.746466974268;894.5750768384966;905.1446383167843;918.6600957645713;937.6343226141673;954.3701157236329;5253.973730131402;5788.565857560699;5815.389384114607;5867.70661466925;5990.131430942489;454.4864303337274;455.4489068227482;459.7861994172726;463.5179413410016;475.76449657419107;3830.7893065881085;3936.626020528045;3955.7626542407547;3955.920881582352;4010.6991975601363;10475.319104258198;10843.403612801214;10862.45687715611;10952.671419857417;10957.671269861918;10750.731231719114;10755.56977720898;10758.139348852073;10876.238663293494;10903.01872831449;7746.767596972099;8133.983252093488;8161.469504482189;8197.05733840604;8445.282151017109;5895.29256489224;5957.523398128053;5959.359898597818;6064.090386442069;6131.509479241739;9842.409455432697;10050.045245701647;10051.447170405698;10156.949215253877;10171.898281017391;8126.634301456331;9750.512474376112;10077.294589378673;10085.798284034425;10108.444035578113;6988.755393561404;7375.926240737524;7601.923980760122;7614.923850761643;7652;8246.175382461706;8311.750647480585;8313.792155196168;8392.748217553482;8455.95772021136;7375;7511.211322811245;7575.053118360205;7577.886331705139;7628.923710762821;7549.547027178387;7604.581748003691;7614.771556853303;7712.884306735516;7719.575423351769;5549.639273447375;6951.930480695129;6981.965090174516;7025.051618031501;7267.164276108315;4223.345381465878;4273;5058.317127187783;5110;5237.083510385591;4684.672072954997;5148.613853961043;5153.149730294609;5374.435684253253;5385.27298814655 23:16:01 INFO - PROCESS | 1948 | |4;prototype.html;320;320.99197520061995;327.96884295991873;332.7055555833087;333.03918196004537;5873.2658417697785;6296.670232014161;6299.433051025407;6309.116723658692;6334.493240540757;5402.864928376793;6201.945669236229;6225.501959843213;6236.251649802019;6255.968720156404;4408.999999999992;4523.592876641102;4603.585677289043;4604.953950460486;4682.180618391785;4603.4015577974815;4664.693885712006;4675.672702910798;4685.226937555316;4688.484266730657;4097.938530922023;4339.305711086226;4363.934540981872;4463.7055253976305;4654.790534425942;4144.233316836365;4165.271077561415;4288.849890253841;4346.15250026245;4624.213883639773;3408.642092580293;3899.844006239747;3901.9219615607813;3908.7850168240734;3925.685945124383;5346.331708536433;5423.752536916531;5720.742566584514;5825.912611310834;5886.499647529965;4297.849575264882;4376.321670141101;5054.671446356012;5299.019681358923;5302.117748199411;5667.433256674341;6451.6774161292215;6513.869722605568;6520;6553.836154096138;3602.4596310553625;3679.638533742506;4132.648724858391;4315.697901146921;4319.762413067311;5696.832149409361;6060.848478788022;6193;6942.444604431583;6977.33715297046;4341.435613370241;4360.585744354281;4362.6475792504425;4369.143114176462;4383.904023994001;3988.6809055275507;4082.755034697928;4440.713032788117;4481.955180448153;4483;4610.700304480265;5165.1735722285;5275.340582427197;5277.656952298165;5299.947000530023;5503.504684578327;6138.048602466625;6217.440430361289;6572.901406478906;6641.966790166019;1239.5723475400973;1453.2225259486124;1487.9776803347959;1542.4524293875513;1546.5283088658068;922.6908985489802;978.706388083572;981.2444417798256;990.4601991914424;1003.5283416794096;4838.564529192389;6040.577159598874;6073;6098.420650038239;6113.908291375633;5183.352080989876;6355.269144048493;6672.599644021374;6779.389854913082;6811.523193376416;6012.518998480111;6091.908621370683;6107.053406721965;6108.601806699169;6115.785947491905;2912.650481942138;3547.084574329833;3551.2776303492788;3554.697850682721;3575.3385623659706;6801.319868013258;6994.825129371807;7009.509334346547;7103.254158313406;7208.098987626547;6884.277150899084;7009.334113259232;7164.283571642898;7188.148000099912;7193.388561972186;6395.514444627689;6961.547499412522;7025.508214424941;7279.781606551812;7288.453365997571;6855.348741869514;6922.757703480354;6924.3421874921805;6929.688164032731;6987.650617469005;6407.167068281094;6803.387695107464;6847.520673552804;6851.8972215415815;6853.657317134223;6757.155355580552;7198.6400679964745;7229.963850180716;7257.367092404378;7311.122665280201;4052.311107111739;4298.398224248545;4381.283604165438;4423.491298500585;4431.601155895984;6878.69045892926;6888.070110535214;6900.102986611709;6914.27400123;6930.549514281555;6510.44661203803;6631.966840165768;6637.070810086689;6665.533412661067;6684.799456016327;5783.248177736868;6158.907616385668;6573.408393244631;6628.767993120217;6648.83377915556;5090.974545127251;5332.760025798926;6315.4947604192475;6528.510361722795;6531.224229702262;5104.2088476286235;5228.764705588182;6168.876622467616;6623.635700036544;6629.602223866584;6084.847878803065;6319.514913995115;6422.632609825364;6502.512311576746;6558.737650494118 23:16:01 INFO - PROCESS | 1948 | |5;yui.html;674.20107173;726.8836986082222;729.584137041886;746.4588173574159;752.4845480845621;14932.477363292273;15156.848431515682;15227.238638068107;15228.08631482112;15341.53975380739;13977.79033314501;14164.50424235152;14165.71668566628;14462.204578748164;14466.059706119095;709.7515869445705;886.3839631456125;887.5784002598779;911.2300106410073;911.3666002128523;892.8973168085656;897.2239013253554;906.1074841281333;913.3880300198849;915.5834095486571;931.2410385535767;942.8962814090445;958.9232861371073;960.6493629825134;965.4689920543708;895.8253140637578;928.8420968435382;932.8787257656495;952.6522819170988;956.421365074127;689.2452764223168;690.0649619765246;695.9860802783967;707.4693979515364;707.4835370179746;12653.051021173449;15078.095314281069;15136.243187840564;15453.690926181525;15480;11841.763164736743;12373.505059797597;13007.479700811957;13197.27414992175;13375.26436046017;10198.490075496196;10343.844842327371;10367.585296588128;10376.844347334796;10708.839367409495;3069.3554353585773;3447.7241820654285;3520.260745243502;3530.770499917523;3602.1534939289245;80034.19965800326;81735.18264817336;82086.17913820905;82827.5858620709;89252.5537372309;9354.158125768714;9363.34456588046;9412.952935235417;9415.952920235355;9501.857472137923;20393.49016274605;20572.79427205739;20944.581108377748;21002.474938126365;21008.26471073505;7289.416846652255;7529.510581812165;7602.391808655294;7627.046619172604;7760.650770715331;3932.646061854447;3996.540397854284;4002.4996875390575;4011.338129208648;4031.2139132869397;3428.9142771430916;3471.7743346682387;3476.1483436558206;3515.367233897923;3519.3137338218803;612;617.8115674719164;620.1442010026135;627.1909237084202;629.2260519560965;462.3503976912443;509.40654137929636;580.3326174898816;595.9851003724941;609.8597322615823;7127.287271272831;7892.960535197287;7929.5242285463055;8068.112507624157;8233.423660343718;557.5567423898011;569.33103603266;575.7246018399479;592.9703514824241;608.4919092557764;3781.757352172371;3806.333891568964;3834.827432765532;3858.3247931611854;4021.2962731522452;86796.56601717077;86986;88288;88740.11259887318;92401;75871.62064189644;77546;78332;78922;79594.60202699066;68299.63400731958;70759.58480830457;71397.2860271401;71740.2825971744;72512.2748772516;7511.73708920207;7609.353204977639;7711.190325015793;7774.650140743567;7877.330426913776;43637;44776.55223447854;45093.32360014668;45473.317900232185;45565.86085347893;51544.74227628838;65360.67319663371;65730.34269657434;66000;66527.66736166288;9610.471424071464;9629.422234665943;9877.40735555869;9928.75178120524;10275.7431064224;10579.89420105789;10645.946770266099;10709.678709638723;10916.290441121302;11474.311541307548;9926.305158638826;10161.593536258466;10250.436226007641;10343.37939723619;10629.096526795309;10045.49772511386;10410.323328983592;10473.21450891171;10578.994995475417;10610.575576977144;9949.701508954742;9986.051325124101;10497.950204979441;10538.894611054098;10559.260851740304;7484.877268409782;8437.95781021091;8711.128887111237;8849.778755530906;8986.460812351279;7807.219278072147;7995.12053674107;8213.3429325655;8223.383246256435;8414.242718155396 23:16:01 INFO - PROCESS | 1948 | __end_tp_report 23:16:01 INFO - PROCESS | 1948 | __start_cc_report 23:16:01 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,404 23:16:01 INFO - PROCESS | 1948 | __end_cc_report 23:16:01 INFO - PROCESS | 1948 | __startTimestamp1461046561213__endTimestamp 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | ------- Summary: start ------- 23:16:01 INFO - PROCESS | 1948 | Number of tests: 6 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5824.04 Median:6121.36 stddev:790.61 (12.9%) stddev-sans-first:771.72 23:16:01 INFO - PROCESS | 1948 | Values: 3499.5 3504.2 3511.8 3558.7 3564.7 5716.7 5932.0 5955.3 5982.3 5987.2 5833.4 5920.2 5994.7 5999.5 6070.9 5317.4 5363.4 5374.7 5410.9 5472.1 107.0 4271.3 5317.3 5335.4 5381.6 5338.5 5344.7 5369.3 5397.5 5399.9 5383.9 5391.8 5410.9 5417.6 5450.9 5268.1 5269.4 5301.5 5330.1 5361.7 5679.7 5800.6 5806.4 5846.3 5889.9 6166.7 6224.6 6281.6 6285.9 6309.4 6221.4 6277.4 6310.2 6329.4 6338.8 4925.3 5247.8 5999.4 6037.5 6064.7 5987.3 6250.1 6498.5 6502.1 6529.4 5789.5 6267.9 6271.9 6291.6 6292.4 5236.3 6269.0 6298.1 6303.2 6356.9 6161.1 6206.8 6255.2 6262.7 6265.3 6217.7 6254.4 6270.8 6290.8 6293.7 5014.7 5030.8 5052.9 5056.0 5065.6 4430.0 4455.0 4489.3 4566.1 4593.8 5936.6 5979.9 5994.3 6027.1 6080.8 6266.4 6324.8 6364.1 6389.6 6391.8 5855.9 5923.2 5986.4 6020.2 6035.2 5479.4 5494.2 5524.4 5529.5 5539.9 6523.7 6550.3 6553.7 6582.7 6593.0 6143.5 6215.5 6267.5 6291.2 6330.1 6543.9 6574.0 6583.4 6585.6 6597.9 6266.5 6276.8 6301.6 6313.2 6335.5 6149.3 6172.6 6196.3 6306.8 6309.4 6121.4 6254.2 6265.4 6268.3 6268.9 6228.4 6268.3 6283.2 6285.1 6326.1 6018.0 6097.8 6126.4 6144.7 6174.9 4884.9 6252.3 6272.5 6284.8 6294.0 6061.5 6165.1 6215.9 6223.7 6234.5 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:65685.98 Median:37930.90 stddev:144438.56 (380.8%) stddev-sans-first:144760.82 23:16:01 INFO - PROCESS | 1948 | Values: 498.3 538.2 556.1 567.8 577.5 33552.0 38173.2 38212.0 38273.0 38302.4 37540.6 38309.0 38663.0 39304.8 39376.0 9394.2 9480.1 9513.4 9561.2 9625.4 8398.1 8825.3 9459.9 9637.2 9696.0 9344.9 9600.3 9685.7 9690.9 9751.3 8390.8 9706.5 9712.0 9719.3 9725.0 9445.9 9504.0 9521.4 9625.9 9667.5 37467.8 38100.0 38510.4 38619.8 39073.2 2502.3 2667.9 2993.0 3110.8 3141.8 26241.0 26243.3 26423.9 26582.0 26701.9 2581.2 2613.8 2631.3 2778.1 2824.2 283352.0 327384.0 332249.0 333250.0 335194.3 58577.7 59091.7 59138.0 59169.0 59203.4 66920.3 68050.0 68723.7 68747.0 68836.3 15938.8 16229.4 16414.7 16457.6 16462.8 12161.3 12220.4 12668.7 12696.0 12728.0 18827.3 19171.9 19246.6 19385.7 19456.3 7056.0 7249.9 8535.7 8617.4 8645.6 915.0 976.2 1023.3 1084.4 1105.4 26662.0 28941.4 29546.4 29574.9 30176.7 1090.5 1112.8 1121.7 1156.9 1239.4 14300.4 15354.3 15408.5 15539.5 16001.5 642021.0 643405.0 645040.9 650499.0 662727.0 60151.7 60668.4 60994.7 61021.7 61137.7 1440.2 1468.6 1473.6 1483.0 1484.6 28991.4 29176.9 29219.1 29489.4 29673.0 51919.7 52065.7 52314.0 52489.0 52766.2 570598.0 587449.0 592768.0 593768.0 594888.1 24775.9 25319.0 25634.0 26668.7 27353.3 45203.8 45559.3 45640.5 46509.6 46519.5 37343.0 37472.8 37833.0 38126.0 38130.0 28001.9 37257.4 37295.0 37511.0 38094.0 32275.4 36439.1 36656.0 38028.8 38189.0 24118.2 31576.2 33241.7 33397.7 33616.8 24758.3 25130.5 25233.4 25315.7 25337.2 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:33218.60 Median:388.01 stddev:96014.98 (24745.8%) stddev-sans-first:96252.59 23:16:01 INFO - PROCESS | 1948 | Values: 336.9 378.2 397.8 409.5 412.0 15482.8 18344.3 20311.0 20443.2 20495.6 19083.9 19488.3 19560.2 19936.5 20336.3 5618.3 5677.0 5869.7 5871.4 5951.6 5697.6 5712.3 5841.7 5854.2 5880.9 5641.8 5690.4 5720.2 5790.1 5840.8 5636.1 5754.2 5763.1 5808.3 5833.9 4869.2 5078.7 5212.3 5212.6 5219.2 12663.4 12915.4 15668.8 23176.4 23728.9 1996.1 2125.4 2137.5 2185.2 2220.3 9548.0 9614.8 9662.0 9674.1 9846.2 1653.1 1656.9 1658.9 1739.0 1739.0 113349.2 115844.0 116643.0 119532.4 120125.0 11320.0 11487.7 11569.0 11760.8 12037.2 14341.9 14420.3 14512.1 14693.8 14703.7 8282.4 8450.8 8454.3 8465.3 8476.4 7692.4 8284.9 8358.4 8361.3 8507.4 3982.3 4478.8 4495.4 4544.4 4615.6 1536.5 1973.3 1983.8 1987.8 1990.5 870.4 895.4 960.3 971.8 1008.9 16478.0 18223.9 18438.8 19789.1 19990.0 862.2 940.0 961.9 961.9 974.9 8597.5 10382.3 10411.2 10453.8 10458.3 536680.0 537284.0 557829.0 616478.0 619390.0 114608.0 122047.4 125623.0 126647.0 128470.0 999.6 1007.4 1018.6 1021.9 1028.2 17450.5 17475.0 17632.4 17727.5 18004.5 40481.6 42494.5 49506.0 49748.5 50857.7 59697.0 69883.7 73501.5 73829.0 74027.0 6890.1 7016.5 7385.6 7416.8 8241.0 10705.3 12193.5 12245.0 12508.8 12594.3 5423.1 7567.0 8867.0 10494.5 10704.8 9646.7 10138.3 10820.5 10908.2 11322.2 11049.7 11185.6 11439.9 11463.5 11591.9 7399.1 7406.0 7448.9 7504.7 7534.3 9506.0 10634.6 10968.9 11293.7 11420.5 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5636.83 Median:4872.07 stddev:3147.80 (64.6%) stddev-sans-first:3140.52 23:16:01 INFO - PROCESS | 1948 | Values: 1398.1 1483.7 1786.6 1953.2 1967.1 7998.2 8531.1 8593.9 8823.4 8854.5 8581.5 8592.0 8682.9 8715.5 8722.4 2398.8 2407.9 2426.7 2430.2 2438.0 2140.9 2271.7 2425.4 2437.8 2444.3 2414.0 2429.7 2431.1 2432.9 2460.0 2392.4 2409.4 2425.2 2439.5 2441.8 2400.7 2420.9 2427.9 2428.8 2451.6 7840.2 8514.3 8672.9 8717.9 8799.1 5194.7 5277.7 5278.2 5320.0 5390.0 2802.8 3440.4 3469.5 3482.3 3512.9 991.7 1003.6 1019.3 1022.1 1024.6 9815.5 11185.4 11225.4 11374.8 11426.0 4481.2 4496.8 4611.2 4613.8 4645.7 8848.1 8991.4 8994.4 9005.5 9024.0 5120.5 5170.0 5184.8 5211.5 5230.1 4404.4 4762.1 4841.0 4903.2 4906.1 3832.9 3870.5 3900.8 3942.4 3996.1 1206.0 1216.9 1221.1 1225.5 1236.7 894.6 905.1 918.7 937.6 954.4 5254.0 5788.6 5815.4 5867.7 5990.1 454.5 455.4 459.8 463.5 475.8 3830.8 3936.6 3955.8 3955.9 4010.7 10475.3 10843.4 10862.5 10952.7 10957.7 10750.7 10755.6 10758.1 10876.2 10903.0 7746.8 8134.0 8161.5 8197.1 8445.3 5895.3 5957.5 5959.4 6064.1 6131.5 9842.4 10050.0 10051.4 10156.9 10171.9 8126.6 9750.5 10077.3 10085.8 10108.4 6988.8 7375.9 7601.9 7614.9 7652.0 8246.2 8311.8 8313.8 8392.7 8456.0 7375.0 7511.2 7575.1 7577.9 7628.9 7549.5 7604.6 7614.8 7712.9 7719.6 5549.6 6951.9 6982.0 7025.1 7267.2 4223.3 4273.0 5058.3 5110.0 5237.1 4684.7 5148.6 5153.1 5374.4 5385.3 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:5281.67 Median:6066.92 stddev:1719.87 (28.3%) stddev-sans-first:1683.89 23:16:01 INFO - PROCESS | 1948 | Values: 320.0 321.0 328.0 332.7 333.0 5873.3 6296.7 6299.4 6309.1 6334.5 5402.9 6201.9 6225.5 6236.3 6256.0 4409.0 4523.6 4603.6 4605.0 4682.2 4603.4 4664.7 4675.7 4685.2 4688.5 4097.9 4339.3 4363.9 4463.7 4654.8 4144.2 4165.3 4288.8 4346.2 4624.2 3408.6 3899.8 3901.9 3908.8 3925.7 5346.3 5423.8 5720.7 5825.9 5886.5 4297.8 4376.3 5054.7 5299.0 5302.1 5667.4 6451.7 6513.9 6520.0 6553.8 3602.5 3679.6 4132.6 4315.7 4319.8 5696.8 6060.8 6193.0 6942.4 6977.3 4341.4 4360.6 4362.6 4369.1 4383.9 3988.7 4082.8 4440.7 4482.0 4483.0 4610.7 5165.2 5275.3 5277.7 5299.9 5503.5 6138.0 6217.4 6572.9 6642.0 1239.6 1453.2 1488.0 1542.5 1546.5 922.7 978.7 981.2 990.5 1003.5 4838.6 6040.6 6073.0 6098.4 6113.9 5183.4 6355.3 6672.6 6779.4 6811.5 6012.5 6091.9 6107.1 6108.6 6115.8 2912.7 3547.1 3551.3 3554.7 3575.3 6801.3 6994.8 7009.5 7103.3 7208.1 6884.3 7009.3 7164.3 7188.1 7193.4 6395.5 6961.5 7025.5 7279.8 7288.5 6855.3 6922.8 6924.3 6929.7 6987.7 6407.2 6803.4 6847.5 6851.9 6853.7 6757.2 7198.6 7230.0 7257.4 7311.1 4052.3 4298.4 4381.3 4423.5 4431.6 6878.7 6888.1 6900.1 6914.3 6930.5 6510.4 6632.0 6637.1 6665.5 6684.8 5783.2 6158.9 6573.4 6628.8 6648.8 5091.0 5332.8 6315.5 6528.5 6531.2 5104.2 5228.8 6168.9 6623.6 6629.6 6084.8 6319.5 6422.6 6502.5 6558.7 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:17759.99 Median:65865.17 stddev:25317.09 (38.4%) stddev-sans-first:25355.61 23:16:01 INFO - PROCESS | 1948 | Values: 674.2 726.9 729.6 746.5 752.5 14932.5 15156.8 15227.2 15228.1 15341.5 13977.8 14164.5 14165.7 14462.2 14466.1 709.8 886.4 887.6 911.2 911.4 892.9 897.2 906.1 913.4 915.6 931.2 942.9 958.9 960.6 965.5 895.8 928.8 932.9 952.7 956.4 689.2 690.1 696.0 707.5 707.5 12653.1 15078.1 15136.2 15453.7 15480.0 11841.8 12373.5 13007.5 13197.3 13375.3 10198.5 10343.8 10367.6 10376.8 10708.8 3069.4 3447.7 3520.3 3530.8 3602.2 80034.2 81735.2 82086.2 82827.6 89252.6 9354.2 9363.3 9413.0 9416.0 9501.9 20393.5 20572.8 20944.6 21002.5 21008.3 7289.4 7529.5 7602.4 7627.0 7760.7 3932.6 3996.5 4002.5 4011.3 4031.2 3428.9 3471.8 3476.1 3515.4 3519.3 612.0 617.8 620.1 627.2 629.2 462.4 509.4 580.3 596.0 609.9 7127.3 7893.0 7929.5 8068.1 8233.4 557.6 569.3 575.7 593.0 608.5 3781.8 3806.3 3834.8 3858.3 4021.3 86796.6 86986.0 88288.0 88740.1 92401.0 75871.6 77546.0 78332.0 78922.0 79594.6 68299.6 70759.6 71397.3 71740.3 72512.3 7511.7 7609.4 7711.2 7774.7 7877.3 43637.0 44776.6 45093.3 45473.3 45565.9 51544.7 65360.7 65730.3 66000.0 66527.7 9610.5 9629.4 9877.4 9928.8 10275.7 10579.9 10645.9 10709.7 10916.3 11474.3 9926.3 10161.6 10250.4 10343.4 10629.1 10045.5 10410.3 10473.2 10579.0 10610.6 9949.7 9986.1 10498.0 10538.9 10559.3 7484.9 8438.0 8711.1 8849.8 8986.5 7807.2 7995.1 8213.3 8223.4 8414.2 23:16:01 INFO - PROCESS | 1948 | -------- Summary: end -------- 23:16:01 INFO - PROCESS | 1948 | 23:16:01 INFO - TEST-INFO | 1948: exit 0 23:16:01 INFO - TEST-OK | dromaeo_css | took 1111272ms 23:16:01 INFO - TEST-START | kraken 23:16:01 INFO - operating with platform_type : mac_ 23:16:01 INFO - Initialising browser for kraken test... 23:16:01 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpdFJMwO/profile http://localhost:52301/getInfo.html) 23:16:04 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 23:16:04 INFO - PROCESS | 1953 | colorDepth:24 23:16:04 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 23:16:04 INFO - PROCESS | 1953 | __metrics 23:16:04 ERROR - PROCESS | 1953 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:16:05 INFO - TEST-INFO | 1953: exit 0 23:16:05 INFO - Browser initialized. 23:16:05 INFO - Running cycle 1/1 for kraken test... 23:16:05 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpdFJMwO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:17 INFO - PROCESS | 1956 | RSS: Main: 247672832 23:16:17 INFO - PROCESS | 1956 | 23:16:20 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52301/tests/kraken/driver.html?testName=audio-beat-detection) 23:16:21 INFO - PROCESS | 1956 | RSS: Main: 259948544 23:16:21 INFO - PROCESS | 1956 | 23:16:23 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52301/tests/kraken/driver.html?testName=audio-dft) 23:16:24 INFO - PROCESS | 1956 | RSS: Main: 268865536 23:16:24 INFO - PROCESS | 1956 | 23:16:27 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52301/tests/kraken/driver.html?testName=audio-fft) 23:16:28 INFO - PROCESS | 1956 | RSS: Main: 272113664 23:16:28 INFO - PROCESS | 1956 | 23:16:30 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52301/tests/kraken/driver.html?testName=audio-oscillator) 23:16:31 INFO - PROCESS | 1956 | RSS: Main: 274202624 23:16:31 INFO - PROCESS | 1956 | 23:16:33 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52301/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:16:33 INFO - PROCESS | 1956 | RSS: Main: 274690048 23:16:33 INFO - PROCESS | 1956 | 23:16:36 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52301/tests/kraken/driver.html?testName=imaging-darkroom) 23:16:37 INFO - PROCESS | 1956 | RSS: Main: 274755584 23:16:37 INFO - PROCESS | 1956 | 23:16:40 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52301/tests/kraken/driver.html?testName=imaging-desaturate) 23:16:40 INFO - PROCESS | 1956 | RSS: Main: 274903040 23:16:40 INFO - PROCESS | 1956 | 23:16:43 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52301/tests/kraken/driver.html?testName=json-parse-financial) 23:16:44 INFO - PROCESS | 1956 | RSS: Main: 275333120 23:16:44 INFO - PROCESS | 1956 | 23:16:46 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52301/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:16:46 INFO - PROCESS | 1956 | RSS: Main: 274845696 23:16:46 INFO - PROCESS | 1956 | 23:16:48 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:16:49 INFO - PROCESS | 1956 | RSS: Main: 275181568 23:16:49 INFO - PROCESS | 1956 | 23:16:51 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:16:52 INFO - PROCESS | 1956 | RSS: Main: 275456000 23:16:52 INFO - PROCESS | 1956 | 23:16:55 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:16:56 INFO - PROCESS | 1956 | RSS: Main: 275783680 23:16:56 INFO - PROCESS | 1956 | 23:16:59 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:16:59 INFO - PROCESS | 1956 | RSS: Main: 275705856 23:16:59 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52301/tests/kraken/driver.html?testName=ai-astar) 23:17:01 INFO - PROCESS | 1956 | RSS: Main: 276844544 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | __start_tp_report 23:17:01 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 23:17:01 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 23:17:01 INFO - PROCESS | 1956 | |i|pagename|runs| 23:17:01 INFO - PROCESS | 1956 | |0;ai-astar;167;158;155;116;126;128;116;115;116;115 23:17:01 INFO - PROCESS | 1956 | |1;audio-beat-detection;169;123;148;170;122;142;140;123;168;122 23:17:01 INFO - PROCESS | 1956 | |2;audio-dft;193;200;210;264;200;200;200;200;202;196 23:17:01 INFO - PROCESS | 1956 | |3;audio-fft;72;75;74;148;82;80;69;83;126;61 23:17:01 INFO - PROCESS | 1956 | |4;audio-oscillator;80;83;134;78;80;81;117;80;79;118 23:17:01 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;92;89;92;91;93;88;92;96;132;89 23:17:01 INFO - PROCESS | 1956 | |6;imaging-darkroom;95;105;98;98;95;96;98;98;101;148 23:17:01 INFO - PROCESS | 1956 | |7;imaging-desaturate;107;128;91;119;119;150;80;153;131;138 23:17:01 INFO - PROCESS | 1956 | |8;json-parse-financial;63;62;61;60;61;60;60;80;60;67 23:17:01 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;67;65;68;99;108;93;72;68;68;61 23:17:01 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;101;99;74;75;106;92;107;104;108;86 23:17:01 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;167;155;155;167;154;154;160;136;175;164 23:17:01 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;169;207;223;177;171;183;170;214;227;187 23:17:01 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;69;66;72;71;101;94;90;96;93;70 23:17:01 INFO - PROCESS | 1956 | __end_tp_report 23:17:01 INFO - PROCESS | 1956 | __start_cc_report 23:17:01 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1055 23:17:01 INFO - PROCESS | 1956 | __end_cc_report 23:17:01 INFO - PROCESS | 1956 | __startTimestamp1461046621836__endTimestamp 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | ------- Summary: start ------- 23:17:01 INFO - PROCESS | 1956 | Number of tests: 14 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:131.20 Median:127.00 stddev:20.61 (16.2%) stddev-sans-first:17.31 23:17:01 INFO - PROCESS | 1956 | Values: 167.0 158.0 155.0 116.0 126.0 128.0 116.0 115.0 116.0 115.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:145.00 stddev:20.40 (14.1%) stddev-sans-first:19.29 23:17:01 INFO - PROCESS | 1956 | Values: 169.0 123.0 148.0 170.0 122.0 142.0 140.0 123.0 168.0 122.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:206.50 Median:200.00 stddev:20.66 (10.3%) stddev-sans-first:21.33 23:17:01 INFO - PROCESS | 1956 | Values: 193.0 200.0 210.0 264.0 200.0 200.0 200.0 200.0 202.0 196.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:87.00 Median:74.50 stddev:27.63 (37.1%) stddev-sans-first:28.77 23:17:01 INFO - PROCESS | 1956 | Values: 72.0 75.0 74.0 148.0 82.0 80.0 69.0 83.0 126.0 61.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:80.00 stddev:21.22 (26.5%) stddev-sans-first:21.98 23:17:01 INFO - PROCESS | 1956 | Values: 80.0 83.0 134.0 78.0 80.0 81.0 117.0 80.0 79.0 118.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:92.00 stddev:13.07 (14.2%) stddev-sans-first:13.80 23:17:01 INFO - PROCESS | 1956 | Values: 92.0 89.0 92.0 91.0 93.0 88.0 92.0 96.0 132.0 89.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:97.00 stddev:16.02 (16.5%) stddev-sans-first:16.71 23:17:01 INFO - PROCESS | 1956 | Values: 95.0 105.0 98.0 98.0 95.0 96.0 98.0 98.0 101.0 148.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:121.60 Median:144.00 stddev:23.77 (16.5%) stddev-sans-first:24.62 23:17:01 INFO - PROCESS | 1956 | Values: 107.0 128.0 91.0 119.0 119.0 150.0 80.0 153.0 131.0 138.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:61.50 stddev:6.22 (10.1%) stddev-sans-first:6.60 23:17:01 INFO - PROCESS | 1956 | Values: 63.0 62.0 61.0 60.0 61.0 60.0 60.0 80.0 60.0 67.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:76.90 Median:68.00 stddev:16.56 (24.4%) stddev-sans-first:17.18 23:17:01 INFO - PROCESS | 1956 | Values: 67.0 65.0 68.0 99.0 108.0 93.0 72.0 68.0 68.0 61.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:74.50 stddev:12.90 (17.3%) stddev-sans-first:13.51 23:17:01 INFO - PROCESS | 1956 | Values: 101.0 99.0 74.0 75.0 106.0 92.0 107.0 104.0 108.0 86.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:162.00 stddev:10.65 (6.6%) stddev-sans-first:10.86 23:17:01 INFO - PROCESS | 1956 | Values: 167.0 155.0 155.0 167.0 154.0 154.0 160.0 136.0 175.0 164.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:197.00 stddev:22.79 (11.6%) stddev-sans-first:22.48 23:17:01 INFO - PROCESS | 1956 | Values: 169.0 207.0 223.0 177.0 171.0 183.0 170.0 214.0 227.0 187.0 23:17:01 INFO - PROCESS | 1956 | 23:17:01 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.20 Median:81.00 stddev:13.64 (16.8%) stddev-sans-first:13.61 23:17:01 INFO - PROCESS | 1956 | Values: 69.0 66.0 72.0 71.0 101.0 94.0 90.0 96.0 93.0 70.0 23:17:01 INFO - PROCESS | 1956 | -------- Summary: end -------- 23:17:01 INFO - PROCESS | 1956 | 23:17:02 INFO - TEST-INFO | 1956: exit 0 23:17:02 INFO - TEST-OK | kraken | took 60971ms 23:17:02 INFO - SUITE-END | took 1172s 23:17:03 INFO - Completed test suite (00:19:32) 23:17:03 INFO - javascript benchmark 23:17:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3499.4925735768315, 3504.1590018395586, 3511.8068506232157, 3558.6797188253054, 3564.679178873901, 5716.657000579969, 5931.970340148293, 5955.315138759044, 5982.312034116078, 5987.221661184046, 5833.3874943130995, 5920.171176035358, 5994.700264986755, 5999.460048595625, 6070.908936365947, 5317.361916570007, 5363.436839131893, 5374.731263436832, 5410.8917821643545, 5472.1244600863865, 107.01072093216143, 4271.273883439822, 5317.335333083364, 5335.439778823226, 5381.569474442035, 5338.546223571001, 5344.732763361836, 5369.301990741179, 5397.541208997221, 5399.919001214985, 5383.919241211385, 5391.78432862685, 5410.891782164374, 5417.59368047394, 5450.890982180374, 5268.0517506848755, 5269.420363759983, 5301.469853014706, 5330.093884039723, 5361.705106219157, 5679.716014199274, 5800.564957628195, 5806.361300256968, 5846.298444186682, 5889.85275368115, 6166.660833654148, 6224.564280500367, 6281.560290779647, 6285.937140628581, 6309.400606942333, 6221.4089661482085, 6277.4350308472, 6310.211223597051, 6329.430351268408, 6338.809835704937, 4925.261210818406, 5247.845473995753, 5999.430054144849, 6037.54718396122, 6064.666443345614, 5987.311459182162, 6250.0937364082865, 6498.480121590262, 6502.089707440962, 6529.444997175198, 5789.507891829157, 6267.874642507124, 6271.905921411182, 6291.591046581958, 6292.402221788922, 5236.29310043147, 6269.0, 6298.0552917062805, 6303.212098487689, 6356.872862542723, 6161.137440758297, 6206.782762603287, 6255.186825712628, 6262.749490020439, 6265.27949285838, 6217.6891155442045, 6254.3745625437095, 6270.7805226816845, 6290.779822706275, 6293.685315734195, 5014.6740461870595, 5030.798768049311, 5052.873678158076, 5056.0, 5065.594752419797, 4429.9778501107285, 4454.977725111418, 4489.326601009875, 4566.086782643484, 4593.816247350069, 5936.643801371932, 5979.850503737442, 5994.340622531518, 6027.126066720301, 6080.847978800565, 6266.404691554295, 6324.841878952971, 6364.1408409865, 6389.648569328639, 6391.776287829996, 5855.912161317584, 5923.229980102559, 5986.401359864066, 6020.217371741734, 6035.2154219952, 5479.397266300703, 5494.230807686927, 5524.447555244444, 5529.529989950819, 5539.889202215933, 6523.739050437929, 6550.2794692583775, 6553.705083271264, 6582.703778329987, 6592.967035164794, 6143.539234557336, 6215.533834962305, 6267.4986001120715, 6291.213598300213, 6330.113784070142, 6543.934560654523, 6574.0, 6583.374579414948, 6585.5719378240265, 6597.901031484435, 6266.467350275278, 6276.811695649138, 6301.558890877594, 6313.2424109107205, 6335.4931605472375, 6149.2620885494025, 6172.629642221481, 6196.287426945959, 6306.84232894163, 6309.400606942333, 6121.3572574879, 6254.155688982111, 6265.404786545453, 6268.341824108586, 6268.937310626835, 6228.377162283917, 6268.279147897868, 6283.214598175228, 6285.057241413824, 6326.145970293951, 6018.037114061729, 6097.766665674493, 6126.417990290915, 6144.740328232612, 6174.9073763892675, 4884.852059765881, 6252.280987686474, 6272.4982001440685, 6284.811455656338, 6293.96853015732, 6061.454469097803, 6165.136880836776, 6215.912215362384, 6223.657698826608, 6234.501239900706], "value": 5776.1414624716435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2824732385362, 538.1980848535682, 556.0907915558064, 567.821136342052, 577.5495113811228, 33552.0, 38173.23653526928, 38212.0, 38273.04317392067, 38302.425463618034, 37540.62459375405, 38309.0, 38663.0, 39304.80347598258, 39376.0, 9394.154526092667, 9480.146786789188, 9513.429194248332, 9561.187299079587, 9625.37435066722, 8398.118197589256, 8825.33809964252, 9459.858102128474, 9637.18083962864, 9695.951520242388, 9344.906550934506, 9600.32797704161, 9685.709428717115, 9690.854637180448, 9751.317407781458, 8390.790230244262, 9706.466144362093, 9711.951440242754, 9719.271054670928, 9724.951375243149, 9445.858312125254, 9503.952480237624, 9521.381110227812, 9625.90374096257, 9667.468289244089, 37467.81266093679, 38100.0, 38510.42234366487, 38619.806900965596, 39073.218535629414, 2502.3243724194367, 2667.9328268692484, 2992.9850350748325, 3110.7977981431136, 3141.8114913105287, 26240.9503619855, 26243.343916402053, 26423.867880660666, 26582.0, 26701.866490667617, 2581.238534632288, 2613.7647611714847, 2631.302704783234, 2778.0693467688347, 2824.209221418006, 283352.0, 327384.0, 332249.0, 333250.00124998146, 335194.32402838074, 58577.70711146417, 59091.70454147702, 59138.0, 59169.0, 59203.407965920655, 66920.33079669141, 68050.0, 68723.65638171777, 68746.96879546811, 68836.311636883, 15938.760918586231, 16229.431969880998, 16414.67170656604, 16457.588560286087, 16462.75305870413, 12161.331126787934, 12220.388980551115, 12668.683282918, 12696.0, 12727.981761459061, 18827.34104306343, 19171.904140479488, 19246.615067698567, 19385.739926904705, 19456.319028833925, 7056.012158297843, 7249.927500724925, 8535.743927682179, 8617.35369847264, 8645.610947507377, 914.9660883201939, 976.2190247801731, 1023.2581378500528, 1084.3710647824244, 1105.42517890698, 26662.0, 28941.42117157645, 29546.40907181844, 29574.852125739235, 30176.69823301739, 1090.5419723716059, 1112.799696054718, 1121.7195701074731, 1156.8611766587894, 1239.3555351217317, 14300.356483958247, 15354.309056092503, 15408.481377067685, 15539.45611903578, 16001.519954401387, 642021.0, 643405.0, 645040.8739781543, 650499.0, 662727.0, 60151.699241504386, 60668.39331606627, 60994.69502652458, 61021.69489152526, 61137.69431152905, 1440.229477229677, 1468.5814542855449, 1473.6463248820205, 1482.9925850370894, 1484.606579256498, 28991.42017159603, 29176.854115730133, 29219.123426297247, 29489.410211795213, 29673.0, 51919.74040129775, 52065.7396713014, 52314.0, 52489.0, 52766.20850687166, 570598.0, 587449.0, 592768.0, 593768.0, 594888.0511195005, 24775.876120619283, 25319.0, 25634.0, 26668.733312667402, 27353.316167095185, 45203.77398112988, 45559.31661025021, 45640.54359456363, 46509.6047118587, 46519.534804652874, 37343.0, 37472.81263593664, 37833.0, 38126.0, 38130.0, 28001.859990699915, 37257.44113838349, 37295.0, 37511.0, 38094.0, 32275.35449290954, 36439.08902277464, 36656.0, 38028.80985595165, 38189.0, 24118.155864544657, 31576.210594734395, 33241.667583323855, 33397.666023340425, 33616.83191584027, 24758.25725228246, 25130.497390052464, 25233.36916577027, 25315.74684253134, 25337.23988280281], "value": 19421.47173633217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8652538984404, 378.21709062241155, 397.79314756326704, 409.52904160215746, 411.96086371794667, 15482.84517154828, 18344.266229350826, 20310.984450777458, 20443.18227270911, 20495.590088198245, 19083.854968701853, 19488.317908873192, 19560.217591296365, 19936.50158746032, 20336.288229911956, 5618.269624948751, 5676.971615141919, 5869.735861886225, 5871.412858714116, 5951.553633477485, 5697.572682048843, 5712.314522257335, 5841.678707671076, 5854.20968169296, 5880.882632299849, 5641.774329026834, 5690.374058853544, 5720.1991721159075, 5790.10253410722, 5840.766369345221, 5636.098224284095, 5754.165645981309, 5763.077907534816, 5808.303003639569, 5833.854153646172, 4869.196582563873, 5078.720670363128, 5212.322398088262, 5212.58299336056, 5219.243209734567, 12663.366831658472, 12915.354232288348, 15668.764968525482, 23176.420589485228, 23728.932198050956, 1996.1236437748614, 2125.4473836802545, 2137.4549489880137, 2185.1805572910157, 2220.2562141682556, 9548.045195480467, 9614.807703845954, 9661.951690241574, 9674.129328360412, 9846.212303015813, 1653.1403670091606, 1656.8508834204858, 1658.8921720088276, 1738.97391539127, 1738.9913050434793, 113349.23182297668, 115844.0, 116643.0, 119532.40233798862, 120125.0, 11320.0, 11487.712807179887, 11569.0, 11760.824247514238, 12037.217580857216, 14341.92829035848, 14420.278986050866, 14512.056716313406, 14693.77959330611, 14703.705925881422, 8282.420230583923, 8450.788730281793, 8454.28138608213, 8465.28045116172, 8476.449030812977, 7692.384609231248, 8284.917150828534, 8358.414910956297, 8361.289290410383, 8507.404481686222, 3982.287616325008, 4478.798454069574, 4495.415595972503, 4544.386507821468, 4615.561521655437, 1536.4852774320727, 1973.289615738333, 1983.7700625611924, 1987.8012198780007, 1990.5222746540726, 870.4385671242013, 895.4135041547796, 960.2558017536326, 971.7959228562081, 1008.8789345278614, 16478.0, 18223.908880455514, 18438.81561184371, 19789.109490072693, 19990.000499974944, 862.1550880137501, 939.9718008459757, 961.8605302231277, 961.8701475300885, 974.8537719342155, 8597.527136007455, 10382.3251488653, 10411.167106631603, 10453.84319235212, 10458.320209186379, 536680.0, 537284.0, 557829.0, 616478.0, 619390.0, 114608.0, 122047.38976305061, 125623.0, 126647.0, 128470.0, 999.645125980281, 1007.4459047523833, 1018.5620183321244, 1021.8620486234263, 1028.2493779540978, 17450.47648570545, 17475.0, 17632.382866599608, 17727.468175954742, 18004.54988625295, 40481.5695896986, 42494.51269205563, 49506.0, 49748.50251497584, 50857.74571127121, 59697.0, 69883.65058174677, 73501.52996940138, 73829.0, 74027.0, 6890.10428644273, 7016.5088443808445, 7385.559815835861, 7416.7774966751085, 8240.958795205986, 10705.250632455653, 12193.451294691582, 12245.020398367931, 12508.812367814671, 12594.3073130975, 5423.132298832168, 7567.0, 8867.0, 10494.530765692962, 10704.839427408751, 9646.697695811257, 10138.290319677551, 10820.458977051274, 10908.181886358396, 11322.150838687296, 11049.674039115358, 11185.608503702331, 11439.885601144215, 11463.484143213742, 11591.88408115908, 7399.1299836373155, 7406.037215161994, 7448.925510744823, 7504.699812007459, 7534.321911028033, 9506.0, 10634.627788027381, 10968.890311096788, 11293.661190164308, 11420.543178272776], "value": 9069.5770601814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.1331574423855, 1483.7032593481304, 1786.6158775863187, 1953.2089503750976, 1967.144292232878, 7998.200179981999, 8531.146885311482, 8593.871091933626, 8823.426477278972, 8854.513001784908, 8581.52801595912, 8591.957040214791, 8682.86975695365, 8715.477071375706, 8722.433041852275, 2398.8080953523718, 2407.879606019701, 2426.696662917135, 2430.2101816909485, 2438.0247900839718, 2140.86084404514, 2271.7273927128767, 2425.3815277104313, 2437.792787613055, 2444.315591634336, 2413.987930060347, 2429.720582133051, 2431.0761910473993, 2432.8540287582714, 2459.950800983979, 2392.4497365605835, 2409.4458274596855, 2425.151197081025, 2439.4755127647463, 2441.829071964963, 2400.663907053014, 2420.92737217882, 2427.927162185137, 2428.8178386621075, 2451.5587194305017, 7840.176781437924, 8514.318854491627, 8672.913270867337, 8717.912820871774, 8799.120087991214, 5194.71429071401, 5277.683338999634, 5278.155495120801, 5319.96260729158, 5390.0, 2802.7897907657007, 3440.415129428003, 3469.4795780632853, 3482.320947415255, 3512.9297414051575, 991.657878032085, 1003.6386900715737, 1019.3119644240109, 1022.0954455307033, 1024.5799222318888, 9815.460149691835, 11185.384803835786, 11225.38260395678, 11374.772504549863, 11426.028787553068, 4481.170983368087, 4496.797644106023, 4611.224678498802, 4613.815447382134, 4645.674802763841, 8848.11518848123, 8991.415557988841, 8994.415363001384, 9005.504697241584, 9023.954880225558, 5120.5391514763105, 5169.991851588979, 5184.818531351385, 5211.5049070338255, 5230.08473517133, 4404.4494438195225, 4762.095201911626, 4840.951590484121, 4903.190973489334, 4906.1414252505665, 3832.9425058624142, 3870.53553573573, 3900.8439662413184, 3942.3692209246383, 3996.1208534122447, 1205.9879401206051, 1216.8904798568171, 1221.071985291185, 1225.5342969671467, 1236.746466974268, 894.5750768384966, 905.1446383167843, 918.6600957645713, 937.6343226141673, 954.3701157236329, 5253.973730131402, 5788.565857560699, 5815.389384114607, 5867.70661466925, 5990.131430942489, 454.4864303337274, 455.4489068227482, 459.7861994172726, 463.5179413410016, 475.76449657419107, 3830.7893065881085, 3936.626020528045, 3955.7626542407547, 3955.920881582352, 4010.6991975601363, 10475.319104258198, 10843.403612801214, 10862.45687715611, 10952.671419857417, 10957.671269861918, 10750.731231719114, 10755.56977720898, 10758.139348852073, 10876.238663293494, 10903.01872831449, 7746.767596972099, 8133.983252093488, 8161.469504482189, 8197.05733840604, 8445.282151017109, 5895.29256489224, 5957.523398128053, 5959.359898597818, 6064.090386442069, 6131.509479241739, 9842.409455432697, 10050.045245701647, 10051.447170405698, 10156.949215253877, 10171.898281017391, 8126.634301456331, 9750.512474376112, 10077.294589378673, 10085.798284034425, 10108.444035578113, 6988.755393561404, 7375.926240737524, 7601.923980760122, 7614.923850761643, 7652.0, 8246.175382461706, 8311.750647480585, 8313.792155196168, 8392.748217553482, 8455.95772021136, 7375.0, 7511.211322811245, 7575.053118360205, 7577.886331705139, 7628.923710762821, 7549.547027178387, 7604.581748003691, 7614.771556853303, 7712.884306735516, 7719.575423351769, 5549.639273447375, 6951.930480695129, 6981.965090174516, 7025.051618031501, 7267.164276108315, 4223.345381465878, 4273.0, 5058.317127187783, 5110.0, 5237.083510385591, 4684.672072954997, 5148.613853961043, 5153.149730294609, 5374.435684253253, 5385.27298814655], "value": 4473.896353196353, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.0, 320.99197520061995, 327.96884295991873, 332.7055555833087, 333.03918196004537, 5873.2658417697785, 6296.670232014161, 6299.433051025407, 6309.116723658692, 6334.493240540757, 5402.864928376793, 6201.945669236229, 6225.501959843213, 6236.251649802019, 6255.968720156404, 4408.999999999992, 4523.592876641102, 4603.585677289043, 4604.953950460486, 4682.180618391785, 4603.4015577974815, 4664.693885712006, 4675.672702910798, 4685.226937555316, 4688.484266730657, 4097.938530922023, 4339.305711086226, 4363.934540981872, 4463.7055253976305, 4654.790534425942, 4144.233316836365, 4165.271077561415, 4288.849890253841, 4346.15250026245, 4624.213883639773, 3408.642092580293, 3899.844006239747, 3901.9219615607813, 3908.7850168240734, 3925.685945124383, 5346.331708536433, 5423.752536916531, 5720.742566584514, 5825.912611310834, 5886.499647529965, 4297.849575264882, 4376.321670141101, 5054.671446356012, 5299.019681358923, 5302.117748199411, 5667.433256674341, 6451.6774161292215, 6513.869722605568, 6520.0, 6553.836154096138, 3602.4596310553625, 3679.638533742506, 4132.648724858391, 4315.697901146921, 4319.762413067311, 5696.832149409361, 6060.848478788022, 6193.0, 6942.444604431583, 6977.33715297046, 4341.435613370241, 4360.585744354281, 4362.6475792504425, 4369.143114176462, 4383.904023994001, 3988.6809055275507, 4082.755034697928, 4440.713032788117, 4481.955180448153, 4483.0, 4610.700304480265, 5165.1735722285, 5275.340582427197, 5277.656952298165, 5299.947000530023, 5503.504684578327, 6138.048602466625, 6217.440430361289, 6572.901406478906, 6641.966790166019, 1239.5723475400973, 1453.2225259486124, 1487.9776803347959, 1542.4524293875513, 1546.5283088658068, 922.6908985489802, 978.706388083572, 981.2444417798256, 990.4601991914424, 1003.5283416794096, 4838.564529192389, 6040.577159598874, 6073.0, 6098.420650038239, 6113.908291375633, 5183.352080989876, 6355.269144048493, 6672.599644021374, 6779.389854913082, 6811.523193376416, 6012.518998480111, 6091.908621370683, 6107.053406721965, 6108.601806699169, 6115.785947491905, 2912.650481942138, 3547.084574329833, 3551.2776303492788, 3554.697850682721, 3575.3385623659706, 6801.319868013258, 6994.825129371807, 7009.509334346547, 7103.254158313406, 7208.098987626547, 6884.277150899084, 7009.334113259232, 7164.283571642898, 7188.148000099912, 7193.388561972186, 6395.514444627689, 6961.547499412522, 7025.508214424941, 7279.781606551812, 7288.453365997571, 6855.348741869514, 6922.757703480354, 6924.3421874921805, 6929.688164032731, 6987.650617469005, 6407.167068281094, 6803.387695107464, 6847.520673552804, 6851.8972215415815, 6853.657317134223, 6757.155355580552, 7198.6400679964745, 7229.963850180716, 7257.367092404378, 7311.122665280201, 4052.311107111739, 4298.398224248545, 4381.283604165438, 4423.491298500585, 4431.601155895984, 6878.69045892926, 6888.070110535214, 6900.102986611709, 6914.27400123, 6930.549514281555, 6510.44661203803, 6631.966840165768, 6637.070810086689, 6665.533412661067, 6684.799456016327, 5783.248177736868, 6158.907616385668, 6573.408393244631, 6628.767993120217, 6648.83377915556, 5090.974545127251, 5332.760025798926, 6315.4947604192475, 6528.510361722795, 6531.224229702262, 5104.2088476286235, 5228.764705588182, 6168.876622467616, 6623.635700036544, 6629.602223866584, 6084.847878803065, 6319.514913995115, 6422.632609825364, 6502.512311576746, 6558.737650494118], "value": 4720.077045501553, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.20107173, 726.8836986082222, 729.584137041886, 746.4588173574159, 752.4845480845621, 14932.477363292273, 15156.848431515682, 15227.238638068107, 15228.08631482112, 15341.53975380739, 13977.79033314501, 14164.50424235152, 14165.71668566628, 14462.204578748164, 14466.059706119095, 709.7515869445705, 886.3839631456125, 887.5784002598779, 911.2300106410073, 911.3666002128523, 892.8973168085656, 897.2239013253554, 906.1074841281333, 913.3880300198849, 915.5834095486571, 931.2410385535767, 942.8962814090445, 958.9232861371073, 960.6493629825134, 965.4689920543708, 895.8253140637578, 928.8420968435382, 932.8787257656495, 952.6522819170988, 956.421365074127, 689.2452764223168, 690.0649619765246, 695.9860802783967, 707.4693979515364, 707.4835370179746, 12653.051021173449, 15078.095314281069, 15136.243187840564, 15453.690926181525, 15480.0, 11841.763164736743, 12373.505059797597, 13007.479700811957, 13197.27414992175, 13375.26436046017, 10198.490075496196, 10343.844842327371, 10367.585296588128, 10376.844347334796, 10708.839367409495, 3069.3554353585773, 3447.7241820654285, 3520.260745243502, 3530.770499917523, 3602.1534939289245, 80034.19965800326, 81735.18264817336, 82086.17913820905, 82827.5858620709, 89252.5537372309, 9354.158125768714, 9363.34456588046, 9412.952935235417, 9415.952920235355, 9501.857472137923, 20393.49016274605, 20572.79427205739, 20944.581108377748, 21002.474938126365, 21008.26471073505, 7289.416846652255, 7529.510581812165, 7602.391808655294, 7627.046619172604, 7760.650770715331, 3932.646061854447, 3996.540397854284, 4002.4996875390575, 4011.338129208648, 4031.2139132869397, 3428.9142771430916, 3471.7743346682387, 3476.1483436558206, 3515.367233897923, 3519.3137338218803, 612.0, 617.8115674719164, 620.1442010026135, 627.1909237084202, 629.2260519560965, 462.3503976912443, 509.40654137929636, 580.3326174898816, 595.9851003724941, 609.8597322615823, 7127.287271272831, 7892.960535197287, 7929.5242285463055, 8068.112507624157, 8233.423660343718, 557.5567423898011, 569.33103603266, 575.7246018399479, 592.9703514824241, 608.4919092557764, 3781.757352172371, 3806.333891568964, 3834.827432765532, 3858.3247931611854, 4021.2962731522452, 86796.56601717077, 86986.0, 88288.0, 88740.11259887318, 92401.0, 75871.62064189644, 77546.0, 78332.0, 78922.0, 79594.60202699066, 68299.63400731958, 70759.58480830457, 71397.2860271401, 71740.2825971744, 72512.2748772516, 7511.73708920207, 7609.353204977639, 7711.190325015793, 7774.650140743567, 7877.330426913776, 43637.0, 44776.55223447854, 45093.32360014668, 45473.317900232185, 45565.86085347893, 51544.74227628838, 65360.67319663371, 65730.34269657434, 66000.0, 66527.66736166288, 9610.471424071464, 9629.422234665943, 9877.40735555869, 9928.75178120524, 10275.7431064224, 10579.89420105789, 10645.946770266099, 10709.678709638723, 10916.290441121302, 11474.311541307548, 9926.305158638826, 10161.593536258466, 10250.436226007641, 10343.37939723619, 10629.096526795309, 10045.49772511386, 10410.323328983592, 10473.21450891171, 10578.994995475417, 10610.575576977144, 9949.701508954742, 9986.051325124101, 10497.950204979441, 10538.894611054098, 10559.260851740304, 7484.877268409782, 8437.95781021091, 8711.128887111237, 8849.778755530906, 8986.460812351279, 7807.219278072147, 7995.12053674107, 8213.3429325655, 8223.383246256435, 8414.242718155396], "value": 6669.235148385293, "unit": "score"}], "name": "dromaeo_css", "value": 7233.9622890480105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 158.0, 155.0, 116.0, 126.0, 128.0, 116.0, 115.0, 116.0, 115.0], "value": 131.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 123.0, 148.0, 170.0, 122.0, 142.0, 140.0, 123.0, 168.0, 122.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 200.0, 210.0, 264.0, 200.0, 200.0, 200.0, 200.0, 202.0, 196.0], "value": 206.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 74.0, 148.0, 82.0, 80.0, 69.0, 83.0, 126.0, 61.0], "value": 87.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 134.0, 78.0, 80.0, 81.0, 117.0, 80.0, 79.0, 118.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 91.0, 93.0, 88.0, 92.0, 96.0, 132.0, 89.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 105.0, 98.0, 98.0, 95.0, 96.0, 98.0, 98.0, 101.0, 148.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 128.0, 91.0, 119.0, 119.0, 150.0, 80.0, 153.0, 131.0, 138.0], "value": 121.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 60.0, 61.0, 60.0, 60.0, 80.0, 60.0, 67.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 99.0, 108.0, 93.0, 72.0, 68.0, 68.0, 61.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 99.0, 74.0, 75.0, 106.0, 92.0, 107.0, 104.0, 108.0, 86.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 155.0, 155.0, 167.0, 154.0, 154.0, 160.0, 136.0, 175.0, 164.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 207.0, 223.0, 177.0, 171.0, 183.0, 170.0, 214.0, 227.0, 187.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 72.0, 71.0, 101.0, 94.0, 90.0, 96.0, 93.0, 70.0], "value": 82.2, "unit": "score"}], "name": "kraken", "value": 1649.8000000000002, "alertThreshold": 2.0}]} 23:17:03 INFO - Return code: 0 23:17:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:17:03 ERROR - # TBPL SUCCESS # 23:17:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:17:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:17:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:17:03 INFO - 2016-04-18 23:17:03.132 screenresolution[1961:19371] starting screenresolution argv=screenresolution get 23:17:03 INFO - 2016-04-18 23:17:03.135 screenresolution[1961:19371] Display 0: 1600x1200x32@60 23:17:03 INFO - 2016-04-18 23:17:03.140 screenresolution[1962:19374] starting screenresolution argv=screenresolution list 23:17:03 INFO - Available Modes on Display 0 23:17:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:17:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:17:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:17:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:17:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:17:03 INFO - Intel Iris: 23:17:03 INFO - Chipset Model: Intel Iris 23:17:03 INFO - Type: GPU 23:17:03 INFO - Bus: Built-In 23:17:03 INFO - VRAM (Dynamic, Max): 1536 MB 23:17:03 INFO - Vendor: Intel (0x8086) 23:17:03 INFO - Device ID: 0x0a2e 23:17:03 INFO - Revision ID: 0x0009 23:17:03 INFO - Displays: 23:17:03 INFO - Display: 23:17:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:17:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:17:03 INFO - Display Serial Number: 1600x1200 60 23:17:03 INFO - Main Display: Yes 23:17:03 INFO - Mirror: Off 23:17:03 INFO - Online: Yes 23:17:03 INFO - Rotation: Supported 23:17:03 INFO - Return code: 0 23:17:03 INFO - Running post-action listener: _resource_record_post_action 23:17:03 INFO - Running post-run listener: _resource_record_post_run 23:17:04 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 315392; Write bytes: 695499776; Read time: 5; Write time: 1915 23:17:04 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 381485568; Write bytes: 376313856; Read time: 29028; Write time: 710 23:17:04 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 315392; Write bytes: 297387008; Read time: 5; Write time: 1158 23:17:04 INFO - Running post-run listener: _upload_blobber_files 23:17:04 INFO - Blob upload gear active. 23:17:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:17:04 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 23:17:04 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'] 23:17:04 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 23:17:04 INFO - (blobuploader) - INFO - Open directory for files ... 23:17:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:05 INFO - (blobuploader) - INFO - Done attempting. 23:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:06 INFO - (blobuploader) - INFO - Done attempting. 23:17:06 INFO - (blobuploader) - INFO - Iteration through files over. 23:17:06 INFO - Return code: 0 23:17:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:17:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:17:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9424fc265a1fcd08be432cb23c17b3c591e181b94bc10dd57dd21869761948dd4e0687b2f513f2afad05f857c9993acf0e055c02a41505797a141c256492bb2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44fbbe627cd25c32831b68de9d28c2212b88b1f8b2d2da698d9a581cfec8bf8ef93900807575d5fd8cd57da8acac669ccafbce1db22433f8970cfb2e8b62fa4f"} 23:17:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:17:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:17:06 INFO - Contents: 23:17:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9424fc265a1fcd08be432cb23c17b3c591e181b94bc10dd57dd21869761948dd4e0687b2f513f2afad05f857c9993acf0e055c02a41505797a141c256492bb2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44fbbe627cd25c32831b68de9d28c2212b88b1f8b2d2da698d9a581cfec8bf8ef93900807575d5fd8cd57da8acac669ccafbce1db22433f8970cfb2e8b62fa4f"} 23:17:06 INFO - Running post-run listener: copy_logs_to_upload_dir 23:17:06 INFO - Copying logs to upload dir... 23:17:06 INFO - mkdir: /builds/slave/test/build/upload/logs 23:17:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.779009 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-04-18 23:17:06.709480) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:06.717109) ========= 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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9424fc265a1fcd08be432cb23c17b3c591e181b94bc10dd57dd21869761948dd4e0687b2f513f2afad05f857c9993acf0e055c02a41505797a141c256492bb2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44fbbe627cd25c32831b68de9d28c2212b88b1f8b2d2da698d9a581cfec8bf8ef93900807575d5fd8cd57da8acac669ccafbce1db22433f8970cfb2e8b62fa4f"} build_url:https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9424fc265a1fcd08be432cb23c17b3c591e181b94bc10dd57dd21869761948dd4e0687b2f513f2afad05f857c9993acf0e055c02a41505797a141c256492bb2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44fbbe627cd25c32831b68de9d28c2212b88b1f8b2d2da698d9a581cfec8bf8ef93900807575d5fd8cd57da8acac669ccafbce1db22433f8970cfb2e8b62fa4f"}' ========= master_lag: 0.98 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:07.705058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-18 23:17:07.705364) ========= 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.uN7xuIa9o6/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.0hJw3HBmuv/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.005252 ========= master_lag: 4.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-18 23:17:11.906152) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 23:17:11.906450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 23:17:11.918010) ========= ========= Total master_lag: 5.81 =========