builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1464800297.7 results: success (0) buildid: 20160601074655 builduid: 631622b2b1ef4f7f958d975965d87d6a revision: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.701575) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.702052) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.702326) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.813283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.813669) ========= 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.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-06-01 09:58:17-- 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% 46.1M=0s 2016-06-01 09:58:17 (46.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.971996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:17.972295) ========= 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.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.028038 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:18.021829) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 09:58:18.022171) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d453fe195446be7ee2a26c05bd7deca5e0cd170 --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 2d453fe195446be7ee2a26c05bd7deca5e0cd170 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-06-01 09:58:18,101 truncating revision to first 12 chars 2016-06-01 09:58:18,101 Setting DEBUG logging. 2016-06-01 09:58:18,101 attempt 1/10 2016-06-01 09:58:18,101 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2d453fe19544?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 09:58:19,078 unpacking tar archive at: mozilla-aurora-2d453fe19544/testing/mozharness/ program finished with exit code 0 elapsedTime=1.235667 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 09:58:19.278550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:19.278864) ========= script_repo_revision: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:19.279264) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:19.279528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 09:58:19.300928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-01 09:58:19.301275) ========= /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.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:58:19 INFO - MultiFileLogger online at 20160601 09:58:19 in /builds/slave/test 09:58:19 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 09:58:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:19 INFO - {'append_to_log': False, 09:58:19 INFO - 'base_work_dir': '/builds/slave/test', 09:58:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:58:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:19 INFO - 'branch': 'Mozilla-Aurora', 09:58:19 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:19 INFO - 'config_files': ('talos/mac_config.py',), 09:58:19 INFO - 'default_actions': ('clobber', 09:58:19 INFO - 'read-buildbot-config', 09:58:19 INFO - 'download-and-extract', 09:58:19 INFO - 'populate-webroot', 09:58:19 INFO - 'create-virtualenv', 09:58:19 INFO - 'install', 09:58:19 INFO - 'run-tests'), 09:58:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:19 INFO - 'download_minidump_stackwalk': True, 09:58:19 INFO - 'download_symbols': 'ondemand', 09:58:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:19 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:19 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:19 INFO - 'installer_path': 'installer.exe', 09:58:19 INFO - 'log_level': 'info', 09:58:19 INFO - 'log_name': 'talos', 09:58:19 INFO - 'log_to_console': True, 09:58:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:58:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:58:19 INFO - 'opt_config_files': (), 09:58:19 INFO - 'pip_index': False, 09:58:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:19 INFO - 'cmd': ('bash', 09:58:19 INFO - '-c', 09:58:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:19 INFO - 'enabled': True, 09:58:19 INFO - 'halt_on_failure': False, 09:58:19 INFO - 'name': 'check_screen_resolution'},), 09:58:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:19 INFO - 'cmd': ('bash', 09:58:19 INFO - '-c', 09:58:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:19 INFO - 'enabled': True, 09:58:19 INFO - 'halt_on_failure': False, 09:58:19 INFO - 'name': 'check_screen_resolution'},), 09:58:19 INFO - 'run_cmd_checks_enabled': True, 09:58:19 INFO - 'sps_profile': False, 09:58:19 INFO - 'sps_profile_interval': 0, 09:58:19 INFO - 'suite': 'dromaeojs', 09:58:19 INFO - 'system_bits': '32', 09:58:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:19 INFO - 'title': 't-yosemite-r7-0171', 09:58:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:19 INFO - 'use_talos_json': True, 09:58:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:19 INFO - 'work_dir': 'build'} 09:58:19 INFO - ##### 09:58:19 INFO - ##### Running clobber step. 09:58:19 INFO - ##### 09:58:19 INFO - Running pre-action listener: _resource_record_pre_action 09:58:19 INFO - Running main action method: clobber 09:58:19 INFO - rmtree: /builds/slave/test/build 09:58:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:21 INFO - Running post-action listener: _resource_record_post_action 09:58:21 INFO - ##### 09:58:21 INFO - ##### Running read-buildbot-config step. 09:58:21 INFO - ##### 09:58:21 INFO - Running pre-action listener: _resource_record_pre_action 09:58:21 INFO - Running main action method: read_buildbot_config 09:58:21 INFO - Using buildbot properties: 09:58:21 INFO - { 09:58:21 INFO - "project": "", 09:58:21 INFO - "product": "firefox", 09:58:21 INFO - "script_repo_revision": "production", 09:58:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:58:21 INFO - "repository": "", 09:58:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:58:21 INFO - "buildid": "20160601074655", 09:58:21 INFO - "pgo_build": "False", 09:58:21 INFO - "basedir": "/builds/slave/test", 09:58:21 INFO - "buildnumber": 14, 09:58:21 INFO - "slavename": "t-yosemite-r7-0171", 09:58:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:58:21 INFO - "platform": "yosemite_r7", 09:58:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:58:21 INFO - "branch": "mozilla-aurora", 09:58:21 INFO - "repo_path": "releases/mozilla-aurora", 09:58:21 INFO - "revision": "2d453fe195446be7ee2a26c05bd7deca5e0cd170", 09:58:21 INFO - "stage_platform": "macosx64", 09:58:21 INFO - "builduid": "631622b2b1ef4f7f958d975965d87d6a", 09:58:21 INFO - "slavebuilddir": "test" 09:58:21 INFO - } 09:58:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:58:21 INFO - Running post-action listener: _resource_record_post_action 09:58:21 INFO - ##### 09:58:21 INFO - ##### Running download-and-extract step. 09:58:21 INFO - ##### 09:58:21 INFO - Running pre-action listener: _resource_record_pre_action 09:58:21 INFO - Running main action method: download_and_extract 09:58:21 INFO - mkdir: /builds/slave/test/build/tests 09:58:21 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')]} 09:58:21 INFO - https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:58:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:58:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:58:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:32 INFO - Downloaded 1199 bytes. 09:58:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:58:32 INFO - Using the following test package requirements: 09:58:32 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:58:32 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:58:32 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:58:32 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'jsshell-mac.zip'], 09:58:32 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:58:32 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:58:32 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:58:32 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:58:32 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:58:32 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:58:32 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:58:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:58:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:32 INFO - https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:58:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:58:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:58:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:33 INFO - Downloaded 18463811 bytes. 09:58:33 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'] 09:58:33 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 09:58:36 INFO - Return code: 0 09:58:36 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 09:58:36 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')]} 09:58:36 INFO - https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:58:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:58:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:58:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:41 INFO - Downloaded 18463811 bytes. 09:58:41 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'] 09:58:41 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 09:58:45 INFO - Return code: 0 09:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:45 INFO - https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:58:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:58:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:58:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:48 INFO - Downloaded 10840145 bytes. 09:58:48 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'] 09:58:48 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 09:58:48 INFO - Return code: 0 09:58: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')]} 09:58:48 INFO - https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:58:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:58:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:58:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:58:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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 09:58:52 INFO - Downloaded 114164561 bytes. 09:58:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:58:52 INFO - mkdir: /builds/slave/test/properties 09:58:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:52 INFO - Contents: 09:58:52 INFO - build_url:https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:58:53 INFO - Running post-action listener: _resource_record_post_action 09:58:53 INFO - Running post-action listener: set_extra_try_arguments 09:58:53 INFO - ##### 09:58:53 INFO - ##### Running populate-webroot step. 09:58:53 INFO - ##### 09:58:53 INFO - Running pre-action listener: _resource_record_pre_action 09:58:53 INFO - Running main action method: populate_webroot 09:58:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:53 INFO - '--tppagecycles', 09:58:53 INFO - '7'], 09:58:53 INFO - 'tests': ['tsvgm']}}, 09:58:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['damp', 'tps']}, 09:58:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'tests': ['damp', 'tps']}, 09:58:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['dromaeo_dom']}, 09:58:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['a11yr', 09:58:53 INFO - 'ts_paint', 09:58:53 INFO - 'tpaint', 09:58:53 INFO - 'sessionrestore', 09:58:53 INFO - 'sessionrestore_no_auto_restore', 09:58:53 INFO - 'tabpaint']}, 09:58:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['tsvgx', 09:58:53 INFO - 'tsvgr_opacity', 09:58:53 INFO - 'tart', 09:58:53 INFO - 'tscrollx', 09:58:53 INFO - 'cart']}, 09:58:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:53 INFO - 'tsvgr_opacity', 09:58:53 INFO - 'tart', 09:58:53 INFO - 'tscrollx', 09:58:53 INFO - 'cart']}, 09:58:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'talos_options': ['--disable-e10s'], 09:58:53 INFO - 'tests': ['tp5o']}, 09:58:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'tests': ['tp5o']}, 09:58:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'talos_options': ['--disable-e10s', 09:58:53 INFO - '--xperf_path', 09:58:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:53 INFO - 'tests': ['tp5n']}, 09:58:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:53 INFO - 'talos_options': ['--xperf_path', 09:58:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:53 INFO - 'tests': ['tp5n']}}, 09:58:53 INFO - 'talos.zip': {'path': '', 09:58:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:53 INFO - Running post-action listener: _resource_record_post_action 09:58:53 INFO - ##### 09:58:53 INFO - ##### Running create-virtualenv step. 09:58:53 INFO - ##### 09:58:53 INFO - Running pre-action listener: _resource_record_pre_action 09:58:53 INFO - Running main action method: create_virtualenv 09:58:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:53 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 09:58:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:53 INFO - Using real prefix '/tools/python27' 09:58:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:54 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:56 INFO - Installing pip.................done. 09:58:56 INFO - Return code: 0 09:58:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:56 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:58:56 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 09:58:56 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 09:58:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:58:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:56 INFO - 'HOME': '/Users/cltbld', 09:58:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:56 INFO - 'LOGNAME': 'cltbld', 09:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:56 INFO - 'MOZ_NO_REMOTE': '1', 09:58:56 INFO - 'NO_EM_RESTART': '1', 09:58:56 INFO - 'PAGER': '/bin/cat', 09:58:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:56 INFO - 'PWD': '/builds/slave/test', 09:58:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:56 INFO - 'SHELL': '/bin/bash', 09:58:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:58:56 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:58:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:56 INFO - 'USER': 'cltbld', 09:58:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:56 INFO - 'XPC_FLAGS': '0x0', 09:58:56 INFO - 'XPC_SERVICE_NAME': '0', 09:58:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:57 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 09:58:57 INFO - Cleaning up... 09:58:57 INFO - Return code: 0 09:58:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:58:57 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')]} 09:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:57 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')]} 09:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:57 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:58:57 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 09:58:57 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 09:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:57 INFO - 'HOME': '/Users/cltbld', 09:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:57 INFO - 'LOGNAME': 'cltbld', 09:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:57 INFO - 'MOZ_NO_REMOTE': '1', 09:58:57 INFO - 'NO_EM_RESTART': '1', 09:58:57 INFO - 'PAGER': '/bin/cat', 09:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:57 INFO - 'PWD': '/builds/slave/test', 09:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:57 INFO - 'SHELL': '/bin/bash', 09:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:58:57 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:57 INFO - 'USER': 'cltbld', 09:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:57 INFO - 'XPC_FLAGS': '0x0', 09:58:57 INFO - 'XPC_SERVICE_NAME': '0', 09:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:57 INFO - Downloading/unpacking psutil>=0.7.1 09:58:57 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 09:58:57 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 09:58:57 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 09:58:57 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 09:58:57 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 09:58:57 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 09:59:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:00 INFO - Installing collected packages: psutil 09:59:00 INFO - Running setup.py install for psutil 09:59:01 INFO - building 'psutil._psutil_osx' extension 09:59:01 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 09:59:01 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 09:59:01 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 09:59:01 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 09:59:01 INFO - building 'psutil._psutil_posix' extension 09:59:01 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 09:59:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:59:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:59:01 INFO - ^ 09:59:02 INFO - 1 warning generated. 09:59:02 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 09:59:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:02 INFO - Successfully installed psutil 09:59:02 INFO - Cleaning up... 09:59:02 INFO - Return code: 0 09:59:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:02 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:02 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 09:59:02 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 09:59:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:02 INFO - 'HOME': '/Users/cltbld', 09:59:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:02 INFO - 'LOGNAME': 'cltbld', 09:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:02 INFO - 'MOZ_NO_REMOTE': '1', 09:59:02 INFO - 'NO_EM_RESTART': '1', 09:59:02 INFO - 'PAGER': '/bin/cat', 09:59:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:02 INFO - 'PWD': '/builds/slave/test', 09:59:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:02 INFO - 'SHELL': '/bin/bash', 09:59:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:02 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:02 INFO - 'USER': 'cltbld', 09:59:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:02 INFO - 'XPC_FLAGS': '0x0', 09:59:02 INFO - 'XPC_SERVICE_NAME': '0', 09:59:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:59:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:59:04 INFO - Installing collected packages: mozsystemmonitor 09:59:04 INFO - Running setup.py install for mozsystemmonitor 09:59:05 INFO - Successfully installed mozsystemmonitor 09:59:05 INFO - Cleaning up... 09:59:05 INFO - Return code: 0 09:59:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:05 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:05 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 09:59:05 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 09:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:05 INFO - 'HOME': '/Users/cltbld', 09:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:05 INFO - 'LOGNAME': 'cltbld', 09:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:05 INFO - 'MOZ_NO_REMOTE': '1', 09:59:05 INFO - 'NO_EM_RESTART': '1', 09:59:05 INFO - 'PAGER': '/bin/cat', 09:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:05 INFO - 'PWD': '/builds/slave/test', 09:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:05 INFO - 'SHELL': '/bin/bash', 09:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:05 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:05 INFO - 'USER': 'cltbld', 09:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:05 INFO - 'XPC_FLAGS': '0x0', 09:59:05 INFO - 'XPC_SERVICE_NAME': '0', 09:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:05 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:05 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 09:59:05 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 09:59:05 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 09:59:05 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 09:59:05 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 09:59:05 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 09:59:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:07 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 09:59:07 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 09:59:07 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 09:59:07 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 09:59:07 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 09:59:07 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 09:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:08 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 09:59:09 INFO - Downloading docopt-0.6.1.tar.gz 09:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:09 INFO - Running setup.py install for blobuploader 09:59:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:09 INFO - Running setup.py install for requests 09:59:09 INFO - Running setup.py install for docopt 09:59:09 INFO - Successfully installed blobuploader requests docopt 09:59:09 INFO - Cleaning up... 09:59:10 INFO - Return code: 0 09:59:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:10 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')]} 09:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:10 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')]} 09:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:10 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:10 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 09:59:10 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 09:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:10 INFO - 'HOME': '/Users/cltbld', 09:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:10 INFO - 'LOGNAME': 'cltbld', 09:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:10 INFO - 'MOZ_NO_REMOTE': '1', 09:59:10 INFO - 'NO_EM_RESTART': '1', 09:59:10 INFO - 'PAGER': '/bin/cat', 09:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:10 INFO - 'PWD': '/builds/slave/test', 09:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:10 INFO - 'SHELL': '/bin/bash', 09:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:10 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:10 INFO - 'USER': 'cltbld', 09:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:10 INFO - 'XPC_FLAGS': '0x0', 09:59:10 INFO - 'XPC_SERVICE_NAME': '0', 09:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-4OKFZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-bSYUjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-PTB5R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-yWKAgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dyjoLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-kpI3Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-so_ebl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-uCZc1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-BK4HKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Z5YoJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-m4sIDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ZAh48G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-pYieu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0wiPxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-X2pbEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-CvUHur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0pBR37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:12 INFO - Running setup.py install for manifestparser 09:59:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:12 INFO - Running setup.py install for mozcrash 09:59:12 INFO - Running setup.py install for mozdebug 09:59:12 INFO - Running setup.py install for mozdevice 09:59:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:59:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:12 INFO - Running setup.py install for mozfile 09:59:12 INFO - Running setup.py install for mozhttpd 09:59:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:12 INFO - Running setup.py install for mozinfo 09:59:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Running setup.py install for mozInstall 09:59:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Running setup.py install for mozleak 09:59:13 INFO - Running setup.py install for mozlog 09:59:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Running setup.py install for moznetwork 09:59:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Running setup.py install for mozprocess 09:59:13 INFO - Running setup.py install for mozprofile 09:59:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:13 INFO - Running setup.py install for mozrunner 09:59:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:14 INFO - Running setup.py install for mozscreenshot 09:59:14 INFO - Running setup.py install for moztest 09:59:14 INFO - Running setup.py install for mozversion 09:59:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:14 INFO - Cleaning up... 09:59:14 INFO - Return code: 0 09:59:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59: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')]} 09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59: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')]} 09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:14 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:14 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 09:59:14 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 09:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:14 INFO - 'HOME': '/Users/cltbld', 09:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:14 INFO - 'LOGNAME': 'cltbld', 09:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:14 INFO - 'MOZ_NO_REMOTE': '1', 09:59:14 INFO - 'NO_EM_RESTART': '1', 09:59:14 INFO - 'PAGER': '/bin/cat', 09:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:14 INFO - 'PWD': '/builds/slave/test', 09:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:14 INFO - 'SHELL': '/bin/bash', 09:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:14 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:14 INFO - 'USER': 'cltbld', 09:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:14 INFO - 'XPC_FLAGS': '0x0', 09:59:14 INFO - 'XPC_SERVICE_NAME': '0', 09:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wYxZFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:14 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)) 09:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-37DN1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-oPZfG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-GimqqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-hhR0Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-w10zye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-T7drUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-DwFN20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Dh93X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OcS6YA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:15 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)) 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-TOK_bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-v71PTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-JYlAw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-PHzs01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dpGvw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-lRiwyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:16 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)) 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-sGto33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:16 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)) 09:59:16 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)) 09:59:16 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)) 09:59:16 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)) 09:59:16 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)) 09:59:16 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)) 09:59:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:16 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 09:59:19 INFO - Downloading blessings-1.6.tar.gz 09:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:19 INFO - Installing collected packages: blessings 09:59:19 INFO - Running setup.py install for blessings 09:59:19 INFO - Successfully installed blessings 09:59:19 INFO - Cleaning up... 09:59:19 INFO - Return code: 0 09:59:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:19 INFO - Reading from file tmpfile_stdout 09:59:19 INFO - Current package versions: 09:59:19 INFO - blessings == 1.6 09:59:19 INFO - blobuploader == 1.2.4 09:59:19 INFO - docopt == 0.6.1 09:59:19 INFO - manifestparser == 1.1 09:59:19 INFO - mozInstall == 1.12 09:59:19 INFO - mozcrash == 0.17 09:59:19 INFO - mozdebug == 0.1 09:59:19 INFO - mozdevice == 0.48 09:59:19 INFO - mozfile == 1.2 09:59:19 INFO - mozhttpd == 0.7 09:59:19 INFO - mozinfo == 0.9 09:59:19 INFO - mozleak == 0.1 09:59:19 INFO - mozlog == 3.1 09:59:19 INFO - moznetwork == 0.27 09:59:19 INFO - mozprocess == 0.22 09:59:19 INFO - mozprofile == 0.28 09:59:19 INFO - mozrunner == 6.11 09:59:19 INFO - mozscreenshot == 0.1 09:59:19 INFO - mozsystemmonitor == 0.0 09:59:19 INFO - moztest == 0.7 09:59:19 INFO - mozversion == 1.4 09:59:19 INFO - psutil == 3.1.1 09:59:19 INFO - requests == 1.2.3 09:59:19 INFO - wsgiref == 0.1.2 09:59:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:19 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 09:59:19 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 09:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:19 INFO - 'HOME': '/Users/cltbld', 09:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:19 INFO - 'LOGNAME': 'cltbld', 09:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:19 INFO - 'MOZ_NO_REMOTE': '1', 09:59:19 INFO - 'NO_EM_RESTART': '1', 09:59:19 INFO - 'PAGER': '/bin/cat', 09:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:19 INFO - 'PWD': '/builds/slave/test', 09:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:19 INFO - 'SHELL': '/bin/bash', 09:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:19 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:19 INFO - 'USER': 'cltbld', 09:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:19 INFO - 'XPC_FLAGS': '0x0', 09:59:19 INFO - 'XPC_SERVICE_NAME': '0', 09:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 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)) 09:59:19 INFO - Cleaning up... 09:59:19 INFO - Return code: 0 09:59:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:19 INFO - 'HOME': '/Users/cltbld', 09:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:19 INFO - 'LOGNAME': 'cltbld', 09:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:19 INFO - 'MOZ_NO_REMOTE': '1', 09:59:19 INFO - 'NO_EM_RESTART': '1', 09:59:19 INFO - 'PAGER': '/bin/cat', 09:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:19 INFO - 'PWD': '/builds/slave/test', 09:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:19 INFO - 'SHELL': '/bin/bash', 09:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:19 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:19 INFO - 'USER': 'cltbld', 09:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:19 INFO - 'XPC_FLAGS': '0x0', 09:59:19 INFO - 'XPC_SERVICE_NAME': '0', 09:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:20 INFO - Downloading/unpacking jsonschema 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:23 INFO - Downloading/unpacking functools32 (from jsonschema) 09:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:23 INFO - warning: no files found matching '*.txt' 09:59:23 INFO - no previously-included directories found matching 'build' 09:59:23 INFO - no previously-included directories found matching 'dist' 09:59:23 INFO - no previously-included directories found matching '.git*' 09:59:23 INFO - Installing collected packages: jsonschema, functools32 09:59:23 INFO - Running setup.py install for functools32 09:59:23 INFO - warning: no files found matching '*.txt' 09:59:23 INFO - no previously-included directories found matching 'build' 09:59:23 INFO - no previously-included directories found matching 'dist' 09:59:23 INFO - no previously-included directories found matching '.git*' 09:59:23 INFO - Successfully installed jsonschema functools32 09:59:23 INFO - Cleaning up... 09:59:24 INFO - Return code: 0 09:59:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:24 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 0x10e6a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe21caabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, '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.UhCZVWEjtF/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.EYY3VyORYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 09:59:24 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 09:59:24 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 09:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 09:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:24 INFO - 'HOME': '/Users/cltbld', 09:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:24 INFO - 'LOGNAME': 'cltbld', 09:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:24 INFO - 'MOZ_NO_REMOTE': '1', 09:59:24 INFO - 'NO_EM_RESTART': '1', 09:59:24 INFO - 'PAGER': '/bin/cat', 09:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:24 INFO - 'PWD': '/builds/slave/test', 09:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:24 INFO - 'SHELL': '/bin/bash', 09:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 09:59:24 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 09:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:24 INFO - 'USER': 'cltbld', 09:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:24 INFO - 'XPC_FLAGS': '0x0', 09:59:24 INFO - 'XPC_SERVICE_NAME': '0', 09:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:24 INFO - Downloading/unpacking flake8 09:59:24 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 09:59:24 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 09:59:24 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 09:59:24 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 09:59:24 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 09:59:24 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 09:59:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:59:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:59:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:59:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:59:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:59:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:59:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:59:28 INFO - no previously-included directories found matching 'docs/_build' 09:59:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:59:28 INFO - Running setup.py install for pep8 09:59:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:59:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:59:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:59:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:59:28 INFO - no previously-included directories found matching 'docs/_build' 09:59:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:59:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:59:28 INFO - Cleaning up... 09:59:28 INFO - Return code: 0 09:59:28 INFO - Running post-action listener: _resource_record_post_action 09:59:28 INFO - Running post-action listener: _start_resource_monitoring 09:59:28 INFO - Starting resource monitoring. 09:59:28 INFO - ##### 09:59:28 INFO - ##### Running install step. 09:59:28 INFO - ##### 09:59:28 INFO - Running pre-action listener: _resource_record_pre_action 09:59:28 INFO - Running main action method: install 09:59:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:29 INFO - Reading from file tmpfile_stdout 09:59:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:29 INFO - Reading from file tmpfile_stdout 09:59:29 INFO - Output received: 09:59:29 INFO - Usage: mozinstall [options] installer 09:59:29 INFO - Options: 09:59:29 INFO - -h, --help show this help message and exit 09:59:29 INFO - -d DEST, --destination=DEST 09:59:29 INFO - Directory to install application into. [default: 09:59:29 INFO - "/builds/slave/test"] 09:59:29 INFO - --app=APP Application being installed. [default: firefox] 09:59:29 INFO - mkdir: /builds/slave/test/build/application 09:59:29 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'] 09:59:29 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 10:00:00 INFO - Reading from file tmpfile_stdout 10:00:00 INFO - Output received: 10:00:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:00:00 INFO - Running post-action listener: _resource_record_post_action 10:00:00 INFO - ##### 10:00:00 INFO - ##### Running run-tests step. 10:00:00 INFO - ##### 10:00:00 INFO - Running pre-action listener: _resource_record_pre_action 10:00:00 INFO - Running main action method: run_tests 10:00:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:00 INFO - 2016-06-01 10:00:00.903 screenresolution[1644:8470] starting screenresolution argv=screenresolution get 10:00:00 INFO - 2016-06-01 10:00:00.906 screenresolution[1644:8470] Display 0: 1600x1200x32@60 10:00:00 INFO - 2016-06-01 10:00:00.912 screenresolution[1645:8473] starting screenresolution argv=screenresolution list 10:00:00 INFO - Available Modes on Display 0 10:00:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:01 INFO - Intel Iris: 10:00:01 INFO - Chipset Model: Intel Iris 10:00:01 INFO - Type: GPU 10:00:01 INFO - Bus: Built-In 10:00:01 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:01 INFO - Vendor: Intel (0x8086) 10:00:01 INFO - Device ID: 0x0a2e 10:00:01 INFO - Revision ID: 0x0009 10:00:01 INFO - Displays: 10:00:01 INFO - Display: 10:00:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:01 INFO - Display Serial Number: 1600x1200 60 10:00:01 INFO - Main Display: Yes 10:00:01 INFO - Mirror: Off 10:00:01 INFO - Online: Yes 10:00:01 INFO - Rotation: Supported 10:00:01 INFO - Return code: 0 10:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:01 INFO - Python 2.7.3 10:00:01 INFO - Return code: 0 10:00:01 INFO - grabbing minidump binary from tooltool 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:01 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 0x10e542e00>, '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 0x10e3abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e53db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:01 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 10:00:01 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 10:00:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:01 INFO - Return code: 0 10:00:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:00:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:00:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:00:02 INFO - Return code: 0 10:00:02 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/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 10:00:02 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/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 10:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UhCZVWEjtF/Render', 10:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:02 INFO - 'HOME': '/Users/cltbld', 10:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:02 INFO - 'LOGNAME': 'cltbld', 10:00:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:02 INFO - 'MOZ_NO_REMOTE': '1', 10:00:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:02 INFO - 'NO_EM_RESTART': '1', 10:00:02 INFO - 'PAGER': '/bin/cat', 10:00:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:02 INFO - 'PWD': '/builds/slave/test', 10:00:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:02 INFO - 'SHELL': '/bin/bash', 10:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYY3VyORYh/Listeners', 10:00:02 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 10:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:02 INFO - 'USER': 'cltbld', 10:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:02 INFO - 'XPC_FLAGS': '0x0', 10:00:02 INFO - 'XPC_SERVICE_NAME': '0', 10:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:02 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/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 10:00:02 INFO - mozversion application_buildid: 20160601074655 10:00:02 INFO - mozversion application_changeset: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 10:00:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:00:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:02 INFO - mozversion application_name: Firefox 10:00:02 INFO - mozversion application_remotingname: firefox-dev 10:00:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:02 INFO - mozversion application_vendor: Mozilla 10:00:02 INFO - mozversion application_version: 48.0a2 10:00:02 INFO - mozversion platform_buildid: 20160601074655 10:00:02 INFO - mozversion platform_changeset: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 10:00:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:02 INFO - mozversion platform_version: 48.0a2 10:00:02 INFO - using testdate: 1464800402 10:00:02 INFO - actual date: 1464800402 10:00:02 INFO - starting webserver on 'localhost:49278' 10:00:02 INFO - SUITE-START | Running 2 tests 10:00:02 INFO - TEST-START | dromaeo_css 10:00:02 INFO - operating with platform_type : mac_ 10:00:02 INFO - Initialising browser for dromaeo_css test... 10:00:02 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpj3ikj6/profile http://localhost:49278/getInfo.html) 10:00:04 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:00:04 INFO - PROCESS | 1663 | colorDepth:24 10:00:04 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:00:04 INFO - PROCESS | 1663 | __metrics 10:00:04 INFO - PROCESS | 1663 | 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" 10:00:04 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:00:05 INFO - TEST-INFO | 1663: exit 0 10:00:05 INFO - Browser initialized. 10:00:05 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:05 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpj3ikj6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:17 INFO - PROCESS | 1666 | RSS: Main: 211218432 10:00:17 INFO - PROCESS | 1666 | 10:01:08 INFO - PROCESS | 1666 | 1464800468827 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] 10:03:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 10:03:20 INFO - PROCESS | 1666 | RSS: Main: 310550528 10:03:20 INFO - PROCESS | 1666 | 10:06:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 10:06:23 INFO - PROCESS | 1666 | RSS: Main: 359768064 10:06:23 INFO - PROCESS | 1666 | 10:09:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 10:09:26 INFO - PROCESS | 1666 | RSS: Main: 361795584 10:09:26 INFO - PROCESS | 1666 | 10:12:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 10:12:29 INFO - PROCESS | 1666 | RSS: Main: 361480192 10:12:29 INFO - PROCESS | 1666 | 10:15:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 10:15:32 INFO - PROCESS | 1666 | RSS: Main: 330539008 10:15:32 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 10:18:35 INFO - PROCESS | 1666 | RSS: Main: 368349184 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | __start_tp_report 10:18:35 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 10:18:35 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 10:18:35 INFO - PROCESS | 1666 | |i|pagename|runs| 10:18:35 INFO - PROCESS | 1666 | |0;dojo.html;3326.6174389945154;3412.6928576428118;3414.573178352706;3419.0597585663954;3468.21965057862;5551.1673249012665;5572.972135139324;5588;5589.35722391924;5601.047821870282;5577.9959607270675;5607.719614019293;5620.156976453534;5620.747066382012;5634.577406694494;5075.111855425286;5109.974450127745;5152.974235128819;5168.870778230536;5203.9219411708855;5182.119039763231;5228.686278823264;5228.712420816853;5231.319928409302;5243.606729495284;4269.5943885331;5172.327597412331;5210.557102646279;5228;5231.790728370861;5182.196759502283;5182.481751824825;5199.740012999335;5205.557527610158;5265.341832270966;5028.547430731252;5072.917598640063;5123.590112790967;5151.2273159026445;5153.330067091291;5423.620346575702;5429.53848922842;5435;5455.754491047913;5459.344878614592;6131.325554189036;6136.938630613726;6150.446459818593;6163.044728067157;6163.907541386883;6062.333143354227;6123.923256659767;6142.416470435302;6212.534059945522;6231.750729970796;5746.971265143731;5769.826905192851;5773.393793651606;5789.218455508537;5812.796552120698;6256.499480041586;6291.1192433059405;6315.905261421082;6323.9683801580695;6326.367363263729;5780.335261444988;5912.083627037816;5927.911081333784;6090.939090609126;6135.662538560333;6055.485283750842;6115.541334399938;6136.048912418582;6137.447629713258;6143.96928015357;6015.879682406415;6026.216591843032;6027.7890273841085;6035.124906888476;6046.788362407295;4986.177280748636;5987.64074155552;6052.576319657582;6079.422454866781;6092.421219984094;4829.855104346875;4849.587785038311;4866.659333846667;4867.172580661296;4884.731339776346;4324.24325743;4324.913501729948;4335.176316499918;4354.477462704495;4359.324304732771;5474.479924407255;5566.137248726453;5566.387697353288;5569.721513924287;5576.247206627135;6104.368208815921;6224.190855188887;6234.563580549318;6234.719437625317;6244.843878902973;5595.076812326002;5599.636023658449;5611.186377975252;5619.7471113799975;5629.240052592848;5225.425203227642;5252.422233554306;5306.124489459273;5313.840584782462;5314.8937021260135;6268.874622507525;6295.055741638791;6307.927652299212;6311.400416960381;6330.556861019776;6240.375962403723;6248.656323902047;6251;6258.311585725658;6271.216097987752;6198.287196972406;6254.21822272216;6296.087067375296;6307.558470906993;6317.5893566918;6070.78752243687;6095.05526643371;6112.847178820565;6117.785877494266;6129.478994285358;6155.138280640804;6195.31851496326;6199.597026193283;6218.875622487434;6238.656873871799;5707.20099186106;5767.163761254678;5901.675407852609;6037.818865434044;6086.178365920544;5978.192943952511;6005.819825405245;6007.669578173068;6024.246969128859;6032.125341825498;5812.01195796724;5817.301923769175;5822.446867547576;5846.152307915243;5865.032269675456;5710.457506536873;5860.736266868086;5952.5237980960555;5992.0712289595185;6019.067044608093;5761.164631128547;5772.480476757112;5795.565332599987;5814.505767009682;5818.563607729353 10:18:35 INFO - PROCESS | 1666 | |1;ext.html;500.3870258932807;530.8725905782611;591.9522445271866;593.1932571702486;618.959767615105;31588.684113158863;32219.51670724941;32325.67674323256;38427.615723842755;39036.414453783145;41973.37039944403;42180.94547636311;42245.78877105618;42309.153816923645;42386.57613423865;8937.329700272474;9016.909830901706;9492.81339832521;9651.5174241288;9663.033696630317;8520.957395213016;8704.390692651517;8720.8691869622;8723.825523489526;9452.527373631325;11373.147013973943;11607.535698572046;11619.360935148563;11640.068794496463;11641.30152190867;11341.716457088598;11442.084633229322;11444.771104577945;11454.255473394203;11465.254758440675;8560.315174786003;8762.167594078552;8786.736397908073;11291.830622540669;11422.371769552672;40307.395389069185;40857.79571102155;40961.79519102415;40964.180716385505;40985.590144098474;3326.9833650831833;3354.1782263345394;3547.343741407822;3753.0242137044293;3855.4987851579117;26769.330766730793;27176.45647087047;27344.58983115255;27401.177964661092;27955;2690.90581829637;2733.671959364889;2768.197222805384;2782.749552540281;2796.0493432232934;496065;497189.51405243104;497555.0244497545;500653;500975;36262.8186859064;36613;36630.450543241874;63028.684856575426;63464.730705385635;35531.64468355283;35613.46579801305;35843.641563584555;35850;35862.282754344764;15128.470503532324;15272.465463708717;15283.235838208046;15296.082235065931;15311.92344038295;12970.546030888874;13037.47850085986;13044.739105217843;13059.21644701321;13064.477420903248;7617.69529218825;7625.923740762521;8120.796980075427;8135.633896474673;8183.549904755296;3312.337532493511;3340.081477593681;3343.5653365062385;3348.8995330140133;3361.1429085583018;1030.6392762533053;1040.7365517788733;1057.735566108473;1171.3264872698062;1178.6169494914186;25062.74937250604;29702.554461683092;29928.850355748084;30327.545086823717;30517.08448746541;1078.6224821312478;1092.470151976291;1101.3997371432588;1137.5790957345835;1139.3847322445952;13116.868831311565;15045.721113705238;15088.24558772057;15255.618609534851;15562.922185389001;611406;623619;624532;624555;626890;60976;61758.382416175264;61876.69061654663;61881.38118618756;61997;1331.5539294336506;1470.9632259193606;1498.1086253679043;1505.2473763118442;1509.2227502836267;28725.28186795263;28989;29098.85548360106;29367;29700.257493562833;47098.52901471079;47376.7631161842;47399.52600474089;47408.76295618638;47466.81332966565;281497;351973;352632;353070;353112;23869.403264918517;27046.864765676833;27062.864685676446;27284.590731139448;27372.726272737018;44612.55387446084;45005.32492012688;45462.54537454583;45886;46242.76878615585;35921.281574367844;36242.8187859059;37291.44062839114;37540.2491950154;37952;36649.08377290589;37007.444888327234;37161.62838371582;37428.81285593664;37867.62132378641;35991.460128098624;36752.448713269856;37671.246575067795;37781.81109094437;37794.81102594469;32357;33335.166620833705;33382.66469341296;33446.665533344356;33531.66468335286;20598.485037874172;24656.630150548113;24683.753162468864;25019.999200032522;25069.87465062663 10:18:35 INFO - PROCESS | 1666 | |2;jquery.html;398.1837233670974;399.7521536647279;402.3120464006549;403.66495808478965;417.28019166937037;19783.703244451346;20269.493262668442;20749.896250518763;20980.58038839224;20987.895060524716;16688.666226675457;17620;20568.86871222082;20862;21483.892580537115;5854.385289544601;5884.6174998625165;6050.546209034318;6067.332593414721;6201.906971395432;6126.8468288292925;6159.353267906872;6192.535559833031;6194.907076393858;6204.596701214429;5214.947850521484;6001.21984142061;6111.724972376232;6186.288576813679;6196.070589411579;6102.633841969474;6131.141640170357;6133.294671112834;6137.232845894263;6144.0783882417545;5818.301803783531;5951.9107213391835;5959.0763431668165;6005.069214271794;6056.909146362808;25870.094546690776;25951.961921523118;26149;26338;26396.736032639812;2121.4908421978766;2175.6627722702847;2175.7171567696255;2184.453886528368;2201.72478440195;9585.712428627152;10182.490875456271;10316.58733650653;10421.739456513573;10572;1736.3749050341994;1741.312181688226;1743.3723859410602;1753.6405036967392;1813.4197056941784;121937;122651.38674306571;125861;126921;127257.99999999907;11612;11809.40952952349;11824.467898944567;11848.111391645662;12005.09961752872;15474.767878481833;15489.148096854557;15494.690106198039;15497.070175789264;15517.379304827906;8799.956000219958;8941.910580894239;8973.865392018994;9032.774180645536;9050;8973.32700047499;8974.14745599167;8996.415233009833;9073.77315567116;9097.40866843653;4602.861914142581;4700.529947005272;4716.174669432836;4756.904861902743;4795.832145874948;1740.9129543522772;1930.2857942561343;1951.180504188216;2008.2167954497756;2035.1248962946074;892.5938697892443;912.9634814607341;966.8066386722684;1014.0417305646241;1031.118393773328;17987.550311242063;20230.797692023185;20495.87272699986;21449.24927627557;21621;981.4503877828439;990.65327135502;993.4833886379042;1016.5730393234859;1032.8915463876335;9123.954380228057;11323.830142547868;11331.773364532828;11336.546538138547;11360.943195283971;407358;428602;498424;503404;634009.8299508473;102905;131514;133435.33282333525;136782;143412;870.8606622940299;1040.947952602382;1054.5465449856636;1057.8680811531742;1059.6450189186555;18524.814751852307;18768.343107991153;19059.618807624047;19303.51741206481;19455.929923853757;52513.474865252385;53293;53476.73261633667;54317;54777.904441911734;72396.27603723896;74885;75572.86640700504;75877.62061189879;76302.23697762951;7921;8376.748697539084;8410;8457.577121143795;8726.301895848444;12226;12670.176438531465;12752.872471275168;12982.545610903573;13178.406971686489;9970.850437243593;10780.029797318279;11106.944465277622;11372.488238029144;11398.829017564909;10757.892421075689;10836.241463097507;10966.287191332538;11209.383483908137;11283.605073822377;11249.887501124884;11339.376334301363;11356.230383239224;11433.59982400612;11883.762324753283;7362;8215.753527394188;8317.708880189164;8348.123447037973;8564.752672135222;9842.163416109712;10852.348859068481;11036.889631103908;11385.259958102697;11427.142964277878 10:18:35 INFO - PROCESS | 1666 | |3;mootools.html;1449.9927500362496;1598.049160749354;1631.6084139806442;1744.8255174482554;1863.0777765006324;7872.5670088145125;8575.828483430327;8725.083866194054;8733.388662793606;8755.036945935943;7441.181470038291;8441.409101362908;8668.436551624156;8680.435771674836;8686.739397818059;2457.778799908008;2480.7643273889043;2482.987585062081;2484.627305904111;2485.3041148478455;2395.7484464131276;2468.8642124683133;2470.234227389506;2472.09768434521;2481.8262721609494;2433.2092070077206;2483.85096894186;2493.8254322197454;2495.8128140389454;2501.312139161736;2418.600930846417;2454.6686197363397;2463.064035666453;2466.580681284186;2470.654108424822;2428.0530593068543;2462.729099799021;2473.0355161487037;2473.591857343536;2474.7277799442045;8419.494830310201;8737.126287371275;8780.956095219546;8782.077881822444;8806.383553151281;5309.973450132763;5458.990086833948;5463.754131064112;5493.038718224295;5505.174223866412;3567.233044895334;3627.4196128619296;3681.987453450296;3690.5940346561856;3714.609965953563;868.7828042989253;1062.8352605346183;1070.5878236878857;1079.95680172793;1082.1613249731442;11397.715057123556;11643.417829108595;11645.068394528418;11652.359120248468;11812.822807657805;3783.5270591176104;4581.908361832745;4641.443026836733;4660.4174478190225;4693.694909830919;9218.539073046455;9228.354015219005;9233.122853328923;9235.261179105655;9237;5320.76056577455;5326.600504962066;5332.813351532678;5340.118880384693;5354.384245811703;4360.367746676714;4473.418455600816;4479.776011199427;4529.230030894756;5020.548150666457;3549.8047607381327;3807.961920380816;3885.805709714503;3892.1242720387686;3896.181801821649;1187.4834447015403;1225.724212052281;1228.2200802490484;1238.2013601227159;1241.1870225002638;914.533587870191;928.8513837785922;935.6678379175431;944.1502647617062;993.4486360070086;5890.528757699374;5938;5948.791792287249;5956.761729530857;6121.387861213843;749.9437542184359;959.822432849925;994.393420013791;1037.3983089807894;1054.5834395413922;3920.3923391874305;3992.640662340345;4008.478897743333;4086.3053280942313;4088.448059511987;10537.315074520131;10871.891281087246;10908.563657453771;10974.890251097546;11028.117750579935;10634.734131646615;10638;10754.247202695893;10786.406747628798;10842.566297348018;7875;8215.178482151738;8305.127961563949;8306.792330191673;8310.085890552155;5645.661260324394;5907.143464197753;6048.818535443944;6058.5153187745755;6094.542909281733;9328.813423731623;9473.621055157717;9487.67172595851;9488.857667135137;9680.951595241979;8261.545614990993;9260;9777.16894063984;9963.900360996297;10014.248931330325;7637.809054773675;7656.119546252029;7771.650275737718;7774.805629859299;7776.7278145264645;7485.5508669480005;7607.277308655669;8299.543525106177;8311.83376332458;8338.833223335378;7323.450741194538;7327.230640782641;7381;7383.187849336461;7394.741184058747;7194.460415468756;7222.566646001257;7310.122785265802;7320.853582928418;7323.377512911462;6874.243833178446;6892.620905850025;6932.896006560007;6966.756163534252;7055.753048643272;4883.267509873547;4939.234418665113;4974.900501990012;5025.145725226794;5103.974480127576;4526.117407105583;4990.650654454299;5023.547880690755;5027.296178534935;5123.641345105807 10:18:35 INFO - PROCESS | 1666 | |4;prototype.html;319.43938388128834;326.3798782313604;329.77245700466676;330.67593758117044;336.6347512948451;5443.292371991641;5518.78586710924;6703.229128650207;6707.832304192398;6748.055272261888;5754.884902301951;6478.805635830932;6717.966410167955;6736.629485378302;6746.966265168668;4728.267118596615;4850.2724591311235;4894.290327902452;4910.705357678551;4932.457429682732;4868.05073010763;4913.950860491385;4936.037472692827;4990.550850423461;4997.575206107485;4845.830395936143;4899.926501102486;4911.238757992499;4913.091078150535;4918.163912134945;4879.902401951977;4895.730734809584;4903.043906438228;4910.386201724785;4913.975430122827;4058.7564746115327;4107.67138628909;4123.835046598132;4128.926479115422;4139.337705967031;6265.8120256392385;6492.383223593751;6511.479081673455;6532.150820393318;6540.771073012469;5875.559333050039;5880.059190529538;5883;5896.734646940898;5916.97041514794;5806.622569532967;5817.970910145465;7210.134783825922;7223.855522889513;7229.602371869545;4611.677182597219;4750.144973904696;4754.881127971794;4756.571908528249;4762.1190079835;7278.454115941273;7309.195988441267;7355.448341374418;7391.445641576849;7459.254074592497;4862.197737373364;4863.708177509361;4865.464798872121;4865.5864251539015;4870.707757534488;4935.679180853233;4941.061198372297;4941.802327906916;4949.381327334083;4951.168067814931;5850.5904586678525;5858.648481091063;5859.296884373817;5866.1494083357675;5892.263467066617;6994.265602111806;7107.218205997337;7109.075820143452;7111.64441777909;7244.492885497964;1641.253229780471;1658.917054147288;1660.435451946344;1662.6674665067035;1684.5451728033363;1096.13405409727;1104.4919337104861;1121.159130652011;1122.5229277557005;1124.5895248234335;6672.399484046459;6675.899861502081;6680.465562754968;6695;6706.597604143767;7130.3939165170505;7286;7298.160711518243;7433.85132297351;7444.032275804111;5617;6776.356246156607;6795.898061529081;6801.965990170018;6815.386615204557;3926.391409331558;3979.224051309967;3986.242613903407;3987.960120398817;3993.740406873602;7919.762407127795;7922.405819563556;7923.920760792318;7924.247196516321;7928.3260922821055;6401.487880969511;7496.475246732785;7784.84430311402;7804.141544430108;7807.219278072147;6308.779192728232;7860;7862.213778622092;7897.60511974399;7910.7231246906085;7461.925380746123;7487.8876816846705;7489.70041198346;7495.775126746206;7498.100227972678;5966.850828729316;7372.815679607838;7387.519811212469;7394.55632662042;7405;6037.849053773515;7849.215078492333;7871.094824095302;7882.448228623941;7888.6450109744055;4885.828995985124;4896.167651499325;4905.533974272438;4909.9018019640125;4913.263010548303;7443.590602516913;7446.292602202782;7468.775936721907;7471.514351567131;7473.177950425557;7146.285371462812;7198.640067996684;7212.531185473137;7230.6384680764695;7236.276372362721;6890.1731792183255;7172.892406614009;7195.71217151329;7198.820029499305;7224.458165637703;6959.686814093277;7027.4729395294535;7031.718731250898;7035.33164349386;7050.2597227292445;7188.712451501881;7189.317014883578;7191.676374563262;7197.8920316196345;7212.386947109554;6809.21694005176;7027.051348067946;7046.612436316052;7049.753258635923;7056.15326160864 10:18:35 INFO - PROCESS | 1666 | |5;yui.html;684.6610927590842;691.733682532225;729.6169511006717;735.4631119282923;789.0491957191587;14758.966872318942;14871.479498217552;15099.62250943727;15101.093934363933;15103.39586416542;13776.58670239892;13878.444862205499;13887.722245555107;13888.791668124986;13890.513832015882;746.4886552711383;752.3868047541264;909.4497828813555;916.1800188830993;937.1986951156765;926.9768255793591;934.3926447808944;934.6401635370363;935.5135329628589;936.3960245641557;982.7887004294075;987.6000219910911;988.2143695761887;991.3110388280148;997.366672163178;769.4229328003997;775.4029397363998;779.9883001754978;781.9951362499161;974.59554284972;689.1351354050635;696.5507247825125;719.7336985315467;732.1360794262768;734.3611058379191;14767.704645907022;14800.777988330183;14809.48166814167;14847.925760371128;14849;12431.254124752544;12512.562060327937;12517.749645007048;12585.874141258562;12794;9806.9509652452;10008.49957502122;10012.94993525035;10043.74890627733;10415.062644362044;3472.2708231271463;3503.351879902201;3527.400341941876;3559.323728491604;3760.3607386744047;68076;76516.23483765147;80929.19070809335;81217;81344;9156.313276504288;9580.473073980998;9653.46905920181;9665.613375465044;9792.755181120529;21093.156273749184;21446.142154313966;21481.248156314454;21496.677549836764;21965.890170549366;7384.150822655356;7386.298301661333;7616.428767842434;7680.078390593053;7784.143744188134;4136.358864376027;4141.5237247716295;4155.376693495939;4167.4790651168605;4235.408290586412;3006.7143621355935;3609.3864043112208;3631.654992775682;3632.0919770057485;3637.363461394298;631.958922670025;632.8038308124496;640.8974564069727;652.4519403700914;661.7617657643244;542.5125798215228;589.8879212949525;605.927288725347;611.623851331428;617.4257940115646;7716.5755883426955;8186.2632363087605;8222.712205072794;8254.257116859511;8306.460080094774;546.3802309698249;586.1705686453705;594.2957595249643;605.7819185093363;606.0908637044433;3815.21788033458;3842.731008829411;3897.7661340319673;3899.395593682984;3922.2155568886337;85321.57339213265;88145.1185488137;98451.01548984418;99423;99554.5022274884;71532.64233678798;71696;73142.26857731461;73369.53260934858;73422.63288683523;63582;64994.67502662551;71461.28538714546;71905;73523.26476735165;8092.352611790925;8283.668653254044;8320.76758085629;8458.111898250841;8596;34799;48047.75976120097;48206.03587928292;48917.0216595659;49367;63756;67662.32337676757;69868.60262794817;70593;70761.64619176871;10541.4729263535;10842.078423334106;10947;11016.559337626406;11103;11612.59427750525;11758.176927614984;11866.762664746484;12084;12335.136540442083;11034;11134.610288639858;11223.270487418291;11457.656270311903;12189.207701499374;11084.944575277072;11463.885361146615;11513.30920144794;11576.536938522364;11637.894400031983;10967.945160274467;11034.779304414027;11072.667819965414;11328.603498877499;11806.996405305645;9349.39228950116;9682;9707.174890134418;9717.514124293615;9983.450910199717;9582.185514231369;9603;9680.51597420112;9686.07982241686;9730.708078757649 10:18:35 INFO - PROCESS | 1666 | __end_tp_report 10:18:35 INFO - PROCESS | 1666 | __start_cc_report 10:18:35 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1241 10:18:35 INFO - PROCESS | 1666 | __end_cc_report 10:18:35 INFO - PROCESS | 1666 | __startTimestamp1464801515787__endTimestamp 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | ------- Summary: start ------- 10:18:35 INFO - PROCESS | 1666 | Number of tests: 6 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5651.99 Median:5812.01 stddev:630.92 (10.9%) stddev-sans-first:605.91 10:18:35 INFO - PROCESS | 1666 | Values: 3326.6 3412.7 3414.6 3419.1 3468.2 5551.2 5573.0 5588.0 5589.4 5601.0 5578.0 5607.7 5620.2 5620.7 5634.6 5075.1 5110.0 5153.0 5168.9 5203.9 5182.1 5228.7 5228.7 5231.3 5243.6 4269.6 5172.3 5210.6 5228.0 5231.8 5182.2 5182.5 5199.7 5205.6 5265.3 5028.5 5072.9 5123.6 5151.2 5153.3 5423.6 5429.5 5435.0 5455.8 5459.3 6131.3 6136.9 6150.4 6163.0 6163.9 6062.3 6123.9 6142.4 6212.5 6231.8 5747.0 5769.8 5773.4 5789.2 5812.8 6256.5 6291.1 6315.9 6324.0 6326.4 5780.3 5912.1 5927.9 6090.9 6135.7 6055.5 6115.5 6136.0 6137.4 6144.0 6015.9 6026.2 6027.8 6035.1 6046.8 4986.2 5987.6 6052.6 6079.4 6092.4 4829.9 4849.6 4866.7 4867.2 4884.7 4324.2 4324.9 4335.2 4354.5 4359.3 5474.5 5566.1 5566.4 5569.7 5576.2 6104.4 6224.2 6234.6 6234.7 6244.8 5595.1 5599.6 5611.2 5619.7 5629.2 5225.4 5252.4 5306.1 5313.8 5314.9 6268.9 6295.1 6307.9 6311.4 6330.6 6240.4 6248.7 6251.0 6258.3 6271.2 6198.3 6254.2 6296.1 6307.6 6317.6 6070.8 6095.1 6112.8 6117.8 6129.5 6155.1 6195.3 6199.6 6218.9 6238.7 5707.2 5767.2 5901.7 6037.8 6086.2 5978.2 6005.8 6007.7 6024.2 6032.1 5812.0 5817.3 5822.4 5846.2 5865.0 5710.5 5860.7 5952.5 5992.1 6019.1 5761.2 5772.5 5795.6 5814.5 5818.6 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:61416.30 Median:353091.00 stddev:133904.91 (37.9%) stddev-sans-first:134202.44 10:18:35 INFO - PROCESS | 1666 | Values: 500.4 530.9 592.0 593.2 619.0 31588.7 32219.5 32325.7 38427.6 39036.4 41973.4 42180.9 42245.8 42309.2 42386.6 8937.3 9016.9 9492.8 9651.5 9663.0 8521.0 8704.4 8720.9 8723.8 9452.5 11373.1 11607.5 11619.4 11640.1 11641.3 11341.7 11442.1 11444.8 11454.3 11465.3 8560.3 8762.2 8786.7 11291.8 11422.4 40307.4 40857.8 40961.8 40964.2 40985.6 3327.0 3354.2 3547.3 3753.0 3855.5 26769.3 27176.5 27344.6 27401.2 27955.0 2690.9 2733.7 2768.2 2782.7 2796.0 496065.0 497189.5 497555.0 500653.0 500975.0 36262.8 36613.0 36630.5 63028.7 63464.7 35531.6 35613.5 35843.6 35850.0 35862.3 15128.5 15272.5 15283.2 15296.1 15311.9 12970.5 13037.5 13044.7 13059.2 13064.5 7617.7 7625.9 8120.8 8135.6 8183.5 3312.3 3340.1 3343.6 3348.9 3361.1 1030.6 1040.7 1057.7 1171.3 1178.6 25062.7 29702.6 29928.9 30327.5 30517.1 1078.6 1092.5 1101.4 1137.6 1139.4 13116.9 15045.7 15088.2 15255.6 15562.9 611406.0 623619.0 624532.0 624555.0 626890.0 60976.0 61758.4 61876.7 61881.4 61997.0 1331.6 1471.0 1498.1 1505.2 1509.2 28725.3 28989.0 29098.9 29367.0 29700.3 47098.5 47376.8 47399.5 47408.8 47466.8 281497.0 351973.0 352632.0 353070.0 353112.0 23869.4 27046.9 27062.9 27284.6 27372.7 44612.6 45005.3 45462.5 45886.0 46242.8 35921.3 36242.8 37291.4 37540.2 37952.0 36649.1 37007.4 37161.6 37428.8 37867.6 35991.5 36752.4 37671.2 37781.8 37794.8 32357.0 33335.2 33382.7 33446.7 33531.7 20598.5 24656.6 24683.8 25020.0 25069.9 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:32424.64 Median:2175.69 stddev:84892.02 (3901.8%) stddev-sans-first:85096.10 10:18:35 INFO - PROCESS | 1666 | Values: 398.2 399.8 402.3 403.7 417.3 19783.7 20269.5 20749.9 20980.6 20987.9 16688.7 17620.0 20568.9 20862.0 21483.9 5854.4 5884.6 6050.5 6067.3 6201.9 6126.8 6159.4 6192.5 6194.9 6204.6 5214.9 6001.2 6111.7 6186.3 6196.1 6102.6 6131.1 6133.3 6137.2 6144.1 5818.3 5951.9 5959.1 6005.1 6056.9 25870.1 25952.0 26149.0 26338.0 26396.7 2121.5 2175.7 2175.7 2184.5 2201.7 9585.7 10182.5 10316.6 10421.7 10572.0 1736.4 1741.3 1743.4 1753.6 1813.4 121937.0 122651.4 125861.0 126921.0 127258.0 11612.0 11809.4 11824.5 11848.1 12005.1 15474.8 15489.1 15494.7 15497.1 15517.4 8800.0 8941.9 8973.9 9032.8 9050.0 8973.3 8974.1 8996.4 9073.8 9097.4 4602.9 4700.5 4716.2 4756.9 4795.8 1740.9 1930.3 1951.2 2008.2 2035.1 892.6 913.0 966.8 1014.0 1031.1 17987.6 20230.8 20495.9 21449.2 21621.0 981.5 990.7 993.5 1016.6 1032.9 9124.0 11323.8 11331.8 11336.5 11360.9 407358.0 428602.0 498424.0 503404.0 634009.8 102905.0 131514.0 133435.3 136782.0 143412.0 870.9 1040.9 1054.5 1057.9 1059.6 18524.8 18768.3 19059.6 19303.5 19455.9 52513.5 53293.0 53476.7 54317.0 54777.9 72396.3 74885.0 75572.9 75877.6 76302.2 7921.0 8376.7 8410.0 8457.6 8726.3 12226.0 12670.2 12752.9 12982.5 13178.4 9970.9 10780.0 11106.9 11372.5 11398.8 10757.9 10836.2 10966.3 11209.4 11283.6 11249.9 11339.4 11356.2 11433.6 11883.8 7362.0 8215.8 8317.7 8348.1 8564.8 9842.2 10852.3 11036.9 11385.3 11427.1 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5662.37 Median:5113.81 stddev:3110.88 (60.8%) stddev-sans-first:3103.50 10:18:35 INFO - PROCESS | 1666 | Values: 1450.0 1598.0 1631.6 1744.8 1863.1 7872.6 8575.8 8725.1 8733.4 8755.0 7441.2 8441.4 8668.4 8680.4 8686.7 2457.8 2480.8 2483.0 2484.6 2485.3 2395.7 2468.9 2470.2 2472.1 2481.8 2433.2 2483.9 2493.8 2495.8 2501.3 2418.6 2454.7 2463.1 2466.6 2470.7 2428.1 2462.7 2473.0 2473.6 2474.7 8419.5 8737.1 8781.0 8782.1 8806.4 5310.0 5459.0 5463.8 5493.0 5505.2 3567.2 3627.4 3682.0 3690.6 3714.6 868.8 1062.8 1070.6 1080.0 1082.2 11397.7 11643.4 11645.1 11652.4 11812.8 3783.5 4581.9 4641.4 4660.4 4693.7 9218.5 9228.4 9233.1 9235.3 9237.0 5320.8 5326.6 5332.8 5340.1 5354.4 4360.4 4473.4 4479.8 4529.2 5020.5 3549.8 3808.0 3885.8 3892.1 3896.2 1187.5 1225.7 1228.2 1238.2 1241.2 914.5 928.9 935.7 944.2 993.4 5890.5 5938.0 5948.8 5956.8 6121.4 749.9 959.8 994.4 1037.4 1054.6 3920.4 3992.6 4008.5 4086.3 4088.4 10537.3 10871.9 10908.6 10974.9 11028.1 10634.7 10638.0 10754.2 10786.4 10842.6 7875.0 8215.2 8305.1 8306.8 8310.1 5645.7 5907.1 6048.8 6058.5 6094.5 9328.8 9473.6 9487.7 9488.9 9681.0 8261.5 9260.0 9777.2 9963.9 10014.2 7637.8 7656.1 7771.7 7774.8 7776.7 7485.6 7607.3 8299.5 8311.8 8338.8 7323.5 7327.2 7381.0 7383.2 7394.7 7194.5 7222.6 7310.1 7320.9 7323.4 6874.2 6892.6 6932.9 6966.8 7055.8 4883.3 4939.2 4974.9 5025.1 5104.0 4526.1 4990.7 5023.5 5027.3 5123.6 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5859.11 Median:6606.59 stddev:1858.34 (28.1%) stddev-sans-first:1816.44 10:18:35 INFO - PROCESS | 1666 | Values: 319.4 326.4 329.8 330.7 336.6 5443.3 5518.8 6703.2 6707.8 6748.1 5754.9 6478.8 6718.0 6736.6 6747.0 4728.3 4850.3 4894.3 4910.7 4932.5 4868.1 4914.0 4936.0 4990.6 4997.6 4845.8 4899.9 4911.2 4913.1 4918.2 4879.9 4895.7 4903.0 4910.4 4914.0 4058.8 4107.7 4123.8 4128.9 4139.3 6265.8 6492.4 6511.5 6532.2 6540.8 5875.6 5880.1 5883.0 5896.7 5917.0 5806.6 5818.0 7210.1 7223.9 7229.6 4611.7 4750.1 4754.9 4756.6 4762.1 7278.5 7309.2 7355.4 7391.4 7459.3 4862.2 4863.7 4865.5 4865.6 4870.7 4935.7 4941.1 4941.8 4949.4 4951.2 5850.6 5858.6 5859.3 5866.1 5892.3 6994.3 7107.2 7109.1 7111.6 7244.5 1641.3 1658.9 1660.4 1662.7 1684.5 1096.1 1104.5 1121.2 1122.5 1124.6 6672.4 6675.9 6680.5 6695.0 6706.6 7130.4 7286.0 7298.2 7433.9 7444.0 5617.0 6776.4 6795.9 6802.0 6815.4 3926.4 3979.2 3986.2 3988.0 3993.7 7919.8 7922.4 7923.9 7924.2 7928.3 6401.5 7496.5 7784.8 7804.1 7807.2 6308.8 7860.0 7862.2 7897.6 7910.7 7461.9 7487.9 7489.7 7495.8 7498.1 5966.9 7372.8 7387.5 7394.6 7405.0 6037.8 7849.2 7871.1 7882.4 7888.6 4885.8 4896.2 4905.5 4909.9 4913.3 7443.6 7446.3 7468.8 7471.5 7473.2 7146.3 7198.6 7212.5 7230.6 7236.3 6890.2 7172.9 7195.7 7198.8 7224.5 6959.7 7027.5 7031.7 7035.3 7050.3 7188.7 7189.3 7191.7 7197.9 7212.4 6809.2 7027.1 7046.6 7049.8 7056.2 10:18:35 INFO - PROCESS | 1666 | 10:18:35 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17988.12 Median:32107.40 stddev:25211.36 (78.5%) stddev-sans-first:25248.61 10:18:35 INFO - PROCESS | 1666 | Values: 684.7 691.7 729.6 735.5 789.0 14759.0 14871.5 15099.6 15101.1 15103.4 13776.6 13878.4 13887.7 13888.8 13890.5 746.5 752.4 909.4 916.2 937.2 927.0 934.4 934.6 935.5 936.4 982.8 987.6 988.2 991.3 997.4 769.4 775.4 780.0 782.0 974.6 689.1 696.6 719.7 732.1 734.4 14767.7 14800.8 14809.5 14847.9 14849.0 12431.3 12512.6 12517.7 12585.9 12794.0 9807.0 10008.5 10012.9 10043.7 10415.1 3472.3 3503.4 3527.4 3559.3 3760.4 68076.0 76516.2 80929.2 81217.0 81344.0 9156.3 9580.5 9653.5 9665.6 9792.8 21093.2 21446.1 21481.2 21496.7 21965.9 7384.2 7386.3 7616.4 7680.1 7784.1 4136.4 4141.5 4155.4 4167.5 4235.4 3006.7 3609.4 3631.7 3632.1 3637.4 632.0 632.8 640.9 652.5 661.8 542.5 589.9 605.9 611.6 617.4 7716.6 8186.3 8222.7 8254.3 8306.5 546.4 586.2 594.3 605.8 606.1 3815.2 3842.7 3897.8 3899.4 3922.2 85321.6 88145.1 98451.0 99423.0 99554.5 71532.6 71696.0 73142.3 73369.5 73422.6 63582.0 64994.7 71461.3 71905.0 73523.3 8092.4 8283.7 8320.8 8458.1 8596.0 34799.0 48047.8 48206.0 48917.0 49367.0 63756.0 67662.3 69868.6 70593.0 70761.6 10541.5 10842.1 10947.0 11016.6 11103.0 11612.6 11758.2 11866.8 12084.0 12335.1 11034.0 11134.6 11223.3 11457.7 12189.2 11084.9 11463.9 11513.3 11576.5 11637.9 10967.9 11034.8 11072.7 11328.6 11807.0 9349.4 9682.0 9707.2 9717.5 9983.5 9582.2 9603.0 9680.5 9686.1 9730.7 10:18:35 INFO - PROCESS | 1666 | -------- Summary: end -------- 10:18:35 INFO - PROCESS | 1666 | 10:18:36 INFO - TEST-INFO | 1666: exit 0 10:18:36 INFO - TEST-OK | dromaeo_css | took 1113814ms 10:18:36 INFO - TEST-START | kraken 10:18:36 INFO - operating with platform_type : mac_ 10:18:36 INFO - Initialising browser for kraken test... 10:18:36 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpjY55JC/profile http://localhost:49278/getInfo.html) 10:18:38 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 10:18:38 INFO - PROCESS | 1672 | colorDepth:24 10:18:38 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 10:18:38 INFO - PROCESS | 1672 | __metrics 10:18:38 INFO - PROCESS | 1672 | 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" 10:18:38 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:18:39 INFO - TEST-INFO | 1672: exit 0 10:18:39 INFO - Browser initialized. 10:18:39 INFO - Running cycle 1/1 for kraken test... 10:18:39 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpjY55JC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:51 INFO - PROCESS | 1674 | RSS: Main: 211316736 10:18:51 INFO - PROCESS | 1674 | 10:18:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:54 INFO - PROCESS | 1674 | RSS: Main: 273715200 10:18:54 INFO - PROCESS | 1674 | 10:18:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 10:18:58 INFO - PROCESS | 1674 | RSS: Main: 288071680 10:18:58 INFO - PROCESS | 1674 | 10:19:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 10:19:01 INFO - PROCESS | 1674 | RSS: Main: 372891648 10:19:01 INFO - PROCESS | 1674 | 10:19:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 10:19:04 INFO - PROCESS | 1674 | RSS: Main: 278888448 10:19:04 INFO - PROCESS | 1674 | 10:19:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:07 INFO - PROCESS | 1674 | RSS: Main: 280367104 10:19:07 INFO - PROCESS | 1674 | 10:19:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:10 INFO - PROCESS | 1674 | RSS: Main: 319717376 10:19:10 INFO - PROCESS | 1674 | 10:19:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:14 INFO - PROCESS | 1674 | RSS: Main: 317210624 10:19:14 INFO - PROCESS | 1674 | 10:19:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 10:19:17 INFO - PROCESS | 1674 | RSS: Main: 318943232 10:19:17 INFO - PROCESS | 1674 | 10:19:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:19 INFO - PROCESS | 1674 | RSS: Main: 309563392 10:19:19 INFO - PROCESS | 1674 | 10:19:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:22 INFO - PROCESS | 1674 | RSS: Main: 317091840 10:19:22 INFO - PROCESS | 1674 | 10:19:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:25 INFO - PROCESS | 1674 | RSS: Main: 319651840 10:19:25 INFO - PROCESS | 1674 | 10:19:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:19:28 INFO - PROCESS | 1674 | RSS: Main: 324403200 10:19:28 INFO - PROCESS | 1674 | 10:19:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:19:32 INFO - PROCESS | 1674 | RSS: Main: 322486272 10:19:32 INFO - PROCESS | 1674 | 10:19:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 10:19:34 INFO - PROCESS | 1674 | RSS: Main: 386678784 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | __start_tp_report 10:19:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 10:19:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 10:19:34 INFO - PROCESS | 1674 | |i|pagename|runs| 10:19:34 INFO - PROCESS | 1674 | |0;ai-astar;114;112;121;112;112;114;119;163;166;140 10:19:34 INFO - PROCESS | 1674 | |1;audio-beat-detection;155;120;158;155;121;141;138;118;168;115 10:19:34 INFO - PROCESS | 1674 | |2;audio-dft;183;185;187;184;187;185;183;184;185;185 10:19:34 INFO - PROCESS | 1674 | |3;audio-fft;76;109;103;202;45;74;81;90;152;81 10:19:34 INFO - PROCESS | 1674 | |4;audio-oscillator;91;91;137;77;78;87;123;79;80;123 10:19:34 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;84;88;88;88;85;88;84;87;84 10:19:34 INFO - PROCESS | 1674 | |6;imaging-darkroom;91;93;94;95;94;94;95;94;94;95 10:19:34 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;87;86;85;93;85;68;130;140;88 10:19:34 INFO - PROCESS | 1674 | |8;json-parse-financial;59;64;64;63;63;65;65;83;58;58 10:19:34 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;64;63;75;71;74;71;69;68;68 10:19:34 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;81;79;81;80;81;81;81;80;82;85 10:19:34 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;130;130;132;128;127;130;126;130;145;129 10:19:34 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;168;167;167;165;166;167;227;199;165;162 10:19:34 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;66;66;70;68;67;66;68;79;89 10:19:34 INFO - PROCESS | 1674 | __end_tp_report 10:19:34 INFO - PROCESS | 1674 | __start_cc_report 10:19:34 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1963 10:19:34 INFO - PROCESS | 1674 | __end_cc_report 10:19:34 INFO - PROCESS | 1674 | __startTimestamp1464801574057__endTimestamp 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | ------- Summary: start ------- 10:19:34 INFO - PROCESS | 1674 | Number of tests: 14 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:127.30 Median:120.00 stddev:21.34 (17.8%) stddev-sans-first:22.08 10:19:34 INFO - PROCESS | 1674 | Values: 114.0 112.0 121.0 112.0 112.0 114.0 119.0 163.0 166.0 140.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:148.00 stddev:19.50 (13.2%) stddev-sans-first:19.79 10:19:34 INFO - PROCESS | 1674 | Values: 155.0 120.0 158.0 155.0 121.0 141.0 138.0 118.0 168.0 115.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.40 (0.8%) stddev-sans-first:1.32 10:19:34 INFO - PROCESS | 1674 | Values: 183.0 185.0 187.0 184.0 187.0 185.0 183.0 184.0 185.0 185.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:101.30 Median:75.00 stddev:45.07 (60.1%) stddev-sans-first:46.86 10:19:34 INFO - PROCESS | 1674 | Values: 76.0 109.0 103.0 202.0 45.0 74.0 81.0 90.0 152.0 81.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:79.50 stddev:22.35 (28.1%) stddev-sans-first:23.62 10:19:34 INFO - PROCESS | 1674 | Values: 91.0 91.0 137.0 77.0 78.0 87.0 123.0 79.0 80.0 123.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.83 (2.1%) stddev-sans-first:1.92 10:19:34 INFO - PROCESS | 1674 | Values: 87.0 84.0 88.0 88.0 88.0 85.0 88.0 84.0 87.0 84.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:1.20 (1.3%) stddev-sans-first:0.67 10:19:34 INFO - PROCESS | 1674 | Values: 91.0 93.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:86.00 stddev:22.26 (25.9%) stddev-sans-first:23.38 10:19:34 INFO - PROCESS | 1674 | Values: 86.0 87.0 86.0 85.0 93.0 85.0 68.0 130.0 140.0 88.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:7.16 (11.2%) stddev-sans-first:7.34 10:19:34 INFO - PROCESS | 1674 | Values: 59.0 64.0 64.0 63.0 63.0 65.0 65.0 83.0 58.0 58.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:70.00 stddev:4.30 (6.1%) stddev-sans-first:4.06 10:19:34 INFO - PROCESS | 1674 | Values: 63.0 64.0 63.0 75.0 71.0 74.0 71.0 69.0 68.0 68.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:1.60 (2.0%) stddev-sans-first:1.69 10:19:34 INFO - PROCESS | 1674 | Values: 81.0 79.0 81.0 80.0 81.0 81.0 81.0 80.0 82.0 85.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:130.00 stddev:5.31 (4.1%) stddev-sans-first:5.63 10:19:34 INFO - PROCESS | 1674 | Values: 130.0 130.0 132.0 128.0 127.0 130.0 126.0 130.0 145.0 129.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:167.00 stddev:21.00 (12.6%) stddev-sans-first:22.11 10:19:34 INFO - PROCESS | 1674 | Values: 168.0 167.0 167.0 165.0 166.0 167.0 227.0 199.0 165.0 162.0 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:68.50 stddev:7.47 (10.9%) stddev-sans-first:7.89 10:19:34 INFO - PROCESS | 1674 | Values: 69.0 66.0 66.0 70.0 68.0 67.0 66.0 68.0 79.0 89.0 10:19:34 INFO - PROCESS | 1674 | -------- Summary: end -------- 10:19:34 INFO - PROCESS | 1674 | 10:19:34 INFO - TEST-INFO | 1674: exit 0 10:19:34 INFO - TEST-OK | kraken | took 58463ms 10:19:34 INFO - SUITE-END | took 1172s 10:19:35 INFO - Completed test suite (00:19:32) 10:19:35 INFO - javascript benchmark 10:19:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3326.6174389945154, 3412.6928576428118, 3414.573178352706, 3419.0597585663954, 3468.21965057862, 5551.1673249012665, 5572.972135139324, 5588.0, 5589.35722391924, 5601.047821870282, 5577.9959607270675, 5607.719614019293, 5620.156976453534, 5620.747066382012, 5634.577406694494, 5075.111855425286, 5109.974450127745, 5152.974235128819, 5168.870778230536, 5203.9219411708855, 5182.119039763231, 5228.686278823264, 5228.712420816853, 5231.319928409302, 5243.606729495284, 4269.5943885331, 5172.327597412331, 5210.557102646279, 5228.0, 5231.790728370861, 5182.196759502283, 5182.481751824825, 5199.740012999335, 5205.557527610158, 5265.341832270966, 5028.547430731252, 5072.917598640063, 5123.590112790967, 5151.2273159026445, 5153.330067091291, 5423.620346575702, 5429.53848922842, 5435.0, 5455.754491047913, 5459.344878614592, 6131.325554189036, 6136.938630613726, 6150.446459818593, 6163.044728067157, 6163.907541386883, 6062.333143354227, 6123.923256659767, 6142.416470435302, 6212.534059945522, 6231.750729970796, 5746.971265143731, 5769.826905192851, 5773.393793651606, 5789.218455508537, 5812.796552120698, 6256.499480041586, 6291.1192433059405, 6315.905261421082, 6323.9683801580695, 6326.367363263729, 5780.335261444988, 5912.083627037816, 5927.911081333784, 6090.939090609126, 6135.662538560333, 6055.485283750842, 6115.541334399938, 6136.048912418582, 6137.447629713258, 6143.96928015357, 6015.879682406415, 6026.216591843032, 6027.7890273841085, 6035.124906888476, 6046.788362407295, 4986.177280748636, 5987.64074155552, 6052.576319657582, 6079.422454866781, 6092.421219984094, 4829.855104346875, 4849.587785038311, 4866.659333846667, 4867.172580661296, 4884.731339776346, 4324.24325743, 4324.913501729948, 4335.176316499918, 4354.477462704495, 4359.324304732771, 5474.479924407255, 5566.137248726453, 5566.387697353288, 5569.721513924287, 5576.247206627135, 6104.368208815921, 6224.190855188887, 6234.563580549318, 6234.719437625317, 6244.843878902973, 5595.076812326002, 5599.636023658449, 5611.186377975252, 5619.7471113799975, 5629.240052592848, 5225.425203227642, 5252.422233554306, 5306.124489459273, 5313.840584782462, 5314.8937021260135, 6268.874622507525, 6295.055741638791, 6307.927652299212, 6311.400416960381, 6330.556861019776, 6240.375962403723, 6248.656323902047, 6251.0, 6258.311585725658, 6271.216097987752, 6198.287196972406, 6254.21822272216, 6296.087067375296, 6307.558470906993, 6317.5893566918, 6070.78752243687, 6095.05526643371, 6112.847178820565, 6117.785877494266, 6129.478994285358, 6155.138280640804, 6195.31851496326, 6199.597026193283, 6218.875622487434, 6238.656873871799, 5707.20099186106, 5767.163761254678, 5901.675407852609, 6037.818865434044, 6086.178365920544, 5978.192943952511, 6005.819825405245, 6007.669578173068, 6024.246969128859, 6032.125341825498, 5812.01195796724, 5817.301923769175, 5822.446867547576, 5846.152307915243, 5865.032269675456, 5710.457506536873, 5860.736266868086, 5952.5237980960555, 5992.0712289595185, 6019.067044608093, 5761.164631128547, 5772.480476757112, 5795.565332599987, 5814.505767009682, 5818.563607729353], "value": 5612.35227426453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.3870258932807, 530.8725905782611, 591.9522445271866, 593.1932571702486, 618.959767615105, 31588.684113158863, 32219.51670724941, 32325.67674323256, 38427.615723842755, 39036.414453783145, 41973.37039944403, 42180.94547636311, 42245.78877105618, 42309.153816923645, 42386.57613423865, 8937.329700272474, 9016.909830901706, 9492.81339832521, 9651.5174241288, 9663.033696630317, 8520.957395213016, 8704.390692651517, 8720.8691869622, 8723.825523489526, 9452.527373631325, 11373.147013973943, 11607.535698572046, 11619.360935148563, 11640.068794496463, 11641.30152190867, 11341.716457088598, 11442.084633229322, 11444.771104577945, 11454.255473394203, 11465.254758440675, 8560.315174786003, 8762.167594078552, 8786.736397908073, 11291.830622540669, 11422.371769552672, 40307.395389069185, 40857.79571102155, 40961.79519102415, 40964.180716385505, 40985.590144098474, 3326.9833650831833, 3354.1782263345394, 3547.343741407822, 3753.0242137044293, 3855.4987851579117, 26769.330766730793, 27176.45647087047, 27344.58983115255, 27401.177964661092, 27955.0, 2690.90581829637, 2733.671959364889, 2768.197222805384, 2782.749552540281, 2796.0493432232934, 496065.0, 497189.51405243104, 497555.0244497545, 500653.0, 500975.0, 36262.8186859064, 36613.0, 36630.450543241874, 63028.684856575426, 63464.730705385635, 35531.64468355283, 35613.46579801305, 35843.641563584555, 35850.0, 35862.282754344764, 15128.470503532324, 15272.465463708717, 15283.235838208046, 15296.082235065931, 15311.92344038295, 12970.546030888874, 13037.47850085986, 13044.739105217843, 13059.21644701321, 13064.477420903248, 7617.69529218825, 7625.923740762521, 8120.796980075427, 8135.633896474673, 8183.549904755296, 3312.337532493511, 3340.081477593681, 3343.5653365062385, 3348.8995330140133, 3361.1429085583018, 1030.6392762533053, 1040.7365517788733, 1057.735566108473, 1171.3264872698062, 1178.6169494914186, 25062.74937250604, 29702.554461683092, 29928.850355748084, 30327.545086823717, 30517.08448746541, 1078.6224821312478, 1092.470151976291, 1101.3997371432588, 1137.5790957345835, 1139.3847322445952, 13116.868831311565, 15045.721113705238, 15088.24558772057, 15255.618609534851, 15562.922185389001, 611406.0, 623619.0, 624532.0, 624555.0, 626890.0, 60976.0, 61758.382416175264, 61876.69061654663, 61881.38118618756, 61997.0, 1331.5539294336506, 1470.9632259193606, 1498.1086253679043, 1505.2473763118442, 1509.2227502836267, 28725.28186795263, 28989.0, 29098.85548360106, 29367.0, 29700.257493562833, 47098.52901471079, 47376.7631161842, 47399.52600474089, 47408.76295618638, 47466.81332966565, 281497.0, 351973.0, 352632.0, 353070.0, 353112.0, 23869.403264918517, 27046.864765676833, 27062.864685676446, 27284.590731139448, 27372.726272737018, 44612.55387446084, 45005.32492012688, 45462.54537454583, 45886.0, 46242.76878615585, 35921.281574367844, 36242.8187859059, 37291.44062839114, 37540.2491950154, 37952.0, 36649.08377290589, 37007.444888327234, 37161.62838371582, 37428.81285593664, 37867.62132378641, 35991.460128098624, 36752.448713269856, 37671.246575067795, 37781.81109094437, 37794.81102594469, 32357.0, 33335.166620833705, 33382.66469341296, 33446.665533344356, 33531.66468335286, 20598.485037874172, 24656.630150548113, 24683.753162468864, 25019.999200032522, 25069.87465062663], "value": 18322.518623776767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.1837233670974, 399.7521536647279, 402.3120464006549, 403.66495808478965, 417.28019166937037, 19783.703244451346, 20269.493262668442, 20749.896250518763, 20980.58038839224, 20987.895060524716, 16688.666226675457, 17620.0, 20568.86871222082, 20862.0, 21483.892580537115, 5854.385289544601, 5884.6174998625165, 6050.546209034318, 6067.332593414721, 6201.906971395432, 6126.8468288292925, 6159.353267906872, 6192.535559833031, 6194.907076393858, 6204.596701214429, 5214.947850521484, 6001.21984142061, 6111.724972376232, 6186.288576813679, 6196.070589411579, 6102.633841969474, 6131.141640170357, 6133.294671112834, 6137.232845894263, 6144.0783882417545, 5818.301803783531, 5951.9107213391835, 5959.0763431668165, 6005.069214271794, 6056.909146362808, 25870.094546690776, 25951.961921523118, 26149.0, 26338.0, 26396.736032639812, 2121.4908421978766, 2175.6627722702847, 2175.7171567696255, 2184.453886528368, 2201.72478440195, 9585.712428627152, 10182.490875456271, 10316.58733650653, 10421.739456513573, 10572.0, 1736.3749050341994, 1741.312181688226, 1743.3723859410602, 1753.6405036967392, 1813.4197056941784, 121937.0, 122651.38674306571, 125861.0, 126921.0, 127257.99999999907, 11612.0, 11809.40952952349, 11824.467898944567, 11848.111391645662, 12005.09961752872, 15474.767878481833, 15489.148096854557, 15494.690106198039, 15497.070175789264, 15517.379304827906, 8799.956000219958, 8941.910580894239, 8973.865392018994, 9032.774180645536, 9050.0, 8973.32700047499, 8974.14745599167, 8996.415233009833, 9073.77315567116, 9097.40866843653, 4602.861914142581, 4700.529947005272, 4716.174669432836, 4756.904861902743, 4795.832145874948, 1740.9129543522772, 1930.2857942561343, 1951.180504188216, 2008.2167954497756, 2035.1248962946074, 892.5938697892443, 912.9634814607341, 966.8066386722684, 1014.0417305646241, 1031.118393773328, 17987.550311242063, 20230.797692023185, 20495.87272699986, 21449.24927627557, 21621.0, 981.4503877828439, 990.65327135502, 993.4833886379042, 1016.5730393234859, 1032.8915463876335, 9123.954380228057, 11323.830142547868, 11331.773364532828, 11336.546538138547, 11360.943195283971, 407358.0, 428602.0, 498424.0, 503404.0, 634009.8299508473, 102905.0, 131514.0, 133435.33282333525, 136782.0, 143412.0, 870.8606622940299, 1040.947952602382, 1054.5465449856636, 1057.8680811531742, 1059.6450189186555, 18524.814751852307, 18768.343107991153, 19059.618807624047, 19303.51741206481, 19455.929923853757, 52513.474865252385, 53293.0, 53476.73261633667, 54317.0, 54777.904441911734, 72396.27603723896, 74885.0, 75572.86640700504, 75877.62061189879, 76302.23697762951, 7921.0, 8376.748697539084, 8410.0, 8457.577121143795, 8726.301895848444, 12226.0, 12670.176438531465, 12752.872471275168, 12982.545610903573, 13178.406971686489, 9970.850437243593, 10780.029797318279, 11106.944465277622, 11372.488238029144, 11398.829017564909, 10757.892421075689, 10836.241463097507, 10966.287191332538, 11209.383483908137, 11283.605073822377, 11249.887501124884, 11339.376334301363, 11356.230383239224, 11433.59982400612, 11883.762324753283, 7362.0, 8215.753527394188, 8317.708880189164, 8348.123447037973, 8564.752672135222, 9842.163416109712, 10852.348859068481, 11036.889631103908, 11385.259958102697, 11427.142964277878], "value": 9684.739903177287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.9927500362496, 1598.049160749354, 1631.6084139806442, 1744.8255174482554, 1863.0777765006324, 7872.5670088145125, 8575.828483430327, 8725.083866194054, 8733.388662793606, 8755.036945935943, 7441.181470038291, 8441.409101362908, 8668.436551624156, 8680.435771674836, 8686.739397818059, 2457.778799908008, 2480.7643273889043, 2482.987585062081, 2484.627305904111, 2485.3041148478455, 2395.7484464131276, 2468.8642124683133, 2470.234227389506, 2472.09768434521, 2481.8262721609494, 2433.2092070077206, 2483.85096894186, 2493.8254322197454, 2495.8128140389454, 2501.312139161736, 2418.600930846417, 2454.6686197363397, 2463.064035666453, 2466.580681284186, 2470.654108424822, 2428.0530593068543, 2462.729099799021, 2473.0355161487037, 2473.591857343536, 2474.7277799442045, 8419.494830310201, 8737.126287371275, 8780.956095219546, 8782.077881822444, 8806.383553151281, 5309.973450132763, 5458.990086833948, 5463.754131064112, 5493.038718224295, 5505.174223866412, 3567.233044895334, 3627.4196128619296, 3681.987453450296, 3690.5940346561856, 3714.609965953563, 868.7828042989253, 1062.8352605346183, 1070.5878236878857, 1079.95680172793, 1082.1613249731442, 11397.715057123556, 11643.417829108595, 11645.068394528418, 11652.359120248468, 11812.822807657805, 3783.5270591176104, 4581.908361832745, 4641.443026836733, 4660.4174478190225, 4693.694909830919, 9218.539073046455, 9228.354015219005, 9233.122853328923, 9235.261179105655, 9237.0, 5320.76056577455, 5326.600504962066, 5332.813351532678, 5340.118880384693, 5354.384245811703, 4360.367746676714, 4473.418455600816, 4479.776011199427, 4529.230030894756, 5020.548150666457, 3549.8047607381327, 3807.961920380816, 3885.805709714503, 3892.1242720387686, 3896.181801821649, 1187.4834447015403, 1225.724212052281, 1228.2200802490484, 1238.2013601227159, 1241.1870225002638, 914.533587870191, 928.8513837785922, 935.6678379175431, 944.1502647617062, 993.4486360070086, 5890.528757699374, 5938.0, 5948.791792287249, 5956.761729530857, 6121.387861213843, 749.9437542184359, 959.822432849925, 994.393420013791, 1037.3983089807894, 1054.5834395413922, 3920.3923391874305, 3992.640662340345, 4008.478897743333, 4086.3053280942313, 4088.448059511987, 10537.315074520131, 10871.891281087246, 10908.563657453771, 10974.890251097546, 11028.117750579935, 10634.734131646615, 10638.0, 10754.247202695893, 10786.406747628798, 10842.566297348018, 7875.0, 8215.178482151738, 8305.127961563949, 8306.792330191673, 8310.085890552155, 5645.661260324394, 5907.143464197753, 6048.818535443944, 6058.5153187745755, 6094.542909281733, 9328.813423731623, 9473.621055157717, 9487.67172595851, 9488.857667135137, 9680.951595241979, 8261.545614990993, 9260.0, 9777.16894063984, 9963.900360996297, 10014.248931330325, 7637.809054773675, 7656.119546252029, 7771.650275737718, 7774.805629859299, 7776.7278145264645, 7485.5508669480005, 7607.277308655669, 8299.543525106177, 8311.83376332458, 8338.833223335378, 7323.450741194538, 7327.230640782641, 7381.0, 7383.187849336461, 7394.741184058747, 7194.460415468756, 7222.566646001257, 7310.122785265802, 7320.853582928418, 7323.377512911462, 6874.243833178446, 6892.620905850025, 6932.896006560007, 6966.756163534252, 7055.753048643272, 4883.267509873547, 4939.234418665113, 4974.900501990012, 5025.145725226794, 5103.974480127576, 4526.117407105583, 4990.650654454299, 5023.547880690755, 5027.296178534935, 5123.641345105807], "value": 4589.610890370043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.43938388128834, 326.3798782313604, 329.77245700466676, 330.67593758117044, 336.6347512948451, 5443.292371991641, 5518.78586710924, 6703.229128650207, 6707.832304192398, 6748.055272261888, 5754.884902301951, 6478.805635830932, 6717.966410167955, 6736.629485378302, 6746.966265168668, 4728.267118596615, 4850.2724591311235, 4894.290327902452, 4910.705357678551, 4932.457429682732, 4868.05073010763, 4913.950860491385, 4936.037472692827, 4990.550850423461, 4997.575206107485, 4845.830395936143, 4899.926501102486, 4911.238757992499, 4913.091078150535, 4918.163912134945, 4879.902401951977, 4895.730734809584, 4903.043906438228, 4910.386201724785, 4913.975430122827, 4058.7564746115327, 4107.67138628909, 4123.835046598132, 4128.926479115422, 4139.337705967031, 6265.8120256392385, 6492.383223593751, 6511.479081673455, 6532.150820393318, 6540.771073012469, 5875.559333050039, 5880.059190529538, 5883.0, 5896.734646940898, 5916.97041514794, 5806.622569532967, 5817.970910145465, 7210.134783825922, 7223.855522889513, 7229.602371869545, 4611.677182597219, 4750.144973904696, 4754.881127971794, 4756.571908528249, 4762.1190079835, 7278.454115941273, 7309.195988441267, 7355.448341374418, 7391.445641576849, 7459.254074592497, 4862.197737373364, 4863.708177509361, 4865.464798872121, 4865.5864251539015, 4870.707757534488, 4935.679180853233, 4941.061198372297, 4941.802327906916, 4949.381327334083, 4951.168067814931, 5850.5904586678525, 5858.648481091063, 5859.296884373817, 5866.1494083357675, 5892.263467066617, 6994.265602111806, 7107.218205997337, 7109.075820143452, 7111.64441777909, 7244.492885497964, 1641.253229780471, 1658.917054147288, 1660.435451946344, 1662.6674665067035, 1684.5451728033363, 1096.13405409727, 1104.4919337104861, 1121.159130652011, 1122.5229277557005, 1124.5895248234335, 6672.399484046459, 6675.899861502081, 6680.465562754968, 6695.0, 6706.597604143767, 7130.3939165170505, 7286.0, 7298.160711518243, 7433.85132297351, 7444.032275804111, 5617.0, 6776.356246156607, 6795.898061529081, 6801.965990170018, 6815.386615204557, 3926.391409331558, 3979.224051309967, 3986.242613903407, 3987.960120398817, 3993.740406873602, 7919.762407127795, 7922.405819563556, 7923.920760792318, 7924.247196516321, 7928.3260922821055, 6401.487880969511, 7496.475246732785, 7784.84430311402, 7804.141544430108, 7807.219278072147, 6308.779192728232, 7860.0, 7862.213778622092, 7897.60511974399, 7910.7231246906085, 7461.925380746123, 7487.8876816846705, 7489.70041198346, 7495.775126746206, 7498.100227972678, 5966.850828729316, 7372.815679607838, 7387.519811212469, 7394.55632662042, 7405.0, 6037.849053773515, 7849.215078492333, 7871.094824095302, 7882.448228623941, 7888.6450109744055, 4885.828995985124, 4896.167651499325, 4905.533974272438, 4909.9018019640125, 4913.263010548303, 7443.590602516913, 7446.292602202782, 7468.775936721907, 7471.514351567131, 7473.177950425557, 7146.285371462812, 7198.640067996684, 7212.531185473137, 7230.6384680764695, 7236.276372362721, 6890.1731792183255, 7172.892406614009, 7195.71217151329, 7198.820029499305, 7224.458165637703, 6959.686814093277, 7027.4729395294535, 7031.718731250898, 7035.33164349386, 7050.2597227292445, 7188.712451501881, 7189.317014883578, 7191.676374563262, 7197.8920316196345, 7212.386947109554, 6809.21694005176, 7027.051348067946, 7046.612436316052, 7049.753258635923, 7056.15326160864], "value": 5239.403350435942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.6610927590842, 691.733682532225, 729.6169511006717, 735.4631119282923, 789.0491957191587, 14758.966872318942, 14871.479498217552, 15099.62250943727, 15101.093934363933, 15103.39586416542, 13776.58670239892, 13878.444862205499, 13887.722245555107, 13888.791668124986, 13890.513832015882, 746.4886552711383, 752.3868047541264, 909.4497828813555, 916.1800188830993, 937.1986951156765, 926.9768255793591, 934.3926447808944, 934.6401635370363, 935.5135329628589, 936.3960245641557, 982.7887004294075, 987.6000219910911, 988.2143695761887, 991.3110388280148, 997.366672163178, 769.4229328003997, 775.4029397363998, 779.9883001754978, 781.9951362499161, 974.59554284972, 689.1351354050635, 696.5507247825125, 719.7336985315467, 732.1360794262768, 734.3611058379191, 14767.704645907022, 14800.777988330183, 14809.48166814167, 14847.925760371128, 14849.0, 12431.254124752544, 12512.562060327937, 12517.749645007048, 12585.874141258562, 12794.0, 9806.9509652452, 10008.49957502122, 10012.94993525035, 10043.74890627733, 10415.062644362044, 3472.2708231271463, 3503.351879902201, 3527.400341941876, 3559.323728491604, 3760.3607386744047, 68076.0, 76516.23483765147, 80929.19070809335, 81217.0, 81344.0, 9156.313276504288, 9580.473073980998, 9653.46905920181, 9665.613375465044, 9792.755181120529, 21093.156273749184, 21446.142154313966, 21481.248156314454, 21496.677549836764, 21965.890170549366, 7384.150822655356, 7386.298301661333, 7616.428767842434, 7680.078390593053, 7784.143744188134, 4136.358864376027, 4141.5237247716295, 4155.376693495939, 4167.4790651168605, 4235.408290586412, 3006.7143621355935, 3609.3864043112208, 3631.654992775682, 3632.0919770057485, 3637.363461394298, 631.958922670025, 632.8038308124496, 640.8974564069727, 652.4519403700914, 661.7617657643244, 542.5125798215228, 589.8879212949525, 605.927288725347, 611.623851331428, 617.4257940115646, 7716.5755883426955, 8186.2632363087605, 8222.712205072794, 8254.257116859511, 8306.460080094774, 546.3802309698249, 586.1705686453705, 594.2957595249643, 605.7819185093363, 606.0908637044433, 3815.21788033458, 3842.731008829411, 3897.7661340319673, 3899.395593682984, 3922.2155568886337, 85321.57339213265, 88145.1185488137, 98451.01548984418, 99423.0, 99554.5022274884, 71532.64233678798, 71696.0, 73142.26857731461, 73369.53260934858, 73422.63288683523, 63582.0, 64994.67502662551, 71461.28538714546, 71905.0, 73523.26476735165, 8092.352611790925, 8283.668653254044, 8320.76758085629, 8458.111898250841, 8596.0, 34799.0, 48047.75976120097, 48206.03587928292, 48917.0216595659, 49367.0, 63756.0, 67662.32337676757, 69868.60262794817, 70593.0, 70761.64619176871, 10541.4729263535, 10842.078423334106, 10947.0, 11016.559337626406, 11103.0, 11612.59427750525, 11758.176927614984, 11866.762664746484, 12084.0, 12335.136540442083, 11034.0, 11134.610288639858, 11223.270487418291, 11457.656270311903, 12189.207701499374, 11084.944575277072, 11463.885361146615, 11513.30920144794, 11576.536938522364, 11637.894400031983, 10967.945160274467, 11034.779304414027, 11072.667819965414, 11328.603498877499, 11806.996405305645, 9349.39228950116, 9682.0, 9707.174890134418, 9717.514124293615, 9983.450910199717, 9582.185514231369, 9603.0, 9680.51597420112, 9686.07982241686, 9730.708078757649], "value": 6861.707650951185, "unit": "score"}], "name": "dromaeo_css", "value": 7401.000529766361, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 121.0, 112.0, 112.0, 114.0, 119.0, 163.0, 166.0, 140.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 158.0, 155.0, 121.0, 141.0, 138.0, 118.0, 168.0, 115.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 187.0, 184.0, 187.0, 185.0, 183.0, 184.0, 185.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 109.0, 103.0, 202.0, 45.0, 74.0, 81.0, 90.0, 152.0, 81.0], "value": 101.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 137.0, 77.0, 78.0, 87.0, 123.0, 79.0, 80.0, 123.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 88.0, 88.0, 85.0, 88.0, 84.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 93.0, 85.0, 68.0, 130.0, 140.0, 88.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 64.0, 63.0, 63.0, 65.0, 65.0, 83.0, 58.0, 58.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 75.0, 71.0, 74.0, 71.0, 69.0, 68.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 81.0, 80.0, 81.0, 81.0, 81.0, 80.0, 82.0, 85.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 132.0, 128.0, 127.0, 130.0, 126.0, 130.0, 145.0, 129.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 165.0, 166.0, 167.0, 227.0, 199.0, 165.0, 162.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 70.0, 68.0, 67.0, 66.0, 68.0, 79.0, 89.0], "value": 70.8, "unit": "score"}], "name": "kraken", "value": 1514.5999999999997, "alertThreshold": 2.0}]} 10:19:35 INFO - Return code: 0 10:19:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:19:35 ERROR - # TBPL SUCCESS # 10:19:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:35 INFO - 2016-06-01 10:19:35.332 screenresolution[1678:10531] starting screenresolution argv=screenresolution get 10:19:35 INFO - 2016-06-01 10:19:35.335 screenresolution[1678:10531] Display 0: 1600x1200x32@60 10:19:35 INFO - 2016-06-01 10:19:35.341 screenresolution[1679:10534] starting screenresolution argv=screenresolution list 10:19:35 INFO - Available Modes on Display 0 10:19:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:35 INFO - Intel Iris: 10:19:35 INFO - Chipset Model: Intel Iris 10:19:35 INFO - Type: GPU 10:19:35 INFO - Bus: Built-In 10:19:35 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:35 INFO - Vendor: Intel (0x8086) 10:19:35 INFO - Device ID: 0x0a2e 10:19:35 INFO - Revision ID: 0x0009 10:19:35 INFO - Displays: 10:19:35 INFO - Display: 10:19:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:35 INFO - Display Serial Number: 1600x1200 60 10:19:35 INFO - Main Display: Yes 10:19:35 INFO - Mirror: Off 10:19:35 INFO - Online: Yes 10:19:35 INFO - Rotation: Supported 10:19:35 INFO - Return code: 0 10:19:35 INFO - Running post-action listener: _resource_record_post_action 10:19:35 INFO - Running post-run listener: _resource_record_post_run 10:19:36 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 32722432; Write bytes: 655125504; Read time: 329; Write time: 2539 10:19:36 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 355068416; Write bytes: 356075520; Read time: 27506; Write time: 701 10:19:36 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30719488; Write bytes: 298202112; Read time: 304; Write time: 1836 10:19:36 INFO - Running post-run listener: _upload_blobber_files 10:19:36 INFO - Blob upload gear active. 10:19:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:36 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 10:19:36 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'] 10:19:36 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 10:19:37 INFO - (blobuploader) - INFO - Open directory for files ... 10:19:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:19:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:19:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:38 INFO - (blobuploader) - INFO - Done attempting. 10:19:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:19:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:19:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:39 INFO - (blobuploader) - INFO - Done attempting. 10:19:39 INFO - (blobuploader) - INFO - Iteration through files over. 10:19:39 INFO - Return code: 0 10:19:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:19:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:19:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a20f4d45e00295035ba12fa529c2ade003e50949492f6682ac4bf8decf2eca1659808b0e4eae881b49eb86868c32a5d21e463f78389ac76dd03d831cc102900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/231939d2fc48f0e1de4f79060411c3568f22c3275b17283e81feaac3d1ebea79d661ed6b2b20ada78ded78b43f115342d272d46c53ade72645953a7cf5281a1b"} 10:19:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:19:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:19:39 INFO - Contents: 10:19:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a20f4d45e00295035ba12fa529c2ade003e50949492f6682ac4bf8decf2eca1659808b0e4eae881b49eb86868c32a5d21e463f78389ac76dd03d831cc102900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/231939d2fc48f0e1de4f79060411c3568f22c3275b17283e81feaac3d1ebea79d661ed6b2b20ada78ded78b43f115342d272d46c53ade72645953a7cf5281a1b"} 10:19:39 INFO - Running post-run listener: copy_logs_to_upload_dir 10:19:39 INFO - Copying logs to upload dir... 10:19:39 INFO - mkdir: /builds/slave/test/build/upload/logs 10:19:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.612156 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-01 10:19:40.441280) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 10:19:40.444460) ========= 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.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/1a20f4d45e00295035ba12fa529c2ade003e50949492f6682ac4bf8decf2eca1659808b0e4eae881b49eb86868c32a5d21e463f78389ac76dd03d831cc102900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/231939d2fc48f0e1de4f79060411c3568f22c3275b17283e81feaac3d1ebea79d661ed6b2b20ada78ded78b43f115342d272d46c53ade72645953a7cf5281a1b"} build_url:https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/MzZ_Tes-Su6xb6EFFSJZcA/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/1a20f4d45e00295035ba12fa529c2ade003e50949492f6682ac4bf8decf2eca1659808b0e4eae881b49eb86868c32a5d21e463f78389ac76dd03d831cc102900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/231939d2fc48f0e1de4f79060411c3568f22c3275b17283e81feaac3d1ebea79d661ed6b2b20ada78ded78b43f115342d272d46c53ade72645953a7cf5281a1b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 10:19:40.483241) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:19:40.483560) ========= 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.UhCZVWEjtF/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.EYY3VyORYh/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005010 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 10:19:40.536401) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 10:19:40.536747) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 10:19:40.825660) ========= ========= Total master_lag: 0.67 =========