builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0134 starttime: 1462526876.98 results: success (0) buildid: 20160506003217 builduid: e7e5bde278b846049020aeeed4c7a097 revision: b17fa0c2f2e4537942a5d91b543c825f124564fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:56.984332) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:56.984785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:56.985060) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:57.004707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:57.004973) ========= 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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-05-06 02:27:57-- 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% 11.0M=0.001s 2016-05-06 02:27:57 (11.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112570 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:57.134789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:57.135093) ========= 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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.027184 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:57.182908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 02:27:57.183234) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b17fa0c2f2e4537942a5d91b543c825f124564fc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b17fa0c2f2e4537942a5d91b543c825f124564fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-05-06 02:27:57,256 truncating revision to first 12 chars 2016-05-06 02:27:57,257 Setting DEBUG logging. 2016-05-06 02:27:57,257 attempt 1/10 2016-05-06 02:27:57,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b17fa0c2f2e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 02:27:58,645 unpacking tar archive at: mozilla-aurora-b17fa0c2f2e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.630117 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 02:27:58.830300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:58.830663) ========= script_repo_revision: b17fa0c2f2e4537942a5d91b543c825f124564fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:58.831066) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:58.831330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 02:27:58.844512) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-06 02:27:58.844806) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:27:59 INFO - MultiFileLogger online at 20160506 02:27:59 in /builds/slave/test 02:27:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:27:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:59 INFO - {'append_to_log': False, 02:27:59 INFO - 'base_work_dir': '/builds/slave/test', 02:27:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:27:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:59 INFO - 'branch': 'Mozilla-Aurora', 02:27:59 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:59 INFO - 'config_files': ('talos/mac_config.py',), 02:27:59 INFO - 'default_actions': ('clobber', 02:27:59 INFO - 'read-buildbot-config', 02:27:59 INFO - 'download-and-extract', 02:27:59 INFO - 'populate-webroot', 02:27:59 INFO - 'create-virtualenv', 02:27:59 INFO - 'install', 02:27:59 INFO - 'run-tests'), 02:27:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:59 INFO - 'download_minidump_stackwalk': True, 02:27:59 INFO - 'download_symbols': 'ondemand', 02:27:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:59 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:59 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:59 INFO - 'installer_path': 'installer.exe', 02:27:59 INFO - 'log_level': 'info', 02:27:59 INFO - 'log_name': 'talos', 02:27:59 INFO - 'log_to_console': True, 02:27:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:59 INFO - 'opt_config_files': (), 02:27:59 INFO - 'pip_index': False, 02:27:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:59 INFO - 'cmd': ('bash', 02:27:59 INFO - '-c', 02:27:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:59 INFO - 'enabled': True, 02:27:59 INFO - 'halt_on_failure': False, 02:27:59 INFO - 'name': 'check_screen_resolution'},), 02:27:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:59 INFO - 'cmd': ('bash', 02:27:59 INFO - '-c', 02:27:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:59 INFO - 'enabled': True, 02:27:59 INFO - 'halt_on_failure': False, 02:27:59 INFO - 'name': 'check_screen_resolution'},), 02:27:59 INFO - 'run_cmd_checks_enabled': True, 02:27:59 INFO - 'sps_profile': False, 02:27:59 INFO - 'sps_profile_interval': 0, 02:27:59 INFO - 'suite': 'dromaeojs', 02:27:59 INFO - 'system_bits': '32', 02:27:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:59 INFO - 'title': 't-yosemite-r7-0134', 02:27:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:59 INFO - 'use_talos_json': True, 02:27:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:59 INFO - 'work_dir': 'build'} 02:27:59 INFO - ##### 02:27:59 INFO - ##### Running clobber step. 02:27:59 INFO - ##### 02:27:59 INFO - Running pre-action listener: _resource_record_pre_action 02:27:59 INFO - Running main action method: clobber 02:27:59 INFO - rmtree: /builds/slave/test/build 02:27:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:00 INFO - Running post-action listener: _resource_record_post_action 02:28:00 INFO - ##### 02:28:00 INFO - ##### Running read-buildbot-config step. 02:28:00 INFO - ##### 02:28:00 INFO - Running pre-action listener: _resource_record_pre_action 02:28:00 INFO - Running main action method: read_buildbot_config 02:28:00 INFO - Using buildbot properties: 02:28:00 INFO - { 02:28:00 INFO - "project": "", 02:28:00 INFO - "product": "firefox", 02:28:00 INFO - "script_repo_revision": "production", 02:28:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:28:00 INFO - "repository": "", 02:28:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:28:00 INFO - "buildid": "20160506003217", 02:28:00 INFO - "pgo_build": "False", 02:28:00 INFO - "basedir": "/builds/slave/test", 02:28:00 INFO - "buildnumber": 227, 02:28:00 INFO - "slavename": "t-yosemite-r7-0134", 02:28:00 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:28:00 INFO - "platform": "yosemite_r7", 02:28:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:28:00 INFO - "branch": "mozilla-aurora", 02:28:00 INFO - "repo_path": "releases/mozilla-aurora", 02:28:00 INFO - "revision": "b17fa0c2f2e4537942a5d91b543c825f124564fc", 02:28:00 INFO - "stage_platform": "macosx64", 02:28:00 INFO - "builduid": "e7e5bde278b846049020aeeed4c7a097", 02:28:00 INFO - "slavebuilddir": "test" 02:28:00 INFO - } 02:28:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:28:00 INFO - Running post-action listener: _resource_record_post_action 02:28:00 INFO - ##### 02:28:00 INFO - ##### Running download-and-extract step. 02:28:00 INFO - ##### 02:28:00 INFO - Running pre-action listener: _resource_record_pre_action 02:28:00 INFO - Running main action method: download_and_extract 02:28:00 INFO - mkdir: /builds/slave/test/build/tests 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:00 INFO - https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:28:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:28:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:28:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:28:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:28:02 INFO - Downloaded 1199 bytes. 02:28:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:28:02 INFO - Using the following test package requirements: 02:28:02 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:28:02 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:28:02 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:28:02 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'jsshell-mac.zip'], 02:28:02 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:28:02 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:28:02 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:28:02 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:28:02 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:28:02 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:28:02 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:28:02 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:02 INFO - https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:28:04 INFO - Downloaded 18113080 bytes. 02:28:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:28:07 INFO - Return code: 0 02:28:07 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:07 INFO - https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:28:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:28:11 INFO - Downloaded 18113080 bytes. 02:28:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:28:14 INFO - Return code: 0 02:28: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')]} 02:28:14 INFO - https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:28:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:28:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:28:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:28:15 INFO - Downloaded 10840145 bytes. 02:28:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:28:15 INFO - Return code: 0 02:28: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')]} 02:28:15 INFO - https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:28:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:28:21 INFO - Downloaded 114085027 bytes. 02:28:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:21 INFO - mkdir: /builds/slave/test/properties 02:28:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:21 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:21 INFO - Contents: 02:28:21 INFO - build_url:https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:28:22 INFO - Running post-action listener: _resource_record_post_action 02:28:22 INFO - Running post-action listener: set_extra_try_arguments 02:28:22 INFO - ##### 02:28:22 INFO - ##### Running populate-webroot step. 02:28:22 INFO - ##### 02:28:22 INFO - Running pre-action listener: _resource_record_pre_action 02:28:22 INFO - Running main action method: populate_webroot 02:28:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:22 INFO - '--tppagecycles', 02:28:22 INFO - '7'], 02:28:22 INFO - 'tests': ['tsvgm']}}, 02:28:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['damp', 'tps']}, 02:28:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'tests': ['damp', 'tps']}, 02:28:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['dromaeo_dom']}, 02:28:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['a11yr', 02:28:22 INFO - 'ts_paint', 02:28:22 INFO - 'tpaint', 02:28:22 INFO - 'sessionrestore', 02:28:22 INFO - 'sessionrestore_no_auto_restore', 02:28:22 INFO - 'tabpaint']}, 02:28:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['tsvgx', 02:28:22 INFO - 'tsvgr_opacity', 02:28:22 INFO - 'tart', 02:28:22 INFO - 'tscrollx', 02:28:22 INFO - 'cart']}, 02:28:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:22 INFO - 'tsvgr_opacity', 02:28:22 INFO - 'tart', 02:28:22 INFO - 'tscrollx', 02:28:22 INFO - 'cart']}, 02:28:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'talos_options': ['--disable-e10s'], 02:28:22 INFO - 'tests': ['tp5o']}, 02:28:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'tests': ['tp5o']}, 02:28:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'talos_options': ['--disable-e10s', 02:28:22 INFO - '--xperf_path', 02:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:22 INFO - 'tests': ['tp5n']}, 02:28:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:22 INFO - 'talos_options': ['--xperf_path', 02:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:22 INFO - 'tests': ['tp5n']}}, 02:28:22 INFO - 'talos.zip': {'path': '', 02:28:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:22 INFO - Running post-action listener: _resource_record_post_action 02:28:22 INFO - ##### 02:28:22 INFO - ##### Running create-virtualenv step. 02:28:22 INFO - ##### 02:28:22 INFO - Running pre-action listener: _resource_record_pre_action 02:28:22 INFO - Running main action method: create_virtualenv 02:28:22 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:22 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 02:28:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:22 INFO - Using real prefix '/tools/python27' 02:28:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:23 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:26 INFO - Installing pip.................done. 02:28:26 INFO - Return code: 0 02:28:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:26 INFO - 'HOME': '/Users/cltbld', 02:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:26 INFO - 'LOGNAME': 'cltbld', 02:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:26 INFO - 'MOZ_NO_REMOTE': '1', 02:28:26 INFO - 'NO_EM_RESTART': '1', 02:28:26 INFO - 'PAGER': '/bin/cat', 02:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:26 INFO - 'PWD': '/builds/slave/test', 02:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:26 INFO - 'SHELL': '/bin/bash', 02:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:26 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:26 INFO - 'USER': 'cltbld', 02:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:26 INFO - 'XPC_FLAGS': '0x0', 02:28:26 INFO - 'XPC_SERVICE_NAME': '0', 02:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:26 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 02:28:26 INFO - Cleaning up... 02:28:26 INFO - Return code: 0 02:28:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:26 INFO - 'HOME': '/Users/cltbld', 02:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:26 INFO - 'LOGNAME': 'cltbld', 02:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:26 INFO - 'MOZ_NO_REMOTE': '1', 02:28:26 INFO - 'NO_EM_RESTART': '1', 02:28:26 INFO - 'PAGER': '/bin/cat', 02:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:26 INFO - 'PWD': '/builds/slave/test', 02:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:26 INFO - 'SHELL': '/bin/bash', 02:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:26 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:26 INFO - 'USER': 'cltbld', 02:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:26 INFO - 'XPC_FLAGS': '0x0', 02:28:26 INFO - 'XPC_SERVICE_NAME': '0', 02:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:26 INFO - Downloading/unpacking psutil>=0.7.1 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:29 INFO - Installing collected packages: psutil 02:28:29 INFO - Running setup.py install for psutil 02:28:29 INFO - building 'psutil._psutil_osx' extension 02:28:29 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 02:28:30 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 02:28:30 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 02:28:30 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 02:28:30 INFO - building 'psutil._psutil_posix' extension 02:28:30 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 02:28:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:30 INFO - ^ 02:28:30 INFO - 1 warning generated. 02:28:30 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 02:28:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:30 INFO - Successfully installed psutil 02:28:30 INFO - Cleaning up... 02:28:31 INFO - Return code: 0 02:28:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:31 INFO - 'HOME': '/Users/cltbld', 02:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:31 INFO - 'LOGNAME': 'cltbld', 02:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:31 INFO - 'MOZ_NO_REMOTE': '1', 02:28:31 INFO - 'NO_EM_RESTART': '1', 02:28:31 INFO - 'PAGER': '/bin/cat', 02:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:31 INFO - 'PWD': '/builds/slave/test', 02:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:31 INFO - 'SHELL': '/bin/bash', 02:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:31 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:31 INFO - 'USER': 'cltbld', 02:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:31 INFO - 'XPC_FLAGS': '0x0', 02:28:31 INFO - 'XPC_SERVICE_NAME': '0', 02:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:28:33 INFO - Installing collected packages: mozsystemmonitor 02:28:33 INFO - Running setup.py install for mozsystemmonitor 02:28:33 INFO - Successfully installed mozsystemmonitor 02:28:33 INFO - Cleaning up... 02:28:34 INFO - Return code: 0 02:28:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:34 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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:34 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 02:28:34 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 02:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:34 INFO - 'HOME': '/Users/cltbld', 02:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:34 INFO - 'LOGNAME': 'cltbld', 02:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:34 INFO - 'MOZ_NO_REMOTE': '1', 02:28:34 INFO - 'NO_EM_RESTART': '1', 02:28:34 INFO - 'PAGER': '/bin/cat', 02:28:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:34 INFO - 'PWD': '/builds/slave/test', 02:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:34 INFO - 'SHELL': '/bin/bash', 02:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:34 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:34 INFO - 'USER': 'cltbld', 02:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:34 INFO - 'XPC_FLAGS': '0x0', 02:28:34 INFO - 'XPC_SERVICE_NAME': '0', 02:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:34 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:36 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 02:28:36 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 02:28:36 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 02:28:36 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 02:28:36 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 02:28:36 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 02:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:37 INFO - Downloading docopt-0.6.1.tar.gz 02:28:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:38 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:38 INFO - Running setup.py install for blobuploader 02:28:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:38 INFO - Running setup.py install for requests 02:28:38 INFO - Running setup.py install for docopt 02:28:38 INFO - Successfully installed blobuploader requests docopt 02:28:38 INFO - Cleaning up... 02:28:38 INFO - Return code: 0 02:28:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:38 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')]} 02:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:38 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')]} 02:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:38 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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:38 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 02:28:38 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 02:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:38 INFO - 'HOME': '/Users/cltbld', 02:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:38 INFO - 'LOGNAME': 'cltbld', 02:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:38 INFO - 'MOZ_NO_REMOTE': '1', 02:28:38 INFO - 'NO_EM_RESTART': '1', 02:28:38 INFO - 'PAGER': '/bin/cat', 02:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:38 INFO - 'PWD': '/builds/slave/test', 02:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:38 INFO - 'SHELL': '/bin/bash', 02:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:38 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:38 INFO - 'USER': 'cltbld', 02:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:38 INFO - 'XPC_FLAGS': '0x0', 02:28:38 INFO - 'XPC_SERVICE_NAME': '0', 02:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-OEajGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IzrcUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-343dar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ufEvTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-oS8luE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-fhiI4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Q33i2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-5n1o8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:39 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-18t6E7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IA6RmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-QjB6rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rRj4le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-XKcwoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-a5B1AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-yIw0QS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-pvxa61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:40 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-pT600d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:40 INFO - Running setup.py install for manifestparser 02:28:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozcrash 02:28:41 INFO - Running setup.py install for mozdebug 02:28:41 INFO - Running setup.py install for mozdevice 02:28:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozfile 02:28:41 INFO - Running setup.py install for mozhttpd 02:28:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozinfo 02:28:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:41 INFO - Running setup.py install for mozInstall 02:28:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Running setup.py install for mozleak 02:28:42 INFO - Running setup.py install for mozlog 02:28:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Running setup.py install for moznetwork 02:28:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Running setup.py install for mozprocess 02:28:42 INFO - Running setup.py install for mozprofile 02:28:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:42 INFO - Running setup.py install for mozrunner 02:28:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:43 INFO - Running setup.py install for mozscreenshot 02:28:43 INFO - Running setup.py install for moztest 02:28:43 INFO - Running setup.py install for mozversion 02:28:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:43 INFO - Cleaning up... 02:28:43 INFO - Return code: 0 02:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:43 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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:43 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 02:28:43 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 02:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:43 INFO - 'HOME': '/Users/cltbld', 02:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:43 INFO - 'LOGNAME': 'cltbld', 02:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:43 INFO - 'MOZ_NO_REMOTE': '1', 02:28:43 INFO - 'NO_EM_RESTART': '1', 02:28:43 INFO - 'PAGER': '/bin/cat', 02:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:43 INFO - 'PWD': '/builds/slave/test', 02:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:43 INFO - 'SHELL': '/bin/bash', 02:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:43 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:43 INFO - 'USER': 'cltbld', 02:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:43 INFO - 'XPC_FLAGS': '0x0', 02:28:43 INFO - 'XPC_SERVICE_NAME': '0', 02:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:43 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7v3BDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:43 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-f4IlCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:43 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)) 02:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:43 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ikLO8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-KIrLLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rN4_Ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-p4g88E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-V_y0Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-p038uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qXwQT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-BdivVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-nPElvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:44 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)) 02:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:44 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-j7iXH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:45 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)) 02:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:45 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-vQdeSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:45 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)) 02:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:45 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-6Sfmxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:45 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)) 02:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:45 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-fIFR80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:45 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)) 02:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:45 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IRngoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:45 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)) 02:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:45 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rFROQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:45 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)) 02:28:45 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)) 02:28:45 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)) 02:28:45 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)) 02:28:45 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)) 02:28:45 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)) 02:28:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:48 INFO - Downloading blessings-1.6.tar.gz 02:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:48 INFO - Installing collected packages: blessings 02:28:48 INFO - Running setup.py install for blessings 02:28:48 INFO - Successfully installed blessings 02:28:48 INFO - Cleaning up... 02:28:48 INFO - Return code: 0 02:28:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:48 INFO - Reading from file tmpfile_stdout 02:28:48 INFO - Current package versions: 02:28:48 INFO - blessings == 1.6 02:28:48 INFO - blobuploader == 1.2.4 02:28:48 INFO - docopt == 0.6.1 02:28:48 INFO - manifestparser == 1.1 02:28:48 INFO - mozInstall == 1.12 02:28:48 INFO - mozcrash == 0.17 02:28:48 INFO - mozdebug == 0.1 02:28:48 INFO - mozdevice == 0.48 02:28:48 INFO - mozfile == 1.2 02:28:48 INFO - mozhttpd == 0.7 02:28:48 INFO - mozinfo == 0.9 02:28:48 INFO - mozleak == 0.1 02:28:48 INFO - mozlog == 3.1 02:28:48 INFO - moznetwork == 0.27 02:28:48 INFO - mozprocess == 0.22 02:28:48 INFO - mozprofile == 0.28 02:28:48 INFO - mozrunner == 6.11 02:28:48 INFO - mozscreenshot == 0.1 02:28:48 INFO - mozsystemmonitor == 0.0 02:28:48 INFO - moztest == 0.7 02:28:48 INFO - mozversion == 1.4 02:28:48 INFO - psutil == 3.1.1 02:28:48 INFO - requests == 1.2.3 02:28:48 INFO - wsgiref == 0.1.2 02:28:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:48 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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:48 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 02:28:48 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 02:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:48 INFO - 'HOME': '/Users/cltbld', 02:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:48 INFO - 'LOGNAME': 'cltbld', 02:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:48 INFO - 'MOZ_NO_REMOTE': '1', 02:28:48 INFO - 'NO_EM_RESTART': '1', 02:28:48 INFO - 'PAGER': '/bin/cat', 02:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:48 INFO - 'PWD': '/builds/slave/test', 02:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:48 INFO - 'SHELL': '/bin/bash', 02:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:48 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:48 INFO - 'USER': 'cltbld', 02:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:48 INFO - 'XPC_FLAGS': '0x0', 02:28:48 INFO - 'XPC_SERVICE_NAME': '0', 02:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 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)) 02:28:48 INFO - Cleaning up... 02:28:48 INFO - Return code: 0 02:28:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:48 INFO - 'HOME': '/Users/cltbld', 02:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:48 INFO - 'LOGNAME': 'cltbld', 02:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:48 INFO - 'MOZ_NO_REMOTE': '1', 02:28:48 INFO - 'NO_EM_RESTART': '1', 02:28:48 INFO - 'PAGER': '/bin/cat', 02:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:48 INFO - 'PWD': '/builds/slave/test', 02:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:48 INFO - 'SHELL': '/bin/bash', 02:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:48 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:48 INFO - 'USER': 'cltbld', 02:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:48 INFO - 'XPC_FLAGS': '0x0', 02:28:48 INFO - 'XPC_SERVICE_NAME': '0', 02:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:49 INFO - Downloading/unpacking jsonschema 02:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:51 INFO - Downloading/unpacking functools32 (from jsonschema) 02:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:51 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:52 INFO - warning: no files found matching '*.txt' 02:28:52 INFO - no previously-included directories found matching 'build' 02:28:52 INFO - no previously-included directories found matching 'dist' 02:28:52 INFO - no previously-included directories found matching '.git*' 02:28:52 INFO - Installing collected packages: jsonschema, functools32 02:28:52 INFO - Running setup.py install for functools32 02:28:52 INFO - warning: no files found matching '*.txt' 02:28:52 INFO - no previously-included directories found matching 'build' 02:28:52 INFO - no previously-included directories found matching 'dist' 02:28:52 INFO - no previously-included directories found matching '.git*' 02:28:52 INFO - Successfully installed jsonschema functools32 02:28:52 INFO - Cleaning up... 02:28:52 INFO - Return code: 0 02:28:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:28:52 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')]} 02:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:52 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')]} 02:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:52 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 0x102f6da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d6e580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, '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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:28:52 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 02:28:52 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 02:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:52 INFO - 'HOME': '/Users/cltbld', 02:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:52 INFO - 'LOGNAME': 'cltbld', 02:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:52 INFO - 'MOZ_NO_REMOTE': '1', 02:28:52 INFO - 'NO_EM_RESTART': '1', 02:28:52 INFO - 'PAGER': '/bin/cat', 02:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:52 INFO - 'PWD': '/builds/slave/test', 02:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:52 INFO - 'SHELL': '/bin/bash', 02:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:28:52 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:52 INFO - 'USER': 'cltbld', 02:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:52 INFO - 'XPC_FLAGS': '0x0', 02:28:52 INFO - 'XPC_SERVICE_NAME': '0', 02:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:52 INFO - Downloading/unpacking flake8 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:28:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:28:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:28:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:56 INFO - no previously-included directories found matching 'docs/_build' 02:28:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:28:56 INFO - Running setup.py install for pep8 02:28:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:56 INFO - no previously-included directories found matching 'docs/_build' 02:28:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:28:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:28:56 INFO - Cleaning up... 02:28:56 INFO - Return code: 0 02:28:56 INFO - Running post-action listener: _resource_record_post_action 02:28:56 INFO - Running post-action listener: _start_resource_monitoring 02:28:56 INFO - Starting resource monitoring. 02:28:56 INFO - ##### 02:28:56 INFO - ##### Running install step. 02:28:56 INFO - ##### 02:28:56 INFO - Running pre-action listener: _resource_record_pre_action 02:28:56 INFO - Running main action method: install 02:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:56 INFO - Reading from file tmpfile_stdout 02:28:56 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:57 INFO - Reading from file tmpfile_stdout 02:28:57 INFO - Output received: 02:28:57 INFO - Usage: mozinstall [options] installer 02:28:57 INFO - Options: 02:28:57 INFO - -h, --help show this help message and exit 02:28:57 INFO - -d DEST, --destination=DEST 02:28:57 INFO - Directory to install application into. [default: 02:28:57 INFO - "/builds/slave/test"] 02:28:57 INFO - --app=APP Application being installed. [default: firefox] 02:28:57 INFO - mkdir: /builds/slave/test/build/application 02:28:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:29:28 INFO - Reading from file tmpfile_stdout 02:29:28 INFO - Output received: 02:29:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:29:28 INFO - Running post-action listener: _resource_record_post_action 02:29:28 INFO - ##### 02:29:28 INFO - ##### Running run-tests step. 02:29:28 INFO - ##### 02:29:28 INFO - Running pre-action listener: _resource_record_pre_action 02:29:28 INFO - Running main action method: run_tests 02:29:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:28 INFO - 2016-05-06 02:29:28.703 screenresolution[1645:7429] starting screenresolution argv=screenresolution get 02:29:28 INFO - 2016-05-06 02:29:28.705 screenresolution[1645:7429] Display 0: 1600x1200x32@60 02:29:28 INFO - 2016-05-06 02:29:28.711 screenresolution[1646:7431] starting screenresolution argv=screenresolution list 02:29:28 INFO - Available Modes on Display 0 02:29:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:28 INFO - Intel Iris: 02:29:28 INFO - Chipset Model: Intel Iris 02:29:28 INFO - Type: GPU 02:29:28 INFO - Bus: Built-In 02:29:28 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:28 INFO - Vendor: Intel (0x8086) 02:29:28 INFO - Device ID: 0x0a2e 02:29:28 INFO - Revision ID: 0x0009 02:29:28 INFO - Displays: 02:29:28 INFO - Display: 02:29:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:28 INFO - Display Serial Number: 1600x1200 60 02:29:28 INFO - Main Display: Yes 02:29:28 INFO - Mirror: Off 02:29:28 INFO - Online: Yes 02:29:28 INFO - Rotation: Supported 02:29:28 INFO - Return code: 0 02:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:28 INFO - Python 2.7.3 02:29:28 INFO - Return code: 0 02:29:28 INFO - grabbing minidump binary from tooltool 02:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e0ce00>, '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 0x102c75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e07b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:29:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:29:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:29 INFO - Return code: 0 02:29:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:29:30 INFO - Return code: 0 02:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 02:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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 02:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cKNP2nl74w/Render', 02:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:30 INFO - 'HOME': '/Users/cltbld', 02:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:30 INFO - 'LOGNAME': 'cltbld', 02:29:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:30 INFO - 'MOZ_NO_REMOTE': '1', 02:29:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:30 INFO - 'NO_EM_RESTART': '1', 02:29:30 INFO - 'PAGER': '/bin/cat', 02:29:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:30 INFO - 'PWD': '/builds/slave/test', 02:29:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:30 INFO - 'SHELL': '/bin/bash', 02:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yi4mZ85Dh0/Listeners', 02:29:30 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:30 INFO - 'USER': 'cltbld', 02:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:30 INFO - 'XPC_FLAGS': '0x0', 02:29:30 INFO - 'XPC_SERVICE_NAME': '0', 02:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 02:29:30 INFO - mozversion application_buildid: 20160506003217 02:29:30 INFO - mozversion application_changeset: b17fa0c2f2e4537942a5d91b543c825f124564fc 02:29:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:30 INFO - mozversion application_name: Firefox 02:29:30 INFO - mozversion application_remotingname: firefox-dev 02:29:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:30 INFO - mozversion application_vendor: Mozilla 02:29:30 INFO - mozversion application_version: 48.0a2 02:29:30 INFO - mozversion platform_buildid: 20160506003217 02:29:30 INFO - mozversion platform_changeset: b17fa0c2f2e4537942a5d91b543c825f124564fc 02:29:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:30 INFO - mozversion platform_version: 48.0a2 02:29:30 INFO - using testdate: 1462526970 02:29:30 INFO - actual date: 1462526970 02:29:30 INFO - starting webserver on 'localhost:49270' 02:29:30 INFO - SUITE-START | Running 2 tests 02:29:30 INFO - TEST-START | dromaeo_css 02:29:30 INFO - operating with platform_type : mac_ 02:29:30 INFO - Initialising browser for dromaeo_css test... 02:29:30 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpYnB4Cd/profile http://localhost:49270/getInfo.html) 02:29:32 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 02:29:32 INFO - PROCESS | 1664 | colorDepth:24 02:29:32 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 02:29:32 INFO - PROCESS | 1664 | __metrics 02:29:32 INFO - PROCESS | 1664 | 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" 02:29:32 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:29:33 INFO - TEST-INFO | 1664: exit 0 02:29:33 INFO - Browser initialized. 02:29:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:33 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpYnB4Cd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:45 INFO - PROCESS | 1666 | RSS: Main: 211435520 02:29:45 INFO - PROCESS | 1666 | 02:30:37 INFO - PROCESS | 1666 | 1462527036999 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] 02:32:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:32:49 INFO - PROCESS | 1666 | RSS: Main: 354050048 02:32:49 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:35:52 INFO - PROCESS | 1666 | RSS: Main: 357384192 02:35:52 INFO - PROCESS | 1666 | 02:38:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:38:55 INFO - PROCESS | 1666 | RSS: Main: 356675584 02:38:55 INFO - PROCESS | 1666 | 02:41:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 02:41:58 INFO - PROCESS | 1666 | RSS: Main: 354177024 02:41:58 INFO - PROCESS | 1666 | 02:45:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 02:45:02 INFO - PROCESS | 1666 | RSS: Main: 326631424 02:45:02 INFO - PROCESS | 1666 | 02:48:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 02:48:05 INFO - PROCESS | 1666 | RSS: Main: 369074176 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | __start_tp_report 02:48:05 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 02:48:05 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 02:48:05 INFO - PROCESS | 1666 | |i|pagename|runs| 02:48:05 INFO - PROCESS | 1666 | |0;dojo.html;2704.8369201243468;3423.606285277193;3428.8971330860054;3447.655234476551;3487.0584942065643;5638.971805140968;5655.802046928358;5658.0381335173015;5664.830055098349;5668.064769313059;5636.15457681348;5665.065264231397;5667.716614169285;5675.290588676416;5744.511716504092;5057.49425057495;5103.438621751604;5111.156659151245;5127.512886275797;5177.404598471187;5110.6422550421485;5111.05445492585;5120.769565369549;5125.00062487815;5130.333056702623;5107.9744601277125;5164.92252616211;5183.818566350178;5188.662736922107;5211.114110601206;5199.272101905755;5211.322528071327;5223.190405487156;5227.111391063529;5268.789248430058;5012.298278241049;5033.370828646419;5061.974690126526;5126.92309615356;5133.922991155136;4574.977125114387;5509.173623956399;5542.667439953616;5565.693886836222;5600.52395546379;6184.536159787989;6193.752249909998;6199.380061993809;6209.844753881144;6210.472109870667;4958.677685950402;6111.834843226386;6157.78447754331;6250.656213908233;6260.373962603749;5713.428657134336;5731.742071606787;5738.11059285803;5744.626599271034;5780.739866706051;6276.152719382916;6279.686015699197;6302.65335406557;6304.275008374004;6308.463780578611;5908.497777688945;6008.789692360834;6050.546209034252;6110.144579758838;6120.418560236771;6158.815235542941;6171.1668924694595;6174.197354343906;6175;6212.596181248295;5958.103967917971;6134.2332208473945;6145.907811382833;6156.353582873823;6188.226471691039;6005.309389420186;6116.908246376308;6130.325664176937;6196.690165491707;6234.0025595904435;4840.782164802592;4869.415670119537;4883.633727470454;4883.780229889663;4886.731229782325;4374.956250437519;4406.228909940747;4423.5576442355505;4443.600075993111;4481.596656300883;4671.696339737906;4685.672002959828;5576.972115139399;5578.330600327987;5592.356878958891;6192.102145189013;6198.442140207403;6224.813255602339;6225.8132256032395;6237.4698150657705;5125.897482050338;5525.419830917695;5545.805896793593;5551.916721249104;5566.666000040011;5247.344081989751;5256.816011439658;5258.526732594085;5267.236250743621;5267.762950667229;5107.412647545506;6254.155688981929;6275.874482510324;6288.5598008138995;6315.368463153648;6212.97485914819;6218.253809542884;6246.094316324016;6251.718672659832;6256.343083976299;6202.534809889186;6254.874902501833;6274.937250627435;6281.842953926189;6288;6076.605020673642;6153.323134455296;6165.938340616716;6171.660558669317;6177.84555386119;4572.542745725401;5756.6759645282655;6192.969035154795;6194.194754681863;6219.906701399573;5838.299404071538;5957.4042595740075;5958.225430693982;5966.820995370146;5978.3423823378525;5942.494887934401;5951.7619295227705;6002.399760023963;6023.819285421444;6063.090536419572;4822.7829747662145;5819.185314056121;5820.388859169896;5826.941730582809;5845.678487683048;5908.793192238251;5983.730732117152;5984.521238300838;5991.970040149771;5999.88000239984;5818.76724930998;5838.211841401527;5885.411458854252;5902.4097590240635;5916.437938395846 02:48:05 INFO - PROCESS | 1666 | |1;ext.html;514.3467795899207;520.731823111098;533.919912013198;541.1422894711882;595.351067336603;30824;31606.84196579017;38994;40223.396649050286;40224.79325620229;44742.88142796432;45472.772636136775;45530;45556.772216138874;45634;9417.623295068206;9676.983916688752;9692.46691431971;9696.418214907093;9696.51517424126;6901.34437228465;6907.620080895549;7382.114146302424;7405.888911666302;7417.777466676009;7445.4415918806035;7451.143118541356;7457.477976541644;7459.142198647143;7620.123685776123;7418.07274090736;7422.2577742225885;7428.145763237243;7431.665575049135;7578.545287282753;9361.859572106356;9470.242380609603;9474.526273686288;9496.762580935463;9497.857532136954;41511.792441037906;41951;41986.16027679429;41989.79005104985;42080.158396831896;3152.4956007038995;3275.410426123297;3295.2585668224697;3384.76306658534;3653.1415117447377;29440.48627372839;29617.407651846843;29874;30025.949091781902;30220.546691799642;2744.6020327052665;2752.3944732159093;2756.476269508787;2769.819961702503;2785.9721402785917;494358.9999999964;495697;496314;498274;498463;64781;65278.67360663261;65282;65392.67303663546;65502.67248663726;64614.676926616004;65797.67101164463;65973.01040484397;67186;67735.3226467729;17830.108494575477;17979.1010449477;18088.547786305186;18108.547286317684;18138.365157219436;13574.321283935764;13673.042886997815;13681.589552313446;13690.726185476435;13705.177689338672;17055.829441705424;20300.390988270374;20626.27808026712;20645.690314645293;20695;6740.528163028541;8046.114927357986;8187.345012398994;8203.876941845878;8241.711540096188;937.3157594955584;965.8840939087354;998.9400635961865;1039.932404393712;1105.6240878101485;29881.701182988327;29905.40189196204;30575.847120764254;30674.38651226963;30696.693033069383;1050.5215302198917;1107.9058280046286;1169.4036041618835;1199.3163896578867;1222.8715984821642;15200.011999220016;15493.07041577509;15571.766423503657;15940.760888586448;15997.440089596808;644449;646670;654786;657307;657767;62880.05679914805;63610.6819465909;63629.36370636327;63647.681761590895;63650;1379.475799196299;1389.7498450279047;1476.5717941796972;1519.3770554072778;1525.1840265457927;30317.393652127274;30429.695703043573;30627.846860765552;31209;31327.530087048257;53109.46890531045;53262.46737532575;53532.197017043996;53547.464525355805;53815;499461;501393;501510;501792;503868;22622.773772262066;22923;23037.07851685981;26501.734982649927;26508.469830602895;43214;44436.11127777491;45773.54226457693;46352.30471542997;46388.768056159504;34459.82770086134;36579;36776.44835327526;37469.250614988094;38192;36554;36561.72033978798;36829.815850920575;37120.44319335158;37579;36113;36408.63591364052;36531.721389751234;36766.264674706894;37381.81309093546;31075.68924310728;31672.93838839077;31711.524327135572;31841.840790795897;32961.505577415875;24813.87593062023;24956.37609059788;25235.87382063078;25332.620010699484;25730.614040789027 02:48:05 INFO - PROCESS | 1666 | |2;jquery.html;340.6338186449566;402.7824974513763;405.8863518214899;410.4151583992809;413.10356526337864;15298.082115073075;18354.908225458887;18815.341463048797;19327.903360483197;19547.609047819034;16366.345346186168;17632.03023833692;20485.48786280344;20547.38357849263;20919.790802091975;5923.85190370241;6109.297430795471;6141.201643786324;6156.199694039791;6183.690815459209;5882.6764527950945;5961.5230781537375;5964.79123230687;6041.3052498962525;6106.206193194878;5870.941290587082;5997.760089596411;6035.849103772397;6040.728167232463;6044.24446944132;5666.886662266752;5923.229980102581;5964.522838172936;5970.791022314198;6003.099535069753;5229.267902493654;5254.632175747699;5255.185446255837;5257.18513630384;5277.445868183824;25773.61339579908;27012.459750804876;27479;27521.724782752117;27831.582526262122;2167.783221677835;2302.9424264393356;2335.544568809083;2363.4445905212433;2956.586077949089;13578.93210533941;14004.71990560183;14827.555173344817;15108.546743597599;15565.533034008884;1677.7147884859603;1727.0932760300816;1739.3738254228467;1790.713485842259;1909.4748944040361;123358;127470.36264818709;130173.34913325372;131300;132638;11079.947404996512;11266.43667816606;11489.42552872353;11706;11713.355765432812;14833.406663733329;14909.701805964038;14944.327505262072;14954;14963.326650300764;7939.6824127035425;8383;8644.481331120152;9082.40964337316;9109.498977556297;9014.504202268938;9037.83675713495;9044.14080662336;9173.44959302444;9207.355485115977;5739.311282646109;6200.410960958701;6831.146106736658;6965.303469652994;7029;1608.3727346334938;1798.8381045705949;1817.1459414075364;1884.7172924061501;1896.535348839543;398.13604478282195;408.15104582468393;424.0437812732269;737.9372753316028;871.5337294547387;11568.76862462746;11687.88312116868;11856;11874.406279686154;12038.87961120395;359.5487662982992;403.90104424416205;407.7757233521551;416.4149370134965;447.2642503082411;5640.520555752725;5652.463016013472;6020.217371741645;6065.332813390523;6141.7543298267565;648510;658747.7062614752;663728;675122;677122.843157353;133419.66580334073;146065;146695.53304466818;148809;149849;469.88018055395656;475.443730834924;477.94025746781654;478.37571968581335;478.80847660935206;11118.499667515143;11635.41822908868;11768.52925882955;11782.52869885195;11841.644750657493;54496.72751636217;55320;57019.42980570141;58114.4188558109;58459.83080338284;70811.29188708047;80612.98467538359;83302;83486;88434;6525.412712855866;8345.874811877706;8421.578921054046;8516;8817.911820881965;8604.354673399395;10359.015893490106;12194.390280486117;13110.410031548412;13430.19418834873;11062.778744425228;11369.4883730234;11613.245139065933;11685.941570292094;11713.531458741554;9655.517224138624;11166.776664466503;11280.041196498389;11366.943165284121;11598.942005290257;10826.89173108259;11599.8260026098;11728.558510398869;11738.94130529342;11826.113041521749;8007.799805004922;8255.579607374833;8395.454295470774;8785.648574056966;8869.467831930106;8511.574421279036;10639.468026598794;11392;11679.598448185932;11714.414279286171 02:48:05 INFO - PROCESS | 1666 | |3;mootools.html;1399.0486469200953;1559.9922000389997;1585.3262363495514;1788.3561917709621;1919.9136038878241;7828.021497312836;8508.574571271434;8718.041015488292;8735.694250701226;8740;8399.832003359945;8604.91395086049;8624.741257762262;8627.827443451128;8630.352723545744;2446.2538925627678;2446.7186273578586;2450.387403149213;2451.7915977141965;2461.237016524883;2395.808335333178;2452.5953217719057;2455.668484754562;2462.8645424501647;2469.6665950096776;2402.147237730607;2427.927162185137;2447.840890342122;2450.767177118171;2456.6192240202795;2404.3989002749313;2419.8185136114776;2436.4639779248537;2439.2194497760725;2441.6093425051995;2394.6288325309606;2420.8305418620703;2421.128393778238;2426.987865060681;2428.089466450081;8544;8571.95714021426;8683.13168683133;8688.956555217246;8700.30397568193;5189.455107213764;5403.729813509309;5407.3781515125875;5443.673379597237;5471.972640136773;3452.188735647121;3564.7861128332124;3630.2920930418577;3636.163682353047;3648.252108317815;1017.9440130792804;1019.8470229465565;1040.4745603470274;1054.225144518778;1071.2929466552039;11644.708882277926;11773.882261177365;11801.94099029508;11802.586909458214;11807.704807379798;3774.7923864187733;4620.775494493962;4724.551167639069;4747.810087596457;4769.570738633471;9234.353595248402;9260.259179265642;9269.90730092704;9271.67549135777;9300.813983720423;5303.124984377611;5370.892582148335;5379.757910894019;5380.43505431924;5380.892382152335;4437.933430998538;4606.930896036562;4729.929051064237;4920.8769780755765;4961.280614310905;4001.1797581495725;4030.496187976503;4124.175164967019;4180.352045432963;4181.97909010453;1335.5325636027508;1365.7678194706923;1375.7179778145455;1380.2270728392132;1384.3562743324403;704.5878161275608;814.9877751833727;844.6325848256029;919.3151102428733;932.0818993291603;5499.422560631157;5661.745221465044;5830.183774271605;5854.648721076749;5885.882282354414;492.11910679883323;492.7117636182873;496.23331952133316;508.6312423492938;658.9275179730225;3765.4351847223134;3928.3910993795434;3983.8605648802154;4051.0277533391773;4072.2466343726505;9232.90767092334;10203.9489802552;10781.02970732638;10807.621733239299;10816.134709223401;10526.789464210673;10602.734931626772;10645.0951669109;10674.0393364596;10675.679729608124;7722.884156737653;8139.837203255784;8197.139300373494;8212.671493140328;8259.95870020646;5825.359210486928;5883.852903677443;5979.252593425822;5994.250718660168;6028.4272994065495;9337.486438245962;9726.173275271682;9740.318177727491;9833.360831545926;9843;9502.952485237529;9789.706308810748;9930.602775888883;10108.39349639024;10230.386176829117;7216.494845360774;7528.623568821647;7533.020707308234;7535.8492830141995;7546.094468663796;8057.677692892219;8131.79670508242;8210.58947052633;8210.63052162642;8251.504909705436;7376.299251571092;7377.0409846719585;7395.519291246052;7467.58928258951;7524.247575242432;7205.207427183111;7284.125904891447;7361.484696071224;7425.480216384801;7448.664810083451;6838.794836154923;6921.82695432598;6927.480438966997;6934.375906168268;6938.93061069403;4843.1524483216;4882.707037577757;5065.645404821627;5074.695518268915;5102.974485127551;4888.828890988798;4930.161872481615;4987.875303117451;5019.372578427697;5220.738963051756 02:48:05 INFO - PROCESS | 1666 | |4;prototype.html;310.54350105345134;319.9328141090371;325.71662653491467;329.31173846660477;329.6406916461057;4172.77049762263;5275.522853600993;5328.040952628526;5340.531353877681;5354.946450535494;4043.2722110020186;4956.190990288545;5107.7701503432345;5130.6152038597065;5208.895822083556;3368.658782146409;3984.362501999688;3997.3404388276044;4013.4983127109113;4047.33219018863;3955.9011024724323;3978.323684973547;4022.43685883975;4035.4955630546183;4075.288378880877;3737.86917457889;3763.41667041609;3808.5620153682403;3820.2932457495313;3998.5001874765653;3361.411752943225;4044.413560033808;4047.251258517169;4051.270771261172;4087.773667899633;3232.466643003925;3385.627580966092;3555.5555555555557;3565.16218688608;3568.6966607838367;4835.2505361669;4896.583790377822;5051.595872330242;5098;5102.872428189288;4084.4281800547947;4406.603405693503;4574.748388838612;4585.770711464447;4633.305004249356;5403.1899313729555;5413.783448662049;5423.267858839085;5487.451254874521;5565.860853478695;3188.473901806199;3873.9806300968316;3900.941485877714;3911.6283953024417;3974.085960229135;5377.220303056035;5506.91739623906;5761.711914404263;5802.100674395476;5881.970590147064;3515.648435156464;3670.7063434925144;3895.714414243293;3925.4700615417123;3943.507061617298;3427.9485807712904;3480.3039392121173;3994.360902255625;4029.2143032108456;4036.6367026967127;3839.8080095995088;4371.30059190528;4575.794089265991;4624.260118381043;4652.860414187579;4738.47876733559;5474.479924407175;5486.286782718221;5551.250581171571;5592.384837667853;1472.6907349456735;1482.6664000599778;1488.761798112297;1492.0003597589641;1494.3798323695544;939.1500691873865;969.4425705219386;982.3074732313702;1004.4073996342194;1013.123648044451;4636.698614590041;4910.170067347474;5208.244804503326;5465;5560.4161563035295;5893.5579831512805;6118.663473508911;6170.598911070766;6198.721057552421;6214.130021796952;4773.665843390999;4818.975905120452;5163.638545301793;5194.454582268883;5201.590346040076;2858.814177078525;3242.918927026843;3243.708066274047;3274.3287626036604;3281.704646581819;4851.830185943546;5859.560532960045;6400.975843865052;6483.935160648333;6557.540972132;5571.888562228733;6401.935980640134;6406.8718625627225;6407.814554307468;6456.612603243821;5082.720450375265;5404.594655400861;5851.6781577012825;6524.934750652623;6547.0865464867675;5133.4096578893905;5999.430054144849;6162.630242185483;6178.907316390168;6211.937880621136;4863.319135321129;5756.2229099072765;6010.918034753787;6099.634021958696;6172.598781079216;5997.670128142994;6207.286162091417;6452.354764523698;6462.967685161544;6534.803955881332;4055.411965265079;4108.938365924573;4134.235166494208;4156.9423135548095;4170.687198460067;5510.559155267489;6305.180326557702;6309.980806141991;6314.652694101868;6324.976007677683;5057.6459647824295;5101.7194054327365;5641.984442800335;5653.607835298788;6118.357572454828;4676.532346765296;5603.663780173202;5642.294713160855;5642.633228840113;5652.406497317723;5132.486751324837;5195;5346.973265133805;5576.260178848109;5580.381689310055;4819.3252944588485;5486.753096110764;5487;5502.58730595199;5519.420460851713;4754.811297175706;5093.261477085876;5391.056565101013;5403.945960540502;5416.322959630046 02:48:05 INFO - PROCESS | 1666 | |5;yui.html;662.8508585568247;684.3532861445932;722.5159143373938;740.7592532426962;752.4281546025021;14693.77959330611;14822.036567623098;15244.390224391025;15270.999999999985;15291.464798732046;13972.790408143861;14063.718725625482;14069.94475414343;14081.507147249848;14088.084274522174;649.1690635985946;910.9726708198765;938.4322484896642;938.8779458670364;941.4210260689661;746;748.5920173505425;749.3293502315424;751.8308380614345;894.4812009034777;810.9229623185818;976.8485884687849;977.0376179463223;977.2377545514474;978.535195782005;951.4243882451121;960.0399600399601;960.0975083421596;960.582146766159;962.2302158273353;561.2283110722756;702.8980797784344;723.8516104198627;725.6988349834812;728.3954317916117;14930.178840163786;15016.924915375463;15028.947973641849;15034.248287585577;15038.699226015528;11478.139139564566;12219.327936963464;12611;13009.21944683322;13258;10041.598336066549;10082.69751907444;10272.383656980604;10485.842712359321;10487.161027117812;3464.1512829356716;3498.0380395391217;3505.1762835733584;3534.169470174507;3723.553173619156;80319.19680803234;82940.58529707312;83203.58398208031;83281.58359208226;83710.58144709299;9542.809143817085;9637.18083962871;9721.319507634538;9761.316707830383;10045.79908401828;22585.661215081785;22586.661200082013;22648.433789155402;22722;22767.08931642749;6387.520935929824;6990.475714321345;7247.782566523013;7320.1581818090535;7576.962115189389;3986.86045988389;4065.0853557949818;4154.210699966997;4154.522229943595;4333.934990975138;3532.823358832048;3602.6217247189193;3606.6032736398975;3609.4224924012556;3612.1330880588994;611.5841227964937;625.6402568523117;627.1314229791681;638.2525359140907;640.7437025189868;523.6282239609843;538.641803200867;562.0562551022948;578.9855253618693;595.4581330988866;8002.959985200153;8130.715424960098;8138.715144969898;8196.42625016255;8278.172182781673;514.3698968763235;533.2933862631951;546.9945300546943;606.263389981175;607.0529973241757;3844.4809950656863;3863.9613603864163;3880.2821478026653;3905.628965248297;3962.0689138052535;78814.60592697114;81850.59074704707;82033.17966820256;99339;101496;72797.27202727905;73477.6326118366;76368;79209.60395197987;80231.59884200542;54298.72850635721;70894;71420.92868606975;72288;73684.15789605303;7819.609019549113;7906.5651389174145;7932.6430310635005;8054.516728996279;8115.959420202861;43989.560104398544;44436.333454997555;44739;44986;45956.7702161487;59835.700821495615;63928.68035659792;63997.36002639914;64255;64532;11124.888751112385;11309.943450282695;11325.660230193107;11568.537258509901;12259.570915017932;11834.171607987359;11883.066630052313;11975.820362694392;12119.272843629411;12672.366381681064;9365.906340936503;11120.221584489009;11288.322700637988;11646.94176529112;11679.649610511698;11099.278546894426;11586.188966772244;11695.76608467809;11907.106966977339;12177.634670959886;10902.182336324966;11126.165537584875;11321.830172547583;11409.885901140882;11556.075513958695;9677.65949527278;9784;9786.85319720219;9807.411555306704;10005.199584033115;9092.181703646704;9292.860607091034;9476.810463790547;9515.33392662507;9573.664921727706 02:48:05 INFO - PROCESS | 1666 | __end_tp_report 02:48:05 INFO - PROCESS | 1666 | __start_cc_report 02:48:05 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1251 02:48:05 INFO - PROCESS | 1666 | __end_cc_report 02:48:05 INFO - PROCESS | 1666 | __startTimestamp1462528085071__endTimestamp 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | ------- Summary: start ------- 02:48:05 INFO - PROCESS | 1666 | Number of tests: 6 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5627.35 Median:5820.39 stddev:663.50 (11.4%) stddev-sans-first:624.67 02:48:05 INFO - PROCESS | 1666 | Values: 2704.8 3423.6 3428.9 3447.7 3487.1 5639.0 5655.8 5658.0 5664.8 5668.1 5636.2 5665.1 5667.7 5675.3 5744.5 5057.5 5103.4 5111.2 5127.5 5177.4 5110.6 5111.1 5120.8 5125.0 5130.3 5108.0 5164.9 5183.8 5188.7 5211.1 5199.3 5211.3 5223.2 5227.1 5268.8 5012.3 5033.4 5062.0 5126.9 5133.9 4575.0 5509.2 5542.7 5565.7 5600.5 6184.5 6193.8 6199.4 6209.8 6210.5 4958.7 6111.8 6157.8 6250.7 6260.4 5713.4 5731.7 5738.1 5744.6 5780.7 6276.2 6279.7 6302.7 6304.3 6308.5 5908.5 6008.8 6050.5 6110.1 6120.4 6158.8 6171.2 6174.2 6175.0 6212.6 5958.1 6134.2 6145.9 6156.4 6188.2 6005.3 6116.9 6130.3 6196.7 6234.0 4840.8 4869.4 4883.6 4883.8 4886.7 4375.0 4406.2 4423.6 4443.6 4481.6 4671.7 4685.7 5577.0 5578.3 5592.4 6192.1 6198.4 6224.8 6225.8 6237.5 5125.9 5525.4 5545.8 5551.9 5566.7 5247.3 5256.8 5258.5 5267.2 5267.8 5107.4 6254.2 6275.9 6288.6 6315.4 6213.0 6218.3 6246.1 6251.7 6256.3 6202.5 6254.9 6274.9 6281.8 6288.0 6076.6 6153.3 6165.9 6171.7 6177.8 4572.5 5756.7 6193.0 6194.2 6219.9 5838.3 5957.4 5958.2 5966.8 5978.3 5942.5 5951.8 6002.4 6023.8 6063.1 4822.8 5819.2 5820.4 5826.9 5845.7 5908.8 5983.7 5984.5 5992.0 5999.9 5818.8 5838.2 5885.4 5902.4 5916.4 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:68804.95 Median:40224.09 stddev:148229.04 (368.5%) stddev-sans-first:148556.18 02:48:05 INFO - PROCESS | 1666 | Values: 514.3 520.7 533.9 541.1 595.4 30824.0 31606.8 38994.0 40223.4 40224.8 44742.9 45472.8 45530.0 45556.8 45634.0 9417.6 9677.0 9692.5 9696.4 9696.5 6901.3 6907.6 7382.1 7405.9 7417.8 7445.4 7451.1 7457.5 7459.1 7620.1 7418.1 7422.3 7428.1 7431.7 7578.5 9361.9 9470.2 9474.5 9496.8 9497.9 41511.8 41951.0 41986.2 41989.8 42080.2 3152.5 3275.4 3295.3 3384.8 3653.1 29440.5 29617.4 29874.0 30025.9 30220.5 2744.6 2752.4 2756.5 2769.8 2786.0 494359.0 495697.0 496314.0 498274.0 498463.0 64781.0 65278.7 65282.0 65392.7 65502.7 64614.7 65797.7 65973.0 67186.0 67735.3 17830.1 17979.1 18088.5 18108.5 18138.4 13574.3 13673.0 13681.6 13690.7 13705.2 17055.8 20300.4 20626.3 20645.7 20695.0 6740.5 8046.1 8187.3 8203.9 8241.7 937.3 965.9 998.9 1039.9 1105.6 29881.7 29905.4 30575.8 30674.4 30696.7 1050.5 1107.9 1169.4 1199.3 1222.9 15200.0 15493.1 15571.8 15940.8 15997.4 644449.0 646670.0 654786.0 657307.0 657767.0 62880.1 63610.7 63629.4 63647.7 63650.0 1379.5 1389.7 1476.6 1519.4 1525.2 30317.4 30429.7 30627.8 31209.0 31327.5 53109.5 53262.5 53532.2 53547.5 53815.0 499461.0 501393.0 501510.0 501792.0 503868.0 22622.8 22923.0 23037.1 26501.7 26508.5 43214.0 44436.1 45773.5 46352.3 46388.8 34459.8 36579.0 36776.4 37469.3 38192.0 36554.0 36561.7 36829.8 37120.4 37579.0 36113.0 36408.6 36531.7 36766.3 37381.8 31075.7 31672.9 31711.5 31841.8 32961.5 24813.9 24956.4 25235.9 25332.6 25730.6 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:37384.97 Median:403.34 stddev:111189.88 (27567.2%) stddev-sans-first:111467.00 02:48:05 INFO - PROCESS | 1666 | Values: 340.6 402.8 405.9 410.4 413.1 15298.1 18354.9 18815.3 19327.9 19547.6 16366.3 17632.0 20485.5 20547.4 20919.8 5923.9 6109.3 6141.2 6156.2 6183.7 5882.7 5961.5 5964.8 6041.3 6106.2 5870.9 5997.8 6035.8 6040.7 6044.2 5666.9 5923.2 5964.5 5970.8 6003.1 5229.3 5254.6 5255.2 5257.2 5277.4 25773.6 27012.5 27479.0 27521.7 27831.6 2167.8 2302.9 2335.5 2363.4 2956.6 13578.9 14004.7 14827.6 15108.5 15565.5 1677.7 1727.1 1739.4 1790.7 1909.5 123358.0 127470.4 130173.3 131300.0 132638.0 11079.9 11266.4 11489.4 11706.0 11713.4 14833.4 14909.7 14944.3 14954.0 14963.3 7939.7 8383.0 8644.5 9082.4 9109.5 9014.5 9037.8 9044.1 9173.4 9207.4 5739.3 6200.4 6831.1 6965.3 7029.0 1608.4 1798.8 1817.1 1884.7 1896.5 398.1 408.2 424.0 737.9 871.5 11568.8 11687.9 11856.0 11874.4 12038.9 359.5 403.9 407.8 416.4 447.3 5640.5 5652.5 6020.2 6065.3 6141.8 648510.0 658747.7 663728.0 675122.0 677122.8 133419.7 146065.0 146695.5 148809.0 149849.0 469.9 475.4 477.9 478.4 478.8 11118.5 11635.4 11768.5 11782.5 11841.6 54496.7 55320.0 57019.4 58114.4 58459.8 70811.3 80613.0 83302.0 83486.0 88434.0 6525.4 8345.9 8421.6 8516.0 8817.9 8604.4 10359.0 12194.4 13110.4 13430.2 11062.8 11369.5 11613.2 11685.9 11713.5 9655.5 11166.8 11280.0 11366.9 11598.9 10826.9 11599.8 11728.6 11738.9 11826.1 8007.8 8255.6 8395.5 8785.6 8869.5 8511.6 10639.5 11392.0 11679.6 11714.4 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5659.85 Median:5070.17 stddev:3132.43 (61.8%) stddev-sans-first:3124.85 02:48:05 INFO - PROCESS | 1666 | Values: 1399.0 1560.0 1585.3 1788.4 1919.9 7828.0 8508.6 8718.0 8735.7 8740.0 8399.8 8604.9 8624.7 8627.8 8630.4 2446.3 2446.7 2450.4 2451.8 2461.2 2395.8 2452.6 2455.7 2462.9 2469.7 2402.1 2427.9 2447.8 2450.8 2456.6 2404.4 2419.8 2436.5 2439.2 2441.6 2394.6 2420.8 2421.1 2427.0 2428.1 8544.0 8572.0 8683.1 8689.0 8700.3 5189.5 5403.7 5407.4 5443.7 5472.0 3452.2 3564.8 3630.3 3636.2 3648.3 1017.9 1019.8 1040.5 1054.2 1071.3 11644.7 11773.9 11801.9 11802.6 11807.7 3774.8 4620.8 4724.6 4747.8 4769.6 9234.4 9260.3 9269.9 9271.7 9300.8 5303.1 5370.9 5379.8 5380.4 5380.9 4437.9 4606.9 4729.9 4920.9 4961.3 4001.2 4030.5 4124.2 4180.4 4182.0 1335.5 1365.8 1375.7 1380.2 1384.4 704.6 815.0 844.6 919.3 932.1 5499.4 5661.7 5830.2 5854.6 5885.9 492.1 492.7 496.2 508.6 658.9 3765.4 3928.4 3983.9 4051.0 4072.2 9232.9 10203.9 10781.0 10807.6 10816.1 10526.8 10602.7 10645.1 10674.0 10675.7 7722.9 8139.8 8197.1 8212.7 8260.0 5825.4 5883.9 5979.3 5994.3 6028.4 9337.5 9726.2 9740.3 9833.4 9843.0 9503.0 9789.7 9930.6 10108.4 10230.4 7216.5 7528.6 7533.0 7535.8 7546.1 8057.7 8131.8 8210.6 8210.6 8251.5 7376.3 7377.0 7395.5 7467.6 7524.2 7205.2 7284.1 7361.5 7425.5 7448.7 6838.8 6921.8 6927.5 6934.4 6938.9 4843.2 4882.7 5065.6 5074.7 5103.0 4888.8 4930.2 4987.9 5019.4 5220.7 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4624.00 Median:5095.63 stddev:1452.69 (28.5%) stddev-sans-first:1420.23 02:48:05 INFO - PROCESS | 1666 | Values: 310.5 319.9 325.7 329.3 329.6 4172.8 5275.5 5328.0 5340.5 5354.9 4043.3 4956.2 5107.8 5130.6 5208.9 3368.7 3984.4 3997.3 4013.5 4047.3 3955.9 3978.3 4022.4 4035.5 4075.3 3737.9 3763.4 3808.6 3820.3 3998.5 3361.4 4044.4 4047.3 4051.3 4087.8 3232.5 3385.6 3555.6 3565.2 3568.7 4835.3 4896.6 5051.6 5098.0 5102.9 4084.4 4406.6 4574.7 4585.8 4633.3 5403.2 5413.8 5423.3 5487.5 5565.9 3188.5 3874.0 3900.9 3911.6 3974.1 5377.2 5506.9 5761.7 5802.1 5882.0 3515.6 3670.7 3895.7 3925.5 3943.5 3427.9 3480.3 3994.4 4029.2 4036.6 3839.8 4371.3 4575.8 4624.3 4652.9 4738.5 5474.5 5486.3 5551.3 5592.4 1472.7 1482.7 1488.8 1492.0 1494.4 939.2 969.4 982.3 1004.4 1013.1 4636.7 4910.2 5208.2 5465.0 5560.4 5893.6 6118.7 6170.6 6198.7 6214.1 4773.7 4819.0 5163.6 5194.5 5201.6 2858.8 3242.9 3243.7 3274.3 3281.7 4851.8 5859.6 6401.0 6483.9 6557.5 5571.9 6401.9 6406.9 6407.8 6456.6 5082.7 5404.6 5851.7 6524.9 6547.1 5133.4 5999.4 6162.6 6178.9 6211.9 4863.3 5756.2 6010.9 6099.6 6172.6 5997.7 6207.3 6452.4 6463.0 6534.8 4055.4 4108.9 4134.2 4156.9 4170.7 5510.6 6305.2 6310.0 6314.7 6325.0 5057.6 5101.7 5642.0 5653.6 6118.4 4676.5 5603.7 5642.3 5642.6 5652.4 5132.5 5195.0 5347.0 5576.3 5580.4 4819.3 5486.8 5487.0 5502.6 5519.4 4754.8 5093.3 5391.1 5403.9 5416.3 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17935.41 Median:587.22 stddev:25041.90 (4264.5%) stddev-sans-first:25078.56 02:48:05 INFO - PROCESS | 1666 | Values: 662.9 684.4 722.5 740.8 752.4 14693.8 14822.0 15244.4 15271.0 15291.5 13972.8 14063.7 14069.9 14081.5 14088.1 649.2 911.0 938.4 938.9 941.4 746.0 748.6 749.3 751.8 894.5 810.9 976.8 977.0 977.2 978.5 951.4 960.0 960.1 960.6 962.2 561.2 702.9 723.9 725.7 728.4 14930.2 15016.9 15028.9 15034.2 15038.7 11478.1 12219.3 12611.0 13009.2 13258.0 10041.6 10082.7 10272.4 10485.8 10487.2 3464.2 3498.0 3505.2 3534.2 3723.6 80319.2 82940.6 83203.6 83281.6 83710.6 9542.8 9637.2 9721.3 9761.3 10045.8 22585.7 22586.7 22648.4 22722.0 22767.1 6387.5 6990.5 7247.8 7320.2 7577.0 3986.9 4065.1 4154.2 4154.5 4333.9 3532.8 3602.6 3606.6 3609.4 3612.1 611.6 625.6 627.1 638.3 640.7 523.6 538.6 562.1 579.0 595.5 8003.0 8130.7 8138.7 8196.4 8278.2 514.4 533.3 547.0 606.3 607.1 3844.5 3864.0 3880.3 3905.6 3962.1 78814.6 81850.6 82033.2 99339.0 101496.0 72797.3 73477.6 76368.0 79209.6 80231.6 54298.7 70894.0 71420.9 72288.0 73684.2 7819.6 7906.6 7932.6 8054.5 8116.0 43989.6 44436.3 44739.0 44986.0 45956.8 59835.7 63928.7 63997.4 64255.0 64532.0 11124.9 11309.9 11325.7 11568.5 12259.6 11834.2 11883.1 11975.8 12119.3 12672.4 9365.9 11120.2 11288.3 11646.9 11679.6 11099.3 11586.2 11695.8 11907.1 12177.6 10902.2 11126.2 11321.8 11409.9 11556.1 9677.7 9784.0 9786.9 9807.4 10005.2 9092.2 9292.9 9476.8 9515.3 9573.7 02:48:05 INFO - PROCESS | 1666 | -------- Summary: end -------- 02:48:05 INFO - PROCESS | 1666 | 02:48:05 INFO - TEST-INFO | 1666: exit 0 02:48:05 INFO - TEST-OK | dromaeo_css | took 1115355ms 02:48:05 INFO - TEST-START | kraken 02:48:05 INFO - operating with platform_type : mac_ 02:48:05 INFO - Initialising browser for kraken test... 02:48:05 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpFGSzT3/profile http://localhost:49270/getInfo.html) 02:48:07 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 02:48:07 INFO - PROCESS | 1676 | colorDepth:24 02:48:07 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 02:48:07 INFO - PROCESS | 1676 | __metrics 02:48:07 INFO - PROCESS | 1676 | 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" 02:48:07 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:48:08 INFO - TEST-INFO | 1676: exit 0 02:48:08 INFO - Browser initialized. 02:48:08 INFO - Running cycle 1/1 for kraken test... 02:48:08 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpFGSzT3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:20 INFO - PROCESS | 1678 | RSS: Main: 211415040 02:48:20 INFO - PROCESS | 1678 | 02:48:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:24 INFO - PROCESS | 1678 | RSS: Main: 268746752 02:48:24 INFO - PROCESS | 1678 | 02:48:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 02:48:27 INFO - PROCESS | 1678 | RSS: Main: 285032448 02:48:27 INFO - PROCESS | 1678 | 02:48:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 02:48:31 INFO - PROCESS | 1678 | RSS: Main: 372842496 02:48:31 INFO - PROCESS | 1678 | 02:48:33 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 02:48:33 INFO - PROCESS | 1678 | RSS: Main: 276828160 02:48:33 INFO - PROCESS | 1678 | 02:48:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:36 INFO - PROCESS | 1678 | RSS: Main: 277225472 02:48:36 INFO - PROCESS | 1678 | 02:48:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:39 INFO - PROCESS | 1678 | RSS: Main: 317542400 02:48:39 INFO - PROCESS | 1678 | 02:48:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:43 INFO - PROCESS | 1678 | RSS: Main: 317767680 02:48:43 INFO - PROCESS | 1678 | 02:48:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 02:48:46 INFO - PROCESS | 1678 | RSS: Main: 314953728 02:48:46 INFO - PROCESS | 1678 | 02:48:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:49 INFO - PROCESS | 1678 | RSS: Main: 306352128 02:48:49 INFO - PROCESS | 1678 | 02:48:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:51 INFO - PROCESS | 1678 | RSS: Main: 313135104 02:48:51 INFO - PROCESS | 1678 | 02:48:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:54 INFO - PROCESS | 1678 | RSS: Main: 315912192 02:48:54 INFO - PROCESS | 1678 | 02:48:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:57 INFO - PROCESS | 1678 | RSS: Main: 321392640 02:48:57 INFO - PROCESS | 1678 | 02:49:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:01 INFO - PROCESS | 1678 | RSS: Main: 319279104 02:49:01 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 02:49:03 INFO - PROCESS | 1678 | RSS: Main: 384524288 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | __start_tp_report 02:49:03 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 02:49:03 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 02:49:03 INFO - PROCESS | 1678 | |i|pagename|runs| 02:49:03 INFO - PROCESS | 1678 | |0;ai-astar;115;121;162;156;113;115;114;114;105;113 02:49:03 INFO - PROCESS | 1678 | |1;audio-beat-detection;154;120;142;166;106;176;170;151;182;118 02:49:03 INFO - PROCESS | 1678 | |2;audio-dft;186;185;184;184;183;183;186;190;182;186 02:49:03 INFO - PROCESS | 1678 | |3;audio-fft;73;77;75;151;60;55;56;66;135;55 02:49:03 INFO - PROCESS | 1678 | |4;audio-oscillator;89;89;143;77;82;86;124;80;77;124 02:49:03 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;85;84;86;83;86;84;87;84;87;83 02:49:03 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;92;101;93;93;93;92;93;92;92 02:49:03 INFO - PROCESS | 1678 | |7;imaging-desaturate;83;86;87;83;86;83;98;142;142;83 02:49:03 INFO - PROCESS | 1678 | |8;json-parse-financial;61;64;63;63;64;66;63;82;60;59 02:49:03 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;61;64;66;77;74;69;67;69;71;67 02:49:03 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;81;81;81;78;81;76;99;106;110 02:49:03 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;137;135;138;146;146;150;148;135;172;139 02:49:03 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;167;170;172;170;167;167;166;170;167;170 02:49:03 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;69;67;67;68;67;67;68;68;67;67 02:49:03 INFO - PROCESS | 1678 | __end_tp_report 02:49:03 INFO - PROCESS | 1678 | __start_cc_report 02:49:03 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1986 02:49:03 INFO - PROCESS | 1678 | __end_cc_report 02:49:03 INFO - PROCESS | 1678 | __startTimestamp1462528143390__endTimestamp 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | ------- Summary: start ------- 02:49:03 INFO - PROCESS | 1678 | Number of tests: 14 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:122.80 Median:115.00 stddev:19.52 (17.0%) stddev-sans-first:20.49 02:49:03 INFO - PROCESS | 1678 | Values: 115.0 121.0 162.0 156.0 113.0 115.0 114.0 114.0 105.0 113.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:160.00 stddev:26.40 (16.5%) stddev-sans-first:27.93 02:49:03 INFO - PROCESS | 1678 | Values: 154.0 120.0 142.0 166.0 106.0 176.0 170.0 151.0 182.0 118.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:2.28 (1.2%) stddev-sans-first:2.39 02:49:03 INFO - PROCESS | 1678 | Values: 186.0 185.0 184.0 184.0 183.0 183.0 186.0 190.0 182.0 186.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:80.30 Median:63.00 stddev:34.27 (54.4%) stddev-sans-first:36.25 02:49:03 INFO - PROCESS | 1678 | Values: 73.0 77.0 75.0 151.0 60.0 55.0 56.0 66.0 135.0 55.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:81.00 stddev:23.89 (29.5%) stddev-sans-first:25.16 02:49:03 INFO - PROCESS | 1678 | Values: 89.0 89.0 143.0 77.0 82.0 86.0 124.0 80.0 77.0 124.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:85.50 stddev:1.52 (1.8%) stddev-sans-first:1.62 02:49:03 INFO - PROCESS | 1678 | Values: 85.0 84.0 86.0 83.0 86.0 84.0 87.0 84.0 87.0 83.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:2.72 (2.9%) stddev-sans-first:2.88 02:49:03 INFO - PROCESS | 1678 | Values: 93.0 92.0 101.0 93.0 93.0 93.0 92.0 93.0 92.0 92.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:84.50 stddev:23.98 (28.4%) stddev-sans-first:24.87 02:49:03 INFO - PROCESS | 1678 | Values: 83.0 86.0 87.0 83.0 86.0 83.0 98.0 142.0 142.0 83.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:63.50 stddev:6.49 (10.2%) stddev-sans-first:6.75 02:49:03 INFO - PROCESS | 1678 | Values: 61.0 64.0 63.0 63.0 64.0 66.0 63.0 82.0 60.0 59.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:4.67 (6.8%) stddev-sans-first:4.09 02:49:03 INFO - PROCESS | 1678 | Values: 61.0 64.0 66.0 77.0 74.0 69.0 67.0 69.0 71.0 67.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:81.00 stddev:12.54 (15.5%) stddev-sans-first:13.08 02:49:03 INFO - PROCESS | 1678 | Values: 81.0 81.0 81.0 81.0 78.0 81.0 76.0 99.0 106.0 110.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:146.00 stddev:11.12 (7.6%) stddev-sans-first:11.45 02:49:03 INFO - PROCESS | 1678 | Values: 137.0 135.0 138.0 146.0 146.0 150.0 148.0 135.0 172.0 139.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:170.00 stddev:2.01 (1.2%) stddev-sans-first:2.05 02:49:03 INFO - PROCESS | 1678 | Values: 167.0 170.0 172.0 170.0 167.0 167.0 166.0 170.0 167.0 170.0 02:49:03 INFO - PROCESS | 1678 | 02:49:03 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:0.71 (1.0%) stddev-sans-first:0.50 02:49:03 INFO - PROCESS | 1678 | Values: 69.0 67.0 67.0 68.0 67.0 67.0 68.0 68.0 67.0 67.0 02:49:03 INFO - PROCESS | 1678 | -------- Summary: end -------- 02:49:03 INFO - PROCESS | 1678 | 02:49:04 INFO - TEST-INFO | 1678: exit 0 02:49:04 INFO - TEST-OK | kraken | took 58473ms 02:49:04 INFO - SUITE-END | took 1173s 02:49:04 INFO - Completed test suite (00:19:34) 02:49:04 INFO - javascript benchmark 02:49:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2704.8369201243468, 3423.606285277193, 3428.8971330860054, 3447.655234476551, 3487.0584942065643, 5638.971805140968, 5655.802046928358, 5658.0381335173015, 5664.830055098349, 5668.064769313059, 5636.15457681348, 5665.065264231397, 5667.716614169285, 5675.290588676416, 5744.511716504092, 5057.49425057495, 5103.438621751604, 5111.156659151245, 5127.512886275797, 5177.404598471187, 5110.6422550421485, 5111.05445492585, 5120.769565369549, 5125.00062487815, 5130.333056702623, 5107.9744601277125, 5164.92252616211, 5183.818566350178, 5188.662736922107, 5211.114110601206, 5199.272101905755, 5211.322528071327, 5223.190405487156, 5227.111391063529, 5268.789248430058, 5012.298278241049, 5033.370828646419, 5061.974690126526, 5126.92309615356, 5133.922991155136, 4574.977125114387, 5509.173623956399, 5542.667439953616, 5565.693886836222, 5600.52395546379, 6184.536159787989, 6193.752249909998, 6199.380061993809, 6209.844753881144, 6210.472109870667, 4958.677685950402, 6111.834843226386, 6157.78447754331, 6250.656213908233, 6260.373962603749, 5713.428657134336, 5731.742071606787, 5738.11059285803, 5744.626599271034, 5780.739866706051, 6276.152719382916, 6279.686015699197, 6302.65335406557, 6304.275008374004, 6308.463780578611, 5908.497777688945, 6008.789692360834, 6050.546209034252, 6110.144579758838, 6120.418560236771, 6158.815235542941, 6171.1668924694595, 6174.197354343906, 6175.0, 6212.596181248295, 5958.103967917971, 6134.2332208473945, 6145.907811382833, 6156.353582873823, 6188.226471691039, 6005.309389420186, 6116.908246376308, 6130.325664176937, 6196.690165491707, 6234.0025595904435, 4840.782164802592, 4869.415670119537, 4883.633727470454, 4883.780229889663, 4886.731229782325, 4374.956250437519, 4406.228909940747, 4423.5576442355505, 4443.600075993111, 4481.596656300883, 4671.696339737906, 4685.672002959828, 5576.972115139399, 5578.330600327987, 5592.356878958891, 6192.102145189013, 6198.442140207403, 6224.813255602339, 6225.8132256032395, 6237.4698150657705, 5125.897482050338, 5525.419830917695, 5545.805896793593, 5551.916721249104, 5566.666000040011, 5247.344081989751, 5256.816011439658, 5258.526732594085, 5267.236250743621, 5267.762950667229, 5107.412647545506, 6254.155688981929, 6275.874482510324, 6288.5598008138995, 6315.368463153648, 6212.97485914819, 6218.253809542884, 6246.094316324016, 6251.718672659832, 6256.343083976299, 6202.534809889186, 6254.874902501833, 6274.937250627435, 6281.842953926189, 6288.0, 6076.605020673642, 6153.323134455296, 6165.938340616716, 6171.660558669317, 6177.84555386119, 4572.542745725401, 5756.6759645282655, 6192.969035154795, 6194.194754681863, 6219.906701399573, 5838.299404071538, 5957.4042595740075, 5958.225430693982, 5966.820995370146, 5978.3423823378525, 5942.494887934401, 5951.7619295227705, 6002.399760023963, 6023.819285421444, 6063.090536419572, 4822.7829747662145, 5819.185314056121, 5820.388859169896, 5826.941730582809, 5845.678487683048, 5908.793192238251, 5983.730732117152, 5984.521238300838, 5991.970040149771, 5999.88000239984, 5818.76724930998, 5838.211841401527, 5885.411458854252, 5902.4097590240635, 5916.437938395846], "value": 5586.392371544389, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.3467795899207, 520.731823111098, 533.919912013198, 541.1422894711882, 595.351067336603, 30824.0, 31606.84196579017, 38994.0, 40223.396649050286, 40224.79325620229, 44742.88142796432, 45472.772636136775, 45530.0, 45556.772216138874, 45634.0, 9417.623295068206, 9676.983916688752, 9692.46691431971, 9696.418214907093, 9696.51517424126, 6901.34437228465, 6907.620080895549, 7382.114146302424, 7405.888911666302, 7417.777466676009, 7445.4415918806035, 7451.143118541356, 7457.477976541644, 7459.142198647143, 7620.123685776123, 7418.07274090736, 7422.2577742225885, 7428.145763237243, 7431.665575049135, 7578.545287282753, 9361.859572106356, 9470.242380609603, 9474.526273686288, 9496.762580935463, 9497.857532136954, 41511.792441037906, 41951.0, 41986.16027679429, 41989.79005104985, 42080.158396831896, 3152.4956007038995, 3275.410426123297, 3295.2585668224697, 3384.76306658534, 3653.1415117447377, 29440.48627372839, 29617.407651846843, 29874.0, 30025.949091781902, 30220.546691799642, 2744.6020327052665, 2752.3944732159093, 2756.476269508787, 2769.819961702503, 2785.9721402785917, 494358.9999999964, 495697.0, 496314.0, 498274.0, 498463.0, 64781.0, 65278.67360663261, 65282.0, 65392.67303663546, 65502.67248663726, 64614.676926616004, 65797.67101164463, 65973.01040484397, 67186.0, 67735.3226467729, 17830.108494575477, 17979.1010449477, 18088.547786305186, 18108.547286317684, 18138.365157219436, 13574.321283935764, 13673.042886997815, 13681.589552313446, 13690.726185476435, 13705.177689338672, 17055.829441705424, 20300.390988270374, 20626.27808026712, 20645.690314645293, 20695.0, 6740.528163028541, 8046.114927357986, 8187.345012398994, 8203.876941845878, 8241.711540096188, 937.3157594955584, 965.8840939087354, 998.9400635961865, 1039.932404393712, 1105.6240878101485, 29881.701182988327, 29905.40189196204, 30575.847120764254, 30674.38651226963, 30696.693033069383, 1050.5215302198917, 1107.9058280046286, 1169.4036041618835, 1199.3163896578867, 1222.8715984821642, 15200.011999220016, 15493.07041577509, 15571.766423503657, 15940.760888586448, 15997.440089596808, 644449.0, 646670.0, 654786.0, 657307.0, 657767.0, 62880.05679914805, 63610.6819465909, 63629.36370636327, 63647.681761590895, 63650.0, 1379.475799196299, 1389.7498450279047, 1476.5717941796972, 1519.3770554072778, 1525.1840265457927, 30317.393652127274, 30429.695703043573, 30627.846860765552, 31209.0, 31327.530087048257, 53109.46890531045, 53262.46737532575, 53532.197017043996, 53547.464525355805, 53815.0, 499461.0, 501393.0, 501510.0, 501792.0, 503868.0, 22622.773772262066, 22923.0, 23037.07851685981, 26501.734982649927, 26508.469830602895, 43214.0, 44436.11127777491, 45773.54226457693, 46352.30471542997, 46388.768056159504, 34459.82770086134, 36579.0, 36776.44835327526, 37469.250614988094, 38192.0, 36554.0, 36561.72033978798, 36829.815850920575, 37120.44319335158, 37579.0, 36113.0, 36408.63591364052, 36531.721389751234, 36766.264674706894, 37381.81309093546, 31075.68924310728, 31672.93838839077, 31711.524327135572, 31841.840790795897, 32961.505577415875, 24813.87593062023, 24956.37609059788, 25235.87382063078, 25332.620010699484, 25730.614040789027], "value": 19673.396707811204, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6338186449566, 402.7824974513763, 405.8863518214899, 410.4151583992809, 413.10356526337864, 15298.082115073075, 18354.908225458887, 18815.341463048797, 19327.903360483197, 19547.609047819034, 16366.345346186168, 17632.03023833692, 20485.48786280344, 20547.38357849263, 20919.790802091975, 5923.85190370241, 6109.297430795471, 6141.201643786324, 6156.199694039791, 6183.690815459209, 5882.6764527950945, 5961.5230781537375, 5964.79123230687, 6041.3052498962525, 6106.206193194878, 5870.941290587082, 5997.760089596411, 6035.849103772397, 6040.728167232463, 6044.24446944132, 5666.886662266752, 5923.229980102581, 5964.522838172936, 5970.791022314198, 6003.099535069753, 5229.267902493654, 5254.632175747699, 5255.185446255837, 5257.18513630384, 5277.445868183824, 25773.61339579908, 27012.459750804876, 27479.0, 27521.724782752117, 27831.582526262122, 2167.783221677835, 2302.9424264393356, 2335.544568809083, 2363.4445905212433, 2956.586077949089, 13578.93210533941, 14004.71990560183, 14827.555173344817, 15108.546743597599, 15565.533034008884, 1677.7147884859603, 1727.0932760300816, 1739.3738254228467, 1790.713485842259, 1909.4748944040361, 123358.0, 127470.36264818709, 130173.34913325372, 131300.0, 132638.0, 11079.947404996512, 11266.43667816606, 11489.42552872353, 11706.0, 11713.355765432812, 14833.406663733329, 14909.701805964038, 14944.327505262072, 14954.0, 14963.326650300764, 7939.6824127035425, 8383.0, 8644.481331120152, 9082.40964337316, 9109.498977556297, 9014.504202268938, 9037.83675713495, 9044.14080662336, 9173.44959302444, 9207.355485115977, 5739.311282646109, 6200.410960958701, 6831.146106736658, 6965.303469652994, 7029.0, 1608.3727346334938, 1798.8381045705949, 1817.1459414075364, 1884.7172924061501, 1896.535348839543, 398.13604478282195, 408.15104582468393, 424.0437812732269, 737.9372753316028, 871.5337294547387, 11568.76862462746, 11687.88312116868, 11856.0, 11874.406279686154, 12038.87961120395, 359.5487662982992, 403.90104424416205, 407.7757233521551, 416.4149370134965, 447.2642503082411, 5640.520555752725, 5652.463016013472, 6020.217371741645, 6065.332813390523, 6141.7543298267565, 648510.0, 658747.7062614752, 663728.0, 675122.0, 677122.843157353, 133419.66580334073, 146065.0, 146695.53304466818, 148809.0, 149849.0, 469.88018055395656, 475.443730834924, 477.94025746781654, 478.37571968581335, 478.80847660935206, 11118.499667515143, 11635.41822908868, 11768.52925882955, 11782.52869885195, 11841.644750657493, 54496.72751636217, 55320.0, 57019.42980570141, 58114.4188558109, 58459.83080338284, 70811.29188708047, 80612.98467538359, 83302.0, 83486.0, 88434.0, 6525.412712855866, 8345.874811877706, 8421.578921054046, 8516.0, 8817.911820881965, 8604.354673399395, 10359.015893490106, 12194.390280486117, 13110.410031548412, 13430.19418834873, 11062.778744425228, 11369.4883730234, 11613.245139065933, 11685.941570292094, 11713.531458741554, 9655.517224138624, 11166.776664466503, 11280.041196498389, 11366.943165284121, 11598.942005290257, 10826.89173108259, 11599.8260026098, 11728.558510398869, 11738.94130529342, 11826.113041521749, 8007.799805004922, 8255.579607374833, 8395.454295470774, 8785.648574056966, 8869.467831930106, 8511.574421279036, 10639.468026598794, 11392.0, 11679.598448185932, 11714.414279286171], "value": 8920.160045723635, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.0486469200953, 1559.9922000389997, 1585.3262363495514, 1788.3561917709621, 1919.9136038878241, 7828.021497312836, 8508.574571271434, 8718.041015488292, 8735.694250701226, 8740.0, 8399.832003359945, 8604.91395086049, 8624.741257762262, 8627.827443451128, 8630.352723545744, 2446.2538925627678, 2446.7186273578586, 2450.387403149213, 2451.7915977141965, 2461.237016524883, 2395.808335333178, 2452.5953217719057, 2455.668484754562, 2462.8645424501647, 2469.6665950096776, 2402.147237730607, 2427.927162185137, 2447.840890342122, 2450.767177118171, 2456.6192240202795, 2404.3989002749313, 2419.8185136114776, 2436.4639779248537, 2439.2194497760725, 2441.6093425051995, 2394.6288325309606, 2420.8305418620703, 2421.128393778238, 2426.987865060681, 2428.089466450081, 8544.0, 8571.95714021426, 8683.13168683133, 8688.956555217246, 8700.30397568193, 5189.455107213764, 5403.729813509309, 5407.3781515125875, 5443.673379597237, 5471.972640136773, 3452.188735647121, 3564.7861128332124, 3630.2920930418577, 3636.163682353047, 3648.252108317815, 1017.9440130792804, 1019.8470229465565, 1040.4745603470274, 1054.225144518778, 1071.2929466552039, 11644.708882277926, 11773.882261177365, 11801.94099029508, 11802.586909458214, 11807.704807379798, 3774.7923864187733, 4620.775494493962, 4724.551167639069, 4747.810087596457, 4769.570738633471, 9234.353595248402, 9260.259179265642, 9269.90730092704, 9271.67549135777, 9300.813983720423, 5303.124984377611, 5370.892582148335, 5379.757910894019, 5380.43505431924, 5380.892382152335, 4437.933430998538, 4606.930896036562, 4729.929051064237, 4920.8769780755765, 4961.280614310905, 4001.1797581495725, 4030.496187976503, 4124.175164967019, 4180.352045432963, 4181.97909010453, 1335.5325636027508, 1365.7678194706923, 1375.7179778145455, 1380.2270728392132, 1384.3562743324403, 704.5878161275608, 814.9877751833727, 844.6325848256029, 919.3151102428733, 932.0818993291603, 5499.422560631157, 5661.745221465044, 5830.183774271605, 5854.648721076749, 5885.882282354414, 492.11910679883323, 492.7117636182873, 496.23331952133316, 508.6312423492938, 658.9275179730225, 3765.4351847223134, 3928.3910993795434, 3983.8605648802154, 4051.0277533391773, 4072.2466343726505, 9232.90767092334, 10203.9489802552, 10781.02970732638, 10807.621733239299, 10816.134709223401, 10526.789464210673, 10602.734931626772, 10645.0951669109, 10674.0393364596, 10675.679729608124, 7722.884156737653, 8139.837203255784, 8197.139300373494, 8212.671493140328, 8259.95870020646, 5825.359210486928, 5883.852903677443, 5979.252593425822, 5994.250718660168, 6028.4272994065495, 9337.486438245962, 9726.173275271682, 9740.318177727491, 9833.360831545926, 9843.0, 9502.952485237529, 9789.706308810748, 9930.602775888883, 10108.39349639024, 10230.386176829117, 7216.494845360774, 7528.623568821647, 7533.020707308234, 7535.8492830141995, 7546.094468663796, 8057.677692892219, 8131.79670508242, 8210.58947052633, 8210.63052162642, 8251.504909705436, 7376.299251571092, 7377.0409846719585, 7395.519291246052, 7467.58928258951, 7524.247575242432, 7205.207427183111, 7284.125904891447, 7361.484696071224, 7425.480216384801, 7448.664810083451, 6838.794836154923, 6921.82695432598, 6927.480438966997, 6934.375906168268, 6938.93061069403, 4843.1524483216, 4882.707037577757, 5065.645404821627, 5074.695518268915, 5102.974485127551, 4888.828890988798, 4930.161872481615, 4987.875303117451, 5019.372578427697, 5220.738963051756], "value": 4517.92197947551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.54350105345134, 319.9328141090371, 325.71662653491467, 329.31173846660477, 329.6406916461057, 4172.77049762263, 5275.522853600993, 5328.040952628526, 5340.531353877681, 5354.946450535494, 4043.2722110020186, 4956.190990288545, 5107.7701503432345, 5130.6152038597065, 5208.895822083556, 3368.658782146409, 3984.362501999688, 3997.3404388276044, 4013.4983127109113, 4047.33219018863, 3955.9011024724323, 3978.323684973547, 4022.43685883975, 4035.4955630546183, 4075.288378880877, 3737.86917457889, 3763.41667041609, 3808.5620153682403, 3820.2932457495313, 3998.5001874765653, 3361.411752943225, 4044.413560033808, 4047.251258517169, 4051.270771261172, 4087.773667899633, 3232.466643003925, 3385.627580966092, 3555.5555555555557, 3565.16218688608, 3568.6966607838367, 4835.2505361669, 4896.583790377822, 5051.595872330242, 5098.0, 5102.872428189288, 4084.4281800547947, 4406.603405693503, 4574.748388838612, 4585.770711464447, 4633.305004249356, 5403.1899313729555, 5413.783448662049, 5423.267858839085, 5487.451254874521, 5565.860853478695, 3188.473901806199, 3873.9806300968316, 3900.941485877714, 3911.6283953024417, 3974.085960229135, 5377.220303056035, 5506.91739623906, 5761.711914404263, 5802.100674395476, 5881.970590147064, 3515.648435156464, 3670.7063434925144, 3895.714414243293, 3925.4700615417123, 3943.507061617298, 3427.9485807712904, 3480.3039392121173, 3994.360902255625, 4029.2143032108456, 4036.6367026967127, 3839.8080095995088, 4371.30059190528, 4575.794089265991, 4624.260118381043, 4652.860414187579, 4738.47876733559, 5474.479924407175, 5486.286782718221, 5551.250581171571, 5592.384837667853, 1472.6907349456735, 1482.6664000599778, 1488.761798112297, 1492.0003597589641, 1494.3798323695544, 939.1500691873865, 969.4425705219386, 982.3074732313702, 1004.4073996342194, 1013.123648044451, 4636.698614590041, 4910.170067347474, 5208.244804503326, 5465.0, 5560.4161563035295, 5893.5579831512805, 6118.663473508911, 6170.598911070766, 6198.721057552421, 6214.130021796952, 4773.665843390999, 4818.975905120452, 5163.638545301793, 5194.454582268883, 5201.590346040076, 2858.814177078525, 3242.918927026843, 3243.708066274047, 3274.3287626036604, 3281.704646581819, 4851.830185943546, 5859.560532960045, 6400.975843865052, 6483.935160648333, 6557.540972132, 5571.888562228733, 6401.935980640134, 6406.8718625627225, 6407.814554307468, 6456.612603243821, 5082.720450375265, 5404.594655400861, 5851.6781577012825, 6524.934750652623, 6547.0865464867675, 5133.4096578893905, 5999.430054144849, 6162.630242185483, 6178.907316390168, 6211.937880621136, 4863.319135321129, 5756.2229099072765, 6010.918034753787, 6099.634021958696, 6172.598781079216, 5997.670128142994, 6207.286162091417, 6452.354764523698, 6462.967685161544, 6534.803955881332, 4055.411965265079, 4108.938365924573, 4134.235166494208, 4156.9423135548095, 4170.687198460067, 5510.559155267489, 6305.180326557702, 6309.980806141991, 6314.652694101868, 6324.976007677683, 5057.6459647824295, 5101.7194054327365, 5641.984442800335, 5653.607835298788, 6118.357572454828, 4676.532346765296, 5603.663780173202, 5642.294713160855, 5642.633228840113, 5652.406497317723, 5132.486751324837, 5195.0, 5346.973265133805, 5576.260178848109, 5580.381689310055, 4819.3252944588485, 5486.753096110764, 5487.0, 5502.58730595199, 5519.420460851713, 4754.811297175706, 5093.261477085876, 5391.056565101013, 5403.945960540502, 5416.322959630046], "value": 4181.834877050569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.8508585568247, 684.3532861445932, 722.5159143373938, 740.7592532426962, 752.4281546025021, 14693.77959330611, 14822.036567623098, 15244.390224391025, 15270.999999999985, 15291.464798732046, 13972.790408143861, 14063.718725625482, 14069.94475414343, 14081.507147249848, 14088.084274522174, 649.1690635985946, 910.9726708198765, 938.4322484896642, 938.8779458670364, 941.4210260689661, 746.0, 748.5920173505425, 749.3293502315424, 751.8308380614345, 894.4812009034777, 810.9229623185818, 976.8485884687849, 977.0376179463223, 977.2377545514474, 978.535195782005, 951.4243882451121, 960.0399600399601, 960.0975083421596, 960.582146766159, 962.2302158273353, 561.2283110722756, 702.8980797784344, 723.8516104198627, 725.6988349834812, 728.3954317916117, 14930.178840163786, 15016.924915375463, 15028.947973641849, 15034.248287585577, 15038.699226015528, 11478.139139564566, 12219.327936963464, 12611.0, 13009.21944683322, 13258.0, 10041.598336066549, 10082.69751907444, 10272.383656980604, 10485.842712359321, 10487.161027117812, 3464.1512829356716, 3498.0380395391217, 3505.1762835733584, 3534.169470174507, 3723.553173619156, 80319.19680803234, 82940.58529707312, 83203.58398208031, 83281.58359208226, 83710.58144709299, 9542.809143817085, 9637.18083962871, 9721.319507634538, 9761.316707830383, 10045.79908401828, 22585.661215081785, 22586.661200082013, 22648.433789155402, 22722.0, 22767.08931642749, 6387.520935929824, 6990.475714321345, 7247.782566523013, 7320.1581818090535, 7576.962115189389, 3986.86045988389, 4065.0853557949818, 4154.210699966997, 4154.522229943595, 4333.934990975138, 3532.823358832048, 3602.6217247189193, 3606.6032736398975, 3609.4224924012556, 3612.1330880588994, 611.5841227964937, 625.6402568523117, 627.1314229791681, 638.2525359140907, 640.7437025189868, 523.6282239609843, 538.641803200867, 562.0562551022948, 578.9855253618693, 595.4581330988866, 8002.959985200153, 8130.715424960098, 8138.715144969898, 8196.42625016255, 8278.172182781673, 514.3698968763235, 533.2933862631951, 546.9945300546943, 606.263389981175, 607.0529973241757, 3844.4809950656863, 3863.9613603864163, 3880.2821478026653, 3905.628965248297, 3962.0689138052535, 78814.60592697114, 81850.59074704707, 82033.17966820256, 99339.0, 101496.0, 72797.27202727905, 73477.6326118366, 76368.0, 79209.60395197987, 80231.59884200542, 54298.72850635721, 70894.0, 71420.92868606975, 72288.0, 73684.15789605303, 7819.609019549113, 7906.5651389174145, 7932.6430310635005, 8054.516728996279, 8115.959420202861, 43989.560104398544, 44436.333454997555, 44739.0, 44986.0, 45956.7702161487, 59835.700821495615, 63928.68035659792, 63997.36002639914, 64255.0, 64532.0, 11124.888751112385, 11309.943450282695, 11325.660230193107, 11568.537258509901, 12259.570915017932, 11834.171607987359, 11883.066630052313, 11975.820362694392, 12119.272843629411, 12672.366381681064, 9365.906340936503, 11120.221584489009, 11288.322700637988, 11646.94176529112, 11679.649610511698, 11099.278546894426, 11586.188966772244, 11695.76608467809, 11907.106966977339, 12177.634670959886, 10902.182336324966, 11126.165537584875, 11321.830172547583, 11409.885901140882, 11556.075513958695, 9677.65949527278, 9784.0, 9786.85319720219, 9807.411555306704, 10005.199584033115, 9092.181703646704, 9292.860607091034, 9476.810463790547, 9515.33392662507, 9573.664921727706], "value": 6821.581016145126, "unit": "score"}], "name": "dromaeo_css", "value": 7083.856067521096, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 162.0, 156.0, 113.0, 115.0, 114.0, 114.0, 105.0, 113.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 142.0, 166.0, 106.0, 176.0, 170.0, 151.0, 182.0, 118.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 184.0, 183.0, 183.0, 186.0, 190.0, 182.0, 186.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 75.0, 151.0, 60.0, 55.0, 56.0, 66.0, 135.0, 55.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 143.0, 77.0, 82.0, 86.0, 124.0, 80.0, 77.0, 124.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 83.0, 86.0, 84.0, 87.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 101.0, 93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 87.0, 83.0, 86.0, 83.0, 98.0, 142.0, 142.0, 83.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 63.0, 63.0, 64.0, 66.0, 63.0, 82.0, 60.0, 59.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 66.0, 77.0, 74.0, 69.0, 67.0, 69.0, 71.0, 67.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 81.0, 78.0, 81.0, 76.0, 99.0, 106.0, 110.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 138.0, 146.0, 146.0, 150.0, 148.0, 135.0, 172.0, 139.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 172.0, 170.0, 167.0, 167.0, 166.0, 170.0, 167.0, 170.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1510.2999999999997, "alertThreshold": 2.0}]} 02:49:04 INFO - Return code: 0 02:49:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:49:04 ERROR - # TBPL SUCCESS # 02:49:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:04 INFO - 2016-05-06 02:49:04.696 screenresolution[1682:9653] starting screenresolution argv=screenresolution get 02:49:04 INFO - 2016-05-06 02:49:04.698 screenresolution[1682:9653] Display 0: 1600x1200x32@60 02:49:04 INFO - 2016-05-06 02:49:04.704 screenresolution[1683:9656] starting screenresolution argv=screenresolution list 02:49:04 INFO - Available Modes on Display 0 02:49:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:04 INFO - Intel Iris: 02:49:04 INFO - Chipset Model: Intel Iris 02:49:04 INFO - Type: GPU 02:49:04 INFO - Bus: Built-In 02:49:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:04 INFO - Vendor: Intel (0x8086) 02:49:04 INFO - Device ID: 0x0a2e 02:49:04 INFO - Revision ID: 0x0009 02:49:04 INFO - Displays: 02:49:04 INFO - Display: 02:49:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:04 INFO - Display Serial Number: 1600x1200 60 02:49:04 INFO - Main Display: Yes 02:49:04 INFO - Mirror: Off 02:49:04 INFO - Online: Yes 02:49:04 INFO - Rotation: Supported 02:49:04 INFO - Return code: 0 02:49:04 INFO - Running post-action listener: _resource_record_post_action 02:49:04 INFO - Running post-run listener: _resource_record_post_run 02:49:05 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 37955072; Write bytes: 652670464; Read time: 406; Write time: 1999 02:49:05 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342737408; Write bytes: 336934400; Read time: 26543; Write time: 819 02:49:06 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37008896; Write bytes: 296402944; Read time: 385; Write time: 1132 02:49:06 INFO - Running post-run listener: _upload_blobber_files 02:49:06 INFO - Blob upload gear active. 02:49:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:06 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 02:49:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:06 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:07 INFO - (blobuploader) - INFO - Done attempting. 02:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:08 INFO - (blobuploader) - INFO - Done attempting. 02:49:08 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:08 INFO - Return code: 0 02:49:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a99901398e46671946544d1513d0aad53f807bff6dd854010b7f873f32c532668043400517574bfa097d8b0bc08358e7178acfa21e42446408c67ead3004193a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eec8f1aa02ec62b19ebecf5482f3f2d03d270ead21bd905b40bebe0ea4e356cc43781146423db038869a955ed70722b2c1cad1db1cf1fa31f410caa4b3447872"} 02:49:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:08 INFO - Contents: 02:49:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a99901398e46671946544d1513d0aad53f807bff6dd854010b7f873f32c532668043400517574bfa097d8b0bc08358e7178acfa21e42446408c67ead3004193a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eec8f1aa02ec62b19ebecf5482f3f2d03d270ead21bd905b40bebe0ea4e356cc43781146423db038869a955ed70722b2c1cad1db1cf1fa31f410caa4b3447872"} 02:49:08 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:08 INFO - Copying logs to upload dir... 02:49:08 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.788978 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-06 02:49:08.664177) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 02:49:08.669854) ========= 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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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/Mozilla-Aurora/sha512/a99901398e46671946544d1513d0aad53f807bff6dd854010b7f873f32c532668043400517574bfa097d8b0bc08358e7178acfa21e42446408c67ead3004193a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eec8f1aa02ec62b19ebecf5482f3f2d03d270ead21bd905b40bebe0ea4e356cc43781146423db038869a955ed70722b2c1cad1db1cf1fa31f410caa4b3447872"} build_url:https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011197 build_url: 'https://queue.taskcluster.net/v1/task/VNIOIKr1RZGljOlYUBdzwQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a99901398e46671946544d1513d0aad53f807bff6dd854010b7f873f32c532668043400517574bfa097d8b0bc08358e7178acfa21e42446408c67ead3004193a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eec8f1aa02ec62b19ebecf5482f3f2d03d270ead21bd905b40bebe0ea4e356cc43781146423db038869a955ed70722b2c1cad1db1cf1fa31f410caa4b3447872"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 02:49:08.696691) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:49:08.696981) ========= 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.cKNP2nl74w/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.yi4mZ85Dh0/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005014 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:49:08.744811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 02:49:08.745081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 02:49:08.745370) ========= ========= Total master_lag: 0.14 =========