builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1461131871.65 results: success (0) buildid: 20160419210627 builduid: 28994cc266314e40a416898707b7d250 revision: 234126a918c833af6d13e317cd961277e3a3dfb1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.646565) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.647013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.647294) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.666588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.666964) ========= 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.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-19 22:57:51-- 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% 16.4M=0.001s 2016-04-19 22:57:51 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122766 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.818895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.819221) ========= 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.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.027415 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.868487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:51.868855) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 234126a918c833af6d13e317cd961277e3a3dfb1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 234126a918c833af6d13e317cd961277e3a3dfb1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-19 22:57:51,946 truncating revision to first 12 chars 2016-04-19 22:57:51,946 Setting DEBUG logging. 2016-04-19 22:57:51,946 attempt 1/10 2016-04-19 22:57:51,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/234126a918c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 22:57:52,590 unpacking tar archive at: fx-team-234126a918c8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.890765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:52.776455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:52.776827) ========= script_repo_revision: 234126a918c833af6d13e317cd961277e3a3dfb1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:52.777230) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:52.777504) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 22:57:52.790822) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 22:57:52.791205) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:57:52 INFO - MultiFileLogger online at 20160419 22:57:52 in /builds/slave/test 22:57:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:57:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:57:52 INFO - {'append_to_log': False, 22:57:52 INFO - 'base_work_dir': '/builds/slave/test', 22:57:52 INFO - 'blob_upload_branch': 'Fx-Team', 22:57:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:57:52 INFO - 'branch': 'Fx-Team', 22:57:52 INFO - 'buildbot_json_path': 'buildprops.json', 22:57:52 INFO - 'config_files': ('talos/mac_config.py',), 22:57:52 INFO - 'default_actions': ('clobber', 22:57:52 INFO - 'read-buildbot-config', 22:57:52 INFO - 'download-and-extract', 22:57:52 INFO - 'populate-webroot', 22:57:52 INFO - 'create-virtualenv', 22:57:52 INFO - 'install', 22:57:52 INFO - 'run-tests'), 22:57:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:57:52 INFO - 'download_minidump_stackwalk': True, 22:57:52 INFO - 'download_symbols': 'ondemand', 22:57:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:57:52 INFO - 'tooltool.py': '/tools/tooltool.py', 22:57:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:57:52 INFO - '/tools/misc-python/virtualenv.py')}, 22:57:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:57:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:57:52 INFO - 'installer_path': 'installer.exe', 22:57:52 INFO - 'log_level': 'info', 22:57:52 INFO - 'log_name': 'talos', 22:57:52 INFO - 'log_to_console': True, 22:57:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:57:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:57:52 INFO - 'opt_config_files': (), 22:57:52 INFO - 'pip_index': False, 22:57:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:57:52 INFO - 'cmd': ('bash', 22:57:52 INFO - '-c', 22:57:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:57:52 INFO - 'enabled': True, 22:57:52 INFO - 'halt_on_failure': False, 22:57:52 INFO - 'name': 'check_screen_resolution'},), 22:57:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:57:52 INFO - 'cmd': ('bash', 22:57:52 INFO - '-c', 22:57:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:57:52 INFO - 'enabled': True, 22:57:52 INFO - 'halt_on_failure': False, 22:57:52 INFO - 'name': 'check_screen_resolution'},), 22:57:52 INFO - 'run_cmd_checks_enabled': True, 22:57:52 INFO - 'sps_profile': False, 22:57:52 INFO - 'sps_profile_interval': 0, 22:57:52 INFO - 'suite': 'dromaeojs', 22:57:52 INFO - 'system_bits': '32', 22:57:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:57:52 INFO - 'title': 't-yosemite-r7-0159', 22:57:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:57:52 INFO - 'use_talos_json': True, 22:57:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:57:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:57:52 INFO - 'work_dir': 'build'} 22:57:52 INFO - ##### 22:57:52 INFO - ##### Running clobber step. 22:57:52 INFO - ##### 22:57:52 INFO - Running pre-action listener: _resource_record_pre_action 22:57:52 INFO - Running main action method: clobber 22:57:52 INFO - rmtree: /builds/slave/test/build 22:57:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:57:54 INFO - Running post-action listener: _resource_record_post_action 22:57:54 INFO - ##### 22:57:54 INFO - ##### Running read-buildbot-config step. 22:57:54 INFO - ##### 22:57:54 INFO - Running pre-action listener: _resource_record_pre_action 22:57:54 INFO - Running main action method: read_buildbot_config 22:57:54 INFO - Using buildbot properties: 22:57:54 INFO - { 22:57:54 INFO - "project": "", 22:57:54 INFO - "product": "firefox", 22:57:54 INFO - "script_repo_revision": "production", 22:57:54 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:57:54 INFO - "repository": "", 22:57:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:57:54 INFO - "buildid": "20160419210627", 22:57:54 INFO - "pgo_build": "False", 22:57:54 INFO - "basedir": "/builds/slave/test", 22:57:54 INFO - "buildnumber": 646, 22:57:54 INFO - "slavename": "t-yosemite-r7-0159", 22:57:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:57:54 INFO - "platform": "yosemite_r7", 22:57:54 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:57:54 INFO - "branch": "fx-team", 22:57:54 INFO - "repo_path": "integration/fx-team", 22:57:54 INFO - "revision": "234126a918c833af6d13e317cd961277e3a3dfb1", 22:57:54 INFO - "stage_platform": "macosx64", 22:57:54 INFO - "builduid": "28994cc266314e40a416898707b7d250", 22:57:54 INFO - "slavebuilddir": "test" 22:57:54 INFO - } 22:57:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:57:54 INFO - Running post-action listener: _resource_record_post_action 22:57:54 INFO - ##### 22:57:54 INFO - ##### Running download-and-extract step. 22:57:54 INFO - ##### 22:57:54 INFO - Running pre-action listener: _resource_record_pre_action 22:57:54 INFO - Running main action method: download_and_extract 22:57:54 INFO - mkdir: /builds/slave/test/build/tests 22:57:54 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:54 INFO - https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:57:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:57:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:57:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:57:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:57:56 INFO - Downloaded 1199 bytes. 22:57:56 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:57:56 INFO - Using the following test package requirements: 22:57:56 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:57:56 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:57:56 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:57:56 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'jsshell-mac.zip'], 22:57:56 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:57:56 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:57:56 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:57:56 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:57:56 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:57:56 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:57:56 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:57:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:56 INFO - https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:57:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:57:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:57:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:57:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:57:57 INFO - Downloaded 18095556 bytes. 22:57: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:57: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:58:01 INFO - Return code: 0 22:58:01 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:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:01 INFO - https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:58:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:58:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:58:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:58:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:58:02 INFO - Downloaded 18095556 bytes. 22:58: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:58: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:58:05 INFO - Return code: 0 22:58: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:58:05 INFO - https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:58:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:58:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:58:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:58:06 INFO - Downloaded 10845952 bytes. 22:58:06 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:58:06 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:58:07 INFO - Return code: 0 22:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:07 INFO - https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:58:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:58:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:58:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:58:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FFFBeChfQOmdQ3JAFwcMGw/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:58:10 INFO - Downloaded 109988893 bytes. 22:58:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:58:10 INFO - mkdir: /builds/slave/test/properties 22:58:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:58:10 INFO - Writing to file /builds/slave/test/properties/build_url 22:58:10 INFO - Contents: 22:58:10 INFO - build_url:https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:58:11 INFO - Running post-action listener: _resource_record_post_action 22:58:11 INFO - Running post-action listener: set_extra_try_arguments 22:58:11 INFO - ##### 22:58:11 INFO - ##### Running populate-webroot step. 22:58:11 INFO - ##### 22:58:11 INFO - Running pre-action listener: _resource_record_pre_action 22:58:11 INFO - Running main action method: populate_webroot 22:58:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:58:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:58:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:58:11 INFO - '--tppagecycles', 22:58:11 INFO - '7'], 22:58:11 INFO - 'tests': ['tsvgm']}}, 22:58:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:58:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:58:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:58:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:58:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['damp', 'tps']}, 22:58:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'tests': ['damp', 'tps']}, 22:58:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['dromaeo_dom']}, 22:58:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:58:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'other-e10s': {'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['a11yr', 22:58:11 INFO - 'ts_paint', 22:58:11 INFO - 'tpaint', 22:58:11 INFO - 'sessionrestore', 22:58:11 INFO - 'sessionrestore_no_auto_restore', 22:58:11 INFO - 'tabpaint']}, 22:58:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['tsvgx', 22:58:11 INFO - 'tsvgr_opacity', 22:58:11 INFO - 'tart', 22:58:11 INFO - 'tscrollx', 22:58:11 INFO - 'cart']}, 22:58:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:58:11 INFO - 'tsvgr_opacity', 22:58:11 INFO - 'tart', 22:58:11 INFO - 'tscrollx', 22:58:11 INFO - 'cart']}, 22:58:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'talos_options': ['--disable-e10s'], 22:58:11 INFO - 'tests': ['tp5o']}, 22:58:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'tests': ['tp5o']}, 22:58:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'talos_options': ['--disable-e10s', 22:58:11 INFO - '--xperf_path', 22:58:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:11 INFO - 'tests': ['tp5n']}, 22:58:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:11 INFO - 'talos_options': ['--xperf_path', 22:58:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:11 INFO - 'tests': ['tp5n']}}, 22:58:11 INFO - 'talos.zip': {'path': '', 22:58:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:58:11 INFO - Running post-action listener: _resource_record_post_action 22:58:11 INFO - ##### 22:58:11 INFO - ##### Running create-virtualenv step. 22:58:11 INFO - ##### 22:58:11 INFO - Running pre-action listener: _resource_record_pre_action 22:58:11 INFO - Running main action method: create_virtualenv 22:58:11 INFO - Creating virtualenv /builds/slave/test/build/venv 22:58:11 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:58:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:58:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:58:11 INFO - Using real prefix '/tools/python27' 22:58:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:58:12 INFO - Installing distribute.............................................................................................................................................................................................done. 22:58:15 INFO - Installing pip.................done. 22:58:15 INFO - Return code: 0 22:58:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:58: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:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:15 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:15 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:58:15 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:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:15 INFO - 'HOME': '/Users/cltbld', 22:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:15 INFO - 'LOGNAME': 'cltbld', 22:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:15 INFO - 'MOZ_NO_REMOTE': '1', 22:58:15 INFO - 'NO_EM_RESTART': '1', 22:58:15 INFO - 'PAGER': '/bin/cat', 22:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:15 INFO - 'PWD': '/builds/slave/test', 22:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:15 INFO - 'SHELL': '/bin/bash', 22:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:15 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:15 INFO - 'USER': 'cltbld', 22:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:15 INFO - 'XPC_FLAGS': '0x0', 22:58:15 INFO - 'XPC_SERVICE_NAME': '0', 22:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:15 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:58:15 INFO - Cleaning up... 22:58:15 INFO - Return code: 0 22:58:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:58: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:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:15 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:15 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:58:15 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:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:15 INFO - 'HOME': '/Users/cltbld', 22:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:15 INFO - 'LOGNAME': 'cltbld', 22:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:15 INFO - 'MOZ_NO_REMOTE': '1', 22:58:15 INFO - 'NO_EM_RESTART': '1', 22:58:15 INFO - 'PAGER': '/bin/cat', 22:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:15 INFO - 'PWD': '/builds/slave/test', 22:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:15 INFO - 'SHELL': '/bin/bash', 22:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:15 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:15 INFO - 'USER': 'cltbld', 22:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:15 INFO - 'XPC_FLAGS': '0x0', 22:58:15 INFO - 'XPC_SERVICE_NAME': '0', 22:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:15 INFO - Downloading/unpacking psutil>=0.7.1 22:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:58:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:18 INFO - Installing collected packages: psutil 22:58:18 INFO - Running setup.py install for psutil 22:58:18 INFO - building 'psutil._psutil_osx' extension 22:58:18 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:58:19 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:58:19 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:58:19 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:58:19 INFO - building 'psutil._psutil_posix' extension 22:58:19 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:58:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:58:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:58:19 INFO - ^ 22:58:19 INFO - 1 warning generated. 22:58:19 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:58:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:19 INFO - Successfully installed psutil 22:58:19 INFO - Cleaning up... 22:58:19 INFO - Return code: 0 22:58:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:19 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:19 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:58:19 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:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:19 INFO - 'HOME': '/Users/cltbld', 22:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:19 INFO - 'LOGNAME': 'cltbld', 22:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:19 INFO - 'MOZ_NO_REMOTE': '1', 22:58:19 INFO - 'NO_EM_RESTART': '1', 22:58:19 INFO - 'PAGER': '/bin/cat', 22:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:19 INFO - 'PWD': '/builds/slave/test', 22:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:19 INFO - 'SHELL': '/bin/bash', 22:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:19 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:19 INFO - 'USER': 'cltbld', 22:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:19 INFO - 'XPC_FLAGS': '0x0', 22:58:19 INFO - 'XPC_SERVICE_NAME': '0', 22:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:58:22 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:58:22 INFO - Installing collected packages: mozsystemmonitor 22:58:22 INFO - Running setup.py install for mozsystemmonitor 22:58:22 INFO - Successfully installed mozsystemmonitor 22:58:22 INFO - Cleaning up... 22:58:22 INFO - Return code: 0 22:58:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:22 INFO - 'HOME': '/Users/cltbld', 22:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:22 INFO - 'LOGNAME': 'cltbld', 22:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:22 INFO - 'MOZ_NO_REMOTE': '1', 22:58:22 INFO - 'NO_EM_RESTART': '1', 22:58:22 INFO - 'PAGER': '/bin/cat', 22:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:22 INFO - 'PWD': '/builds/slave/test', 22:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:22 INFO - 'SHELL': '/bin/bash', 22:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:22 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:22 INFO - 'USER': 'cltbld', 22:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:22 INFO - 'XPC_FLAGS': '0x0', 22:58:22 INFO - 'XPC_SERVICE_NAME': '0', 22:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:23 INFO - Downloading/unpacking blobuploader==1.2.4 22:58: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:58: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:58: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:58: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:58: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:58: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:58:25 INFO - Downloading blobuploader-1.2.4.tar.gz 22:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:58:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:58:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:58:26 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:58:26 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:58:26 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:58:26 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:58:26 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:58:26 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:58:26 INFO - Downloading docopt-0.6.1.tar.gz 22:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:58:26 INFO - Installing collected packages: blobuploader, requests, docopt 22:58:26 INFO - Running setup.py install for blobuploader 22:58:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:58:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:58:26 INFO - Running setup.py install for requests 22:58:27 INFO - Running setup.py install for docopt 22:58:27 INFO - Successfully installed blobuploader requests docopt 22:58:27 INFO - Cleaning up... 22:58:27 INFO - Return code: 0 22:58:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58: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:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:27 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:27 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:58:27 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:58:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:27 INFO - 'HOME': '/Users/cltbld', 22:58:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:27 INFO - 'LOGNAME': 'cltbld', 22:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:27 INFO - 'MOZ_NO_REMOTE': '1', 22:58:27 INFO - 'NO_EM_RESTART': '1', 22:58:27 INFO - 'PAGER': '/bin/cat', 22:58:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:27 INFO - 'PWD': '/builds/slave/test', 22:58:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:27 INFO - 'SHELL': '/bin/bash', 22:58:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:27 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:27 INFO - 'USER': 'cltbld', 22:58:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:27 INFO - 'XPC_FLAGS': '0x0', 22:58:27 INFO - 'XPC_SERVICE_NAME': '0', 22:58:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:27 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8g8gwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:27 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-c_Gbt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kTFQQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-EOpL1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3iUAq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GgIV60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-vRPzWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ysdVep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eQ8zyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-FeuobY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lr7OY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:28 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-_PmBQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:29 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mezLL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:29 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-BSTzJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:29 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GN64uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:29 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-4BRiNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:29 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eZsve9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:58:29 INFO - Running setup.py install for manifestparser 22:58:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:58:29 INFO - Running setup.py install for mozcrash 22:58:29 INFO - Running setup.py install for mozdebug 22:58:29 INFO - Running setup.py install for mozdevice 22:58:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Running setup.py install for mozfile 22:58:30 INFO - Running setup.py install for mozhttpd 22:58:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Running setup.py install for mozinfo 22:58:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Running setup.py install for mozInstall 22:58:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:58:30 INFO - Running setup.py install for mozleak 22:58:30 INFO - Running setup.py install for mozlog 22:58:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Running setup.py install for moznetwork 22:58:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Running setup.py install for mozprocess 22:58:31 INFO - Running setup.py install for mozprofile 22:58:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Running setup.py install for mozrunner 22:58:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:58:31 INFO - Running setup.py install for mozscreenshot 22:58:31 INFO - Running setup.py install for moztest 22:58:31 INFO - Running setup.py install for mozversion 22:58:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:58:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:58:32 INFO - Cleaning up... 22:58:32 INFO - Return code: 0 22:58:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:32 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:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:32 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:58:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:32 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:32 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:58:32 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:58:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:32 INFO - 'HOME': '/Users/cltbld', 22:58:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:32 INFO - 'LOGNAME': 'cltbld', 22:58:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:32 INFO - 'MOZ_NO_REMOTE': '1', 22:58:32 INFO - 'NO_EM_RESTART': '1', 22:58:32 INFO - 'PAGER': '/bin/cat', 22:58:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:32 INFO - 'PWD': '/builds/slave/test', 22:58:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:32 INFO - 'SHELL': '/bin/bash', 22:58:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:32 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:32 INFO - 'USER': 'cltbld', 22:58:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:32 INFO - 'XPC_FLAGS': '0x0', 22:58:32 INFO - 'XPC_SERVICE_NAME': '0', 22:58:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gaS6yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:32 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:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-OzLj2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:32 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:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ZMDO5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:32 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:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YiFvOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:32 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:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-wLeff2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:32 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:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:32 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-712CnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-i2bFSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Eh8Mwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-MXaDNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-UqnzKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YgS1mD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-As85R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-X5lGaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Kaq5lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:33 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:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:33 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-pSK_Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:34 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:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:34 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YSGnxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:34 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:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:34 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kpsoyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:34 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:58:34 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:58:34 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:58:34 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:58:34 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:58:34 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:58:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:58: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:58: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:58: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:58: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:58: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:58: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:58:36 INFO - Downloading blessings-1.6.tar.gz 22:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:36 INFO - Installing collected packages: blessings 22:58:36 INFO - Running setup.py install for blessings 22:58:36 INFO - Successfully installed blessings 22:58:36 INFO - Cleaning up... 22:58:36 INFO - Return code: 0 22:58:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:58:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:37 INFO - Reading from file tmpfile_stdout 22:58:37 INFO - Current package versions: 22:58:37 INFO - blessings == 1.6 22:58:37 INFO - blobuploader == 1.2.4 22:58:37 INFO - docopt == 0.6.1 22:58:37 INFO - manifestparser == 1.1 22:58:37 INFO - mozInstall == 1.12 22:58:37 INFO - mozcrash == 0.17 22:58:37 INFO - mozdebug == 0.1 22:58:37 INFO - mozdevice == 0.48 22:58:37 INFO - mozfile == 1.2 22:58:37 INFO - mozhttpd == 0.7 22:58:37 INFO - mozinfo == 0.9 22:58:37 INFO - mozleak == 0.1 22:58:37 INFO - mozlog == 3.1 22:58:37 INFO - moznetwork == 0.27 22:58:37 INFO - mozprocess == 0.22 22:58:37 INFO - mozprofile == 0.28 22:58:37 INFO - mozrunner == 6.11 22:58:37 INFO - mozscreenshot == 0.1 22:58:37 INFO - mozsystemmonitor == 0.0 22:58:37 INFO - moztest == 0.7 22:58:37 INFO - mozversion == 1.4 22:58:37 INFO - psutil == 3.1.1 22:58:37 INFO - requests == 1.2.3 22:58:37 INFO - wsgiref == 0.1.2 22:58:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:37 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:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:37 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:58:37 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:58:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:37 INFO - 'HOME': '/Users/cltbld', 22:58:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PAGER': '/bin/cat', 22:58:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:37 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - 'XPC_FLAGS': '0x0', 22:58:37 INFO - 'XPC_SERVICE_NAME': '0', 22:58:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 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:58:37 INFO - Cleaning up... 22:58:37 INFO - Return code: 0 22:58:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:58:37 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:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:37 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:58:37 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:58:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:37 INFO - 'HOME': '/Users/cltbld', 22:58:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PAGER': '/bin/cat', 22:58:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:37 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - 'XPC_FLAGS': '0x0', 22:58:37 INFO - 'XPC_SERVICE_NAME': '0', 22:58:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 INFO - Downloading/unpacking jsonschema 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:40 INFO - Downloading/unpacking functools32 (from jsonschema) 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:40 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:40 INFO - warning: no files found matching '*.txt' 22:58:40 INFO - no previously-included directories found matching 'build' 22:58:40 INFO - no previously-included directories found matching 'dist' 22:58:40 INFO - no previously-included directories found matching '.git*' 22:58:40 INFO - Installing collected packages: jsonschema, functools32 22:58:40 INFO - Running setup.py install for functools32 22:58:40 INFO - warning: no files found matching '*.txt' 22:58:40 INFO - no previously-included directories found matching 'build' 22:58:40 INFO - no previously-included directories found matching 'dist' 22:58:40 INFO - no previously-included directories found matching '.git*' 22:58:40 INFO - Successfully installed jsonschema functools32 22:58:40 INFO - Cleaning up... 22:58:40 INFO - Return code: 0 22:58:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:58: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:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:40 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 0x10e2e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa062d588b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, '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.yezVgyJODK/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.RwIMdoYFcL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:58:40 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:58:40 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:58:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:58:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:40 INFO - 'HOME': '/Users/cltbld', 22:58:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:40 INFO - 'LOGNAME': 'cltbld', 22:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:40 INFO - 'MOZ_NO_REMOTE': '1', 22:58:40 INFO - 'NO_EM_RESTART': '1', 22:58:40 INFO - 'PAGER': '/bin/cat', 22:58:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:40 INFO - 'PWD': '/builds/slave/test', 22:58:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:40 INFO - 'SHELL': '/bin/bash', 22:58:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:58:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:58:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:40 INFO - 'USER': 'cltbld', 22:58:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:40 INFO - 'XPC_FLAGS': '0x0', 22:58:40 INFO - 'XPC_SERVICE_NAME': '0', 22:58:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:41 INFO - Downloading/unpacking flake8 22:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:58:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:58:43 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:58:43 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:58:43 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:58:43 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:58:43 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:58:43 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:58:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:58:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:58:43 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:58:43 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:58:43 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:58:43 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:58:43 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:58:43 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:58:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:58:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:44 INFO - no previously-included directories found matching 'docs/_build' 22:58:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:58:44 INFO - Running setup.py install for pep8 22:58:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:44 INFO - no previously-included directories found matching 'docs/_build' 22:58:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:58:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:58:45 INFO - Cleaning up... 22:58:45 INFO - Return code: 0 22:58:45 INFO - Running post-action listener: _resource_record_post_action 22:58:45 INFO - Running post-action listener: _start_resource_monitoring 22:58:45 INFO - Starting resource monitoring. 22:58:45 INFO - ##### 22:58:45 INFO - ##### Running install step. 22:58:45 INFO - ##### 22:58:45 INFO - Running pre-action listener: _resource_record_pre_action 22:58:45 INFO - Running main action method: install 22:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:45 INFO - Reading from file tmpfile_stdout 22:58:45 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:58:45 INFO - Reading from file tmpfile_stdout 22:58:45 INFO - Output received: 22:58:45 INFO - Usage: mozinstall [options] installer 22:58:45 INFO - Options: 22:58:45 INFO - -h, --help show this help message and exit 22:58:45 INFO - -d DEST, --destination=DEST 22:58:45 INFO - Directory to install application into. [default: 22:58:45 INFO - "/builds/slave/test"] 22:58:45 INFO - --app=APP Application being installed. [default: firefox] 22:58:45 INFO - mkdir: /builds/slave/test/build/application 22:58:45 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:58:45 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:59:18 INFO - Reading from file tmpfile_stdout 22:59:18 INFO - Output received: 22:59:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:59:18 INFO - Running post-action listener: _resource_record_post_action 22:59:18 INFO - ##### 22:59:18 INFO - ##### Running run-tests step. 22:59:18 INFO - ##### 22:59:18 INFO - Running pre-action listener: _resource_record_pre_action 22:59:18 INFO - Running main action method: run_tests 22:59:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:59:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:59:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:59:18 INFO - 2016-04-19 22:59:18.867 screenresolution[1640:7380] starting screenresolution argv=screenresolution get 22:59:18 INFO - 2016-04-19 22:59:18.869 screenresolution[1640:7380] Display 0: 1600x1200x32@60 22:59:18 INFO - 2016-04-19 22:59:18.875 screenresolution[1641:7382] starting screenresolution argv=screenresolution list 22:59:18 INFO - Available Modes on Display 0 22:59:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:59:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:59:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:59:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:59:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:59:19 INFO - Intel Iris: 22:59:19 INFO - Chipset Model: Intel Iris 22:59:19 INFO - Type: GPU 22:59:19 INFO - Bus: Built-In 22:59:19 INFO - VRAM (Dynamic, Max): 1536 MB 22:59:19 INFO - Vendor: Intel (0x8086) 22:59:19 INFO - Device ID: 0x0a2e 22:59:19 INFO - Revision ID: 0x0009 22:59:19 INFO - Displays: 22:59:19 INFO - Display: 22:59:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:59:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:59:19 INFO - Display Serial Number: 1600x1200 60 22:59:19 INFO - Main Display: Yes 22:59:19 INFO - Mirror: Off 22:59:19 INFO - Online: Yes 22:59:19 INFO - Rotation: Supported 22:59:19 INFO - Return code: 0 22:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:59:19 INFO - Python 2.7.3 22:59:19 INFO - Return code: 0 22:59:19 INFO - grabbing minidump binary from tooltool 22:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:19 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 0x10e17fe00>, '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 0x10dfa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e17ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:59:19 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:59:19 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:59:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:59:19 INFO - Return code: 0 22:59:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:59:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:59:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:59:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:59:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:59:20 INFO - Return code: 0 22:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yezVgyJODK/Render', 22:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:59:20 INFO - 'HOME': '/Users/cltbld', 22:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:59:20 INFO - 'LOGNAME': 'cltbld', 22:59:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:59:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:20 INFO - 'MOZ_NO_REMOTE': '1', 22:59:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:59:20 INFO - 'NO_EM_RESTART': '1', 22:59:20 INFO - 'PAGER': '/bin/cat', 22:59:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:20 INFO - 'PWD': '/builds/slave/test', 22:59:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:59:20 INFO - 'SHELL': '/bin/bash', 22:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RwIMdoYFcL/Listeners', 22:59:20 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 22:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:59:20 INFO - 'USER': 'cltbld', 22:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:20 INFO - 'XPC_FLAGS': '0x0', 22:59:20 INFO - 'XPC_SERVICE_NAME': '0', 22:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:59:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:59:20 INFO - mozversion application_buildid: 20160419210627 22:59:20 INFO - mozversion application_changeset: 234126a918c833af6d13e317cd961277e3a3dfb1 22:59:20 INFO - mozversion application_display_name: Nightly 22:59:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:59:20 INFO - mozversion application_name: Firefox 22:59:20 INFO - mozversion application_remotingname: firefox 22:59:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:59:20 INFO - mozversion application_vendor: Mozilla 22:59:20 INFO - mozversion application_version: 48.0a1 22:59:20 INFO - mozversion platform_buildid: 20160419210627 22:59:20 INFO - mozversion platform_changeset: 234126a918c833af6d13e317cd961277e3a3dfb1 22:59:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:59:20 INFO - mozversion platform_version: 48.0a1 22:59:20 INFO - using testdate: 1461131960 22:59:20 INFO - actual date: 1461131960 22:59:20 INFO - starting webserver on 'localhost:49270' 22:59:20 INFO - SUITE-START | Running 2 tests 22:59:20 INFO - TEST-START | dromaeo_css 22:59:20 INFO - operating with platform_type : mac_ 22:59:20 INFO - Initialising browser for dromaeo_css test... 22:59:20 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpQSh3Q0/profile http://localhost:49270/getInfo.html) 22:59:22 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 22:59:22 INFO - PROCESS | 1659 | colorDepth:24 22:59:22 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 22:59:22 INFO - PROCESS | 1659 | __metrics 22:59:23 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:59:23 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:59:23 INFO - TEST-INFO | 1659: exit 0 22:59:23 INFO - Browser initialized. 22:59:23 INFO - Running cycle 1/1 for dromaeo_css test... 22:59:23 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpQSh3Q0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:35 INFO - PROCESS | 1661 | RSS: Main: 237846528 22:59:35 INFO - PROCESS | 1661 | 23:00:26 INFO - PROCESS | 1661 | 1461132026944 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:02:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 23:02:38 INFO - PROCESS | 1661 | RSS: Main: 326365184 23:02:38 INFO - PROCESS | 1661 | 23:05:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:05:41 INFO - PROCESS | 1661 | RSS: Main: 372367360 23:05:41 INFO - PROCESS | 1661 | 23:08:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:08:44 INFO - PROCESS | 1661 | RSS: Main: 370323456 23:08:44 INFO - PROCESS | 1661 | 23:11:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:11:47 INFO - PROCESS | 1661 | RSS: Main: 375828480 23:11:47 INFO - PROCESS | 1661 | 23:14:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:14:51 INFO - PROCESS | 1661 | RSS: Main: 347435008 23:14:51 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:17:54 INFO - PROCESS | 1661 | RSS: Main: 381181952 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | __start_tp_report 23:17:54 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 23:17:54 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 23:17:54 INFO - PROCESS | 1661 | |i|pagename|runs| 23:17:54 INFO - PROCESS | 1661 | |0;dojo.html;2941.617589713337;3388.271521622851;3484.1638006878343;3499.8600055997754;3542.2207114434814;4870.951290487094;5559.722013899309;5755.827325180241;5755.913661295079;5756.453136951994;4804.783784729687;5703.087505999041;5735.770569177228;5869.50109240714;5893.263342082239;5111.69329840209;5155.922661160085;5165.586753059746;5177.922331165036;5262.079136151158;5247.819240670838;5281.788728450857;5298.178782288752;5312.096943519611;5325;5136.537711605936;5157.48425157485;5217.921731174036;5239.13554263546;5240.9213861792105;5195.090859099642;5196.45437229093;5199.14214154664;5213.191955246943;5225.425203227642;5139.383274007143;5181.5077567631015;5211.895762084737;5215.217717342391;5227.79088836446;5586.972065139689;5660.3490598581275;5705.229793977802;5787.102999035156;5798.9420105799245;6115.713144167403;6127.908081378783;6157.322694503601;6158.107074474221;6169.29053158888;5245.186996015624;6183.134361189437;6185.41238582334;6189.319174890758;6205.317415084337;5497.945020549783;5580.051391263495;5586.664800112007;5587.469190426903;5863.912041319383;6259.155014073133;6268.2478102627065;6270.467010304175;6275.3724627536885;6275.654838983808;6131.96934015327;6150.938490615126;6152.2002139722445;6159.876802463926;6166.691665416801;6119.602225855305;6164.260288765377;6205.503559715212;6217.347178546278;6235.844103897348;6114.449440885012;6164.907526387108;6202.162708034448;6234.719437625317;6235.314115447297;6173.290071641729;6185.474234690012;6197.442230199304;6211.254649442096;6222.253329600477;4860.854174374775;4911.189653707098;4937.136001199848;4955.4796746341835;4980.377452818398;4466.084452687256;4506.752128632891;4508.07584445188;4509.954900450954;4515.2324104902245;5772.278465191851;5773.5958482906635;5783.421657834267;5790.42095790426;5792.247007889032;6160.414760597736;6185.84535386619;6259.71831267594;6260.655663938436;6270.31026587075;5764.971175144097;5779.566532510079;5835.737391817378;5844.211031510777;5860.2967643883;5336.252924590561;5416.350037995466;5425.969065877471;5434.266374039454;5438.945610543923;5580.581456390706;6236.719347629368;6286.937130628727;6310.211223597051;6331.746730130835;5202.687838729688;6026.7287972040485;6230.345813689497;6254.093156492374;6255;6207.689615519296;6292.087647291209;6370.394812492806;6371.617702937839;6374.968125159345;6116.579253789288;6213.813585592439;6222.408871157233;6223.844403889939;6243.313235543996;6217.4715149212825;6233.75064997395;6238.189035425366;6246.063090536456;6246.187995560548;4470.821167153407;5341.717987683006;5996;6043;6099.11562823397;6106.430331452303;6108.85814249354;6127.69183955469;6128.86980888409;6149.323574406902;5975.820725378246;5978.8206353809455;5986.2217911671205;5993;5995.400459954144;5770.480656740913;5896.734646940812;5927.851803704942;5959.642421454727;6139.079138129317;5954.434328738763;5956.463918247378;5967.791127310524;5973.104034394702;5973.522118230444 23:17:54 INFO - PROCESS | 1661 | |1;ext.html;465.59260646933933;500.9499050094991;524.7795925711201;535.5153586004667;583.5535815101447;26842.865785671067;30272;37469.87590372287;38328;38374.616253837456;42712.572874271245;43539.564604353945;43554.78222608883;43562.56437435633;43588.564114358844;9585.712428627136;9765.902340976605;9773;9778.364406313602;9781.755456113582;8005.279524842792;9652.565634546427;9656.662016829412;9666.951665241699;9675.612975480972;9636;9675.129238368545;9685.41887486753;9686.806263874718;9704.466254356008;9602.951985240064;9661.90338096617;9673.03269673034;9677.661281855102;9677.806443871083;9585.137337639646;9673.322867399285;9675.467849268287;9680.273979451498;9682.370645907993;39587.8020609898;40014;40038.79980600107;40046.39930401046;40160;3022.758179345647;3125.718685318332;3189.1389324882384;3437.7937323760652;3799.5060642116623;26642.33394165142;28250.717492825013;28265.85867070672;28294.717052829415;28309.15072547827;2629.9211023669322;2642.735726427361;2752.0092766604007;2787.5957986092108;2796.832190068582;381399;381997;385764;385843;386115;61059.08411373833;61384.693076534335;61997;62018.37981620126;62029;65220.673896630215;65249;65364.67317663381;65416;65562.34437655658;17140.571485712957;17406.95558266508;17410.39063632767;17413;17454.389096381565;13293.601191964257;13336.599902002956;13339.533116340881;13352.933235333761;13353.599392018255;18574.907125464557;19274.310595065148;19507.062970885512;19563.263838964693;19597.216111355672;6521.771737989147;6704.812791578588;8015.559144247122;8226.629801658939;8344.874826877602;923.5890028937193;942.8020115775627;963.9469829159324;1005.6166884483674;1043.1029314789275;23884.88057559701;27745;28369.95736306991;28559.14322570326;29234.853825730734;923.5105394141116;1046.874375074996;1064.6912395405245;1196.342011893455;1262.4634530324759;14940.850591494163;14991.700165996837;15147.697046059016;15426.382944682087;15750;765094;785887;787215.0639246767;787311;788081;57899;59868.70065649644;60005.69997149986;60026.09960850591;60068;1320.42561485754;1369.897257705676;1513.5913303408017;1522.4975757999835;1550.1241798383876;27907.302317442227;28035.859820700767;28711.856440717664;28809.423811523233;29042;47713.76143119262;47766;48723;48750.512494874594;48941;337641;348591;362301.1884940558;365895.1705241563;367689;23402.946867390667;26202;27162.864185678944;27200.455990879676;27270.863645681646;40837.38743918784;44188.11623767571;45910;45911.54088459073;46462;34447.483287750205;34774.13064673302;34837.65162348344;35642;35711.10722231965;34575.82712086423;34690.57165998457;35077.824610876785;35499.29001420009;35544.644553554135;34257.629694812924;34475.31049378948;34597.13507162341;34994.650053500154;36430.4535431974;29254.41491170122;32042.679573204903;32290.35419291554;32307.838460807547;32325.35349293048;23579.29262122139;23727.52544949126;23782.16762413307;24231;24268.878655606608 23:17:54 INFO - PROCESS | 1661 | |2;jquery.html;398.1678292368948;399.62834563855614;401.760952233421;403.7799399327366;411.08738600307305;14168.37782340862;17894.463166105023;18950.052497375127;19185.808141918576;19995;15775;17325;19854.900725496387;20049.298274560395;20725.896370518163;5870.442307980746;6072.726727297282;6083.026715725485;6091.847703807396;6094.8171554853425;6006.42938920804;6013.819585412444;6056.969715151418;6077.11881777142;6131.9386806131815;5883.499902508292;6029.366916473773;6051.092336149569;6052.515798736113;6067.453929146376;5969.343372229051;5987.401259873999;6044.365341639109;6051.425114614105;6071.574989750719;5031.949680503185;5151.7681704323395;5221.634485586011;5226.92159617606;5231.973840130775;25583.360415989748;25646.87176564105;25951.74048259512;26311.684415779135;26352.86823565889;2246.6854640350366;2340.0873659272897;2352.5882970480097;2513.8743062846784;2607.8565678887653;12387.256764594153;14496.057756245811;14512.782308265385;14772.55682329532;14989.700205995929;1694.5424735321517;1756.323815331094;1775.3519965212604;1784.2238626197645;1883.7567205644345;97087;100011.9994000297;101105;102222.48888755582;103633.4818325911;10379.636712715019;10483.318584291997;10832.350058996486;10869;11045.171612129123;13718.314084295745;13786.65533361674;13795.965302602344;13825.308734563232;13847.515336963157;7542.358899493604;7645.655945482466;8560.400771946028;8700.695475658322;8742.388032837767;7015.754448594274;8513.531755753369;8557.315414766803;8617.827643447095;8627.482351058956;6462.4183823456115;6557.803265901935;6563.7702680405955;6568.014797780371;6568.507361947777;1874.240932422371;1903.6097599991967;1904.3620387170454;1904.7523821903064;1946.347973428889;932.4871320773548;940.8682784410188;1020.2399212586522;1036.4196050211906;1045.00724311904;20635.793642063687;20958.16167353319;20966.685499717518;21086.894565527073;21525.67711484329;961.1589858873485;1000.4347543637822;1008.1128606825946;1011.0698157694791;1024.7540590258434;10994.450277486094;11383.203175777782;11484.081273498101;11503.884961150448;11538;617860;622233;636619.2561806772;640834;642319;76785;106484.46757766316;108390.45804770925;110458.89541104647;111494;1048.6015314180563;1050.7373156710823;1069.753956589973;1072.3673032910465;1073.8657667791526;17645.205965731315;18008.549786255448;18093.909530452263;18137.727934080736;18503.81496185021;31880;39548.40677389784;40496.595034050464;46476;46549;53831;67101.11665928256;67155;67630.66184669243;68356.6582167086;8013.519188848688;8076;8232.794180145544;8233.46482478637;8469.872951905603;12640;12714.11001229905;12953.611391658265;13095;13199.472021119047;8955.552222388724;10689.465526723789;11396.772064558496;11409.372484513431;11501.309921404743;10908.836367454653;10962.342259464458;11073.5570577176;11237.606683766357;11289.604863829727;11225.66323010311;11370.658880233606;11572.826407604061;11704.239224450383;11831.290122592673;6599.274079851309;7566.940628312151;7644.847103058019;8108.9594552026865;8363.832723345377;9075.364724469224;11157.49791259379;11202.607908723481;11485.425728713699;11542.19204655666 23:17:54 INFO - PROCESS | 1661 | |3;mootools.html;1438.9928050359747;1542.4909779772672;1596.489123480486;1903;2034.8982550872452;7884.645190966406;8561.61472733727;8809.691660791857;8812;8824.867626985584;8491.74524764258;8692.826143477127;8697.608607612658;8699.652013919436;8701.608427620757;2146.8711877287333;2423.0913407472153;2441.9877900610472;2452.5217582571404;2462.113639089935;2392.0790495659207;2432.0879670123704;2441.218809980807;2441.2676197140877;2445.327534927891;2406.57884870147;2427.0291883246664;2436.744141865105;2443.1815341860497;2443.2792326263707;2167.024838822528;2334.6147885599025;2417.685700858886;2421.4672771990136;2435.1233555919853;2361.7047869016374;2403.966294493367;2414.5774489464275;2414.9758502414925;2417.697787776528;7307.561546307238;8410.201030902055;8623.137686231326;8665.393422460367;8692.391532592721;4377.934330985037;5402.486763757437;5403.891922161535;5429.484199001088;5445.101558242885;3454.7236221102503;3458.3774920514297;3522.8590856365713;3553.147244661263;3573.3746594345885;1033.2818691009752;1046.3094357723942;1047.1465755409317;1057.0644979193391;1060.3319908457622;11351.545938162464;11563.248388854698;11569.595064172798;11577.363245021521;11592.826107608393;4404.207242696345;4420.668449866273;4615.053913947632;4619.445666519972;4655.162070827216;9158.450492970444;9158.90841091594;9164.495952722531;9175.311851611023;9178;5262.289590905179;5272.340957380327;5299.2845965794895;5299.629025968223;5299.814506492255;4042.8787136385954;4422.646188304928;4553.180427523078;4817.27740838877;4949.925751113737;3323.5347051412823;3799.088218827517;3817.6256547642824;3845.65389114981;3916.7649941003633;1201.5193922431097;1208.4501551794087;1208.697825543614;1218.317742064429;1218.9085818563642;659.7229163751236;788.8501184774874;873.5195642396657;917.6726470982538;930.0559931669361;5504.009278329859;5685.090385538294;5702.429757024347;5769.365369809318;5860.619059761102;913.57975331347;915.2677857713802;948.9762755931157;970.3644113105927;1017.8167929772712;3287.112479630486;3533.7278579711283;3630.945535816965;3792.677622402127;3858.3826587745825;10215.591376344863;10622.94688526568;10705.892941070646;10710.946445267724;10715.089217416451;10250.436226007641;10696.251262411712;10820.350778953289;10842.783144337069;10888.61889833852;8131.878021829677;8175.427720059658;8255.504669719716;8302.252797248277;8302.54336011525;5515.972420137874;5634.6337488063145;5684.232628595087;5740.13897915316;5769.192313076257;9849.704508864745;9894.356866803635;9906.554205060645;10002.399856008662;10004.399736015863;9502.809943801223;9736.41581505112;10040.497975101069;10086.495675216356;10096.242781791541;7555.16893141743;7622.58075805836;7733.613319333898;7744.5740484271655;7747.2640099190485;8092.150324216108;8254.215849494289;8325.625346859284;8353.289970352587;8368.748937531884;7436.405087593088;7456.515326503977;7505.174430812715;7574.091109066947;7632.542047476947;6994.2656021117045;7005.264447233171;7082.185548661764;7140.000399943908;7188.38898693596;6795.490338224554;6849.212340580897;6854.657267136723;6862.142232220973;6867.759628412981;4599.747013914267;4671.462781780003;4768.998510312873;4800.3759511263315;4865.051314993542;4784.47370789206;4868.196747536616;4880.194767863405;4907.484714104968;4942.413395437986 23:17:54 INFO - PROCESS | 1661 | |4;prototype.html;317.741041051543;322.8176080514509;329.71150243536914;335.1203091883805;338.7764075710032;4839.685420447673;5423.430539793324;5879.470847623713;5935.287765468139;5964.821055368335;4633.791479383427;5833.708314584275;5840.853978650536;5889.85275368115;5891.911621325673;3498.702610278129;4183.330667093266;4227.957720422787;4310.2026125166785;4473.7986790594505;4279.443672322609;4356.518783613571;4432.468103827545;4440.97779511102;4457.264551349023;4402.933955990663;4406.00391733621;4426.380306757057;4428.778561071949;4444.488883778375;4150.398192262121;4173.207090652766;4395.4725432947935;4426.867193984185;4467.084247729208;3217.8391080445886;3800.7909564973916;3824.3116239076953;3832.4826148470156;3843.385058390644;5359.705216213107;5646.830595082113;5675.574331925122;5691.288588926384;5705.972924873521;4338.804753786056;4879.63402744792;5144.254083157921;5154.30416893722;5163.845084647466;5525;6179.69101544921;6233.563650544464;6234.625922444668;6245.3442388549;3694.11341278094;4220.050488640031;4296.226679197744;4297.4413326267695;4305.921266294583;5263.894722105575;6320.27316858558;6564.573302735354;6575.178102737158;6679.499037572153;3953.486046813953;4166.687498437629;4232.703710740218;4395.340698895191;4402.8679139625865;3815.550090965471;4028.9194216115516;4301.161273551627;4499.4150760400935;4501.864944051684;4852.902941941142;5169.06956747789;5185;5229.946791236822;5230.541645426643;5097.413797413271;6333.2716737575765;6376.939069246695;6400;6402.839929001719;1300.5903140510898;1336.565616174747;1410.2243765928904;1526.7633516804913;1552.410084168009;961.2598299309493;991.8363470027365;1024.6669832304383;1046.7121541576128;1047.7747284333905;5101.20931255656;5174.7930082796265;5637.577181711388;5646.237757902631;5738.225339579187;5781.768729250867;6218.067289906459;6369.20384951881;6435.774747883802;6646.2356828965285;5185.781341384772;5219.869503262373;5688.516476099495;5834.708264586754;5893.734781934823;2579.1102069785893;3151.75505675257;3406.5060566218253;3428.851334802869;3441.711262010735;5724.255846739898;6754.527183097234;6822.4200942919415;6855.108835851307;6875.1749790025515;6591.714615649902;6716.664166791641;6754.635877442855;6771.289014653489;6772.72909083641;5320.973395133077;6594.538382313384;6784.321567843176;6813.897791533131;6821.283765204681;5680;6186;6500.349965003462;6542.574732642363;6545.967270163809;6408.871822563429;6411.30100523371;6438.100760275636;6459.967700161469;6464.644444555406;6364.363563643598;6811.876040453468;6874.140732408449;6874.690638921361;6880.724771009103;3999.7600143991453;4222.282212024024;4230.534641189405;4244.405783190417;4244.978775106105;6351.936480635134;6499.960006398954;6504.869902602016;6538.084668146558;6546.279909210079;5583.944160558342;6250.468710159687;6325.146105275729;6366.745330186741;6393.968030159819;5866.823995280149;5935.881282374415;6311.842203944754;6313.842153946188;6346.921742954571;5902.940970590411;5936.46571808522;6074.969625151846;6147.877042459215;6208.782692605737;6183.290025984374;6217;6223.533235007483;6224.564280500322;6246.406591373812;5903.321118071477;6113.786017489367;6146.139540464428;6200.844978875564;6204.658743769136 23:17:54 INFO - PROCESS | 1661 | |5;yui.html;697.4176562570254;746.9701211951523;764.2433990349556;766.2796970847403;777.5645638442471;14468.421263149474;14865.851341486581;14908;14918;14961.95266331356;12534.2479451233;12557.183783054095;12782.936085319561;12789.488420463194;12800.551980680679;721.6716394040725;741.6217728958243;815.898012748405;909.3861643390718;949.2548349545608;921.9677311294106;924.685606893656;938.629241449627;940.111594543157;940.7036783413237;988.3575675810708;1004.3271008424373;1005.2561104782482;1006.7583779892809;1007.0734923870057;758.3781299334576;965.8454647256406;984.7784248544092;998.2313618513776;1003.703907347327;746.6565379925241;747.700919632146;748.8652042632324;749.1946157880301;749.8013026547969;14424.855751442456;14501.927490362586;14503.92748036253;14524;14558.053726507744;12289.016878649776;12426;12616.873831261662;12899;12963.87036129636;9829.655962041366;9850.113489785954;9897.950510247474;10045.09594136524;10176.236782241362;3077.9692203077907;3443.345764304799;3493.179102910814;3527.8236088195745;3596.2447885943984;78577.21422785813;78801.60599197025;79203;80171.5991420045;80202.79695804567;9351.766205844771;9459.90540094604;9549.665761698447;9760.463174525326;9760.902390976142;21615.135394584042;21637.35087947364;21671.67492487614;21712.240071597418;21742.13031478723;7332.743353982692;7336.7065317386705;7561.130469995911;7610.429217808686;7850.2542258485355;4106.91786164281;4127.669786417079;4133.627973482401;4153.937690934638;4177.895552611149;3124.937521242743;3520.8415621297104;3630.636936306401;3658.835352409095;3666.944995825065;556.2074044486591;650.1190886349024;666.2371584535776;673.5352606701458;688.3013741052836;551.6221388348994;591.249113625693;595.1370512756521;598.7994022002678;607.4509999501254;7313.634318284065;7650.846983060308;7695.461317707706;8058.395620328499;8060.556669383241;530.8301343570021;570.5122120586922;585.2304219950751;585.3941170888111;598.9341172471024;3608.8556457741397;3630.636936306401;3658.176910195185;3700.666939975415;3721.3859713147026;96346.55480167804;96496;98153;98171;99493;64756;73586.63206683932;81056;82570.76143857847;82676.17323826684;59486.40513594809;60177.99999999913;61325;64177;68285.80570331591;7122.85754284901;7780.533168009937;7906.5651389174145;8025.719099831478;8299.211574900375;39824.2035159301;41071;41361.379579305736;41982;42570.36144457898;44623;62677.11968640948;62934.37065629285;63867;64257.678711606146;9219.400738951947;10655.149739972292;11103.888961110286;11121.721956951142;11312.717182070184;11359.716007099889;11644.08495444596;11752.647420577396;11754.471048802654;12271.263724176579;11027;11159.832602510807;11178.10575154002;11439.828402573801;11572.6528204154;11030.724231893946;11317.264377815416;11318.207725459139;11425.885741142814;11682.70793230142;11166.274192177483;11189.160812939224;11344.035756960751;11355.318680879174;11558.075353971493;9547.713568592953;9627.663031793854;9631.181349585364;9685.17675997548;9726.659566915125;9364.157225849705;10193;10196.745081373025;10218.948905255425;10243.795124097625 23:17:54 INFO - PROCESS | 1661 | __end_tp_report 23:17:54 INFO - PROCESS | 1661 | __start_cc_report 23:17:54 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1225 23:17:54 INFO - PROCESS | 1661 | __end_cc_report 23:17:54 INFO - PROCESS | 1661 | __startTimestamp1461133074521__endTimestamp 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | ------- Summary: start ------- 23:17:54 INFO - PROCESS | 1661 | Number of tests: 6 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5704.08 Median:5927.85 stddev:639.26 (10.8%) stddev-sans-first:603.38 23:17:54 INFO - PROCESS | 1661 | Values: 2941.6 3388.3 3484.2 3499.9 3542.2 4871.0 5559.7 5755.8 5755.9 5756.5 4804.8 5703.1 5735.8 5869.5 5893.3 5111.7 5155.9 5165.6 5177.9 5262.1 5247.8 5281.8 5298.2 5312.1 5325.0 5136.5 5157.5 5217.9 5239.1 5240.9 5195.1 5196.5 5199.1 5213.2 5225.4 5139.4 5181.5 5211.9 5215.2 5227.8 5587.0 5660.3 5705.2 5787.1 5798.9 6115.7 6127.9 6157.3 6158.1 6169.3 5245.2 6183.1 6185.4 6189.3 6205.3 5497.9 5580.1 5586.7 5587.5 5863.9 6259.2 6268.2 6270.5 6275.4 6275.7 6132.0 6150.9 6152.2 6159.9 6166.7 6119.6 6164.3 6205.5 6217.3 6235.8 6114.4 6164.9 6202.2 6234.7 6235.3 6173.3 6185.5 6197.4 6211.3 6222.3 4860.9 4911.2 4937.1 4955.5 4980.4 4466.1 4506.8 4508.1 4510.0 4515.2 5772.3 5773.6 5783.4 5790.4 5792.2 6160.4 6185.8 6259.7 6260.7 6270.3 5765.0 5779.6 5835.7 5844.2 5860.3 5336.3 5416.4 5426.0 5434.3 5438.9 5580.6 6236.7 6286.9 6310.2 6331.7 5202.7 6026.7 6230.3 6254.1 6255.0 6207.7 6292.1 6370.4 6371.6 6375.0 6116.6 6213.8 6222.4 6223.8 6243.3 6217.5 6233.8 6238.2 6246.1 6246.2 4470.8 5341.7 5996.0 6043.0 6099.1 6106.4 6108.9 6127.7 6128.9 6149.3 5975.8 5978.8 5986.2 5993.0 5995.4 5770.5 5896.7 5927.9 5959.6 6139.1 5954.4 5956.5 5967.8 5973.1 5973.5 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:64515.59 Median:366792.09 stddev:146492.27 (39.9%) stddev-sans-first:146824.28 23:17:54 INFO - PROCESS | 1661 | Values: 465.6 500.9 524.8 535.5 583.6 26842.9 30272.0 37469.9 38328.0 38374.6 42712.6 43539.6 43554.8 43562.6 43588.6 9585.7 9765.9 9773.0 9778.4 9781.8 8005.3 9652.6 9656.7 9667.0 9675.6 9636.0 9675.1 9685.4 9686.8 9704.5 9603.0 9661.9 9673.0 9677.7 9677.8 9585.1 9673.3 9675.5 9680.3 9682.4 39587.8 40014.0 40038.8 40046.4 40160.0 3022.8 3125.7 3189.1 3437.8 3799.5 26642.3 28250.7 28265.9 28294.7 28309.2 2629.9 2642.7 2752.0 2787.6 2796.8 381399.0 381997.0 385764.0 385843.0 386115.0 61059.1 61384.7 61997.0 62018.4 62029.0 65220.7 65249.0 65364.7 65416.0 65562.3 17140.6 17407.0 17410.4 17413.0 17454.4 13293.6 13336.6 13339.5 13352.9 13353.6 18574.9 19274.3 19507.1 19563.3 19597.2 6521.8 6704.8 8015.6 8226.6 8344.9 923.6 942.8 963.9 1005.6 1043.1 23884.9 27745.0 28370.0 28559.1 29234.9 923.5 1046.9 1064.7 1196.3 1262.5 14940.9 14991.7 15147.7 15426.4 15750.0 765094.0 785887.0 787215.1 787311.0 788081.0 57899.0 59868.7 60005.7 60026.1 60068.0 1320.4 1369.9 1513.6 1522.5 1550.1 27907.3 28035.9 28711.9 28809.4 29042.0 47713.8 47766.0 48723.0 48750.5 48941.0 337641.0 348591.0 362301.2 365895.2 367689.0 23402.9 26202.0 27162.9 27200.5 27270.9 40837.4 44188.1 45910.0 45911.5 46462.0 34447.5 34774.1 34837.7 35642.0 35711.1 34575.8 34690.6 35077.8 35499.3 35544.6 34257.6 34475.3 34597.1 34994.7 36430.5 29254.4 32042.7 32290.4 32307.8 32325.4 23579.3 23727.5 23782.2 24231.0 24268.9 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34120.33 Median:21026.79 stddev:104150.24 (495.3%) stddev-sans-first:104411.63 23:17:54 INFO - PROCESS | 1661 | Values: 398.2 399.6 401.8 403.8 411.1 14168.4 17894.5 18950.1 19185.8 19995.0 15775.0 17325.0 19854.9 20049.3 20725.9 5870.4 6072.7 6083.0 6091.8 6094.8 6006.4 6013.8 6057.0 6077.1 6131.9 5883.5 6029.4 6051.1 6052.5 6067.5 5969.3 5987.4 6044.4 6051.4 6071.6 5031.9 5151.8 5221.6 5226.9 5232.0 25583.4 25646.9 25951.7 26311.7 26352.9 2246.7 2340.1 2352.6 2513.9 2607.9 12387.3 14496.1 14512.8 14772.6 14989.7 1694.5 1756.3 1775.4 1784.2 1883.8 97087.0 100012.0 101105.0 102222.5 103633.5 10379.6 10483.3 10832.4 10869.0 11045.2 13718.3 13786.7 13796.0 13825.3 13847.5 7542.4 7645.7 8560.4 8700.7 8742.4 7015.8 8513.5 8557.3 8617.8 8627.5 6462.4 6557.8 6563.8 6568.0 6568.5 1874.2 1903.6 1904.4 1904.8 1946.3 932.5 940.9 1020.2 1036.4 1045.0 20635.8 20958.2 20966.7 21086.9 21525.7 961.2 1000.4 1008.1 1011.1 1024.8 10994.5 11383.2 11484.1 11503.9 11538.0 617860.0 622233.0 636619.3 640834.0 642319.0 76785.0 106484.5 108390.5 110458.9 111494.0 1048.6 1050.7 1069.8 1072.4 1073.9 17645.2 18008.5 18093.9 18137.7 18503.8 31880.0 39548.4 40496.6 46476.0 46549.0 53831.0 67101.1 67155.0 67630.7 68356.7 8013.5 8076.0 8232.8 8233.5 8469.9 12640.0 12714.1 12953.6 13095.0 13199.5 8955.6 10689.5 11396.8 11409.4 11501.3 10908.8 10962.3 11073.6 11237.6 11289.6 11225.7 11370.7 11572.8 11704.2 11831.3 6599.3 7566.9 7644.8 8109.0 8363.8 9075.4 11157.5 11202.6 11485.4 11542.2 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5618.07 Median:4841.16 stddev:3148.25 (65.0%) stddev-sans-first:3141.41 23:17:54 INFO - PROCESS | 1661 | Values: 1439.0 1542.5 1596.5 1903.0 2034.9 7884.6 8561.6 8809.7 8812.0 8824.9 8491.7 8692.8 8697.6 8699.7 8701.6 2146.9 2423.1 2442.0 2452.5 2462.1 2392.1 2432.1 2441.2 2441.3 2445.3 2406.6 2427.0 2436.7 2443.2 2443.3 2167.0 2334.6 2417.7 2421.5 2435.1 2361.7 2404.0 2414.6 2415.0 2417.7 7307.6 8410.2 8623.1 8665.4 8692.4 4377.9 5402.5 5403.9 5429.5 5445.1 3454.7 3458.4 3522.9 3553.1 3573.4 1033.3 1046.3 1047.1 1057.1 1060.3 11351.5 11563.2 11569.6 11577.4 11592.8 4404.2 4420.7 4615.1 4619.4 4655.2 9158.5 9158.9 9164.5 9175.3 9178.0 5262.3 5272.3 5299.3 5299.6 5299.8 4042.9 4422.6 4553.2 4817.3 4949.9 3323.5 3799.1 3817.6 3845.7 3916.8 1201.5 1208.5 1208.7 1218.3 1218.9 659.7 788.9 873.5 917.7 930.1 5504.0 5685.1 5702.4 5769.4 5860.6 913.6 915.3 949.0 970.4 1017.8 3287.1 3533.7 3630.9 3792.7 3858.4 10215.6 10622.9 10705.9 10710.9 10715.1 10250.4 10696.3 10820.4 10842.8 10888.6 8131.9 8175.4 8255.5 8302.3 8302.5 5516.0 5634.6 5684.2 5740.1 5769.2 9849.7 9894.4 9906.6 10002.4 10004.4 9502.8 9736.4 10040.5 10086.5 10096.2 7555.2 7622.6 7733.6 7744.6 7747.3 8092.2 8254.2 8325.6 8353.3 8368.7 7436.4 7456.5 7505.2 7574.1 7632.5 6994.3 7005.3 7082.2 7140.0 7188.4 6795.5 6849.2 6854.7 6862.1 6867.8 4599.7 4671.5 4769.0 4800.4 4865.1 4784.5 4868.2 4880.2 4907.5 4942.4 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5082.55 Median:5684.26 stddev:1612.62 (28.4%) stddev-sans-first:1576.99 23:17:54 INFO - PROCESS | 1661 | Values: 317.7 322.8 329.7 335.1 338.8 4839.7 5423.4 5879.5 5935.3 5964.8 4633.8 5833.7 5840.9 5889.9 5891.9 3498.7 4183.3 4228.0 4310.2 4473.8 4279.4 4356.5 4432.5 4441.0 4457.3 4402.9 4406.0 4426.4 4428.8 4444.5 4150.4 4173.2 4395.5 4426.9 4467.1 3217.8 3800.8 3824.3 3832.5 3843.4 5359.7 5646.8 5675.6 5691.3 5706.0 4338.8 4879.6 5144.3 5154.3 5163.8 5525.0 6179.7 6233.6 6234.6 6245.3 3694.1 4220.1 4296.2 4297.4 4305.9 5263.9 6320.3 6564.6 6575.2 6679.5 3953.5 4166.7 4232.7 4395.3 4402.9 3815.6 4028.9 4301.2 4499.4 4501.9 4852.9 5169.1 5185.0 5229.9 5230.5 5097.4 6333.3 6376.9 6400.0 6402.8 1300.6 1336.6 1410.2 1526.8 1552.4 961.3 991.8 1024.7 1046.7 1047.8 5101.2 5174.8 5637.6 5646.2 5738.2 5781.8 6218.1 6369.2 6435.8 6646.2 5185.8 5219.9 5688.5 5834.7 5893.7 2579.1 3151.8 3406.5 3428.9 3441.7 5724.3 6754.5 6822.4 6855.1 6875.2 6591.7 6716.7 6754.6 6771.3 6772.7 5321.0 6594.5 6784.3 6813.9 6821.3 5680.0 6186.0 6500.3 6542.6 6546.0 6408.9 6411.3 6438.1 6460.0 6464.6 6364.4 6811.9 6874.1 6874.7 6880.7 3999.8 4222.3 4230.5 4244.4 4245.0 6351.9 6500.0 6504.9 6538.1 6546.3 5583.9 6250.5 6325.1 6366.7 6394.0 5866.8 5935.9 6311.8 6313.8 6346.9 5902.9 5936.5 6075.0 6147.9 6208.8 6183.3 6217.0 6223.5 6224.6 6246.4 5903.3 6113.8 6146.1 6200.8 6204.7 23:17:54 INFO - PROCESS | 1661 | 23:17:54 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17628.15 Median:585.31 stddev:25007.05 (4272.4%) stddev-sans-first:25044.89 23:17:54 INFO - PROCESS | 1661 | Values: 697.4 747.0 764.2 766.3 777.6 14468.4 14865.9 14908.0 14918.0 14962.0 12534.2 12557.2 12782.9 12789.5 12800.6 721.7 741.6 815.9 909.4 949.3 922.0 924.7 938.6 940.1 940.7 988.4 1004.3 1005.3 1006.8 1007.1 758.4 965.8 984.8 998.2 1003.7 746.7 747.7 748.9 749.2 749.8 14424.9 14501.9 14503.9 14524.0 14558.1 12289.0 12426.0 12616.9 12899.0 12963.9 9829.7 9850.1 9898.0 10045.1 10176.2 3078.0 3443.3 3493.2 3527.8 3596.2 78577.2 78801.6 79203.0 80171.6 80202.8 9351.8 9459.9 9549.7 9760.5 9760.9 21615.1 21637.4 21671.7 21712.2 21742.1 7332.7 7336.7 7561.1 7610.4 7850.3 4106.9 4127.7 4133.6 4153.9 4177.9 3124.9 3520.8 3630.6 3658.8 3666.9 556.2 650.1 666.2 673.5 688.3 551.6 591.2 595.1 598.8 607.5 7313.6 7650.8 7695.5 8058.4 8060.6 530.8 570.5 585.2 585.4 598.9 3608.9 3630.6 3658.2 3700.7 3721.4 96346.6 96496.0 98153.0 98171.0 99493.0 64756.0 73586.6 81056.0 82570.8 82676.2 59486.4 60178.0 61325.0 64177.0 68285.8 7122.9 7780.5 7906.6 8025.7 8299.2 39824.2 41071.0 41361.4 41982.0 42570.4 44623.0 62677.1 62934.4 63867.0 64257.7 9219.4 10655.1 11103.9 11121.7 11312.7 11359.7 11644.1 11752.6 11754.5 12271.3 11027.0 11159.8 11178.1 11439.8 11572.7 11030.7 11317.3 11318.2 11425.9 11682.7 11166.3 11189.2 11344.0 11355.3 11558.1 9547.7 9627.7 9631.2 9685.2 9726.7 9364.2 10193.0 10196.7 10218.9 10243.8 23:17:54 INFO - PROCESS | 1661 | -------- Summary: end -------- 23:17:54 INFO - PROCESS | 1661 | 23:17:55 INFO - TEST-INFO | 1661: exit 0 23:17:55 INFO - TEST-OK | dromaeo_css | took 1114424ms 23:17:55 INFO - TEST-START | kraken 23:17:55 INFO - operating with platform_type : mac_ 23:17:55 INFO - Initialising browser for kraken test... 23:17:55 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpgviYG2/profile http://localhost:49270/getInfo.html) 23:17:57 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 23:17:57 INFO - PROCESS | 1670 | colorDepth:24 23:17:57 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 23:17:57 INFO - PROCESS | 1670 | __metrics 23:17:57 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:17:57 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:17:58 INFO - TEST-INFO | 1670: exit 0 23:17:58 INFO - Browser initialized. 23:17:58 INFO - Running cycle 1/1 for kraken test... 23:17:58 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpgviYG2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:09 INFO - PROCESS | 1672 | RSS: Main: 237768704 23:18:09 INFO - PROCESS | 1672 | 23:18:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:13 INFO - PROCESS | 1672 | RSS: Main: 285679616 23:18:13 INFO - PROCESS | 1672 | 23:18:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 23:18:16 INFO - PROCESS | 1672 | RSS: Main: 300539904 23:18:16 INFO - PROCESS | 1672 | 23:18:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 23:18:21 INFO - PROCESS | 1672 | RSS: Main: 385310720 23:18:21 INFO - PROCESS | 1672 | 23:18:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 23:18:23 INFO - PROCESS | 1672 | RSS: Main: 286601216 23:18:23 INFO - PROCESS | 1672 | 23:18:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:18:26 INFO - PROCESS | 1672 | RSS: Main: 290582528 23:18:26 INFO - PROCESS | 1672 | 23:18:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 23:18:29 INFO - PROCESS | 1672 | RSS: Main: 330211328 23:18:29 INFO - PROCESS | 1672 | 23:18:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 23:18:33 INFO - PROCESS | 1672 | RSS: Main: 329981952 23:18:33 INFO - PROCESS | 1672 | 23:18:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 23:18:36 INFO - PROCESS | 1672 | RSS: Main: 326680576 23:18:36 INFO - PROCESS | 1672 | 23:18:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:18:38 INFO - PROCESS | 1672 | RSS: Main: 319803392 23:18:38 INFO - PROCESS | 1672 | 23:18:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:18:41 INFO - PROCESS | 1672 | RSS: Main: 326737920 23:18:41 INFO - PROCESS | 1672 | 23:18:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:18:43 INFO - PROCESS | 1672 | RSS: Main: 329871360 23:18:43 INFO - PROCESS | 1672 | 23:18:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:18:47 INFO - PROCESS | 1672 | RSS: Main: 334659584 23:18:47 INFO - PROCESS | 1672 | 23:18:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:18:50 INFO - PROCESS | 1672 | RSS: Main: 332165120 23:18:50 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 23:18:52 INFO - PROCESS | 1672 | RSS: Main: 396804096 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | __start_tp_report 23:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 23:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 23:18:52 INFO - PROCESS | 1672 | |i|pagename|runs| 23:18:52 INFO - PROCESS | 1672 | |0;ai-astar;112;109;110;122;157;160;118;110;110;111 23:18:52 INFO - PROCESS | 1672 | |1;audio-beat-detection;165;149;173;167;119;140;129;121;173;122 23:18:52 INFO - PROCESS | 1672 | |2;audio-dft;187;186;185;187;186;183;181;186;183;184 23:18:52 INFO - PROCESS | 1672 | |3;audio-fft;76;76;87;91;56;57;60;78;84;55 23:18:52 INFO - PROCESS | 1672 | |4;audio-oscillator;90;98;138;78;78;76;125;80;77;126 23:18:52 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;86;85;87;84;87;83;87;85;86;84 23:18:52 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;92;92;93;92;92;93;91;93;93 23:18:52 INFO - PROCESS | 1672 | |7;imaging-desaturate;87;86;84;84;85;84;86;85;85;85 23:18:52 INFO - PROCESS | 1672 | |8;json-parse-financial;59;61;60;57;58;58;60;77;59;59 23:18:52 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;62;71;74;72;67;67;67;66;67 23:18:52 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;84;82;82;82;81;81;80;94;102;87 23:18:52 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;141;140;139;137;139;137;132;149;192;140 23:18:52 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;172;174;170;168;198;212;169;169;167 23:18:52 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;71;69;69;68;68;67;68;66;69 23:18:52 INFO - PROCESS | 1672 | __end_tp_report 23:18:52 INFO - PROCESS | 1672 | __start_cc_report 23:18:52 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1952 23:18:52 INFO - PROCESS | 1672 | __end_cc_report 23:18:52 INFO - PROCESS | 1672 | __startTimestamp1461133132831__endTimestamp 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | ------- Summary: start ------- 23:18:52 INFO - PROCESS | 1672 | Number of tests: 14 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:121.90 Median:115.00 stddev:19.74 (17.2%) stddev-sans-first:20.61 23:18:52 INFO - PROCESS | 1672 | Values: 112.0 109.0 110.0 122.0 157.0 160.0 118.0 110.0 110.0 111.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:157.00 stddev:22.42 (14.3%) stddev-sans-first:22.68 23:18:52 INFO - PROCESS | 1672 | Values: 165.0 149.0 173.0 167.0 119.0 140.0 129.0 121.0 173.0 122.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:1.99 (1.1%) stddev-sans-first:1.94 23:18:52 INFO - PROCESS | 1672 | Values: 187.0 186.0 185.0 187.0 186.0 183.0 181.0 186.0 183.0 184.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:72.00 Median:77.00 stddev:13.79 (17.9%) stddev-sans-first:14.55 23:18:52 INFO - PROCESS | 1672 | Values: 76.0 76.0 87.0 91.0 56.0 57.0 60.0 78.0 84.0 55.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:78.00 stddev:24.05 (30.8%) stddev-sans-first:25.39 23:18:52 INFO - PROCESS | 1672 | Values: 90.0 98.0 138.0 78.0 78.0 76.0 125.0 80.0 77.0 126.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.50 23:18:52 INFO - PROCESS | 1672 | Values: 86.0 85.0 87.0 84.0 87.0 83.0 87.0 85.0 86.0 84.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 23:18:52 INFO - PROCESS | 1672 | Values: 93.0 92.0 92.0 93.0 92.0 92.0 93.0 91.0 93.0 93.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:0.78 23:18:52 INFO - PROCESS | 1672 | Values: 87.0 86.0 84.0 84.0 85.0 84.0 86.0 85.0 85.0 85.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:59.50 stddev:5.81 (9.8%) stddev-sans-first:6.12 23:18:52 INFO - PROCESS | 1672 | Values: 59.0 61.0 60.0 57.0 58.0 58.0 60.0 77.0 59.0 59.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:67.00 stddev:3.55 (5.3%) stddev-sans-first:3.62 23:18:52 INFO - PROCESS | 1672 | Values: 65.0 62.0 71.0 74.0 72.0 67.0 67.0 67.0 66.0 67.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:82.00 stddev:7.12 (8.7%) stddev-sans-first:7.53 23:18:52 INFO - PROCESS | 1672 | Values: 84.0 82.0 82.0 82.0 81.0 81.0 80.0 94.0 102.0 87.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:140.00 stddev:17.19 (12.3%) stddev-sans-first:18.18 23:18:52 INFO - PROCESS | 1672 | Values: 141.0 140.0 139.0 137.0 139.0 137.0 132.0 149.0 192.0 140.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:171.00 stddev:15.41 (9.0%) stddev-sans-first:16.02 23:18:52 INFO - PROCESS | 1672 | Values: 168.0 172.0 174.0 170.0 168.0 198.0 212.0 169.0 169.0 167.0 23:18:52 INFO - PROCESS | 1672 | 23:18:52 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.40 (2.0%) stddev-sans-first:1.41 23:18:52 INFO - PROCESS | 1672 | Values: 67.0 71.0 69.0 69.0 68.0 68.0 67.0 68.0 66.0 69.0 23:18:52 INFO - PROCESS | 1672 | -------- Summary: end -------- 23:18:52 INFO - PROCESS | 1672 | 23:18:53 INFO - TEST-INFO | 1672: exit 0 23:18:53 INFO - TEST-OK | kraken | took 58531ms 23:18:53 INFO - SUITE-END | took 1172s 23:18:53 INFO - Completed test suite (00:19:33) 23:18:53 INFO - javascript benchmark 23:18:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2941.617589713337, 3388.271521622851, 3484.1638006878343, 3499.8600055997754, 3542.2207114434814, 4870.951290487094, 5559.722013899309, 5755.827325180241, 5755.913661295079, 5756.453136951994, 4804.783784729687, 5703.087505999041, 5735.770569177228, 5869.50109240714, 5893.263342082239, 5111.69329840209, 5155.922661160085, 5165.586753059746, 5177.922331165036, 5262.079136151158, 5247.819240670838, 5281.788728450857, 5298.178782288752, 5312.096943519611, 5325.0, 5136.537711605936, 5157.48425157485, 5217.921731174036, 5239.13554263546, 5240.9213861792105, 5195.090859099642, 5196.45437229093, 5199.14214154664, 5213.191955246943, 5225.425203227642, 5139.383274007143, 5181.5077567631015, 5211.895762084737, 5215.217717342391, 5227.79088836446, 5586.972065139689, 5660.3490598581275, 5705.229793977802, 5787.102999035156, 5798.9420105799245, 6115.713144167403, 6127.908081378783, 6157.322694503601, 6158.107074474221, 6169.29053158888, 5245.186996015624, 6183.134361189437, 6185.41238582334, 6189.319174890758, 6205.317415084337, 5497.945020549783, 5580.051391263495, 5586.664800112007, 5587.469190426903, 5863.912041319383, 6259.155014073133, 6268.2478102627065, 6270.467010304175, 6275.3724627536885, 6275.654838983808, 6131.96934015327, 6150.938490615126, 6152.2002139722445, 6159.876802463926, 6166.691665416801, 6119.602225855305, 6164.260288765377, 6205.503559715212, 6217.347178546278, 6235.844103897348, 6114.449440885012, 6164.907526387108, 6202.162708034448, 6234.719437625317, 6235.314115447297, 6173.290071641729, 6185.474234690012, 6197.442230199304, 6211.254649442096, 6222.253329600477, 4860.854174374775, 4911.189653707098, 4937.136001199848, 4955.4796746341835, 4980.377452818398, 4466.084452687256, 4506.752128632891, 4508.07584445188, 4509.954900450954, 4515.2324104902245, 5772.278465191851, 5773.5958482906635, 5783.421657834267, 5790.42095790426, 5792.247007889032, 6160.414760597736, 6185.84535386619, 6259.71831267594, 6260.655663938436, 6270.31026587075, 5764.971175144097, 5779.566532510079, 5835.737391817378, 5844.211031510777, 5860.2967643883, 5336.252924590561, 5416.350037995466, 5425.969065877471, 5434.266374039454, 5438.945610543923, 5580.581456390706, 6236.719347629368, 6286.937130628727, 6310.211223597051, 6331.746730130835, 5202.687838729688, 6026.7287972040485, 6230.345813689497, 6254.093156492374, 6255.0, 6207.689615519296, 6292.087647291209, 6370.394812492806, 6371.617702937839, 6374.968125159345, 6116.579253789288, 6213.813585592439, 6222.408871157233, 6223.844403889939, 6243.313235543996, 6217.4715149212825, 6233.75064997395, 6238.189035425366, 6246.063090536456, 6246.187995560548, 4470.821167153407, 5341.717987683006, 5996.0, 6043.0, 6099.11562823397, 6106.430331452303, 6108.85814249354, 6127.69183955469, 6128.86980888409, 6149.323574406902, 5975.820725378246, 5978.8206353809455, 5986.2217911671205, 5993.0, 5995.400459954144, 5770.480656740913, 5896.734646940812, 5927.851803704942, 5959.642421454727, 6139.079138129317, 5954.434328738763, 5956.463918247378, 5967.791127310524, 5973.104034394702, 5973.522118230444], "value": 5665.420673123947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.59260646933933, 500.9499050094991, 524.7795925711201, 535.5153586004667, 583.5535815101447, 26842.865785671067, 30272.0, 37469.87590372287, 38328.0, 38374.616253837456, 42712.572874271245, 43539.564604353945, 43554.78222608883, 43562.56437435633, 43588.564114358844, 9585.712428627136, 9765.902340976605, 9773.0, 9778.364406313602, 9781.755456113582, 8005.279524842792, 9652.565634546427, 9656.662016829412, 9666.951665241699, 9675.612975480972, 9636.0, 9675.129238368545, 9685.41887486753, 9686.806263874718, 9704.466254356008, 9602.951985240064, 9661.90338096617, 9673.03269673034, 9677.661281855102, 9677.806443871083, 9585.137337639646, 9673.322867399285, 9675.467849268287, 9680.273979451498, 9682.370645907993, 39587.8020609898, 40014.0, 40038.79980600107, 40046.39930401046, 40160.0, 3022.758179345647, 3125.718685318332, 3189.1389324882384, 3437.7937323760652, 3799.5060642116623, 26642.33394165142, 28250.717492825013, 28265.85867070672, 28294.717052829415, 28309.15072547827, 2629.9211023669322, 2642.735726427361, 2752.0092766604007, 2787.5957986092108, 2796.832190068582, 381399.0, 381997.0, 385764.0, 385843.0, 386115.0, 61059.08411373833, 61384.693076534335, 61997.0, 62018.37981620126, 62029.0, 65220.673896630215, 65249.0, 65364.67317663381, 65416.0, 65562.34437655658, 17140.571485712957, 17406.95558266508, 17410.39063632767, 17413.0, 17454.389096381565, 13293.601191964257, 13336.599902002956, 13339.533116340881, 13352.933235333761, 13353.599392018255, 18574.907125464557, 19274.310595065148, 19507.062970885512, 19563.263838964693, 19597.216111355672, 6521.771737989147, 6704.812791578588, 8015.559144247122, 8226.629801658939, 8344.874826877602, 923.5890028937193, 942.8020115775627, 963.9469829159324, 1005.6166884483674, 1043.1029314789275, 23884.88057559701, 27745.0, 28369.95736306991, 28559.14322570326, 29234.853825730734, 923.5105394141116, 1046.874375074996, 1064.6912395405245, 1196.342011893455, 1262.4634530324759, 14940.850591494163, 14991.700165996837, 15147.697046059016, 15426.382944682087, 15750.0, 765094.0, 785887.0, 787215.0639246767, 787311.0, 788081.0, 57899.0, 59868.70065649644, 60005.69997149986, 60026.09960850591, 60068.0, 1320.42561485754, 1369.897257705676, 1513.5913303408017, 1522.4975757999835, 1550.1241798383876, 27907.302317442227, 28035.859820700767, 28711.856440717664, 28809.423811523233, 29042.0, 47713.76143119262, 47766.0, 48723.0, 48750.512494874594, 48941.0, 337641.0, 348591.0, 362301.1884940558, 365895.1705241563, 367689.0, 23402.946867390667, 26202.0, 27162.864185678944, 27200.455990879676, 27270.863645681646, 40837.38743918784, 44188.11623767571, 45910.0, 45911.54088459073, 46462.0, 34447.483287750205, 34774.13064673302, 34837.65162348344, 35642.0, 35711.10722231965, 34575.82712086423, 34690.57165998457, 35077.824610876785, 35499.29001420009, 35544.644553554135, 34257.629694812924, 34475.31049378948, 34597.13507162341, 34994.650053500154, 36430.4535431974, 29254.41491170122, 32042.679573204903, 32290.35419291554, 32307.838460807547, 32325.35349293048, 23579.29262122139, 23727.52544949126, 23782.16762413307, 24231.0, 24268.878655606608], "value": 19308.77862920725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.1678292368948, 399.62834563855614, 401.760952233421, 403.7799399327366, 411.08738600307305, 14168.37782340862, 17894.463166105023, 18950.052497375127, 19185.808141918576, 19995.0, 15775.0, 17325.0, 19854.900725496387, 20049.298274560395, 20725.896370518163, 5870.442307980746, 6072.726727297282, 6083.026715725485, 6091.847703807396, 6094.8171554853425, 6006.42938920804, 6013.819585412444, 6056.969715151418, 6077.11881777142, 6131.9386806131815, 5883.499902508292, 6029.366916473773, 6051.092336149569, 6052.515798736113, 6067.453929146376, 5969.343372229051, 5987.401259873999, 6044.365341639109, 6051.425114614105, 6071.574989750719, 5031.949680503185, 5151.7681704323395, 5221.634485586011, 5226.92159617606, 5231.973840130775, 25583.360415989748, 25646.87176564105, 25951.74048259512, 26311.684415779135, 26352.86823565889, 2246.6854640350366, 2340.0873659272897, 2352.5882970480097, 2513.8743062846784, 2607.8565678887653, 12387.256764594153, 14496.057756245811, 14512.782308265385, 14772.55682329532, 14989.700205995929, 1694.5424735321517, 1756.323815331094, 1775.3519965212604, 1784.2238626197645, 1883.7567205644345, 97087.0, 100011.9994000297, 101105.0, 102222.48888755582, 103633.4818325911, 10379.636712715019, 10483.318584291997, 10832.350058996486, 10869.0, 11045.171612129123, 13718.314084295745, 13786.65533361674, 13795.965302602344, 13825.308734563232, 13847.515336963157, 7542.358899493604, 7645.655945482466, 8560.400771946028, 8700.695475658322, 8742.388032837767, 7015.754448594274, 8513.531755753369, 8557.315414766803, 8617.827643447095, 8627.482351058956, 6462.4183823456115, 6557.803265901935, 6563.7702680405955, 6568.014797780371, 6568.507361947777, 1874.240932422371, 1903.6097599991967, 1904.3620387170454, 1904.7523821903064, 1946.347973428889, 932.4871320773548, 940.8682784410188, 1020.2399212586522, 1036.4196050211906, 1045.00724311904, 20635.793642063687, 20958.16167353319, 20966.685499717518, 21086.894565527073, 21525.67711484329, 961.1589858873485, 1000.4347543637822, 1008.1128606825946, 1011.0698157694791, 1024.7540590258434, 10994.450277486094, 11383.203175777782, 11484.081273498101, 11503.884961150448, 11538.0, 617860.0, 622233.0, 636619.2561806772, 640834.0, 642319.0, 76785.0, 106484.46757766316, 108390.45804770925, 110458.89541104647, 111494.0, 1048.6015314180563, 1050.7373156710823, 1069.753956589973, 1072.3673032910465, 1073.8657667791526, 17645.205965731315, 18008.549786255448, 18093.909530452263, 18137.727934080736, 18503.81496185021, 31880.0, 39548.40677389784, 40496.595034050464, 46476.0, 46549.0, 53831.0, 67101.11665928256, 67155.0, 67630.66184669243, 68356.6582167086, 8013.519188848688, 8076.0, 8232.794180145544, 8233.46482478637, 8469.872951905603, 12640.0, 12714.11001229905, 12953.611391658265, 13095.0, 13199.472021119047, 8955.552222388724, 10689.465526723789, 11396.772064558496, 11409.372484513431, 11501.309921404743, 10908.836367454653, 10962.342259464458, 11073.5570577176, 11237.606683766357, 11289.604863829727, 11225.66323010311, 11370.658880233606, 11572.826407604061, 11704.239224450383, 11831.290122592673, 6599.274079851309, 7566.940628312151, 7644.847103058019, 8108.9594552026865, 8363.832723345377, 9075.364724469224, 11157.49791259379, 11202.607908723481, 11485.425728713699, 11542.19204655666], "value": 9538.402974503542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.9928050359747, 1542.4909779772672, 1596.489123480486, 1903.0, 2034.8982550872452, 7884.645190966406, 8561.61472733727, 8809.691660791857, 8812.0, 8824.867626985584, 8491.74524764258, 8692.826143477127, 8697.608607612658, 8699.652013919436, 8701.608427620757, 2146.8711877287333, 2423.0913407472153, 2441.9877900610472, 2452.5217582571404, 2462.113639089935, 2392.0790495659207, 2432.0879670123704, 2441.218809980807, 2441.2676197140877, 2445.327534927891, 2406.57884870147, 2427.0291883246664, 2436.744141865105, 2443.1815341860497, 2443.2792326263707, 2167.024838822528, 2334.6147885599025, 2417.685700858886, 2421.4672771990136, 2435.1233555919853, 2361.7047869016374, 2403.966294493367, 2414.5774489464275, 2414.9758502414925, 2417.697787776528, 7307.561546307238, 8410.201030902055, 8623.137686231326, 8665.393422460367, 8692.391532592721, 4377.934330985037, 5402.486763757437, 5403.891922161535, 5429.484199001088, 5445.101558242885, 3454.7236221102503, 3458.3774920514297, 3522.8590856365713, 3553.147244661263, 3573.3746594345885, 1033.2818691009752, 1046.3094357723942, 1047.1465755409317, 1057.0644979193391, 1060.3319908457622, 11351.545938162464, 11563.248388854698, 11569.595064172798, 11577.363245021521, 11592.826107608393, 4404.207242696345, 4420.668449866273, 4615.053913947632, 4619.445666519972, 4655.162070827216, 9158.450492970444, 9158.90841091594, 9164.495952722531, 9175.311851611023, 9178.0, 5262.289590905179, 5272.340957380327, 5299.2845965794895, 5299.629025968223, 5299.814506492255, 4042.8787136385954, 4422.646188304928, 4553.180427523078, 4817.27740838877, 4949.925751113737, 3323.5347051412823, 3799.088218827517, 3817.6256547642824, 3845.65389114981, 3916.7649941003633, 1201.5193922431097, 1208.4501551794087, 1208.697825543614, 1218.317742064429, 1218.9085818563642, 659.7229163751236, 788.8501184774874, 873.5195642396657, 917.6726470982538, 930.0559931669361, 5504.009278329859, 5685.090385538294, 5702.429757024347, 5769.365369809318, 5860.619059761102, 913.57975331347, 915.2677857713802, 948.9762755931157, 970.3644113105927, 1017.8167929772712, 3287.112479630486, 3533.7278579711283, 3630.945535816965, 3792.677622402127, 3858.3826587745825, 10215.591376344863, 10622.94688526568, 10705.892941070646, 10710.946445267724, 10715.089217416451, 10250.436226007641, 10696.251262411712, 10820.350778953289, 10842.783144337069, 10888.61889833852, 8131.878021829677, 8175.427720059658, 8255.504669719716, 8302.252797248277, 8302.54336011525, 5515.972420137874, 5634.6337488063145, 5684.232628595087, 5740.13897915316, 5769.192313076257, 9849.704508864745, 9894.356866803635, 9906.554205060645, 10002.399856008662, 10004.399736015863, 9502.809943801223, 9736.41581505112, 10040.497975101069, 10086.495675216356, 10096.242781791541, 7555.16893141743, 7622.58075805836, 7733.613319333898, 7744.5740484271655, 7747.2640099190485, 8092.150324216108, 8254.215849494289, 8325.625346859284, 8353.289970352587, 8368.748937531884, 7436.405087593088, 7456.515326503977, 7505.174430812715, 7574.091109066947, 7632.542047476947, 6994.2656021117045, 7005.264447233171, 7082.185548661764, 7140.000399943908, 7188.38898693596, 6795.490338224554, 6849.212340580897, 6854.657267136723, 6862.142232220973, 6867.759628412981, 4599.747013914267, 4671.462781780003, 4768.998510312873, 4800.3759511263315, 4865.051314993542, 4784.47370789206, 4868.196747536616, 4880.194767863405, 4907.484714104968, 4942.413395437986], "value": 4521.2536923499165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.741041051543, 322.8176080514509, 329.71150243536914, 335.1203091883805, 338.7764075710032, 4839.685420447673, 5423.430539793324, 5879.470847623713, 5935.287765468139, 5964.821055368335, 4633.791479383427, 5833.708314584275, 5840.853978650536, 5889.85275368115, 5891.911621325673, 3498.702610278129, 4183.330667093266, 4227.957720422787, 4310.2026125166785, 4473.7986790594505, 4279.443672322609, 4356.518783613571, 4432.468103827545, 4440.97779511102, 4457.264551349023, 4402.933955990663, 4406.00391733621, 4426.380306757057, 4428.778561071949, 4444.488883778375, 4150.398192262121, 4173.207090652766, 4395.4725432947935, 4426.867193984185, 4467.084247729208, 3217.8391080445886, 3800.7909564973916, 3824.3116239076953, 3832.4826148470156, 3843.385058390644, 5359.705216213107, 5646.830595082113, 5675.574331925122, 5691.288588926384, 5705.972924873521, 4338.804753786056, 4879.63402744792, 5144.254083157921, 5154.30416893722, 5163.845084647466, 5525.0, 6179.69101544921, 6233.563650544464, 6234.625922444668, 6245.3442388549, 3694.11341278094, 4220.050488640031, 4296.226679197744, 4297.4413326267695, 4305.921266294583, 5263.894722105575, 6320.27316858558, 6564.573302735354, 6575.178102737158, 6679.499037572153, 3953.486046813953, 4166.687498437629, 4232.703710740218, 4395.340698895191, 4402.8679139625865, 3815.550090965471, 4028.9194216115516, 4301.161273551627, 4499.4150760400935, 4501.864944051684, 4852.902941941142, 5169.06956747789, 5185.0, 5229.946791236822, 5230.541645426643, 5097.413797413271, 6333.2716737575765, 6376.939069246695, 6400.0, 6402.839929001719, 1300.5903140510898, 1336.565616174747, 1410.2243765928904, 1526.7633516804913, 1552.410084168009, 961.2598299309493, 991.8363470027365, 1024.6669832304383, 1046.7121541576128, 1047.7747284333905, 5101.20931255656, 5174.7930082796265, 5637.577181711388, 5646.237757902631, 5738.225339579187, 5781.768729250867, 6218.067289906459, 6369.20384951881, 6435.774747883802, 6646.2356828965285, 5185.781341384772, 5219.869503262373, 5688.516476099495, 5834.708264586754, 5893.734781934823, 2579.1102069785893, 3151.75505675257, 3406.5060566218253, 3428.851334802869, 3441.711262010735, 5724.255846739898, 6754.527183097234, 6822.4200942919415, 6855.108835851307, 6875.1749790025515, 6591.714615649902, 6716.664166791641, 6754.635877442855, 6771.289014653489, 6772.72909083641, 5320.973395133077, 6594.538382313384, 6784.321567843176, 6813.897791533131, 6821.283765204681, 5680.0, 6186.0, 6500.349965003462, 6542.574732642363, 6545.967270163809, 6408.871822563429, 6411.30100523371, 6438.100760275636, 6459.967700161469, 6464.644444555406, 6364.363563643598, 6811.876040453468, 6874.140732408449, 6874.690638921361, 6880.724771009103, 3999.7600143991453, 4222.282212024024, 4230.534641189405, 4244.405783190417, 4244.978775106105, 6351.936480635134, 6499.960006398954, 6504.869902602016, 6538.084668146558, 6546.279909210079, 5583.944160558342, 6250.468710159687, 6325.146105275729, 6366.745330186741, 6393.968030159819, 5866.823995280149, 5935.881282374415, 6311.842203944754, 6313.842153946188, 6346.921742954571, 5902.940970590411, 5936.46571808522, 6074.969625151846, 6147.877042459215, 6208.782692605737, 6183.290025984374, 6217.0, 6223.533235007483, 6224.564280500322, 6246.406591373812, 5903.321118071477, 6113.786017489367, 6146.139540464428, 6200.844978875564, 6204.658743769136], "value": 4564.969083356159, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.4176562570254, 746.9701211951523, 764.2433990349556, 766.2796970847403, 777.5645638442471, 14468.421263149474, 14865.851341486581, 14908.0, 14918.0, 14961.95266331356, 12534.2479451233, 12557.183783054095, 12782.936085319561, 12789.488420463194, 12800.551980680679, 721.6716394040725, 741.6217728958243, 815.898012748405, 909.3861643390718, 949.2548349545608, 921.9677311294106, 924.685606893656, 938.629241449627, 940.111594543157, 940.7036783413237, 988.3575675810708, 1004.3271008424373, 1005.2561104782482, 1006.7583779892809, 1007.0734923870057, 758.3781299334576, 965.8454647256406, 984.7784248544092, 998.2313618513776, 1003.703907347327, 746.6565379925241, 747.700919632146, 748.8652042632324, 749.1946157880301, 749.8013026547969, 14424.855751442456, 14501.927490362586, 14503.92748036253, 14524.0, 14558.053726507744, 12289.016878649776, 12426.0, 12616.873831261662, 12899.0, 12963.87036129636, 9829.655962041366, 9850.113489785954, 9897.950510247474, 10045.09594136524, 10176.236782241362, 3077.9692203077907, 3443.345764304799, 3493.179102910814, 3527.8236088195745, 3596.2447885943984, 78577.21422785813, 78801.60599197025, 79203.0, 80171.5991420045, 80202.79695804567, 9351.766205844771, 9459.90540094604, 9549.665761698447, 9760.463174525326, 9760.902390976142, 21615.135394584042, 21637.35087947364, 21671.67492487614, 21712.240071597418, 21742.13031478723, 7332.743353982692, 7336.7065317386705, 7561.130469995911, 7610.429217808686, 7850.2542258485355, 4106.91786164281, 4127.669786417079, 4133.627973482401, 4153.937690934638, 4177.895552611149, 3124.937521242743, 3520.8415621297104, 3630.636936306401, 3658.835352409095, 3666.944995825065, 556.2074044486591, 650.1190886349024, 666.2371584535776, 673.5352606701458, 688.3013741052836, 551.6221388348994, 591.249113625693, 595.1370512756521, 598.7994022002678, 607.4509999501254, 7313.634318284065, 7650.846983060308, 7695.461317707706, 8058.395620328499, 8060.556669383241, 530.8301343570021, 570.5122120586922, 585.2304219950751, 585.3941170888111, 598.9341172471024, 3608.8556457741397, 3630.636936306401, 3658.176910195185, 3700.666939975415, 3721.3859713147026, 96346.55480167804, 96496.0, 98153.0, 98171.0, 99493.0, 64756.0, 73586.63206683932, 81056.0, 82570.76143857847, 82676.17323826684, 59486.40513594809, 60177.99999999913, 61325.0, 64177.0, 68285.80570331591, 7122.85754284901, 7780.533168009937, 7906.5651389174145, 8025.719099831478, 8299.211574900375, 39824.2035159301, 41071.0, 41361.379579305736, 41982.0, 42570.36144457898, 44623.0, 62677.11968640948, 62934.37065629285, 63867.0, 64257.678711606146, 9219.400738951947, 10655.149739972292, 11103.888961110286, 11121.721956951142, 11312.717182070184, 11359.716007099889, 11644.08495444596, 11752.647420577396, 11754.471048802654, 12271.263724176579, 11027.0, 11159.832602510807, 11178.10575154002, 11439.828402573801, 11572.6528204154, 11030.724231893946, 11317.264377815416, 11318.207725459139, 11425.885741142814, 11682.70793230142, 11166.274192177483, 11189.160812939224, 11344.035756960751, 11355.318680879174, 11558.075353971493, 9547.713568592953, 9627.663031793854, 9631.181349585364, 9685.17675997548, 9726.659566915125, 9364.157225849705, 10193.0, 10196.745081373025, 10218.948905255425, 10243.795124097625], "value": 6788.739702892263, "unit": "score"}], "name": "dromaeo_css", "value": 7258.243864308342, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 110.0, 122.0, 157.0, 160.0, 118.0, 110.0, 110.0, 111.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 149.0, 173.0, 167.0, 119.0, 140.0, 129.0, 121.0, 173.0, 122.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 185.0, 187.0, 186.0, 183.0, 181.0, 186.0, 183.0, 184.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 87.0, 91.0, 56.0, 57.0, 60.0, 78.0, 84.0, 55.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 138.0, 78.0, 78.0, 76.0, 125.0, 80.0, 77.0, 126.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 87.0, 83.0, 87.0, 85.0, 86.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 93.0, 91.0, 93.0, 93.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 84.0, 85.0, 84.0, 86.0, 85.0, 85.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 57.0, 58.0, 58.0, 60.0, 77.0, 59.0, 59.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 71.0, 74.0, 72.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 82.0, 81.0, 81.0, 80.0, 94.0, 102.0, 87.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 139.0, 137.0, 139.0, 137.0, 132.0, 149.0, 192.0, 140.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 172.0, 174.0, 170.0, 168.0, 198.0, 212.0, 169.0, 169.0, 167.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 69.0, 69.0, 68.0, 68.0, 67.0, 68.0, 66.0, 69.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1487.6, "alertThreshold": 2.0}]} 23:18:54 INFO - Return code: 0 23:18:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:18:54 ERROR - # TBPL SUCCESS # 23:18:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:18:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:18:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:18:54 INFO - 2016-04-19 23:18:54.154 screenresolution[1676:9556] starting screenresolution argv=screenresolution get 23:18:54 INFO - 2016-04-19 23:18:54.157 screenresolution[1676:9556] Display 0: 1600x1200x32@60 23:18:54 INFO - 2016-04-19 23:18:54.162 screenresolution[1677:9559] starting screenresolution argv=screenresolution list 23:18:54 INFO - Available Modes on Display 0 23:18:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:18:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:18:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:18:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:18:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:18:54 INFO - Intel Iris: 23:18:54 INFO - Chipset Model: Intel Iris 23:18:54 INFO - Type: GPU 23:18:54 INFO - Bus: Built-In 23:18:54 INFO - VRAM (Dynamic, Max): 1536 MB 23:18:54 INFO - Vendor: Intel (0x8086) 23:18:54 INFO - Device ID: 0x0a2e 23:18:54 INFO - Revision ID: 0x0009 23:18:54 INFO - Displays: 23:18:54 INFO - Display: 23:18:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:18:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:18:54 INFO - Display Serial Number: 1600x1200 60 23:18:54 INFO - Main Display: Yes 23:18:54 INFO - Mirror: Off 23:18:54 INFO - Online: Yes 23:18:54 INFO - Rotation: Supported 23:18:54 INFO - Return code: 0 23:18:54 INFO - Running post-action listener: _resource_record_post_action 23:18:54 INFO - Running post-run listener: _resource_record_post_run 23:18:55 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37217792; Write bytes: 694162432; Read time: 388; Write time: 3476 23:18:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379767296; Write bytes: 374171648; Read time: 28447; Write time: 755 23:18:55 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 36263424; Write bytes: 296901632; Read time: 374; Write time: 2678 23:18:55 INFO - Running post-run listener: _upload_blobber_files 23:18:55 INFO - Blob upload gear active. 23:18:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:18:55 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:18:55 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:18:55 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:18:55 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:18:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:56 INFO - (blobuploader) - INFO - Done attempting. 23:18:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:18:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:57 INFO - (blobuploader) - INFO - Done attempting. 23:18:57 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:57 INFO - Return code: 0 23:18:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:18:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b0db2562c5e29336a27bbc729617216a28f603efbf3ceea92c867010cc5ce42f44f64b48db425009c28fa79cfb5a3cdc4fd8464c2771688001f3a5ed35edbd2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a137ebd47e3b9a71c2a8f38c8d28b76c861744c3119ed29b450858aa6a4a8de49bbb569769734c82c15a6a3103d828613c76909386cd4ccb05f3d71e1bf2e77b"} 23:18:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:18:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:18:57 INFO - Contents: 23:18:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b0db2562c5e29336a27bbc729617216a28f603efbf3ceea92c867010cc5ce42f44f64b48db425009c28fa79cfb5a3cdc4fd8464c2771688001f3a5ed35edbd2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a137ebd47e3b9a71c2a8f38c8d28b76c861744c3119ed29b450858aa6a4a8de49bbb569769734c82c15a6a3103d828613c76909386cd4ccb05f3d71e1bf2e77b"} 23:18:57 INFO - Running post-run listener: copy_logs_to_upload_dir 23:18:57 INFO - Copying logs to upload dir... 23:18:57 INFO - mkdir: /builds/slave/test/build/upload/logs 23:18:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.879284 ========= master_lag: 3.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 23:19:00.988463) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 23:19:00.996162) ========= 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.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b0db2562c5e29336a27bbc729617216a28f603efbf3ceea92c867010cc5ce42f44f64b48db425009c28fa79cfb5a3cdc4fd8464c2771688001f3a5ed35edbd2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a137ebd47e3b9a71c2a8f38c8d28b76c861744c3119ed29b450858aa6a4a8de49bbb569769734c82c15a6a3103d828613c76909386cd4ccb05f3d71e1bf2e77b"} build_url:https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010563 build_url: 'https://queue.taskcluster.net/v1/task/FFFBeChfQOmdQ3JAFwcMGw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b0db2562c5e29336a27bbc729617216a28f603efbf3ceea92c867010cc5ce42f44f64b48db425009c28fa79cfb5a3cdc4fd8464c2771688001f3a5ed35edbd2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a137ebd47e3b9a71c2a8f38c8d28b76c861744c3119ed29b450858aa6a4a8de49bbb569769734c82c15a6a3103d828613c76909386cd4ccb05f3d71e1bf2e77b"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 23:19:01.050791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 23:19:01.051163) ========= 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.yezVgyJODK/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.RwIMdoYFcL/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005209 ========= master_lag: 1.33 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 23:19:02.388058) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-19 23:19:02.388390) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-19 23:19:04.280678) ========= ========= Total master_lag: 4.76 =========