builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0069 starttime: 1461070390.89 results: success (0) buildid: 20160419025453 builduid: 9fbd07adeb474b179aadf484958cff5a revision: 759244c242e1c96a2fad70608e4a646aa9a5761c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:10.889957) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:10.890338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:10.890610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:10.907613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:10.907880) ========= 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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:53:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-04-19 05:53:10 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084030 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:11.008044) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:11.008354) ========= 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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.019784 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:11.056018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 05:53:11.056342) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 759244c242e1c96a2fad70608e4a646aa9a5761c --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 759244c242e1c96a2fad70608e4a646aa9a5761c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:53:11,132 truncating revision to first 12 chars 2016-04-19 05:53:11,132 Setting DEBUG logging. 2016-04-19 05:53:11,132 attempt 1/10 2016-04-19 05:53:11,132 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/759244c242e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 05:53:12,507 unpacking tar archive at: fx-team-759244c242e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.621824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 05:53:12.694500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:12.694832) ========= script_repo_revision: 759244c242e1c96a2fad70608e4a646aa9a5761c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:12.695205) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:12.695464) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 05:53:12.708412) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-19 05:53:12.708688) ========= /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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:53:12 INFO - MultiFileLogger online at 20160419 05:53:12 in /builds/slave/test 05:53:12 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 05:53:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:53:12 INFO - {'append_to_log': False, 05:53:12 INFO - 'base_work_dir': '/builds/slave/test', 05:53:12 INFO - 'blob_upload_branch': 'Fx-Team', 05:53:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:53:12 INFO - 'branch': 'Fx-Team', 05:53:12 INFO - 'buildbot_json_path': 'buildprops.json', 05:53:12 INFO - 'config_files': ('talos/mac_config.py',), 05:53:12 INFO - 'default_actions': ('clobber', 05:53:12 INFO - 'read-buildbot-config', 05:53:12 INFO - 'download-and-extract', 05:53:12 INFO - 'populate-webroot', 05:53:12 INFO - 'create-virtualenv', 05:53:12 INFO - 'install', 05:53:12 INFO - 'run-tests'), 05:53:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:53:12 INFO - 'download_minidump_stackwalk': True, 05:53:12 INFO - 'download_symbols': 'ondemand', 05:53:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:53:12 INFO - 'tooltool.py': '/tools/tooltool.py', 05:53:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:53:12 INFO - '/tools/misc-python/virtualenv.py')}, 05:53:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:53:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:53:12 INFO - 'installer_path': 'installer.exe', 05:53:12 INFO - 'log_level': 'info', 05:53:12 INFO - 'log_name': 'talos', 05:53:12 INFO - 'log_to_console': True, 05:53:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:53:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:53:12 INFO - 'opt_config_files': (), 05:53:12 INFO - 'pip_index': False, 05:53:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:12 INFO - 'cmd': ('bash', 05:53:12 INFO - '-c', 05:53:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:12 INFO - 'enabled': True, 05:53:12 INFO - 'halt_on_failure': False, 05:53:12 INFO - 'name': 'check_screen_resolution'},), 05:53:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:53:12 INFO - 'cmd': ('bash', 05:53:12 INFO - '-c', 05:53:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:53:12 INFO - 'enabled': True, 05:53:12 INFO - 'halt_on_failure': False, 05:53:12 INFO - 'name': 'check_screen_resolution'},), 05:53:12 INFO - 'run_cmd_checks_enabled': True, 05:53:12 INFO - 'sps_profile': False, 05:53:12 INFO - 'sps_profile_interval': 0, 05:53:12 INFO - 'suite': 'dromaeojs', 05:53:12 INFO - 'system_bits': '32', 05:53:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:53:12 INFO - 'title': 't-yosemite-r7-0069', 05:53:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:53:12 INFO - 'use_talos_json': True, 05:53:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:53:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:53:12 INFO - 'work_dir': 'build'} 05:53:12 INFO - ##### 05:53:12 INFO - ##### Running clobber step. 05:53:12 INFO - ##### 05:53:12 INFO - Running pre-action listener: _resource_record_pre_action 05:53:12 INFO - Running main action method: clobber 05:53:12 INFO - rmtree: /builds/slave/test/build 05:53:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:53:14 INFO - Running post-action listener: _resource_record_post_action 05:53:14 INFO - ##### 05:53:14 INFO - ##### Running read-buildbot-config step. 05:53:14 INFO - ##### 05:53:14 INFO - Running pre-action listener: _resource_record_pre_action 05:53:14 INFO - Running main action method: read_buildbot_config 05:53:14 INFO - Using buildbot properties: 05:53:14 INFO - { 05:53:14 INFO - "project": "", 05:53:14 INFO - "product": "firefox", 05:53:14 INFO - "script_repo_revision": "production", 05:53:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:53:14 INFO - "repository": "", 05:53:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:53:14 INFO - "buildid": "20160419025453", 05:53:14 INFO - "pgo_build": "False", 05:53:14 INFO - "basedir": "/builds/slave/test", 05:53:14 INFO - "buildnumber": 634, 05:53:14 INFO - "slavename": "t-yosemite-r7-0069", 05:53:14 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:53:14 INFO - "platform": "yosemite_r7", 05:53:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:53:14 INFO - "branch": "fx-team", 05:53:14 INFO - "repo_path": "integration/fx-team", 05:53:14 INFO - "revision": "759244c242e1c96a2fad70608e4a646aa9a5761c", 05:53:14 INFO - "stage_platform": "macosx64", 05:53:14 INFO - "builduid": "9fbd07adeb474b179aadf484958cff5a", 05:53:14 INFO - "slavebuilddir": "test" 05:53:14 INFO - } 05:53:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:53:14 INFO - Running post-action listener: _resource_record_post_action 05:53:14 INFO - ##### 05:53:14 INFO - ##### Running download-and-extract step. 05:53:14 INFO - ##### 05:53:14 INFO - Running pre-action listener: _resource_record_pre_action 05:53:14 INFO - Running main action method: download_and_extract 05:53:14 INFO - mkdir: /builds/slave/test/build/tests 05:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:14 INFO - https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:53:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:53:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:53:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:53:15 INFO - Downloaded 1199 bytes. 05:53:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:53:15 INFO - Using the following test package requirements: 05:53:15 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:53:15 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:53:15 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:53:15 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'jsshell-mac.zip'], 05:53:15 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:53:15 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:53:15 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:53:15 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:53:15 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:53:15 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:53:15 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:53:15 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:53: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')]} 05:53:15 INFO - https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:53:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:53:16 INFO - Downloaded 18037966 bytes. 05:53:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:53:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:53:19 INFO - Return code: 0 05:53:19 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:53: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')]} 05:53:19 INFO - https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:53:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:53:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:53:20 INFO - Downloaded 18037966 bytes. 05:53:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:53:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:53:24 INFO - Return code: 0 05:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:24 INFO - https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:53:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:53:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:53:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:53:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:53:25 INFO - Downloaded 10845952 bytes. 05:53:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:53:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:53:25 INFO - Return code: 0 05:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:25 INFO - https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:53:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:53:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:53:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:53:28 INFO - Downloaded 109952253 bytes. 05:53:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:53:28 INFO - mkdir: /builds/slave/test/properties 05:53:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:53:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:53:28 INFO - Contents: 05:53:28 INFO - build_url:https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:53:29 INFO - Running post-action listener: _resource_record_post_action 05:53:29 INFO - Running post-action listener: set_extra_try_arguments 05:53:29 INFO - ##### 05:53:29 INFO - ##### Running populate-webroot step. 05:53:29 INFO - ##### 05:53:29 INFO - Running pre-action listener: _resource_record_pre_action 05:53:29 INFO - Running main action method: populate_webroot 05:53:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:53:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:53:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:53:29 INFO - '--tppagecycles', 05:53:29 INFO - '7'], 05:53:29 INFO - 'tests': ['tsvgm']}}, 05:53:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:53:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:53:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:53:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:53:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:53:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['damp', 'tps']}, 05:53:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'tests': ['damp', 'tps']}, 05:53:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['dromaeo_dom']}, 05:53:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:53:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['a11yr', 05:53:29 INFO - 'ts_paint', 05:53:29 INFO - 'tpaint', 05:53:29 INFO - 'sessionrestore', 05:53:29 INFO - 'sessionrestore_no_auto_restore', 05:53:29 INFO - 'tabpaint']}, 05:53:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['tsvgx', 05:53:29 INFO - 'tsvgr_opacity', 05:53:29 INFO - 'tart', 05:53:29 INFO - 'tscrollx', 05:53:29 INFO - 'cart']}, 05:53:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:53:29 INFO - 'tsvgr_opacity', 05:53:29 INFO - 'tart', 05:53:29 INFO - 'tscrollx', 05:53:29 INFO - 'cart']}, 05:53:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'talos_options': ['--disable-e10s'], 05:53:29 INFO - 'tests': ['tp5o']}, 05:53:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'tests': ['tp5o']}, 05:53:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'talos_options': ['--disable-e10s', 05:53:29 INFO - '--xperf_path', 05:53:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:29 INFO - 'tests': ['tp5n']}, 05:53:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:53:29 INFO - 'talos_options': ['--xperf_path', 05:53:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:53:29 INFO - 'tests': ['tp5n']}}, 05:53:29 INFO - 'talos.zip': {'path': '', 05:53:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:53:29 INFO - Running post-action listener: _resource_record_post_action 05:53:29 INFO - ##### 05:53:29 INFO - ##### Running create-virtualenv step. 05:53:29 INFO - ##### 05:53:29 INFO - Running pre-action listener: _resource_record_pre_action 05:53:29 INFO - Running main action method: create_virtualenv 05:53:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:53:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:53:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:53:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:53:29 INFO - Using real prefix '/tools/python27' 05:53:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:53:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:53:33 INFO - Installing pip.................done. 05:53:33 INFO - Return code: 0 05:53:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:33 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:33 INFO - 'HOME': '/Users/cltbld', 05:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:33 INFO - 'LOGNAME': 'cltbld', 05:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:33 INFO - 'MOZ_NO_REMOTE': '1', 05:53:33 INFO - 'NO_EM_RESTART': '1', 05:53:33 INFO - 'PAGER': '/bin/cat', 05:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:33 INFO - 'PWD': '/builds/slave/test', 05:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:33 INFO - 'SHELL': '/bin/bash', 05:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:33 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:33 INFO - 'USER': 'cltbld', 05:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:33 INFO - 'XPC_FLAGS': '0x0', 05:53:33 INFO - 'XPC_SERVICE_NAME': '0', 05:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:53:33 INFO - Cleaning up... 05:53:33 INFO - Return code: 0 05:53:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:33 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:33 INFO - 'HOME': '/Users/cltbld', 05:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:33 INFO - 'LOGNAME': 'cltbld', 05:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:33 INFO - 'MOZ_NO_REMOTE': '1', 05:53:33 INFO - 'NO_EM_RESTART': '1', 05:53:33 INFO - 'PAGER': '/bin/cat', 05:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:33 INFO - 'PWD': '/builds/slave/test', 05:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:33 INFO - 'SHELL': '/bin/bash', 05:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:33 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:33 INFO - 'USER': 'cltbld', 05:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:33 INFO - 'XPC_FLAGS': '0x0', 05:53:33 INFO - 'XPC_SERVICE_NAME': '0', 05:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:33 INFO - Downloading/unpacking psutil>=0.7.1 05:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:53:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:36 INFO - Installing collected packages: psutil 05:53:36 INFO - Running setup.py install for psutil 05:53:36 INFO - building 'psutil._psutil_osx' extension 05:53:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:53:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:53:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:53:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:53:37 INFO - building 'psutil._psutil_posix' extension 05:53:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:53:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:53:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:53:37 INFO - ^ 05:53:37 INFO - 1 warning generated. 05:53:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:53:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:53:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:53:37 INFO - Successfully installed psutil 05:53:37 INFO - Cleaning up... 05:53:37 INFO - Return code: 0 05:53:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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', '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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:53: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 mozsystemmonitor==0.0.0 05:53:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:37 INFO - 'HOME': '/Users/cltbld', 05:53:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:37 INFO - 'LOGNAME': 'cltbld', 05:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:37 INFO - 'MOZ_NO_REMOTE': '1', 05:53:37 INFO - 'NO_EM_RESTART': '1', 05:53:37 INFO - 'PAGER': '/bin/cat', 05:53:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:37 INFO - 'PWD': '/builds/slave/test', 05:53:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:37 INFO - 'SHELL': '/bin/bash', 05:53:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:37 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:37 INFO - 'USER': 'cltbld', 05:53:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:37 INFO - 'XPC_FLAGS': '0x0', 05:53:37 INFO - 'XPC_SERVICE_NAME': '0', 05:53:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:53:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:53:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:53:40 INFO - Installing collected packages: mozsystemmonitor 05:53:40 INFO - Running setup.py install for mozsystemmonitor 05:53:40 INFO - Successfully installed mozsystemmonitor 05:53:40 INFO - Cleaning up... 05:53:40 INFO - Return code: 0 05:53:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:53: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')]} 05:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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')]} 05:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53: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', '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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:53: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 blobuploader==1.2.4 05:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:40 INFO - 'HOME': '/Users/cltbld', 05:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:40 INFO - 'LOGNAME': 'cltbld', 05:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:40 INFO - 'MOZ_NO_REMOTE': '1', 05:53:40 INFO - 'NO_EM_RESTART': '1', 05:53:40 INFO - 'PAGER': '/bin/cat', 05:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:40 INFO - 'PWD': '/builds/slave/test', 05:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:40 INFO - 'SHELL': '/bin/bash', 05:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:40 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:40 INFO - 'USER': 'cltbld', 05:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:40 INFO - 'XPC_FLAGS': '0x0', 05:53:40 INFO - 'XPC_SERVICE_NAME': '0', 05:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:53:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:53:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53: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 05:53:44 INFO - Downloading docopt-0.6.1.tar.gz 05:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:53:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:53:44 INFO - Running setup.py install for blobuploader 05:53:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:53:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:53:44 INFO - Running setup.py install for requests 05:53:45 INFO - Running setup.py install for docopt 05:53:45 INFO - Successfully installed blobuploader requests docopt 05:53:45 INFO - Cleaning up... 05:53:45 INFO - Return code: 0 05:53:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:45 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:45 INFO - 'HOME': '/Users/cltbld', 05:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:45 INFO - 'LOGNAME': 'cltbld', 05:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:45 INFO - 'MOZ_NO_REMOTE': '1', 05:53:45 INFO - 'NO_EM_RESTART': '1', 05:53:45 INFO - 'PAGER': '/bin/cat', 05:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:45 INFO - 'PWD': '/builds/slave/test', 05:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:45 INFO - 'SHELL': '/bin/bash', 05:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:45 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:45 INFO - 'USER': 'cltbld', 05:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:45 INFO - 'XPC_FLAGS': '0x0', 05:53:45 INFO - 'XPC_SERVICE_NAME': '0', 05:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:45 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-ZCvpzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:45 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-yipPS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:45 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-9kfhXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-TgHCbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-trcr2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-uH4UaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-02HuY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-TpGNmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-fqvk8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-hvDt6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-fWzwSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:46 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-j3dvcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:47 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-7T44vD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:47 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Y2B3PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:47 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-cUtOs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:47 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-vgXGuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:47 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Aopy0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:53:47 INFO - Running setup.py install for manifestparser 05:53:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:53:47 INFO - Running setup.py install for mozcrash 05:53:47 INFO - Running setup.py install for mozdebug 05:53:47 INFO - Running setup.py install for mozdevice 05:53:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Running setup.py install for mozfile 05:53:48 INFO - Running setup.py install for mozhttpd 05:53:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Running setup.py install for mozinfo 05:53:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Running setup.py install for mozInstall 05:53:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:53:48 INFO - Running setup.py install for mozleak 05:53:48 INFO - Running setup.py install for mozlog 05:53:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Running setup.py install for moznetwork 05:53:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Running setup.py install for mozprocess 05:53:49 INFO - Running setup.py install for mozprofile 05:53:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Running setup.py install for mozrunner 05:53:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:53:49 INFO - Running setup.py install for mozscreenshot 05:53:49 INFO - Running setup.py install for moztest 05:53:49 INFO - Running setup.py install for mozversion 05:53:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:53:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:53:50 INFO - Cleaning up... 05:53:50 INFO - Return code: 0 05:53:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:50 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:50 INFO - 'HOME': '/Users/cltbld', 05:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:50 INFO - 'LOGNAME': 'cltbld', 05:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:50 INFO - 'MOZ_NO_REMOTE': '1', 05:53:50 INFO - 'NO_EM_RESTART': '1', 05:53:50 INFO - 'PAGER': '/bin/cat', 05:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:50 INFO - 'PWD': '/builds/slave/test', 05:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:50 INFO - 'SHELL': '/bin/bash', 05:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:50 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:50 INFO - 'USER': 'cltbld', 05:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:50 INFO - 'XPC_FLAGS': '0x0', 05:53:50 INFO - 'XPC_SERVICE_NAME': '0', 05:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-CEjAYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-gEG41p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-dvhvjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-KVU0h8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-RXFubE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:50 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-lllo4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-xVnj0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-gGapJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-tTahW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-itOh_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-LL8h0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-q7h7Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-7cRCF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-DgCGGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-3x2I9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:51 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-fKIgVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:52 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-dSCKbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:53:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:54 INFO - Downloading blessings-1.6.tar.gz 05:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:53:54 INFO - Installing collected packages: blessings 05:53:54 INFO - Running setup.py install for blessings 05:53:54 INFO - Successfully installed blessings 05:53:54 INFO - Cleaning up... 05:53:54 INFO - Return code: 0 05:53:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:53:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:53:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:53:55 INFO - Reading from file tmpfile_stdout 05:53:55 INFO - Current package versions: 05:53:55 INFO - blessings == 1.6 05:53:55 INFO - blobuploader == 1.2.4 05:53:55 INFO - docopt == 0.6.1 05:53:55 INFO - manifestparser == 1.1 05:53:55 INFO - mozInstall == 1.12 05:53:55 INFO - mozcrash == 0.17 05:53:55 INFO - mozdebug == 0.1 05:53:55 INFO - mozdevice == 0.48 05:53:55 INFO - mozfile == 1.2 05:53:55 INFO - mozhttpd == 0.7 05:53:55 INFO - mozinfo == 0.9 05:53:55 INFO - mozleak == 0.1 05:53:55 INFO - mozlog == 3.1 05:53:55 INFO - moznetwork == 0.27 05:53:55 INFO - mozprocess == 0.22 05:53:55 INFO - mozprofile == 0.28 05:53:55 INFO - mozrunner == 6.11 05:53:55 INFO - mozscreenshot == 0.1 05:53:55 INFO - mozsystemmonitor == 0.0 05:53:55 INFO - moztest == 0.7 05:53:55 INFO - mozversion == 1.4 05:53:55 INFO - psutil == 3.1.1 05:53:55 INFO - requests == 1.2.3 05:53:55 INFO - wsgiref == 0.1.2 05:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:55 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:55 INFO - 'HOME': '/Users/cltbld', 05:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:55 INFO - 'LOGNAME': 'cltbld', 05:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:55 INFO - 'MOZ_NO_REMOTE': '1', 05:53:55 INFO - 'NO_EM_RESTART': '1', 05:53:55 INFO - 'PAGER': '/bin/cat', 05:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:55 INFO - 'PWD': '/builds/slave/test', 05:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:55 INFO - 'SHELL': '/bin/bash', 05:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:55 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:55 INFO - 'USER': 'cltbld', 05:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:55 INFO - 'XPC_FLAGS': '0x0', 05:53:55 INFO - 'XPC_SERVICE_NAME': '0', 05:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:53:55 INFO - Cleaning up... 05:53:55 INFO - Return code: 0 05:53:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:55 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:55 INFO - 'HOME': '/Users/cltbld', 05:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:55 INFO - 'LOGNAME': 'cltbld', 05:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:55 INFO - 'MOZ_NO_REMOTE': '1', 05:53:55 INFO - 'NO_EM_RESTART': '1', 05:53:55 INFO - 'PAGER': '/bin/cat', 05:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:55 INFO - 'PWD': '/builds/slave/test', 05:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:55 INFO - 'SHELL': '/bin/bash', 05:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:55 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:55 INFO - 'USER': 'cltbld', 05:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:55 INFO - 'XPC_FLAGS': '0x0', 05:53:55 INFO - 'XPC_SERVICE_NAME': '0', 05:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:55 INFO - Downloading/unpacking jsonschema 05:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:53:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:53:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:53:58 INFO - warning: no files found matching '*.txt' 05:53:58 INFO - no previously-included directories found matching 'build' 05:53:58 INFO - no previously-included directories found matching 'dist' 05:53:58 INFO - no previously-included directories found matching '.git*' 05:53:58 INFO - Installing collected packages: jsonschema, functools32 05:53:58 INFO - Running setup.py install for functools32 05:53:58 INFO - warning: no files found matching '*.txt' 05:53:58 INFO - no previously-included directories found matching 'build' 05:53:58 INFO - no previously-included directories found matching 'dist' 05:53:58 INFO - no previously-included directories found matching '.git*' 05:53:58 INFO - Successfully installed jsonschema functools32 05:53:58 INFO - Cleaning up... 05:53:58 INFO - Return code: 0 05:53:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:58 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 0x103274a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103284420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee11ca23c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, '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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:53:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:53:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:58 INFO - 'HOME': '/Users/cltbld', 05:53:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:58 INFO - 'LOGNAME': 'cltbld', 05:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:58 INFO - 'MOZ_NO_REMOTE': '1', 05:53:58 INFO - 'NO_EM_RESTART': '1', 05:53:58 INFO - 'PAGER': '/bin/cat', 05:53:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:58 INFO - 'PWD': '/builds/slave/test', 05:53:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:58 INFO - 'SHELL': '/bin/bash', 05:53:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:53:58 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:58 INFO - 'USER': 'cltbld', 05:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:58 INFO - 'XPC_FLAGS': '0x0', 05:53:58 INFO - 'XPC_SERVICE_NAME': '0', 05:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:59 INFO - Downloading/unpacking flake8 05:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:54:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:54:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:54:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:02 INFO - no previously-included directories found matching 'docs/_build' 05:54:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:54:02 INFO - Running setup.py install for pep8 05:54:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:03 INFO - no previously-included directories found matching 'docs/_build' 05:54:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:54:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:54:03 INFO - Cleaning up... 05:54:03 INFO - Return code: 0 05:54:03 INFO - Running post-action listener: _resource_record_post_action 05:54:03 INFO - Running post-action listener: _start_resource_monitoring 05:54:03 INFO - Starting resource monitoring. 05:54:03 INFO - ##### 05:54:03 INFO - ##### Running install step. 05:54:03 INFO - ##### 05:54:03 INFO - Running pre-action listener: _resource_record_pre_action 05:54:03 INFO - Running main action method: install 05:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:03 INFO - Reading from file tmpfile_stdout 05:54:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:03 INFO - Reading from file tmpfile_stdout 05:54:03 INFO - Output received: 05:54:03 INFO - Usage: mozinstall [options] installer 05:54:03 INFO - Options: 05:54:03 INFO - -h, --help show this help message and exit 05:54:03 INFO - -d DEST, --destination=DEST 05:54:03 INFO - Directory to install application into. [default: 05:54:03 INFO - "/builds/slave/test"] 05:54:03 INFO - --app=APP Application being installed. [default: firefox] 05:54:03 INFO - mkdir: /builds/slave/test/build/application 05:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:54:37 INFO - Reading from file tmpfile_stdout 05:54:37 INFO - Output received: 05:54:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:54:37 INFO - Running post-action listener: _resource_record_post_action 05:54:37 INFO - ##### 05:54:37 INFO - ##### Running run-tests step. 05:54:37 INFO - ##### 05:54:37 INFO - Running pre-action listener: _resource_record_pre_action 05:54:37 INFO - Running main action method: run_tests 05:54:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:54:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:54:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:54:37 INFO - 2016-04-19 05:54:37.126 screenresolution[1913:9269] starting screenresolution argv=screenresolution get 05:54:37 INFO - 2016-04-19 05:54:37.129 screenresolution[1913:9269] Display 0: 1600x1200x32@60 05:54:37 INFO - 2016-04-19 05:54:37.135 screenresolution[1914:9271] starting screenresolution argv=screenresolution list 05:54:37 INFO - Available Modes on Display 0 05:54:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:54:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:54:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:54:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:54:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:54:37 INFO - Intel Iris: 05:54:37 INFO - Chipset Model: Intel Iris 05:54:37 INFO - Type: GPU 05:54:37 INFO - Bus: Built-In 05:54:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:54:37 INFO - Vendor: Intel (0x8086) 05:54:37 INFO - Device ID: 0x0a2e 05:54:37 INFO - Revision ID: 0x0009 05:54:37 INFO - Displays: 05:54:37 INFO - Display: 05:54:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:54:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:54:37 INFO - Display Serial Number: 1600x1200 60 05:54:37 INFO - Main Display: Yes 05:54:37 INFO - Mirror: Off 05:54:37 INFO - Online: Yes 05:54:37 INFO - Rotation: Supported 05:54:37 INFO - Return code: 0 05:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:54:37 INFO - Python 2.7.3 05:54:37 INFO - Return code: 0 05:54:37 INFO - grabbing minidump binary from tooltool 05:54: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')]} 05:54:37 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 0x103113e00>, '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 0x102f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10310eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:54:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:54:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:54:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:54:37 INFO - Return code: 0 05:54:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:54:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:54:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:54:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:54:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:54:38 INFO - Return code: 0 05:54:38 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/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:54:38 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/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PLJSj5Glxa/Render', 05:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:38 INFO - 'HOME': '/Users/cltbld', 05:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:38 INFO - 'LOGNAME': 'cltbld', 05:54:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:54:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:38 INFO - 'MOZ_NO_REMOTE': '1', 05:54:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:54:38 INFO - 'NO_EM_RESTART': '1', 05:54:38 INFO - 'PAGER': '/bin/cat', 05:54:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:38 INFO - 'PWD': '/builds/slave/test', 05:54:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:38 INFO - 'SHELL': '/bin/bash', 05:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6jQwaSzLkY/Listeners', 05:54:38 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:38 INFO - 'USER': 'cltbld', 05:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:38 INFO - 'XPC_FLAGS': '0x0', 05:54:38 INFO - 'XPC_SERVICE_NAME': '0', 05:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:38 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/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:54:38 INFO - mozversion application_buildid: 20160419025453 05:54:38 INFO - mozversion application_changeset: 759244c242e1c96a2fad70608e4a646aa9a5761c 05:54:38 INFO - mozversion application_display_name: Nightly 05:54:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:54:38 INFO - mozversion application_name: Firefox 05:54:38 INFO - mozversion application_remotingname: firefox 05:54:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:54:38 INFO - mozversion application_vendor: Mozilla 05:54:38 INFO - mozversion application_version: 48.0a1 05:54:38 INFO - mozversion platform_buildid: 20160419025453 05:54:38 INFO - mozversion platform_changeset: 759244c242e1c96a2fad70608e4a646aa9a5761c 05:54:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:54:38 INFO - mozversion platform_version: 48.0a1 05:54:38 INFO - using testdate: 1461070478 05:54:38 INFO - actual date: 1461070478 05:54:38 INFO - starting webserver on 'localhost:49738' 05:54:38 INFO - SUITE-START | Running 2 tests 05:54:38 INFO - TEST-START | dromaeo_css 05:54:38 INFO - operating with platform_type : mac_ 05:54:38 INFO - Initialising browser for dromaeo_css test... 05:54:38 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpayapPy/profile http://localhost:49738/getInfo.html) 05:54:40 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 05:54:40 INFO - PROCESS | 1932 | colorDepth:24 05:54:40 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 05:54:40 INFO - PROCESS | 1932 | __metrics 05:54:41 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:54:41 ERROR - PROCESS | 1932 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:54:41 INFO - TEST-INFO | 1932: exit 0 05:54:41 INFO - Browser initialized. 05:54:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:54:41 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpayapPy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:54:53 INFO - PROCESS | 1934 | RSS: Main: 237371392 05:54:53 INFO - PROCESS | 1934 | 05:55:44 INFO - PROCESS | 1934 | 1461070544607 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:57:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49738/tests/dromaeo/cssquery-ext.html) 05:57:56 INFO - PROCESS | 1934 | RSS: Main: 403681280 05:57:56 INFO - PROCESS | 1934 | 06:00:59 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-ext.html (next: http://localhost:49738/tests/dromaeo/cssquery-jquery.html) 06:00:59 INFO - PROCESS | 1934 | RSS: Main: 382717952 06:00:59 INFO - PROCESS | 1934 | 06:04:01 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49738/tests/dromaeo/cssquery-mootools.html) 06:04:02 INFO - PROCESS | 1934 | RSS: Main: 374972416 06:04:02 INFO - PROCESS | 1934 | 06:07:04 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49738/tests/dromaeo/cssquery-prototype.html) 06:07:05 INFO - PROCESS | 1934 | RSS: Main: 376307712 06:07:05 INFO - PROCESS | 1934 | 06:10:08 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49738/tests/dromaeo/cssquery-yui.html) 06:10:09 INFO - PROCESS | 1934 | RSS: Main: 346218496 06:10:09 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-yui.html (next: http://localhost:49738/tests/dromaeo/cssquery-dojo.html) 06:13:12 INFO - PROCESS | 1934 | RSS: Main: 384565248 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | __start_tp_report 06:13:12 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 06:13:12 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 06:13:12 INFO - PROCESS | 1934 | |i|pagename|runs| 06:13:12 INFO - PROCESS | 1934 | |0;dojo.html;2893.4936386132426;3432.1591210153515;3458.775179613325;3464.982675086624;3516.613172551019;5654.915176272349;5715.199872017915;5729.770809167629;5741.827745167647;5749.683767392802;5397.757100930457;5718.685472299032;5774.82675519734;5844.064949608063;5851.3563508014195;5157.510036546522;5174.249733788598;5185.377754669426;5207.010667973092;5285.682859028451;5270.657407268534;5273.182656688219;5308.761105750251;5309.8938021239555;5356.651817631861;5102.056119617882;5154.4072431670465;5157.30376399185;5211.374635043781;5262.552683021948;5158.303629010074;5182.144946083891;5182.455842136596;5188.195829646411;5250.081235783722;5128.538431541179;5164.096283150471;5182.378114626269;5186.818461353834;5199.714015729133;5532.972335138298;5551.611387202897;5580.302462192226;5653.943460565424;5677.318721753375;6229.58830069951;6232.688365581748;6238.968805155991;6243;6258.186435763367;6154.938450615481;6240.781572644981;6296.118543403927;6307.432331090224;6318.968405157991;5766.481016708474;5883.764649414061;5885.617434866763;5892.852678683067;5903.2325797646035;5227.738613069408;6250.402923863478;6281.403266689657;6312.43188113072;6338.461230795342;6038.849028774316;6088.6042407243385;6109.053096770008;6112.144299798032;6113.205283313141;6132.814448934737;6245.906311405332;6254.781082662176;6255.624662520263;6264.655443950626;6129.939558357044;6176;6280.2777680566405;6286.779962701375;6288.371162883675;5426.701531415731;5531.640443371168;6283.622982621057;6292.653904035231;6310.432061114522;4954.256861470808;4962.503749625081;4970.676906001098;4985.177445721415;4985.800567977313;3726.6832319253167;4396.07682386696;4508.143452744033;4530.161920044802;4536.455625324982;5663.801766938137;5666.886662266814;5668.0647693130995;5677;5688.146777983253;6210.192674952227;6269.028300613412;6282.811515654537;6284.24589049317;6287.151234583364;5579.246801681802;5649.632773869767;5689.203511508392;5739.368669446358;5767.250257466587;5366.838994830161;5420;5421.620486565981;5422.539084177889;5430.212619170277;6382.553221274559;6382.648954307558;6384.297727249906;6399.104125422352;6405.583637063576;6156.569040167145;6238.126662267196;6256.687165641791;6267.874642507216;6273.404026617464;6258.749650013949;6273.62358258506;6394.616323020634;6401.8719625608155;6405.903911441239;6205.968970155121;6221.222347206599;6228.532860035606;6236.877362074689;6260.874782504416;6120.538559592075;6252.5935814171935;6293.83564040654;6313.715882785377;6325.746970121144;6129.325774164749;6189.84525386869;6191.071339298961;6191.349908259748;6193.659348735682;5597.608167428404;5948.631814682561;6104.236970378703;6118.663473509;6121.173641558333;5867.853303667443;5961.165436838933;6005.069214271794;6008.218931538872;6030.728617212324;5974.492168165581;6008.969955150371;6083.391660833881;6105.084237364431;6115.816525504242;5974.61165024272;5994.580379373402;6005.939940600713;6006.639601623741;6014.699265036818 06:13:12 INFO - PROCESS | 1934 | |1;ext.html;491.3244289102484;507.13533425509496;517.6531723745095;518.277003580006;577.8468705792965;29309.853450732742;38699.613003869956;39769.60806371777;39837;39988.60011399885;44177;44846.55153448464;44930.32604510935;44947.32579011309;45023.39812814967;8546.70086546971;9663.323567350271;9885.851712224323;9903.702888913309;9957.10386065254;8640.30877529796;11120.833187502194;11908.583199588016;11958.162928595002;11963.102767292445;11471.024962878124;11570.479328430241;11587.246828956091;11589.6523104307;11590.130740194478;8709.608067636907;11539.423028848609;11549.653510394659;11630.011449026884;11898.345590992492;11420.371879546621;11584.362860042695;11585.420728963518;11593.6521904343;11607.76784464306;38510.80744596287;39038.82883513471;39089.80455097735;39155.804220979;39158.80420597879;3123.234807472184;3157.289609837791;3170.397624451347;3371;3871.825767840454;26354.86822565875;26850.7917143729;28569.142925712054;28998.855005725047;29271.97548085807;2718.9320266993286;2759.1308737747545;2765.806393552452;2835.7447829695425;2969.7624190064744;485095;485264;486901;487572.5621371906;488095.5595222037;35635.82182089073;66041.66979165074;66791.66604166948;66893;66898;36085.63914360875;36314.45528317078;36388;36389.81805091011;36538.63461365405;14845.628859278388;15182.46861359847;15200.619984500256;15201.01193422424;15266.694666106616;11779.823302650468;12099.697507562205;12110.455029523693;12153;12792.296423696785;8014.15851335613;8232.17678232172;8259.339252859756;8269.139443625136;8271.379646526415;3395.3379091077495;3419.2477654916374;3423.7603367764514;3432.5709286339206;3451;882.1311008656468;951.9143277104955;974.3277138774223;1061.984070238947;1110.4336788238056;22804.885975570018;29383.55924661132;29766.553501697494;29885.551716724265;30502.54246186309;1068.7808999155154;1095.3263742798122;1101.961431349899;1138.5332013874272;1147.3345459633392;15536.223188840513;15623.238857108685;15783.289751961189;16002.67994640124;16084.67830643404;643134;650577.7471112709;651841;653451;653453;62780.37219627837;63538.72922541616;63647.36352636414;63685;63738;1329.6808765896308;1374.4570894496617;1457.4388860289077;1521.710874933759;1596.0104735064335;28674.426511470072;28881.566776497948;29293.85353073221;29484.705152949053;29689.703102969557;48686;48727.756361219384;49278;49419.7529012367;49456.50543494663;362155;363567;364643;365967;366073.71410287643;21434.035468403647;24877.378065548506;24888.87555562283;26775.062872799357;28041.439171216054;36568.817155914054;36909;38132.80933595314;43811;44703.55296446994;31043.68956310408;37701;37929.62070379261;38828.41757373585;38908.41637375498;34468;36884.446733298486;37132.62867371292;37890.2421951565;38392.42411363776;36806.815965919996;36818.26363472769;37255;37444.6255537452;37615.6238437623;25420.491590167723;32449.34475554583;33162.50256246207;33212;33472.497912530845;19264;24310.878445607657;24404.145854894992;24963;25143.49713005766 06:13:12 INFO - PROCESS | 1934 | |2;jquery.html;343.75593328736596;390.9003204182933;393.56117928509707;394.5758309816946;405.6268233225432;18934.337298194565;20047.1981120755;21435.464113397134;21455.785442145592;21477.355679329605;11941.940290298558;12099.637010889688;12312.938435307833;12467.127301088927;12474.93963013145;5256.710880901539;5376.354837419515;5434.83695489136;5438.320209973754;5441.945580544183;3874.748141370816;4167.1457351242925;5739.139129130622;6090.969545152268;6106.328303886569;6083.330833608299;6087.299960504554;6101.603395779282;6107.877842443148;6147.784827531037;6694.83262918426;6709.496787740939;6713.496487763413;6719.529632925722;6720.5631633943885;3863.517060367454;3954.980225098856;4092.4270602115726;5756.856078598027;5791.305043394777;13532.240808694807;13706.314684265746;14440.783388249185;25195.24414267575;25910.352241193934;1908.9618207635908;2012.1341686708922;2159.546495236002;2370.3363058343693;2442.206282958046;9854.556544955494;10091.79816403675;10227.335223210543;10236.948815255875;10365.118964887994;1690.8478236958733;1724.6981778188767;1749.95625109372;1797;1808.9909550452164;112981;114068;116270.99999999916;118943;120814;11497.712557186138;11595.362255075883;11651.12616553745;11658.358790266448;11819.172657914036;15359.692806143814;15422.305996230196;15477.22613869302;15477.458288959833;15490.9225453872;8518.61666225009;8695.130486951255;8714.738557843275;8806.86789698155;8818.603162857558;8413;8440.282575980988;8441.282490988211;8543.145685431407;8581.699640512552;3835.808209589565;4539.958227987257;4635.473666851507;4693.507181745936;4708.599769019604;1761.7357396390644;1780.8130146334706;1839.86949298825;1950.249154075691;1975.4764987278384;762.4624639629048;768.7501561992375;902.4855832175729;917.953532972412;979.7011911366959;16399.098049607146;19031;19251.941143236978;20194.09126589307;20242;942.2414955960518;950.277788880442;954.5322791832091;1041.9791604167874;1067.055655744656;8830.896137982752;10132.54403551842;10663.306885052447;10719.678409647722;10748.78502429947;569135;590595;597386;613442;615274;114244.85755142343;118806;130213.34893325662;131488.68511314955;131938;1008.5562352564848;1015.294370412571;1032.4992378696327;1033.8294181460126;1038.963636272742;14545.92727036358;17446.738298925273;17555.12224388801;17574.912125439292;18072.638547229246;33224.33551329008;47157.05685886332;48550.51449485601;49014.26478602895;50420.243696345315;69650.91047268591;72029.6398518004;72240.27759722546;72954;74765;7213.458990575623;7397.334239918433;7649.77603583424;7682.8079298018;8461.830763384822;7668.463207575416;9148.359614826899;11953.342566158597;12229.755404891674;12996.545120920722;10670.199735019749;10843.457827108456;10875.728106797076;11024.118070554498;11202.887971120184;10708.357498550113;10786.406747628957;10828.18788590843;11007.724806879572;11052;10817.687575164193;11032.393218373067;11383.886161138615;11383.975442210241;11596.188266821242;6747.966260168668;7544.886826697713;7673.616319183907;7684.65419056155;8573.657053717781;5403.729813509231;7941.880871787043;9954.850677239703;10931.398773067558;11297.943510282395 06:13:12 INFO - PROCESS | 1934 | |3;mootools.html;1425.2090090000045;1592.3153044190997;1613.9677206455872;1903.6287923854845;2033.7762846086928;7960.442769006172;8524.422981748374;8792.164744349277;8834.95582522088;8837.204651581356;8308.293795027415;8671;8671.089535598767;8673.392862499628;8677.305815534757;2174.8695078295355;2390.3665528634924;2431.671724317221;2468.0004598137693;2486.29140694902;2489.2034548944343;2503.7496250374907;2504.173622704503;2508.5233805576972;2508.9749102509013;2399.4841109161525;2470.2095329494477;2473.109680515013;2473.950520989579;2475.195561442529;2154.407537927075;2371.5731168389684;2442.3771938155833;2449.3998970252223;2450.473148273112;2458.913938012161;2466.075221791828;2492.264781889329;2494.2641920633273;2498.4378514834198;7407.259274072603;8560.186782255738;8609.139086091403;8651.610677519528;8661.523616201106;4910.165271903749;5359.437259087778;5367.765413954812;5367.919481207785;5389.51494365509;3510.1400156961454;3535.292941411728;3560.768550044239;3594.19130695594;3616;1019.0675531888387;1019.4800651667703;1040.4537617750666;1045.0646671229283;1048.0201012053753;11037.227394082338;11289.0968722502;11311.886881131166;11321.037711794508;11343.546258149665;4761.928571071437;4793.542501289109;4819.108464933998;4904.313396124546;4920.21276595743;9936.205103591696;9962.750931226632;9963.701088967342;9968.451735154491;9979.80040399188;5371.382291036581;5385.353757549041;5404.459554044564;5429.104197807396;5443.992861320668;4400.515943246304;4549.658775591844;4761.142994261067;4908.067467181224;4908.852734417973;3293.440115180425;3543.7773967981007;4018.33697439925;4020.0954785173103;4148.43996060534;1332.7201287729492;1375.0924389902816;1375.9862401376058;1384.5984664447399;1388.3891087921281;905.2215095018279;914.7438717159206;917.1974522292994;947.7062110745553;953.7663272498282;5681.914771278434;5722.62802917809;5795.9277533656405;5858.179854820329;5950.345461999176;447.8634016624961;451.85314772699;458.09526185783477;460.70975285570546;477.4413935695176;3845.384738441836;3896.785676787747;3947.6447119758786;3976.980115099406;3978.6817054635558;10102.69691909244;10498.527566259536;10644.680659580225;10648.574057037787;10662.573497060032;10419.635312764018;10542.209334299958;10670.199735019905;10676.51955661997;10716.678499645022;6790.253072162058;7284.526505777108;7737.767866963887;7878.763637090897;8084.353251739847;5641.379448260606;5645.9153112702525;5709.172170035405;5732.398098199629;5845.599069019417;9460.574274157818;9682.27382946296;9683.176929960751;9700.175485083846;9719.222462202866;9340.906590934004;9819.852702209615;9904.35621684617;9927.305088643725;9954.850677239992;6421.5825971311715;6871.4502839773695;7069.82325441868;7327.926720732938;7473.850522989401;7438.59087750179;7521.05986751656;7526.134494533198;7535.54786712799;7538.773836784905;6778.288279730557;7044.225135235222;7054.929450705427;7166;7201.3518783309755;6065.332813390612;6919.927411251264;6959.652017399211;6972.930270697431;7078.4691148163065;6496.220453545605;6598.3401659833635;6633.1376920999965;6634.933650663432;6639.668016599054;4089;4524.773761311987;4790.520947905182;4794.113089078532;4900.897298107897;4720.858374248778;4751.928721069117;4755.167845626932;4808.9519104808505;4875.0493653737185 06:13:12 INFO - PROCESS | 1934 | |4;prototype.html;305.7553956834532;313.70198311603974;322.162377817674;325.14649046253584;325.3639135490114;5244.984134122876;5735.483806457418;5751.252337196169;5813.186153938452;5857.092150716646;5435.211894275328;5498.422665620113;5706.3437704663975;5745.511631511326;5779.428154471262;3650.872219472319;3796.3925771876507;4255.212785634652;4393.62654174394;4413.801378937956;4318.460192475941;4360.389545463622;4392.912141757163;4397.604215620593;4407.713498622596;4043.3126368517424;4246.893827654302;4273.67947403946;4284.592963668462;4354.651627869763;4234.682398820086;4278.272693642057;4288.485381754178;4291.24903141952;4295.484541854966;3715;3716.9628303716886;3720;3720.386136287509;3742.607026262258;4972.5027497249985;5349.635842860084;5412.9458705412835;5596.916046259309;5654.943450565483;4300.80646370914;4391.253486907233;4896.95103048972;4907.435644900811;4957.90084198314;5749;5963.850903727398;6025.035994240944;6046;6055.485283750886;3447.8106402434382;3619.7285203609836;3936.665383442411;4054.4265910522986;4089.5092588889524;5988.401159883977;6302.275238347603;6330.145430366933;6353.205849268841;6380.489560835122;3222.38774632819;4123.60825721556;4147.470043273601;4170.4995400552125;4191.517975432865;4054.270231358325;4149.875503734893;4186.455760751144;4215.957840421556;4221.556736542619;4136.358864376027;4311.267084595626;4393.244849247496;4900.436449808318;4941.219966725458;6124.816255512342;6167.414095660905;6206.348333425016;6210.285817130997;6230.43926046658;1202.8977536909285;1319.617310979805;1354.537099932071;1440.3590402271097;1457.271364317841;803.2289002557493;938.7043081429327;949.2263804998904;952.2001518724305;956.5456408206186;4769;5559.027170245191;5588.21764952907;5671.6597004179885;5683.914741278884;6024.3072046714315;6222.3466536013975;6223.315435302113;6271.498280137578;6303.558750887486;5055.595552355802;5770.942290577041;5778.422157784272;5783.277090363705;5789.089600431847;2844.573314002916;2934.471795076864;3295.9176020599675;3305.454599991023;3358.9160270992934;6166.414190651881;6270.717817698214;6651.439195909609;6652.83367915806;6703.994400839913;5838.1242813577455;6249.375062493805;6477.902831457532;6628.270890202074;6634;5736.311642602915;6239.78160764371;6652.667366631649;6697.866042679217;6776.7628133015105;5099.5665368442615;6283;6408.647524386203;6414.61512309263;6448.580842245239;5186.403563590085;5255.608698042305;5936;6069.787557435468;6406.487481001415;5825.184474173704;6681.365270299314;6705.396514313735;6707.3292670732535;6710.228323742832;4128.979355103205;4187.518435379849;4208.5901223090605;4216.388623649615;4219.873403797891;6094.3600921904335;6094.695265236632;6122.6938653068055;6338.778142764981;6422.719934423212;5385.946140538545;5514.310711161105;6152.462334157788;6154.969225153845;6195.535334849814;6140.416660417253;6144.38556144382;6147.262328520606;6168.660723660062;6188.164597779422;5551.500364967173;5649.519790817826;6015.759369625166;6046.12331211963;6076.392360763888;6111.327753947151;6145.9692701536205;6152.907706384497;6185.319614842454;6197.566170368031;5291.973540132275;5711.257536520226;6037.9094313584455;6058.363871793398;6125.84685382869 06:13:12 INFO - PROCESS | 1934 | |5;yui.html;634.4099987012077;657.898025806;673.9966300168498;685.2154283345566;757.129301303501;14093.154410735364;14510.782338264935;14560.635984100403;14587.854121458782;14592.927035364808;13864.722705545908;13961.860381396184;14075.859241407583;14078.788818167735;14088.577342679737;735.1472292141117;765.5712800831552;937.2174234514172;955.8996305387939;956.6508224498043;902.5036230073466;936.0171819589438;937.8968313485545;939.741571067955;941.0401390581603;978.0806042320232;988.0169231614518;991.2912267728573;992.1517102877044;996.1035068438391;763.3473380259909;953.7949340891672;980.3284749946274;983.8868530119056;985.635314933472;733.6551820644289;737.6127533045141;738.8448425830582;739.6967243430222;740.5075624709561;12727.109102362838;12768.936155319258;12779.233246005177;12785.16896401731;12787.87212127876;12326;12400;12618.369081545978;12663.36683165838;12747.362631868462;9888.357256778287;10331.32846364984;10453.634122805664;10475.1096156826;10757;3426.554547908756;3480.1647604574973;3541.0262177901027;3572.267685124569;3645.179834537208;62246;83843;83977;84076;84147.57926210391;9423.811523769486;9497;9507.524623768784;9588.90411095894;9675.984021677763;22426.551468970854;22436.32691019272;22448.438789030406;22467.775322246896;22496.32511024672;6332.176817013758;6533.738650454024;7357.632118394059;7475.5888426136;7728.536287822749;3197.7921435106646;4018.9397159042637;4049.4735684360844;4085.222928026318;4290.485141783006;3487.128217945514;3592.586852511942;3592.928141437209;3595.586507551665;3596.029072150505;615.9476444502178;638.7508871540103;639.68015992004;659.9967000164968;662.0598749775235;532.8135152696525;563.7660370946094;578.2049681687679;598.5540772124795;613.923259592551;5996.310424301174;7788.610569471503;7800.336971357385;8151.714689985822;8471.364647651451;525.8659041944356;548.9560835133179;569.8147109480939;573.4494884910449;600.1268154834705;3503.089196808848;3597.4963505109304;3602.351576716164;3627.9455808162897;3674.8713795017047;78092;79457;79842.40315193664;80120.59939700381;81268.59365703251;73532.63233683797;74597.62701186459;74979;75390;76090;55209.171862422096;58659.12011319833;64682.67658661676;65805;65909;7041.6831242595235;7114.217436081924;7161.9641901790155;7207.835904593501;7247;42917;43048.78475607602;43481.34777978269;43522.78238608786;43833.205853091546;58915.705421472616;62554.061689075614;62894.68552657207;62943;63107.684461577395;9704.514774261399;9914.20686345105;10243.641472548425;10558.31370960885;10897.400642964396;10169.084782369622;10325.690229293134;11015.944920275348;11172.664820055412;11216.943915280697;9394.953025235103;9577.952110239683;9741.123298903132;10160.695179144637;10628.990245926625;8959;8977.596008179517;9036.23192028685;9153;9761.560729767036;8211.95894020526;9994.651256954465;10073.899261007296;10180.796384072426;10301.587936482456;8594.914050859661;8642.783930401538;8735.956320218358;8995.730128096167;9058.411203271766;7716.614169291401;8780.560971951249;8806.20744133031;8867.512286824029;9315.906840931504 06:13:12 INFO - PROCESS | 1934 | __end_tp_report 06:13:12 INFO - PROCESS | 1934 | __start_cc_report 06:13:12 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1613 06:13:12 INFO - PROCESS | 1934 | __end_cc_report 06:13:12 INFO - PROCESS | 1934 | __startTimestamp1461071592420__endTimestamp 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | ------- Summary: start ------- 06:13:12 INFO - PROCESS | 1934 | Number of tests: 6 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5739.93 Median:5974.49 stddev:654.19 (10.9%) stddev-sans-first:616.91 06:13:12 INFO - PROCESS | 1934 | Values: 2893.5 3432.2 3458.8 3465.0 3516.6 5654.9 5715.2 5729.8 5741.8 5749.7 5397.8 5718.7 5774.8 5844.1 5851.4 5157.5 5174.2 5185.4 5207.0 5285.7 5270.7 5273.2 5308.8 5309.9 5356.7 5102.1 5154.4 5157.3 5211.4 5262.6 5158.3 5182.1 5182.5 5188.2 5250.1 5128.5 5164.1 5182.4 5186.8 5199.7 5533.0 5551.6 5580.3 5653.9 5677.3 6229.6 6232.7 6239.0 6243.0 6258.2 6154.9 6240.8 6296.1 6307.4 6319.0 5766.5 5883.8 5885.6 5892.9 5903.2 5227.7 6250.4 6281.4 6312.4 6338.5 6038.8 6088.6 6109.1 6112.1 6113.2 6132.8 6245.9 6254.8 6255.6 6264.7 6129.9 6176.0 6280.3 6286.8 6288.4 5426.7 5531.6 6283.6 6292.7 6310.4 4954.3 4962.5 4970.7 4985.2 4985.8 3726.7 4396.1 4508.1 4530.2 4536.5 5663.8 5666.9 5668.1 5677.0 5688.1 6210.2 6269.0 6282.8 6284.2 6287.2 5579.2 5649.6 5689.2 5739.4 5767.3 5366.8 5420.0 5421.6 5422.5 5430.2 6382.6 6382.6 6384.3 6399.1 6405.6 6156.6 6238.1 6256.7 6267.9 6273.4 6258.7 6273.6 6394.6 6401.9 6405.9 6206.0 6221.2 6228.5 6236.9 6260.9 6120.5 6252.6 6293.8 6313.7 6325.7 6129.3 6189.8 6191.1 6191.3 6193.7 5597.6 5948.6 6104.2 6118.7 6121.2 5867.9 5961.2 6005.1 6008.2 6030.7 5974.5 6009.0 6083.4 6105.1 6115.8 5974.6 5994.6 6005.9 6006.6 6014.7 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:63034.20 Median:18959.50 stddev:137616.42 (725.8%) stddev-sans-first:137922.35 06:13:12 INFO - PROCESS | 1934 | Values: 491.3 507.1 517.7 518.3 577.8 29309.9 38699.6 39769.6 39837.0 39988.6 44177.0 44846.6 44930.3 44947.3 45023.4 8546.7 9663.3 9885.9 9903.7 9957.1 8640.3 11120.8 11908.6 11958.2 11963.1 11471.0 11570.5 11587.2 11589.7 11590.1 8709.6 11539.4 11549.7 11630.0 11898.3 11420.4 11584.4 11585.4 11593.7 11607.8 38510.8 39038.8 39089.8 39155.8 39158.8 3123.2 3157.3 3170.4 3371.0 3871.8 26354.9 26850.8 28569.1 28998.9 29272.0 2718.9 2759.1 2765.8 2835.7 2969.8 485095.0 485264.0 486901.0 487572.6 488095.6 35635.8 66041.7 66791.7 66893.0 66898.0 36085.6 36314.5 36388.0 36389.8 36538.6 14845.6 15182.5 15200.6 15201.0 15266.7 11779.8 12099.7 12110.5 12153.0 12792.3 8014.2 8232.2 8259.3 8269.1 8271.4 3395.3 3419.2 3423.8 3432.6 3451.0 882.1 951.9 974.3 1062.0 1110.4 22804.9 29383.6 29766.6 29885.6 30502.5 1068.8 1095.3 1102.0 1138.5 1147.3 15536.2 15623.2 15783.3 16002.7 16084.7 643134.0 650577.7 651841.0 653451.0 653453.0 62780.4 63538.7 63647.4 63685.0 63738.0 1329.7 1374.5 1457.4 1521.7 1596.0 28674.4 28881.6 29293.9 29484.7 29689.7 48686.0 48727.8 49278.0 49419.8 49456.5 362155.0 363567.0 364643.0 365967.0 366073.7 21434.0 24877.4 24888.9 26775.1 28041.4 36568.8 36909.0 38132.8 43811.0 44703.6 31043.7 37701.0 37929.6 38828.4 38908.4 34468.0 36884.4 37132.6 37890.2 38392.4 36806.8 36818.3 37255.0 37444.6 37615.6 25420.5 32449.3 33162.5 33212.0 33472.5 19264.0 24310.9 24404.1 24963.0 25143.5 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:33867.87 Median:2264.94 stddev:99658.53 (4400.0%) stddev-sans-first:99906.31 06:13:12 INFO - PROCESS | 1934 | Values: 343.8 390.9 393.6 394.6 405.6 18934.3 20047.2 21435.5 21455.8 21477.4 11941.9 12099.6 12312.9 12467.1 12474.9 5256.7 5376.4 5434.8 5438.3 5441.9 3874.7 4167.1 5739.1 6091.0 6106.3 6083.3 6087.3 6101.6 6107.9 6147.8 6694.8 6709.5 6713.5 6719.5 6720.6 3863.5 3955.0 4092.4 5756.9 5791.3 13532.2 13706.3 14440.8 25195.2 25910.4 1909.0 2012.1 2159.5 2370.3 2442.2 9854.6 10091.8 10227.3 10236.9 10365.1 1690.8 1724.7 1750.0 1797.0 1809.0 112981.0 114068.0 116271.0 118943.0 120814.0 11497.7 11595.4 11651.1 11658.4 11819.2 15359.7 15422.3 15477.2 15477.5 15490.9 8518.6 8695.1 8714.7 8806.9 8818.6 8413.0 8440.3 8441.3 8543.1 8581.7 3835.8 4540.0 4635.5 4693.5 4708.6 1761.7 1780.8 1839.9 1950.2 1975.5 762.5 768.8 902.5 918.0 979.7 16399.1 19031.0 19251.9 20194.1 20242.0 942.2 950.3 954.5 1042.0 1067.1 8830.9 10132.5 10663.3 10719.7 10748.8 569135.0 590595.0 597386.0 613442.0 615274.0 114244.9 118806.0 130213.3 131488.7 131938.0 1008.6 1015.3 1032.5 1033.8 1039.0 14545.9 17446.7 17555.1 17574.9 18072.6 33224.3 47157.1 48550.5 49014.3 50420.2 69650.9 72029.6 72240.3 72954.0 74765.0 7213.5 7397.3 7649.8 7682.8 8461.8 7668.5 9148.4 11953.3 12229.8 12996.5 10670.2 10843.5 10875.7 11024.1 11202.9 10708.4 10786.4 10828.2 11007.7 11052.0 10817.7 11032.4 11383.9 11384.0 11596.2 6748.0 7544.9 7673.6 7684.7 8573.7 5403.7 7941.9 9954.9 10931.4 11297.9 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5557.10 Median:4902.61 stddev:3084.41 (62.9%) stddev-sans-first:3077.43 06:13:12 INFO - PROCESS | 1934 | Values: 1425.2 1592.3 1614.0 1903.6 2033.8 7960.4 8524.4 8792.2 8835.0 8837.2 8308.3 8671.0 8671.1 8673.4 8677.3 2174.9 2390.4 2431.7 2468.0 2486.3 2489.2 2503.7 2504.2 2508.5 2509.0 2399.5 2470.2 2473.1 2474.0 2475.2 2154.4 2371.6 2442.4 2449.4 2450.5 2458.9 2466.1 2492.3 2494.3 2498.4 7407.3 8560.2 8609.1 8651.6 8661.5 4910.2 5359.4 5367.8 5367.9 5389.5 3510.1 3535.3 3560.8 3594.2 3616.0 1019.1 1019.5 1040.5 1045.1 1048.0 11037.2 11289.1 11311.9 11321.0 11343.5 4761.9 4793.5 4819.1 4904.3 4920.2 9936.2 9962.8 9963.7 9968.5 9979.8 5371.4 5385.4 5404.5 5429.1 5444.0 4400.5 4549.7 4761.1 4908.1 4908.9 3293.4 3543.8 4018.3 4020.1 4148.4 1332.7 1375.1 1376.0 1384.6 1388.4 905.2 914.7 917.2 947.7 953.8 5681.9 5722.6 5795.9 5858.2 5950.3 447.9 451.9 458.1 460.7 477.4 3845.4 3896.8 3947.6 3977.0 3978.7 10102.7 10498.5 10644.7 10648.6 10662.6 10419.6 10542.2 10670.2 10676.5 10716.7 6790.3 7284.5 7737.8 7878.8 8084.4 5641.4 5645.9 5709.2 5732.4 5845.6 9460.6 9682.3 9683.2 9700.2 9719.2 9340.9 9819.9 9904.4 9927.3 9954.9 6421.6 6871.5 7069.8 7327.9 7473.9 7438.6 7521.1 7526.1 7535.5 7538.8 6778.3 7044.2 7054.9 7166.0 7201.4 6065.3 6919.9 6959.7 6972.9 7078.5 6496.2 6598.3 6633.1 6634.9 6639.7 4089.0 4524.8 4790.5 4794.1 4900.9 4720.9 4751.9 4755.2 4809.0 4875.0 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4947.49 Median:5677.79 stddev:1577.56 (27.8%) stddev-sans-first:1543.04 06:13:12 INFO - PROCESS | 1934 | Values: 305.8 313.7 322.2 325.1 325.4 5245.0 5735.5 5751.3 5813.2 5857.1 5435.2 5498.4 5706.3 5745.5 5779.4 3650.9 3796.4 4255.2 4393.6 4413.8 4318.5 4360.4 4392.9 4397.6 4407.7 4043.3 4246.9 4273.7 4284.6 4354.7 4234.7 4278.3 4288.5 4291.2 4295.5 3715.0 3717.0 3720.0 3720.4 3742.6 4972.5 5349.6 5412.9 5596.9 5654.9 4300.8 4391.3 4897.0 4907.4 4957.9 5749.0 5963.9 6025.0 6046.0 6055.5 3447.8 3619.7 3936.7 4054.4 4089.5 5988.4 6302.3 6330.1 6353.2 6380.5 3222.4 4123.6 4147.5 4170.5 4191.5 4054.3 4149.9 4186.5 4216.0 4221.6 4136.4 4311.3 4393.2 4900.4 4941.2 6124.8 6167.4 6206.3 6210.3 6230.4 1202.9 1319.6 1354.5 1440.4 1457.3 803.2 938.7 949.2 952.2 956.5 4769.0 5559.0 5588.2 5671.7 5683.9 6024.3 6222.3 6223.3 6271.5 6303.6 5055.6 5770.9 5778.4 5783.3 5789.1 2844.6 2934.5 3295.9 3305.5 3358.9 6166.4 6270.7 6651.4 6652.8 6704.0 5838.1 6249.4 6477.9 6628.3 6634.0 5736.3 6239.8 6652.7 6697.9 6776.8 5099.6 6283.0 6408.6 6414.6 6448.6 5186.4 5255.6 5936.0 6069.8 6406.5 5825.2 6681.4 6705.4 6707.3 6710.2 4129.0 4187.5 4208.6 4216.4 4219.9 6094.4 6094.7 6122.7 6338.8 6422.7 5385.9 5514.3 6152.5 6155.0 6195.5 6140.4 6144.4 6147.3 6168.7 6188.2 5551.5 5649.5 6015.8 6046.1 6076.4 6111.3 6146.0 6152.9 6185.3 6197.6 5292.0 5711.3 6037.9 6058.4 6125.8 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:16911.94 Median:32661.18 stddev:23641.93 (72.4%) stddev-sans-first:23676.66 06:13:12 INFO - PROCESS | 1934 | Values: 634.4 657.9 674.0 685.2 757.1 14093.2 14510.8 14560.6 14587.9 14592.9 13864.7 13961.9 14075.9 14078.8 14088.6 735.1 765.6 937.2 955.9 956.7 902.5 936.0 937.9 939.7 941.0 978.1 988.0 991.3 992.2 996.1 763.3 953.8 980.3 983.9 985.6 733.7 737.6 738.8 739.7 740.5 12727.1 12768.9 12779.2 12785.2 12787.9 12326.0 12400.0 12618.4 12663.4 12747.4 9888.4 10331.3 10453.6 10475.1 10757.0 3426.6 3480.2 3541.0 3572.3 3645.2 62246.0 83843.0 83977.0 84076.0 84147.6 9423.8 9497.0 9507.5 9588.9 9676.0 22426.6 22436.3 22448.4 22467.8 22496.3 6332.2 6533.7 7357.6 7475.6 7728.5 3197.8 4018.9 4049.5 4085.2 4290.5 3487.1 3592.6 3592.9 3595.6 3596.0 615.9 638.8 639.7 660.0 662.1 532.8 563.8 578.2 598.6 613.9 5996.3 7788.6 7800.3 8151.7 8471.4 525.9 549.0 569.8 573.4 600.1 3503.1 3597.5 3602.4 3627.9 3674.9 78092.0 79457.0 79842.4 80120.6 81268.6 73532.6 74597.6 74979.0 75390.0 76090.0 55209.2 58659.1 64682.7 65805.0 65909.0 7041.7 7114.2 7162.0 7207.8 7247.0 42917.0 43048.8 43481.3 43522.8 43833.2 58915.7 62554.1 62894.7 62943.0 63107.7 9704.5 9914.2 10243.6 10558.3 10897.4 10169.1 10325.7 11015.9 11172.7 11216.9 9395.0 9578.0 9741.1 10160.7 10629.0 8959.0 8977.6 9036.2 9153.0 9761.6 8212.0 9994.7 10073.9 10180.8 10301.6 8594.9 8642.8 8736.0 8995.7 9058.4 7716.6 8780.6 8806.2 8867.5 9315.9 06:13:12 INFO - PROCESS | 1934 | -------- Summary: end -------- 06:13:12 INFO - PROCESS | 1934 | 06:13:12 INFO - TEST-INFO | 1934: exit 0 06:13:12 INFO - TEST-OK | dromaeo_css | took 1114085ms 06:13:12 INFO - TEST-START | kraken 06:13:12 INFO - operating with platform_type : mac_ 06:13:12 INFO - Initialising browser for kraken test... 06:13:12 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmppIiZ4z/profile http://localhost:49738/getInfo.html) 06:13:15 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 06:13:15 INFO - PROCESS | 1939 | colorDepth:24 06:13:15 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 06:13:15 INFO - PROCESS | 1939 | __metrics 06:13:15 INFO - PROCESS | 1939 | 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" 06:13:15 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:13:15 INFO - TEST-INFO | 1939: exit 0 06:13:15 INFO - Browser initialized. 06:13:15 INFO - Running cycle 1/1 for kraken test... 06:13:15 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmppIiZ4z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:13:27 INFO - PROCESS | 1941 | RSS: Main: 237772800 06:13:27 INFO - PROCESS | 1941 | 06:13:30 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49738/tests/kraken/driver.html?testName=audio-beat-detection) 06:13:31 INFO - PROCESS | 1941 | RSS: Main: 287309824 06:13:31 INFO - PROCESS | 1941 | 06:13:34 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49738/tests/kraken/driver.html?testName=audio-dft) 06:13:34 INFO - PROCESS | 1941 | RSS: Main: 300863488 06:13:34 INFO - PROCESS | 1941 | 06:13:38 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49738/tests/kraken/driver.html?testName=audio-fft) 06:13:38 INFO - PROCESS | 1941 | RSS: Main: 383639552 06:13:38 INFO - PROCESS | 1941 | 06:13:40 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49738/tests/kraken/driver.html?testName=audio-oscillator) 06:13:41 INFO - PROCESS | 1941 | RSS: Main: 286752768 06:13:41 INFO - PROCESS | 1941 | 06:13:43 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49738/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:13:44 INFO - PROCESS | 1941 | RSS: Main: 290938880 06:13:44 INFO - PROCESS | 1941 | 06:13:47 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49738/tests/kraken/driver.html?testName=imaging-darkroom) 06:13:47 INFO - PROCESS | 1941 | RSS: Main: 330362880 06:13:47 INFO - PROCESS | 1941 | 06:13:50 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49738/tests/kraken/driver.html?testName=imaging-desaturate) 06:13:51 INFO - PROCESS | 1941 | RSS: Main: 328871936 06:13:51 INFO - PROCESS | 1941 | 06:13:54 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49738/tests/kraken/driver.html?testName=json-parse-financial) 06:13:54 INFO - PROCESS | 1941 | RSS: Main: 327565312 06:13:54 INFO - PROCESS | 1941 | 06:13:56 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49738/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:13:56 INFO - PROCESS | 1941 | RSS: Main: 320016384 06:13:56 INFO - PROCESS | 1941 | 06:13:58 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:13:59 INFO - PROCESS | 1941 | RSS: Main: 326639616 06:13:59 INFO - PROCESS | 1941 | 06:14:01 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:02 INFO - PROCESS | 1941 | RSS: Main: 331067392 06:14:02 INFO - PROCESS | 1941 | 06:14:04 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:05 INFO - PROCESS | 1941 | RSS: Main: 334663680 06:14:05 INFO - PROCESS | 1941 | 06:14:08 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:14:09 INFO - PROCESS | 1941 | RSS: Main: 332353536 06:14:09 INFO - PROCESS | 1941 | 06:14:10 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49738/tests/kraken/driver.html?testName=ai-astar) 06:14:11 INFO - PROCESS | 1941 | RSS: Main: 397258752 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | __start_tp_report 06:14:11 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 06:14:11 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 06:14:11 INFO - PROCESS | 1941 | |i|pagename|runs| 06:14:11 INFO - PROCESS | 1941 | |0;ai-astar;111;109;105;111;111;113;117;112;117;162 06:14:11 INFO - PROCESS | 1941 | |1;audio-beat-detection;158;121;142;170;120;139;140;123;151;128 06:14:11 INFO - PROCESS | 1941 | |2;audio-dft;191;185;186;184;185;177;184;183;181;187 06:14:11 INFO - PROCESS | 1941 | |3;audio-fft;73;74;85;88;58;91;84;115;85;55 06:14:11 INFO - PROCESS | 1941 | |4;audio-oscillator;98;130;182;97;79;78;129;80;77;127 06:14:11 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;88;86;89;85;89;85;88;85;88;85 06:14:11 INFO - PROCESS | 1941 | |6;imaging-darkroom;93;93;93;94;92;93;105;126;94;94 06:14:11 INFO - PROCESS | 1941 | |7;imaging-desaturate;85;83;85;83;83;83;83;85;84;83 06:14:11 INFO - PROCESS | 1941 | |8;json-parse-financial;60;62;59;59;58;58;58;80;60;57 06:14:11 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;63;64;71;75;68;67;67;67;65;68 06:14:11 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;81;82;82;82;82;78;77;81;84;83 06:14:11 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;154;154;141;135;136;137;135;139;178;142 06:14:11 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;171;171;178;166;171;170;170;167;168;168 06:14:11 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;70;70;71;69;68;101;94;97;99;69 06:14:11 INFO - PROCESS | 1941 | __end_tp_report 06:14:11 INFO - PROCESS | 1941 | __start_cc_report 06:14:11 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1996 06:14:11 INFO - PROCESS | 1941 | __end_cc_report 06:14:11 INFO - PROCESS | 1941 | __startTimestamp1461071651205__endTimestamp 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | ------- Summary: start ------- 06:14:11 INFO - PROCESS | 1941 | Number of tests: 14 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:116.80 Median:112.50 stddev:16.27 (14.5%) stddev-sans-first:17.12 06:14:11 INFO - PROCESS | 1941 | Values: 111.0 109.0 105.0 111.0 111.0 113.0 117.0 112.0 117.0 162.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:141.00 stddev:16.79 (11.9%) stddev-sans-first:16.37 06:14:11 INFO - PROCESS | 1941 | Values: 158.0 121.0 142.0 170.0 120.0 139.0 140.0 123.0 151.0 128.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:3.68 (2.0%) stddev-sans-first:3.00 06:14:11 INFO - PROCESS | 1941 | Values: 191.0 185.0 186.0 184.0 185.0 177.0 184.0 183.0 181.0 187.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:80.80 Median:84.50 stddev:17.20 (20.4%) stddev-sans-first:18.01 06:14:11 INFO - PROCESS | 1941 | Values: 73.0 74.0 85.0 88.0 58.0 91.0 84.0 115.0 85.0 55.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:107.70 Median:78.50 stddev:34.10 (43.4%) stddev-sans-first:35.99 06:14:11 INFO - PROCESS | 1941 | Values: 98.0 130.0 182.0 97.0 79.0 78.0 129.0 80.0 77.0 127.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 06:14:11 INFO - PROCESS | 1941 | Values: 88.0 86.0 89.0 85.0 89.0 85.0 88.0 85.0 88.0 85.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:93.00 stddev:10.63 (11.4%) stddev-sans-first:11.13 06:14:11 INFO - PROCESS | 1941 | Values: 93.0 93.0 93.0 94.0 92.0 93.0 105.0 126.0 94.0 94.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:83.50 stddev:0.95 (1.1%) stddev-sans-first:0.88 06:14:11 INFO - PROCESS | 1941 | Values: 85.0 83.0 85.0 83.0 83.0 83.0 83.0 85.0 84.0 83.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:59.50 stddev:6.79 (11.4%) stddev-sans-first:7.19 06:14:11 INFO - PROCESS | 1941 | Values: 60.0 62.0 59.0 59.0 58.0 58.0 58.0 80.0 60.0 57.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:67.50 stddev:3.47 (5.1%) stddev-sans-first:3.28 06:14:11 INFO - PROCESS | 1941 | Values: 63.0 64.0 71.0 75.0 68.0 67.0 67.0 67.0 65.0 68.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:82.00 stddev:2.15 (2.6%) stddev-sans-first:2.28 06:14:11 INFO - PROCESS | 1941 | Values: 81.0 82.0 82.0 82.0 82.0 78.0 77.0 81.0 84.0 83.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:141.50 stddev:13.57 (9.6%) stddev-sans-first:14.00 06:14:11 INFO - PROCESS | 1941 | Values: 154.0 154.0 141.0 135.0 136.0 137.0 135.0 139.0 178.0 142.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.50 stddev:3.33 (2.0%) stddev-sans-first:3.52 06:14:11 INFO - PROCESS | 1941 | Values: 171.0 171.0 178.0 166.0 171.0 170.0 170.0 167.0 168.0 168.0 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.80 Median:70.50 stddev:14.71 (20.9%) stddev-sans-first:15.07 06:14:11 INFO - PROCESS | 1941 | Values: 70.0 70.0 71.0 69.0 68.0 101.0 94.0 97.0 99.0 69.0 06:14:11 INFO - PROCESS | 1941 | -------- Summary: end -------- 06:14:11 INFO - PROCESS | 1941 | 06:14:11 INFO - TEST-INFO | 1941: exit 0 06:14:11 INFO - TEST-OK | kraken | took 58980ms 06:14:11 INFO - SUITE-END | took 1173s 06:14:12 INFO - Completed test suite (00:19:33) 06:14:12 INFO - javascript benchmark 06:14:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2893.4936386132426, 3432.1591210153515, 3458.775179613325, 3464.982675086624, 3516.613172551019, 5654.915176272349, 5715.199872017915, 5729.770809167629, 5741.827745167647, 5749.683767392802, 5397.757100930457, 5718.685472299032, 5774.82675519734, 5844.064949608063, 5851.3563508014195, 5157.510036546522, 5174.249733788598, 5185.377754669426, 5207.010667973092, 5285.682859028451, 5270.657407268534, 5273.182656688219, 5308.761105750251, 5309.8938021239555, 5356.651817631861, 5102.056119617882, 5154.4072431670465, 5157.30376399185, 5211.374635043781, 5262.552683021948, 5158.303629010074, 5182.144946083891, 5182.455842136596, 5188.195829646411, 5250.081235783722, 5128.538431541179, 5164.096283150471, 5182.378114626269, 5186.818461353834, 5199.714015729133, 5532.972335138298, 5551.611387202897, 5580.302462192226, 5653.943460565424, 5677.318721753375, 6229.58830069951, 6232.688365581748, 6238.968805155991, 6243.0, 6258.186435763367, 6154.938450615481, 6240.781572644981, 6296.118543403927, 6307.432331090224, 6318.968405157991, 5766.481016708474, 5883.764649414061, 5885.617434866763, 5892.852678683067, 5903.2325797646035, 5227.738613069408, 6250.402923863478, 6281.403266689657, 6312.43188113072, 6338.461230795342, 6038.849028774316, 6088.6042407243385, 6109.053096770008, 6112.144299798032, 6113.205283313141, 6132.814448934737, 6245.906311405332, 6254.781082662176, 6255.624662520263, 6264.655443950626, 6129.939558357044, 6176.0, 6280.2777680566405, 6286.779962701375, 6288.371162883675, 5426.701531415731, 5531.640443371168, 6283.622982621057, 6292.653904035231, 6310.432061114522, 4954.256861470808, 4962.503749625081, 4970.676906001098, 4985.177445721415, 4985.800567977313, 3726.6832319253167, 4396.07682386696, 4508.143452744033, 4530.161920044802, 4536.455625324982, 5663.801766938137, 5666.886662266814, 5668.0647693130995, 5677.0, 5688.146777983253, 6210.192674952227, 6269.028300613412, 6282.811515654537, 6284.24589049317, 6287.151234583364, 5579.246801681802, 5649.632773869767, 5689.203511508392, 5739.368669446358, 5767.250257466587, 5366.838994830161, 5420.0, 5421.620486565981, 5422.539084177889, 5430.212619170277, 6382.553221274559, 6382.648954307558, 6384.297727249906, 6399.104125422352, 6405.583637063576, 6156.569040167145, 6238.126662267196, 6256.687165641791, 6267.874642507216, 6273.404026617464, 6258.749650013949, 6273.62358258506, 6394.616323020634, 6401.8719625608155, 6405.903911441239, 6205.968970155121, 6221.222347206599, 6228.532860035606, 6236.877362074689, 6260.874782504416, 6120.538559592075, 6252.5935814171935, 6293.83564040654, 6313.715882785377, 6325.746970121144, 6129.325774164749, 6189.84525386869, 6191.071339298961, 6191.349908259748, 6193.659348735682, 5597.608167428404, 5948.631814682561, 6104.236970378703, 6118.663473509, 6121.173641558333, 5867.853303667443, 5961.165436838933, 6005.069214271794, 6008.218931538872, 6030.728617212324, 5974.492168165581, 6008.969955150371, 6083.391660833881, 6105.084237364431, 6115.816525504242, 5974.61165024272, 5994.580379373402, 6005.939940600713, 6006.639601623741, 6014.699265036818], "value": 5697.590284729696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.3244289102484, 507.13533425509496, 517.6531723745095, 518.277003580006, 577.8468705792965, 29309.853450732742, 38699.613003869956, 39769.60806371777, 39837.0, 39988.60011399885, 44177.0, 44846.55153448464, 44930.32604510935, 44947.32579011309, 45023.39812814967, 8546.70086546971, 9663.323567350271, 9885.851712224323, 9903.702888913309, 9957.10386065254, 8640.30877529796, 11120.833187502194, 11908.583199588016, 11958.162928595002, 11963.102767292445, 11471.024962878124, 11570.479328430241, 11587.246828956091, 11589.6523104307, 11590.130740194478, 8709.608067636907, 11539.423028848609, 11549.653510394659, 11630.011449026884, 11898.345590992492, 11420.371879546621, 11584.362860042695, 11585.420728963518, 11593.6521904343, 11607.76784464306, 38510.80744596287, 39038.82883513471, 39089.80455097735, 39155.804220979, 39158.80420597879, 3123.234807472184, 3157.289609837791, 3170.397624451347, 3371.0, 3871.825767840454, 26354.86822565875, 26850.7917143729, 28569.142925712054, 28998.855005725047, 29271.97548085807, 2718.9320266993286, 2759.1308737747545, 2765.806393552452, 2835.7447829695425, 2969.7624190064744, 485095.0, 485264.0, 486901.0, 487572.5621371906, 488095.5595222037, 35635.82182089073, 66041.66979165074, 66791.66604166948, 66893.0, 66898.0, 36085.63914360875, 36314.45528317078, 36388.0, 36389.81805091011, 36538.63461365405, 14845.628859278388, 15182.46861359847, 15200.619984500256, 15201.01193422424, 15266.694666106616, 11779.823302650468, 12099.697507562205, 12110.455029523693, 12153.0, 12792.296423696785, 8014.15851335613, 8232.17678232172, 8259.339252859756, 8269.139443625136, 8271.379646526415, 3395.3379091077495, 3419.2477654916374, 3423.7603367764514, 3432.5709286339206, 3451.0, 882.1311008656468, 951.9143277104955, 974.3277138774223, 1061.984070238947, 1110.4336788238056, 22804.885975570018, 29383.55924661132, 29766.553501697494, 29885.551716724265, 30502.54246186309, 1068.7808999155154, 1095.3263742798122, 1101.961431349899, 1138.5332013874272, 1147.3345459633392, 15536.223188840513, 15623.238857108685, 15783.289751961189, 16002.67994640124, 16084.67830643404, 643134.0, 650577.7471112709, 651841.0, 653451.0, 653453.0, 62780.37219627837, 63538.72922541616, 63647.36352636414, 63685.0, 63738.0, 1329.6808765896308, 1374.4570894496617, 1457.4388860289077, 1521.710874933759, 1596.0104735064335, 28674.426511470072, 28881.566776497948, 29293.85353073221, 29484.705152949053, 29689.703102969557, 48686.0, 48727.756361219384, 49278.0, 49419.7529012367, 49456.50543494663, 362155.0, 363567.0, 364643.0, 365967.0, 366073.71410287643, 21434.035468403647, 24877.378065548506, 24888.87555562283, 26775.062872799357, 28041.439171216054, 36568.817155914054, 36909.0, 38132.80933595314, 43811.0, 44703.55296446994, 31043.68956310408, 37701.0, 37929.62070379261, 38828.41757373585, 38908.41637375498, 34468.0, 36884.446733298486, 37132.62867371292, 37890.2421951565, 38392.42411363776, 36806.815965919996, 36818.26363472769, 37255.0, 37444.6255537452, 37615.6238437623, 25420.491590167723, 32449.34475554583, 33162.50256246207, 33212.0, 33472.497912530845, 19264.0, 24310.878445607657, 24404.145854894992, 24963.0, 25143.49713005766], "value": 18564.8003514158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.75593328736596, 390.9003204182933, 393.56117928509707, 394.5758309816946, 405.6268233225432, 18934.337298194565, 20047.1981120755, 21435.464113397134, 21455.785442145592, 21477.355679329605, 11941.940290298558, 12099.637010889688, 12312.938435307833, 12467.127301088927, 12474.93963013145, 5256.710880901539, 5376.354837419515, 5434.83695489136, 5438.320209973754, 5441.945580544183, 3874.748141370816, 4167.1457351242925, 5739.139129130622, 6090.969545152268, 6106.328303886569, 6083.330833608299, 6087.299960504554, 6101.603395779282, 6107.877842443148, 6147.784827531037, 6694.83262918426, 6709.496787740939, 6713.496487763413, 6719.529632925722, 6720.5631633943885, 3863.517060367454, 3954.980225098856, 4092.4270602115726, 5756.856078598027, 5791.305043394777, 13532.240808694807, 13706.314684265746, 14440.783388249185, 25195.24414267575, 25910.352241193934, 1908.9618207635908, 2012.1341686708922, 2159.546495236002, 2370.3363058343693, 2442.206282958046, 9854.556544955494, 10091.79816403675, 10227.335223210543, 10236.948815255875, 10365.118964887994, 1690.8478236958733, 1724.6981778188767, 1749.95625109372, 1797.0, 1808.9909550452164, 112981.0, 114068.0, 116270.99999999916, 118943.0, 120814.0, 11497.712557186138, 11595.362255075883, 11651.12616553745, 11658.358790266448, 11819.172657914036, 15359.692806143814, 15422.305996230196, 15477.22613869302, 15477.458288959833, 15490.9225453872, 8518.61666225009, 8695.130486951255, 8714.738557843275, 8806.86789698155, 8818.603162857558, 8413.0, 8440.282575980988, 8441.282490988211, 8543.145685431407, 8581.699640512552, 3835.808209589565, 4539.958227987257, 4635.473666851507, 4693.507181745936, 4708.599769019604, 1761.7357396390644, 1780.8130146334706, 1839.86949298825, 1950.249154075691, 1975.4764987278384, 762.4624639629048, 768.7501561992375, 902.4855832175729, 917.953532972412, 979.7011911366959, 16399.098049607146, 19031.0, 19251.941143236978, 20194.09126589307, 20242.0, 942.2414955960518, 950.277788880442, 954.5322791832091, 1041.9791604167874, 1067.055655744656, 8830.896137982752, 10132.54403551842, 10663.306885052447, 10719.678409647722, 10748.78502429947, 569135.0, 590595.0, 597386.0, 613442.0, 615274.0, 114244.85755142343, 118806.0, 130213.34893325662, 131488.68511314955, 131938.0, 1008.5562352564848, 1015.294370412571, 1032.4992378696327, 1033.8294181460126, 1038.963636272742, 14545.92727036358, 17446.738298925273, 17555.12224388801, 17574.912125439292, 18072.638547229246, 33224.33551329008, 47157.05685886332, 48550.51449485601, 49014.26478602895, 50420.243696345315, 69650.91047268591, 72029.6398518004, 72240.27759722546, 72954.0, 74765.0, 7213.458990575623, 7397.334239918433, 7649.77603583424, 7682.8079298018, 8461.830763384822, 7668.463207575416, 9148.359614826899, 11953.342566158597, 12229.755404891674, 12996.545120920722, 10670.199735019749, 10843.457827108456, 10875.728106797076, 11024.118070554498, 11202.887971120184, 10708.357498550113, 10786.406747628957, 10828.18788590843, 11007.724806879572, 11052.0, 10817.687575164193, 11032.393218373067, 11383.886161138615, 11383.975442210241, 11596.188266821242, 6747.966260168668, 7544.886826697713, 7673.616319183907, 7684.65419056155, 8573.657053717781, 5403.729813509231, 7941.880871787043, 9954.850677239703, 10931.398773067558, 11297.943510282395], "value": 9049.48016816708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.2090090000045, 1592.3153044190997, 1613.9677206455872, 1903.6287923854845, 2033.7762846086928, 7960.442769006172, 8524.422981748374, 8792.164744349277, 8834.95582522088, 8837.204651581356, 8308.293795027415, 8671.0, 8671.089535598767, 8673.392862499628, 8677.305815534757, 2174.8695078295355, 2390.3665528634924, 2431.671724317221, 2468.0004598137693, 2486.29140694902, 2489.2034548944343, 2503.7496250374907, 2504.173622704503, 2508.5233805576972, 2508.9749102509013, 2399.4841109161525, 2470.2095329494477, 2473.109680515013, 2473.950520989579, 2475.195561442529, 2154.407537927075, 2371.5731168389684, 2442.3771938155833, 2449.3998970252223, 2450.473148273112, 2458.913938012161, 2466.075221791828, 2492.264781889329, 2494.2641920633273, 2498.4378514834198, 7407.259274072603, 8560.186782255738, 8609.139086091403, 8651.610677519528, 8661.523616201106, 4910.165271903749, 5359.437259087778, 5367.765413954812, 5367.919481207785, 5389.51494365509, 3510.1400156961454, 3535.292941411728, 3560.768550044239, 3594.19130695594, 3616.0, 1019.0675531888387, 1019.4800651667703, 1040.4537617750666, 1045.0646671229283, 1048.0201012053753, 11037.227394082338, 11289.0968722502, 11311.886881131166, 11321.037711794508, 11343.546258149665, 4761.928571071437, 4793.542501289109, 4819.108464933998, 4904.313396124546, 4920.21276595743, 9936.205103591696, 9962.750931226632, 9963.701088967342, 9968.451735154491, 9979.80040399188, 5371.382291036581, 5385.353757549041, 5404.459554044564, 5429.104197807396, 5443.992861320668, 4400.515943246304, 4549.658775591844, 4761.142994261067, 4908.067467181224, 4908.852734417973, 3293.440115180425, 3543.7773967981007, 4018.33697439925, 4020.0954785173103, 4148.43996060534, 1332.7201287729492, 1375.0924389902816, 1375.9862401376058, 1384.5984664447399, 1388.3891087921281, 905.2215095018279, 914.7438717159206, 917.1974522292994, 947.7062110745553, 953.7663272498282, 5681.914771278434, 5722.62802917809, 5795.9277533656405, 5858.179854820329, 5950.345461999176, 447.8634016624961, 451.85314772699, 458.09526185783477, 460.70975285570546, 477.4413935695176, 3845.384738441836, 3896.785676787747, 3947.6447119758786, 3976.980115099406, 3978.6817054635558, 10102.69691909244, 10498.527566259536, 10644.680659580225, 10648.574057037787, 10662.573497060032, 10419.635312764018, 10542.209334299958, 10670.199735019905, 10676.51955661997, 10716.678499645022, 6790.253072162058, 7284.526505777108, 7737.767866963887, 7878.763637090897, 8084.353251739847, 5641.379448260606, 5645.9153112702525, 5709.172170035405, 5732.398098199629, 5845.599069019417, 9460.574274157818, 9682.27382946296, 9683.176929960751, 9700.175485083846, 9719.222462202866, 9340.906590934004, 9819.852702209615, 9904.35621684617, 9927.305088643725, 9954.850677239992, 6421.5825971311715, 6871.4502839773695, 7069.82325441868, 7327.926720732938, 7473.850522989401, 7438.59087750179, 7521.05986751656, 7526.134494533198, 7535.54786712799, 7538.773836784905, 6778.288279730557, 7044.225135235222, 7054.929450705427, 7166.0, 7201.3518783309755, 6065.332813390612, 6919.927411251264, 6959.652017399211, 6972.930270697431, 7078.4691148163065, 6496.220453545605, 6598.3401659833635, 6633.1376920999965, 6634.933650663432, 6639.668016599054, 4089.0, 4524.773761311987, 4790.520947905182, 4794.113089078532, 4900.897298107897, 4720.858374248778, 4751.928721069117, 4755.167845626932, 4808.9519104808505, 4875.0493653737185], "value": 4445.196032995128, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.7553956834532, 313.70198311603974, 322.162377817674, 325.14649046253584, 325.3639135490114, 5244.984134122876, 5735.483806457418, 5751.252337196169, 5813.186153938452, 5857.092150716646, 5435.211894275328, 5498.422665620113, 5706.3437704663975, 5745.511631511326, 5779.428154471262, 3650.872219472319, 3796.3925771876507, 4255.212785634652, 4393.62654174394, 4413.801378937956, 4318.460192475941, 4360.389545463622, 4392.912141757163, 4397.604215620593, 4407.713498622596, 4043.3126368517424, 4246.893827654302, 4273.67947403946, 4284.592963668462, 4354.651627869763, 4234.682398820086, 4278.272693642057, 4288.485381754178, 4291.24903141952, 4295.484541854966, 3715.0, 3716.9628303716886, 3720.0, 3720.386136287509, 3742.607026262258, 4972.5027497249985, 5349.635842860084, 5412.9458705412835, 5596.916046259309, 5654.943450565483, 4300.80646370914, 4391.253486907233, 4896.95103048972, 4907.435644900811, 4957.90084198314, 5749.0, 5963.850903727398, 6025.035994240944, 6046.0, 6055.485283750886, 3447.8106402434382, 3619.7285203609836, 3936.665383442411, 4054.4265910522986, 4089.5092588889524, 5988.401159883977, 6302.275238347603, 6330.145430366933, 6353.205849268841, 6380.489560835122, 3222.38774632819, 4123.60825721556, 4147.470043273601, 4170.4995400552125, 4191.517975432865, 4054.270231358325, 4149.875503734893, 4186.455760751144, 4215.957840421556, 4221.556736542619, 4136.358864376027, 4311.267084595626, 4393.244849247496, 4900.436449808318, 4941.219966725458, 6124.816255512342, 6167.414095660905, 6206.348333425016, 6210.285817130997, 6230.43926046658, 1202.8977536909285, 1319.617310979805, 1354.537099932071, 1440.3590402271097, 1457.271364317841, 803.2289002557493, 938.7043081429327, 949.2263804998904, 952.2001518724305, 956.5456408206186, 4769.0, 5559.027170245191, 5588.21764952907, 5671.6597004179885, 5683.914741278884, 6024.3072046714315, 6222.3466536013975, 6223.315435302113, 6271.498280137578, 6303.558750887486, 5055.595552355802, 5770.942290577041, 5778.422157784272, 5783.277090363705, 5789.089600431847, 2844.573314002916, 2934.471795076864, 3295.9176020599675, 3305.454599991023, 3358.9160270992934, 6166.414190651881, 6270.717817698214, 6651.439195909609, 6652.83367915806, 6703.994400839913, 5838.1242813577455, 6249.375062493805, 6477.902831457532, 6628.270890202074, 6634.0, 5736.311642602915, 6239.78160764371, 6652.667366631649, 6697.866042679217, 6776.7628133015105, 5099.5665368442615, 6283.0, 6408.647524386203, 6414.61512309263, 6448.580842245239, 5186.403563590085, 5255.608698042305, 5936.0, 6069.787557435468, 6406.487481001415, 5825.184474173704, 6681.365270299314, 6705.396514313735, 6707.3292670732535, 6710.228323742832, 4128.979355103205, 4187.518435379849, 4208.5901223090605, 4216.388623649615, 4219.873403797891, 6094.3600921904335, 6094.695265236632, 6122.6938653068055, 6338.778142764981, 6422.719934423212, 5385.946140538545, 5514.310711161105, 6152.462334157788, 6154.969225153845, 6195.535334849814, 6140.416660417253, 6144.38556144382, 6147.262328520606, 6168.660723660062, 6188.164597779422, 5551.500364967173, 5649.519790817826, 6015.759369625166, 6046.12331211963, 6076.392360763888, 6111.327753947151, 6145.9692701536205, 6152.907706384497, 6185.319614842454, 6197.566170368031, 5291.973540132275, 5711.257536520226, 6037.9094313584455, 6058.363871793398, 6125.84685382869], "value": 4432.845065197504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.4099987012077, 657.898025806, 673.9966300168498, 685.2154283345566, 757.129301303501, 14093.154410735364, 14510.782338264935, 14560.635984100403, 14587.854121458782, 14592.927035364808, 13864.722705545908, 13961.860381396184, 14075.859241407583, 14078.788818167735, 14088.577342679737, 735.1472292141117, 765.5712800831552, 937.2174234514172, 955.8996305387939, 956.6508224498043, 902.5036230073466, 936.0171819589438, 937.8968313485545, 939.741571067955, 941.0401390581603, 978.0806042320232, 988.0169231614518, 991.2912267728573, 992.1517102877044, 996.1035068438391, 763.3473380259909, 953.7949340891672, 980.3284749946274, 983.8868530119056, 985.635314933472, 733.6551820644289, 737.6127533045141, 738.8448425830582, 739.6967243430222, 740.5075624709561, 12727.109102362838, 12768.936155319258, 12779.233246005177, 12785.16896401731, 12787.87212127876, 12326.0, 12400.0, 12618.369081545978, 12663.36683165838, 12747.362631868462, 9888.357256778287, 10331.32846364984, 10453.634122805664, 10475.1096156826, 10757.0, 3426.554547908756, 3480.1647604574973, 3541.0262177901027, 3572.267685124569, 3645.179834537208, 62246.0, 83843.0, 83977.0, 84076.0, 84147.57926210391, 9423.811523769486, 9497.0, 9507.524623768784, 9588.90411095894, 9675.984021677763, 22426.551468970854, 22436.32691019272, 22448.438789030406, 22467.775322246896, 22496.32511024672, 6332.176817013758, 6533.738650454024, 7357.632118394059, 7475.5888426136, 7728.536287822749, 3197.7921435106646, 4018.9397159042637, 4049.4735684360844, 4085.222928026318, 4290.485141783006, 3487.128217945514, 3592.586852511942, 3592.928141437209, 3595.586507551665, 3596.029072150505, 615.9476444502178, 638.7508871540103, 639.68015992004, 659.9967000164968, 662.0598749775235, 532.8135152696525, 563.7660370946094, 578.2049681687679, 598.5540772124795, 613.923259592551, 5996.310424301174, 7788.610569471503, 7800.336971357385, 8151.714689985822, 8471.364647651451, 525.8659041944356, 548.9560835133179, 569.8147109480939, 573.4494884910449, 600.1268154834705, 3503.089196808848, 3597.4963505109304, 3602.351576716164, 3627.9455808162897, 3674.8713795017047, 78092.0, 79457.0, 79842.40315193664, 80120.59939700381, 81268.59365703251, 73532.63233683797, 74597.62701186459, 74979.0, 75390.0, 76090.0, 55209.171862422096, 58659.12011319833, 64682.67658661676, 65805.0, 65909.0, 7041.6831242595235, 7114.217436081924, 7161.9641901790155, 7207.835904593501, 7247.0, 42917.0, 43048.78475607602, 43481.34777978269, 43522.78238608786, 43833.205853091546, 58915.705421472616, 62554.061689075614, 62894.68552657207, 62943.0, 63107.684461577395, 9704.514774261399, 9914.20686345105, 10243.641472548425, 10558.31370960885, 10897.400642964396, 10169.084782369622, 10325.690229293134, 11015.944920275348, 11172.664820055412, 11216.943915280697, 9394.953025235103, 9577.952110239683, 9741.123298903132, 10160.695179144637, 10628.990245926625, 8959.0, 8977.596008179517, 9036.23192028685, 9153.0, 9761.560729767036, 8211.95894020526, 9994.651256954465, 10073.899261007296, 10180.796384072426, 10301.587936482456, 8594.914050859661, 8642.783930401538, 8735.956320218358, 8995.730128096167, 9058.411203271766, 7716.614169291401, 8780.560971951249, 8806.20744133031, 8867.512286824029, 9315.906840931504], "value": 6546.22555433624, "unit": "score"}], "name": "dromaeo_css", "value": 7056.699971285653, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 105.0, 111.0, 111.0, 113.0, 117.0, 112.0, 117.0, 162.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 142.0, 170.0, 120.0, 139.0, 140.0, 123.0, 151.0, 128.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 185.0, 186.0, 184.0, 185.0, 177.0, 184.0, 183.0, 181.0, 187.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 85.0, 88.0, 58.0, 91.0, 84.0, 115.0, 85.0, 55.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 130.0, 182.0, 97.0, 79.0, 78.0, 129.0, 80.0, 77.0, 127.0], "value": 107.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 89.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 92.0, 93.0, 105.0, 126.0, 94.0, 94.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 83.0, 83.0, 83.0, 83.0, 85.0, 84.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 59.0, 59.0, 58.0, 58.0, 58.0, 80.0, 60.0, 57.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 75.0, 68.0, 67.0, 67.0, 67.0, 65.0, 68.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 82.0, 78.0, 77.0, 81.0, 84.0, 83.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 141.0, 135.0, 136.0, 137.0, 135.0, 139.0, 178.0, 142.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 178.0, 166.0, 171.0, 170.0, 170.0, 167.0, 168.0, 168.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 69.0, 68.0, 101.0, 94.0, 97.0, 99.0, 69.0], "value": 80.8, "unit": "score"}], "name": "kraken", "value": 1502.7, "alertThreshold": 2.0}]} 06:14:12 INFO - Return code: 0 06:14:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:14:12 ERROR - # TBPL SUCCESS # 06:14:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:14:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:14:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:14:12 INFO - 2016-04-19 06:14:12.543 screenresolution[1945:11204] starting screenresolution argv=screenresolution get 06:14:12 INFO - 2016-04-19 06:14:12.546 screenresolution[1945:11204] Display 0: 1600x1200x32@60 06:14:12 INFO - 2016-04-19 06:14:12.552 screenresolution[1946:11207] starting screenresolution argv=screenresolution list 06:14:12 INFO - Available Modes on Display 0 06:14:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:14:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:14:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:14:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:14:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:14:12 INFO - Intel Iris: 06:14:12 INFO - Chipset Model: Intel Iris 06:14:12 INFO - Type: GPU 06:14:12 INFO - Bus: Built-In 06:14:12 INFO - VRAM (Dynamic, Max): 1536 MB 06:14:12 INFO - Vendor: Intel (0x8086) 06:14:12 INFO - Device ID: 0x0a2e 06:14:12 INFO - Revision ID: 0x0009 06:14:12 INFO - Displays: 06:14:12 INFO - Display: 06:14:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:14:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:14:12 INFO - Display Serial Number: 1600x1200 60 06:14:12 INFO - Main Display: Yes 06:14:12 INFO - Mirror: Off 06:14:12 INFO - Online: Yes 06:14:12 INFO - Rotation: Supported 06:14:12 INFO - Return code: 0 06:14:12 INFO - Running post-action listener: _resource_record_post_action 06:14:12 INFO - Running post-run listener: _resource_record_post_run 06:14:13 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 995328; Write bytes: 694486016; Read time: 12; Write time: 1908 06:14:13 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373115392; Write bytes: 367977984; Read time: 28106; Write time: 756 06:14:13 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 995328; Write bytes: 297147904; Read time: 12; Write time: 1100 06:14:13 INFO - Running post-run listener: _upload_blobber_files 06:14:13 INFO - Blob upload gear active. 06:14:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:13 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 06:14:13 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'] 06:14:13 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 06:14:14 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:14:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:14:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:14 INFO - (blobuploader) - INFO - Done attempting. 06:14:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:14:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:14:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:15 INFO - (blobuploader) - INFO - Done attempting. 06:14:15 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:15 INFO - Return code: 0 06:14:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8eb5040014f2857f4acc3c603543022553b7cb0de2d179fdb6f131fbe9e8198030a1e662aa3a931475333ea86e732053f3abb6c577947d1d7fbb6909072d50c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e90792a59132fd1440e92ffeca57bcd66656d019b257b359106693ce2f8c7ac8fa3f9f39b871f4c704837935710367874bd974d95fad0eb617c75bdb60bd2375"} 06:14:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:15 INFO - Contents: 06:14:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8eb5040014f2857f4acc3c603543022553b7cb0de2d179fdb6f131fbe9e8198030a1e662aa3a931475333ea86e732053f3abb6c577947d1d7fbb6909072d50c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e90792a59132fd1440e92ffeca57bcd66656d019b257b359106693ce2f8c7ac8fa3f9f39b871f4c704837935710367874bd974d95fad0eb617c75bdb60bd2375"} 06:14:15 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:15 INFO - Copying logs to upload dir... 06:14:15 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.169075 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-19 06:14:16.316792) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-19 06:14:16.322227) ========= 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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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/8eb5040014f2857f4acc3c603543022553b7cb0de2d179fdb6f131fbe9e8198030a1e662aa3a931475333ea86e732053f3abb6c577947d1d7fbb6909072d50c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e90792a59132fd1440e92ffeca57bcd66656d019b257b359106693ce2f8c7ac8fa3f9f39b871f4c704837935710367874bd974d95fad0eb617c75bdb60bd2375"} build_url:https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/abwSz6csT4yn3IjwSlHsfw/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/8eb5040014f2857f4acc3c603543022553b7cb0de2d179fdb6f131fbe9e8198030a1e662aa3a931475333ea86e732053f3abb6c577947d1d7fbb6909072d50c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e90792a59132fd1440e92ffeca57bcd66656d019b257b359106693ce2f8c7ac8fa3f9f39b871f4c704837935710367874bd974d95fad0eb617c75bdb60bd2375"}' ========= master_lag: 3.87 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-19 06:14:20.200794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:14:20.201147) ========= 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.PLJSj5Glxa/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.6jQwaSzLkY/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.005380 ========= master_lag: 0.22 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:14:20.427328) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 06:14:20.428933) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 06:14:21.034298) ========= ========= Total master_lag: 4.59 =========